builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0148 starttime: 1464322644.1 results: success (0) buildid: 20160526190243 builduid: adcc5cc5cf1041dabe88ef7c8cf828e6 revision: f2125a888d23ff0a5b6df4c31bc66f0132610750 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-26 21:17:24.098325) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-26 21:17:24.098867) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-26 21:17:24.099145) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-26 21:17:24.163437) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 21:17:24.163731) ========= 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.Z4GDO3vMW6/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.Mv7JVtew76/Listeners TMPDIR=/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-26 21:17:24-- 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% 10.1M=0.001s 2016-05-26 21:17:24 (10.1 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.448420 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 21:17:24.632055) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 21:17:24.632377) ========= 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.Z4GDO3vMW6/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.Mv7JVtew76/Listeners TMPDIR=/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/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.020007 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 21:17:24.679887) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-26 21:17:24.680200) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev f2125a888d23ff0a5b6df4c31bc66f0132610750 --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 f2125a888d23ff0a5b6df4c31bc66f0132610750 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Z4GDO3vMW6/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.Mv7JVtew76/Listeners TMPDIR=/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-26 21:17:24,755 truncating revision to first 12 chars 2016-05-26 21:17:24,755 Setting DEBUG logging. 2016-05-26 21:17:24,755 attempt 1/10 2016-05-26 21:17:24,756 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/f2125a888d23?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-26 21:17:26,194 unpacking tar archive at: fx-team-f2125a888d23/testing/mozharness/ program finished with exit code 0 elapsedTime=1.692822 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-26 21:17:26.397703) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-26 21:17:26.398011) ========= script_repo_revision: f2125a888d23ff0a5b6df4c31bc66f0132610750 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-26 21:17:26.398408) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-26 21:17:26.398689) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-26 21:17:26.419230) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 34 secs) (at 2016-05-26 21:17:26.419563) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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.Z4GDO3vMW6/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.Mv7JVtew76/Listeners TMPDIR=/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/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 21:17:26 INFO - MultiFileLogger online at 20160526 21:17:26 in /builds/slave/test 21:17:26 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 21:17:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:17:26 INFO - {'append_to_log': False, 21:17:26 INFO - 'base_work_dir': '/builds/slave/test', 21:17:26 INFO - 'blob_upload_branch': 'Fx-Team', 21:17:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:17:26 INFO - 'branch': 'Fx-Team', 21:17:26 INFO - 'buildbot_json_path': 'buildprops.json', 21:17:26 INFO - 'config_files': ('talos/mac_config.py',), 21:17:26 INFO - 'default_actions': ('clobber', 21:17:26 INFO - 'read-buildbot-config', 21:17:26 INFO - 'download-and-extract', 21:17:26 INFO - 'populate-webroot', 21:17:26 INFO - 'create-virtualenv', 21:17:26 INFO - 'install', 21:17:26 INFO - 'run-tests'), 21:17:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:17:26 INFO - 'download_minidump_stackwalk': True, 21:17:26 INFO - 'download_symbols': 'ondemand', 21:17:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:17:26 INFO - 'tooltool.py': '/tools/tooltool.py', 21:17:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:17:26 INFO - '/tools/misc-python/virtualenv.py')}, 21:17:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:17:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:17:26 INFO - 'installer_path': 'installer.exe', 21:17:26 INFO - 'log_level': 'info', 21:17:26 INFO - 'log_name': 'talos', 21:17:26 INFO - 'log_to_console': True, 21:17:26 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 21:17:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 21:17:26 INFO - 'opt_config_files': (), 21:17:26 INFO - 'pip_index': False, 21:17:26 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:17:26 INFO - 'cmd': ('bash', 21:17:26 INFO - '-c', 21:17:26 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:17:26 INFO - 'enabled': True, 21:17:26 INFO - 'halt_on_failure': False, 21:17:26 INFO - 'name': 'check_screen_resolution'},), 21:17:26 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:17:26 INFO - 'cmd': ('bash', 21:17:26 INFO - '-c', 21:17:26 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:17:26 INFO - 'enabled': True, 21:17:26 INFO - 'halt_on_failure': False, 21:17:26 INFO - 'name': 'check_screen_resolution'},), 21:17:26 INFO - 'run_cmd_checks_enabled': True, 21:17:26 INFO - 'sps_profile': False, 21:17:26 INFO - 'sps_profile_interval': 0, 21:17:26 INFO - 'suite': 'dromaeojs-e10s', 21:17:26 INFO - 'system_bits': '32', 21:17:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:17:26 INFO - 'title': 't-yosemite-r7-0148', 21:17:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:17:26 INFO - 'use_talos_json': True, 21:17:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:17:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:17:26 INFO - 'work_dir': 'build'} 21:17:26 INFO - ##### 21:17:26 INFO - ##### Running clobber step. 21:17:26 INFO - ##### 21:17:26 INFO - Running pre-action listener: _resource_record_pre_action 21:17:26 INFO - Running main action method: clobber 21:17:26 INFO - rmtree: /builds/slave/test/build 21:17:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:17:28 INFO - Running post-action listener: _resource_record_post_action 21:17:28 INFO - ##### 21:17:28 INFO - ##### Finished clobber step (success) 21:17:28 INFO - ##### 21:17:28 INFO - ##### 21:17:28 INFO - ##### Running read-buildbot-config step. 21:17:28 INFO - ##### 21:17:28 INFO - Running pre-action listener: _resource_record_pre_action 21:17:28 INFO - Running main action method: read_buildbot_config 21:17:28 INFO - Using buildbot properties: 21:17:28 INFO - { 21:17:28 INFO - "project": "", 21:17:28 INFO - "product": "firefox", 21:17:28 INFO - "script_repo_revision": "production", 21:17:28 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 21:17:28 INFO - "repository": "", 21:17:28 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 21:17:28 INFO - "buildid": "20160526190243", 21:17:28 INFO - "pgo_build": "False", 21:17:28 INFO - "basedir": "/builds/slave/test", 21:17:28 INFO - "buildnumber": 29, 21:17:28 INFO - "slavename": "t-yosemite-r7-0148", 21:17:28 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 21:17:28 INFO - "platform": "yosemite_r7", 21:17:28 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 21:17:28 INFO - "branch": "fx-team", 21:17:28 INFO - "repo_path": "integration/fx-team", 21:17:28 INFO - "revision": "f2125a888d23ff0a5b6df4c31bc66f0132610750", 21:17:28 INFO - "stage_platform": "macosx64", 21:17:28 INFO - "builduid": "adcc5cc5cf1041dabe88ef7c8cf828e6", 21:17:28 INFO - "slavebuilddir": "test" 21:17:28 INFO - } 21:17:28 INFO - Found installer url https://queue.taskcluster.net/v1/task/HjX7zCRzTVWYUsE8TXQKSw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 21:17:28 INFO - Running post-action listener: _resource_record_post_action 21:17:28 INFO - ##### 21:17:28 INFO - ##### Finished read-buildbot-config step (success) 21:17:28 INFO - ##### 21:17:28 INFO - ##### 21:17:28 INFO - ##### Running download-and-extract step. 21:17:28 INFO - ##### 21:17:28 INFO - Running pre-action listener: _resource_record_pre_action 21:17:28 INFO - Running main action method: download_and_extract 21:17:28 INFO - mkdir: /builds/slave/test/build/tests 21:17:28 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')]} 21:17:28 INFO - https://queue.taskcluster.net/v1/task/HjX7zCRzTVWYUsE8TXQKSw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 21:17:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HjX7zCRzTVWYUsE8TXQKSw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 21:17:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HjX7zCRzTVWYUsE8TXQKSw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 21:17:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HjX7zCRzTVWYUsE8TXQKSw/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 21:17:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HjX7zCRzTVWYUsE8TXQKSw/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 21:17:41 INFO - Downloaded 1199 bytes. 21:17:41 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 21:17:41 INFO - Using the following test package requirements: 21:17:41 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 21:17:41 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 21:17:41 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 21:17:41 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 21:17:41 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 21:17:41 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 21:17:41 INFO - u'jsshell-mac.zip'], 21:17:41 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 21:17:41 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 21:17:41 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 21:17:41 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 21:17:41 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 21:17:41 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 21:17:41 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 21:17:41 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 21:17:41 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 21:17:41 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 21:17:41 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 21:17:41 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 21:17:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:17:41 INFO - https://queue.taskcluster.net/v1/task/HjX7zCRzTVWYUsE8TXQKSw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 21:17:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HjX7zCRzTVWYUsE8TXQKSw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 21:17:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HjX7zCRzTVWYUsE8TXQKSw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 21:17:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HjX7zCRzTVWYUsE8TXQKSw/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 21:17:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HjX7zCRzTVWYUsE8TXQKSw/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 21:17:57 INFO - Downloaded 18684827 bytes. 21:17:57 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'] 21:17:57 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 21:18:00 INFO - Return code: 0 21:18:00 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 21:18:00 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')]} 21:18:00 INFO - https://queue.taskcluster.net/v1/task/HjX7zCRzTVWYUsE8TXQKSw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 21:18:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HjX7zCRzTVWYUsE8TXQKSw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 21:18:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HjX7zCRzTVWYUsE8TXQKSw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 21:18:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HjX7zCRzTVWYUsE8TXQKSw/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 21:18:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HjX7zCRzTVWYUsE8TXQKSw/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 21:18:01 INFO - Downloaded 18684827 bytes. 21:18:01 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'] 21:18:01 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 21:18:05 INFO - Return code: 0 21:18:05 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')]} 21:18:05 INFO - https://queue.taskcluster.net/v1/task/HjX7zCRzTVWYUsE8TXQKSw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 21:18:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HjX7zCRzTVWYUsE8TXQKSw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 21:18:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HjX7zCRzTVWYUsE8TXQKSw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 21:18:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HjX7zCRzTVWYUsE8TXQKSw/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 21:18:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HjX7zCRzTVWYUsE8TXQKSw/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 21:18:06 INFO - Downloaded 10834034 bytes. 21:18:06 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'] 21:18:06 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 21:18:06 INFO - Return code: 0 21:18:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:18:06 INFO - https://queue.taskcluster.net/v1/task/HjX7zCRzTVWYUsE8TXQKSw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 21:18:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HjX7zCRzTVWYUsE8TXQKSw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 21:18:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HjX7zCRzTVWYUsE8TXQKSw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 21:18:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HjX7zCRzTVWYUsE8TXQKSw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 21:18:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HjX7zCRzTVWYUsE8TXQKSw/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 21:18:11 INFO - Downloaded 110478822 bytes. 21:18:11 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HjX7zCRzTVWYUsE8TXQKSw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 21:18:11 INFO - mkdir: /builds/slave/test/properties 21:18:11 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:18:11 INFO - Writing to file /builds/slave/test/properties/build_url 21:18:11 INFO - Contents: 21:18:11 INFO - build_url:https://queue.taskcluster.net/v1/task/HjX7zCRzTVWYUsE8TXQKSw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 21:18:12 INFO - Running post-action listener: _resource_record_post_action 21:18:12 INFO - Running post-action listener: set_extra_try_arguments 21:18:12 INFO - ##### 21:18:12 INFO - ##### Finished download-and-extract step (success) 21:18:12 INFO - ##### 21:18:12 INFO - ##### 21:18:12 INFO - ##### Running populate-webroot step. 21:18:12 INFO - ##### 21:18:12 INFO - Running pre-action listener: _resource_record_pre_action 21:18:12 INFO - Running main action method: populate_webroot 21:18:12 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:18:12 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:18:12 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:18:12 INFO - '--tppagecycles', 21:18:12 INFO - '7'], 21:18:12 INFO - 'tests': ['tsvgm']}}, 21:18:12 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:18:12 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:18:12 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:18:12 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:18:12 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:18:12 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:18:12 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:18:12 INFO - 'talos_options': ['--disable-e10s'], 21:18:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:18:12 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:18:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:18:12 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:18:12 INFO - 'talos_options': ['--disable-e10s'], 21:18:12 INFO - 'tests': ['damp', 'tps']}, 21:18:12 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:18:12 INFO - 'tests': ['damp', 'tps']}, 21:18:12 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:18:12 INFO - 'tests': ['dromaeo_dom']}, 21:18:12 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:18:12 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:18:12 INFO - 'tests': ['a11yr', 21:18:12 INFO - 'ts_paint', 21:18:12 INFO - 'tpaint', 21:18:12 INFO - 'sessionrestore', 21:18:12 INFO - 'sessionrestore_no_auto_restore', 21:18:12 INFO - 'tabpaint']}, 21:18:12 INFO - 'other-e10s': {'tests': ['a11yr', 21:18:12 INFO - 'ts_paint', 21:18:12 INFO - 'tpaint', 21:18:12 INFO - 'sessionrestore', 21:18:12 INFO - 'sessionrestore_no_auto_restore', 21:18:12 INFO - 'tabpaint']}, 21:18:12 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:18:12 INFO - 'ts_paint', 21:18:12 INFO - 'tpaint', 21:18:12 INFO - 'sessionrestore', 21:18:12 INFO - 'sessionrestore_no_auto_restore', 21:18:12 INFO - 'tabpaint']}, 21:18:12 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:18:12 INFO - 'ts_paint', 21:18:12 INFO - 'tpaint', 21:18:12 INFO - 'sessionrestore', 21:18:12 INFO - 'sessionrestore_no_auto_restore', 21:18:12 INFO - 'tabpaint']}, 21:18:12 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:18:12 INFO - 'tests': ['a11yr', 21:18:12 INFO - 'ts_paint', 21:18:12 INFO - 'tpaint', 21:18:12 INFO - 'sessionrestore', 21:18:12 INFO - 'sessionrestore_no_auto_restore', 21:18:12 INFO - 'tabpaint']}, 21:18:12 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:18:12 INFO - 'tests': ['a11yr', 21:18:12 INFO - 'ts_paint', 21:18:12 INFO - 'tpaint', 21:18:12 INFO - 'sessionrestore', 21:18:12 INFO - 'sessionrestore_no_auto_restore', 21:18:12 INFO - 'tabpaint']}, 21:18:12 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:18:12 INFO - 'tests': ['tsvgx', 21:18:12 INFO - 'tsvgr_opacity', 21:18:12 INFO - 'tart', 21:18:12 INFO - 'tscrollx', 21:18:12 INFO - 'cart']}, 21:18:12 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:18:12 INFO - 'tsvgr_opacity', 21:18:12 INFO - 'tart', 21:18:12 INFO - 'tscrollx', 21:18:12 INFO - 'cart']}, 21:18:12 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:18:12 INFO - 'talos_options': ['--disable-e10s'], 21:18:12 INFO - 'tests': ['tp5o']}, 21:18:12 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:18:12 INFO - 'tests': ['tp5o']}, 21:18:12 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:18:12 INFO - 'talos_options': ['--disable-e10s', 21:18:12 INFO - '--xperf_path', 21:18:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:18:12 INFO - 'tests': ['tp5n']}, 21:18:12 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:18:12 INFO - 'talos_options': ['--xperf_path', 21:18:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:18:12 INFO - 'tests': ['tp5n']}}, 21:18:12 INFO - 'talos.zip': {'path': '', 21:18:12 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:18:12 INFO - Running post-action listener: _resource_record_post_action 21:18:12 INFO - ##### 21:18:12 INFO - ##### Finished populate-webroot step (success) 21:18:12 INFO - ##### 21:18:12 INFO - ##### 21:18:12 INFO - ##### Running create-virtualenv step. 21:18:12 INFO - ##### 21:18:12 INFO - Running pre-action listener: _resource_record_pre_action 21:18:12 INFO - Running main action method: create_virtualenv 21:18:12 INFO - Creating virtualenv /builds/slave/test/build/venv 21:18:12 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 21:18:12 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:18:12 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:18:12 INFO - Using real prefix '/tools/python27' 21:18:12 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:18:13 INFO - Installing distribute.............................................................................................................................................................................................done. 21:18:16 INFO - Installing pip.................done. 21:18:16 INFO - Return code: 0 21:18:16 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:18:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:18:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:18:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:18:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:18:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:18:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:18:16 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 0x1066fca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106702570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8903cb2400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1065a0d30>, '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 0x1063c4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106597b20>, '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.Z4GDO3vMW6/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.Mv7JVtew76/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/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 21:18:16 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 21:18:16 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 21:18:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Z4GDO3vMW6/Render', 21:18:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:18:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:18:16 INFO - 'HOME': '/Users/cltbld', 21:18:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:18:16 INFO - 'LOGNAME': 'cltbld', 21:18:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:18:16 INFO - 'MOZ_NO_REMOTE': '1', 21:18:16 INFO - 'NO_EM_RESTART': '1', 21:18:16 INFO - 'PAGER': '/bin/cat', 21:18:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:18:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:18:16 INFO - 'PWD': '/builds/slave/test', 21:18:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:18:16 INFO - 'SHELL': '/bin/bash', 21:18:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Mv7JVtew76/Listeners', 21:18:16 INFO - 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/', 21:18:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:18:16 INFO - 'USER': 'cltbld', 21:18:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:18:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:18:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:18:16 INFO - 'XPC_FLAGS': '0x0', 21:18:16 INFO - 'XPC_SERVICE_NAME': '0', 21:18:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:18:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:18:16 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 21:18:16 INFO - Cleaning up... 21:18:16 INFO - Return code: 0 21:18:16 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:18:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:18:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:18:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:18:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:18:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:18:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:18:16 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 0x1066fca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106702570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8903cb2400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1065a0d30>, '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 0x1063c4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106597b20>, '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.Z4GDO3vMW6/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.Mv7JVtew76/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/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 21:18:16 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 21:18:16 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 21:18:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Z4GDO3vMW6/Render', 21:18:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:18:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:18:16 INFO - 'HOME': '/Users/cltbld', 21:18:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:18:16 INFO - 'LOGNAME': 'cltbld', 21:18:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:18:16 INFO - 'MOZ_NO_REMOTE': '1', 21:18:16 INFO - 'NO_EM_RESTART': '1', 21:18:16 INFO - 'PAGER': '/bin/cat', 21:18:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:18:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:18:16 INFO - 'PWD': '/builds/slave/test', 21:18:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:18:16 INFO - 'SHELL': '/bin/bash', 21:18:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Mv7JVtew76/Listeners', 21:18:16 INFO - 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/', 21:18:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:18:16 INFO - 'USER': 'cltbld', 21:18:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:18:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:18:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:18:16 INFO - 'XPC_FLAGS': '0x0', 21:18:16 INFO - 'XPC_SERVICE_NAME': '0', 21:18:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:18:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:18:16 INFO - Downloading/unpacking psutil>=3.1.1 21:18:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:18:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:18:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:18:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:18:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:18:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:18:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:18:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:18:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:18:19 INFO - Installing collected packages: psutil 21:18:19 INFO - Running setup.py install for psutil 21:18:19 INFO - building 'psutil._psutil_osx' extension 21:18:19 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 21:18:19 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 21:18:19 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 21:18:19 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 21:18:19 INFO - building 'psutil._psutil_posix' extension 21:18:19 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 21:18:20 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 21:18:20 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 21:18:20 INFO - ^ 21:18:20 INFO - 1 warning generated. 21:18:20 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 21:18:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:18:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:18:20 INFO - Successfully installed psutil 21:18:20 INFO - Cleaning up... 21:18:20 INFO - Return code: 0 21:18:20 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:18:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:18:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:18:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:18:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:18:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:18:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:18:20 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 0x1066fca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106702570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8903cb2400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1065a0d30>, '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 0x1063c4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106597b20>, '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.Z4GDO3vMW6/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.Mv7JVtew76/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/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 21:18:20 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 21:18:20 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 21:18:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Z4GDO3vMW6/Render', 21:18:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:18:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:18:20 INFO - 'HOME': '/Users/cltbld', 21:18:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:18:20 INFO - 'LOGNAME': 'cltbld', 21:18:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:18:20 INFO - 'MOZ_NO_REMOTE': '1', 21:18:20 INFO - 'NO_EM_RESTART': '1', 21:18:20 INFO - 'PAGER': '/bin/cat', 21:18:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:18:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:18:20 INFO - 'PWD': '/builds/slave/test', 21:18:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:18:20 INFO - 'SHELL': '/bin/bash', 21:18:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Mv7JVtew76/Listeners', 21:18:20 INFO - 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/', 21:18:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:18:20 INFO - 'USER': 'cltbld', 21:18:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:18:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:18:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:18:20 INFO - 'XPC_FLAGS': '0x0', 21:18:20 INFO - 'XPC_SERVICE_NAME': '0', 21:18:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:18:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:18:20 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:18:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:18:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:18:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:18:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:18:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:18:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:18:22 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:18:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:18:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:18:23 INFO - Installing collected packages: mozsystemmonitor 21:18:23 INFO - Running setup.py install for mozsystemmonitor 21:18:23 INFO - Successfully installed mozsystemmonitor 21:18:23 INFO - Cleaning up... 21:18:23 INFO - Return code: 0 21:18:23 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:18:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:18:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:18:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:18:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:18:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:18:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:18:23 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 0x1066fca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106702570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8903cb2400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1065a0d30>, '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 0x1063c4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106597b20>, '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.Z4GDO3vMW6/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.Mv7JVtew76/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/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 21:18:23 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 21:18:23 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 21:18:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Z4GDO3vMW6/Render', 21:18:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:18:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:18:23 INFO - 'HOME': '/Users/cltbld', 21:18:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:18:23 INFO - 'LOGNAME': 'cltbld', 21:18:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:18:23 INFO - 'MOZ_NO_REMOTE': '1', 21:18:23 INFO - 'NO_EM_RESTART': '1', 21:18:23 INFO - 'PAGER': '/bin/cat', 21:18:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:18:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:18:23 INFO - 'PWD': '/builds/slave/test', 21:18:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:18:23 INFO - 'SHELL': '/bin/bash', 21:18:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Mv7JVtew76/Listeners', 21:18:23 INFO - 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/', 21:18:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:18:23 INFO - 'USER': 'cltbld', 21:18:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:18:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:18:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:18:23 INFO - 'XPC_FLAGS': '0x0', 21:18:23 INFO - 'XPC_SERVICE_NAME': '0', 21:18:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:18:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:18:23 INFO - Downloading/unpacking blobuploader==1.2.4 21:18:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:18:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:18:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:18:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:18:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:18:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:18:25 INFO - Downloading blobuploader-1.2.4.tar.gz 21:18:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:18:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:18: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 21:18: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 21:18: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 21:18: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 21:18: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 21:18: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 21:18:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:18:26 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:18: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 21:18: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 21:18: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 21:18: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 21:18: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 21:18: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 21:18:27 INFO - Downloading docopt-0.6.1.tar.gz 21:18:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:18:27 INFO - Installing collected packages: blobuploader, requests, docopt 21:18:27 INFO - Running setup.py install for blobuploader 21:18:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:18:27 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:18:27 INFO - Running setup.py install for requests 21:18:27 INFO - Running setup.py install for docopt 21:18:28 INFO - Successfully installed blobuploader requests docopt 21:18:28 INFO - Cleaning up... 21:18:28 INFO - Return code: 0 21:18:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:18:28 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')]} 21:18:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:18:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:18:28 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')]} 21:18:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:18:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:18:28 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 0x1066fca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106702570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8903cb2400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1065a0d30>, '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 0x1063c4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106597b20>, '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.Z4GDO3vMW6/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.Mv7JVtew76/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/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 21:18:28 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 21:18:28 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 21:18:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Z4GDO3vMW6/Render', 21:18:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:18:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:18:28 INFO - 'HOME': '/Users/cltbld', 21:18:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:18:28 INFO - 'LOGNAME': 'cltbld', 21:18:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:18:28 INFO - 'MOZ_NO_REMOTE': '1', 21:18:28 INFO - 'NO_EM_RESTART': '1', 21:18:28 INFO - 'PAGER': '/bin/cat', 21:18:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:18:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:18:28 INFO - 'PWD': '/builds/slave/test', 21:18:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:18:28 INFO - 'SHELL': '/bin/bash', 21:18:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Mv7JVtew76/Listeners', 21:18:28 INFO - 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/', 21:18:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:18:28 INFO - 'USER': 'cltbld', 21:18:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:18:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:18:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:18:28 INFO - 'XPC_FLAGS': '0x0', 21:18:28 INFO - 'XPC_SERVICE_NAME': '0', 21:18:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:18:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:18:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:18:28 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-nK8hvt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:18:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:18:28 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-LORErx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:18:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:18:28 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-L3ygAR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:18:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:18:28 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-YEPXLD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:18:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:18:28 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-GQ79fv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:18:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:18:28 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-CL11vn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:18:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:18:28 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-JWefsd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:18:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:18:29 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-tVxGLM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:18:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:18:29 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-JiGP5j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:18:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:18:29 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-CjMxcX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:18:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:18:29 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-2kc93f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:18:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:18:29 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-qlu_JG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:18:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:18:29 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-Pfx8kw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:18:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:18:29 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-Citrw4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:18:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:18:29 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-0DD7Gs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:18:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:18:29 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-F2KaW0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:18:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:18:30 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-y2L2mA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:18:30 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:18:30 INFO - Running setup.py install for manifestparser 21:18:30 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:18:30 INFO - Running setup.py install for mozcrash 21:18:30 INFO - Running setup.py install for mozdebug 21:18:30 INFO - Running setup.py install for mozdevice 21:18:30 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:18:30 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:18:30 INFO - Running setup.py install for mozfile 21:18:30 INFO - Running setup.py install for mozhttpd 21:18:31 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:18:31 INFO - Running setup.py install for mozinfo 21:18:31 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:18:31 INFO - Running setup.py install for mozInstall 21:18:31 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:18:31 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:18:31 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:18:31 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:18:31 INFO - Running setup.py install for mozleak 21:18:31 INFO - Running setup.py install for mozlog 21:18:31 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:18:31 INFO - Running setup.py install for moznetwork 21:18:31 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:18:31 INFO - Running setup.py install for mozprocess 21:18:31 INFO - Running setup.py install for mozprofile 21:18:32 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:18:32 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:18:32 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:18:32 INFO - Running setup.py install for mozrunner 21:18:32 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:18:32 INFO - Running setup.py install for mozscreenshot 21:18:32 INFO - Running setup.py install for moztest 21:18:32 INFO - Running setup.py install for mozversion 21:18:32 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:18:32 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:18:32 INFO - Cleaning up... 21:18:32 INFO - Return code: 0 21:18:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:18:32 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')]} 21:18:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:18:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:18:32 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')]} 21:18:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:18:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:18:32 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 0x1066fca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106702570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8903cb2400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1065a0d30>, '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 0x1063c4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106597b20>, '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.Z4GDO3vMW6/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.Mv7JVtew76/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/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 21:18:32 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 21:18:32 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 21:18:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Z4GDO3vMW6/Render', 21:18:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:18:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:18:32 INFO - 'HOME': '/Users/cltbld', 21:18:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:18:32 INFO - 'LOGNAME': 'cltbld', 21:18:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:18:32 INFO - 'MOZ_NO_REMOTE': '1', 21:18:32 INFO - 'NO_EM_RESTART': '1', 21:18:32 INFO - 'PAGER': '/bin/cat', 21:18:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:18:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:18:32 INFO - 'PWD': '/builds/slave/test', 21:18:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:18:32 INFO - 'SHELL': '/bin/bash', 21:18:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Mv7JVtew76/Listeners', 21:18:32 INFO - 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/', 21:18:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:18:32 INFO - 'USER': 'cltbld', 21:18:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:18:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:18:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:18:32 INFO - 'XPC_FLAGS': '0x0', 21:18:32 INFO - 'XPC_SERVICE_NAME': '0', 21:18:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:18:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:18:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:18:33 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-AsVeVF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:18:33 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)) 21:18:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:18:33 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-v9mWmr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:18:33 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)) 21:18:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:18:33 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-2CvWzQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:18:33 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)) 21:18:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:18:33 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-knyAH8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:18:33 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)) 21:18:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:18:33 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-wbC5n9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:18:33 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)) 21:18:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:18:33 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-FZsbuF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:18:33 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)) 21:18:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:18:33 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-t1VoFE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:18:33 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)) 21:18:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:18:33 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-5bNH_K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:18:33 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)) 21:18:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:18:33 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-ujUO_i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:18:33 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)) 21:18:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:18:33 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-O3dTlA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:18:34 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)) 21:18:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:18:34 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-qxxsdx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:18:34 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)) 21:18:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:18:34 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-_RflI5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:18:34 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)) 21:18:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:18:34 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-rLQj0f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:18:34 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)) 21:18:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:18:34 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-2LzJ_T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:18:34 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)) 21:18:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:18:34 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-RGnsj2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:18:34 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)) 21:18:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:18:34 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-4RN6bZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:18:34 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)) 21:18:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:18:34 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-iBei2H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:18:34 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)) 21:18:34 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)) 21:18:34 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)) 21:18:34 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)) 21:18:34 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)) 21:18:34 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)) 21:18:34 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:18:34 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 21:18:34 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 21:18:34 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 21:18:34 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 21:18:34 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 21:18:34 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 21:18:37 INFO - Downloading blessings-1.6.tar.gz 21:18:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:18:37 INFO - Installing collected packages: blessings 21:18:37 INFO - Running setup.py install for blessings 21:18:37 INFO - Successfully installed blessings 21:18:37 INFO - Cleaning up... 21:18:38 INFO - Return code: 0 21:18:38 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:18:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:18:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:18:38 INFO - Reading from file tmpfile_stdout 21:18:38 INFO - Current package versions: 21:18:38 INFO - blessings == 1.6 21:18:38 INFO - blobuploader == 1.2.4 21:18:38 INFO - docopt == 0.6.1 21:18:38 INFO - manifestparser == 1.1 21:18:38 INFO - mozInstall == 1.12 21:18:38 INFO - mozcrash == 0.17 21:18:38 INFO - mozdebug == 0.1 21:18:38 INFO - mozdevice == 0.48 21:18:38 INFO - mozfile == 1.2 21:18:38 INFO - mozhttpd == 0.7 21:18:38 INFO - mozinfo == 0.9 21:18:38 INFO - mozleak == 0.1 21:18:38 INFO - mozlog == 3.2 21:18:38 INFO - moznetwork == 0.27 21:18:38 INFO - mozprocess == 0.22 21:18:38 INFO - mozprofile == 0.28 21:18:38 INFO - mozrunner == 6.11 21:18:38 INFO - mozscreenshot == 0.1 21:18:38 INFO - mozsystemmonitor == 0.3 21:18:38 INFO - moztest == 0.7 21:18:38 INFO - mozversion == 1.4 21:18:38 INFO - psutil == 3.1.1 21:18:38 INFO - requests == 1.2.3 21:18:38 INFO - wsgiref == 0.1.2 21:18:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:18:38 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')]} 21:18:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:18:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:18:38 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')]} 21:18:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:18:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:18:38 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 0x1066fca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106702570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8903cb2400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1065a0d30>, '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 0x1063c4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106597b20>, '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.Z4GDO3vMW6/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.Mv7JVtew76/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/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 21:18:38 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 21:18:38 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 21:18:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Z4GDO3vMW6/Render', 21:18:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:18:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:18:38 INFO - 'HOME': '/Users/cltbld', 21:18:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:18:38 INFO - 'LOGNAME': 'cltbld', 21:18:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:18:38 INFO - 'MOZ_NO_REMOTE': '1', 21:18:38 INFO - 'NO_EM_RESTART': '1', 21:18:38 INFO - 'PAGER': '/bin/cat', 21:18:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:18:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:18:38 INFO - 'PWD': '/builds/slave/test', 21:18:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:18:38 INFO - 'SHELL': '/bin/bash', 21:18:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Mv7JVtew76/Listeners', 21:18:38 INFO - 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/', 21:18:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:18:38 INFO - 'USER': 'cltbld', 21:18:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:18:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:18:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:18:38 INFO - 'XPC_FLAGS': '0x0', 21:18:38 INFO - 'XPC_SERVICE_NAME': '0', 21:18:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:18:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:18:38 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)) 21:18:38 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)) 21:18:38 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)) 21:18:38 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)) 21:18:38 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)) 21:18:38 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)) 21:18:38 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)) 21:18:38 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)) 21:18:38 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)) 21:18:38 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)) 21:18:38 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)) 21:18:38 INFO - Cleaning up... 21:18:38 INFO - Return code: 0 21:18:38 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:18:38 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')]} 21:18:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:18:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:18:38 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')]} 21:18:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:18:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:18:38 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 0x1066fca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106702570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8903cb2400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1065a0d30>, '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 0x1063c4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106597b20>, '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.Z4GDO3vMW6/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.Mv7JVtew76/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/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 21:18:38 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 21:18:38 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 21:18:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Z4GDO3vMW6/Render', 21:18:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:18:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:18:38 INFO - 'HOME': '/Users/cltbld', 21:18:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:18:38 INFO - 'LOGNAME': 'cltbld', 21:18:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:18:38 INFO - 'MOZ_NO_REMOTE': '1', 21:18:38 INFO - 'NO_EM_RESTART': '1', 21:18:38 INFO - 'PAGER': '/bin/cat', 21:18:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:18:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:18:38 INFO - 'PWD': '/builds/slave/test', 21:18:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:18:38 INFO - 'SHELL': '/bin/bash', 21:18:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Mv7JVtew76/Listeners', 21:18:38 INFO - 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/', 21:18:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:18:38 INFO - 'USER': 'cltbld', 21:18:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:18:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:18:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:18:38 INFO - 'XPC_FLAGS': '0x0', 21:18:38 INFO - 'XPC_SERVICE_NAME': '0', 21:18:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:18:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:18:38 INFO - Downloading/unpacking jsonschema 21:18:38 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 21:18:38 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 21:18:38 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 21:18:38 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 21:18:38 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 21:18:38 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 21:18:41 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:18:41 INFO - Downloading/unpacking functools32 (from jsonschema) 21:18:41 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 21:18:41 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 21:18:41 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 21:18:41 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 21:18:41 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 21:18:41 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 21:18:41 INFO - Downloading functools32-3.2.3-2.tar.gz 21:18:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:18:41 INFO - warning: no files found matching '*.txt' 21:18:41 INFO - no previously-included directories found matching 'build' 21:18:41 INFO - no previously-included directories found matching 'dist' 21:18:41 INFO - no previously-included directories found matching '.git*' 21:18:41 INFO - Installing collected packages: jsonschema, functools32 21:18:41 INFO - Running setup.py install for functools32 21:18:42 INFO - warning: no files found matching '*.txt' 21:18:42 INFO - no previously-included directories found matching 'build' 21:18:42 INFO - no previously-included directories found matching 'dist' 21:18:42 INFO - no previously-included directories found matching '.git*' 21:18:42 INFO - Successfully installed jsonschema functools32 21:18:42 INFO - Cleaning up... 21:18:42 INFO - Return code: 0 21:18:42 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 21:18:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:18:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:18:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:18:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:18:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:18:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:18:42 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 0x1066fca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106702570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8903cb2400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1065a0d30>, '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 0x1063c4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106597b20>, '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.Z4GDO3vMW6/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.Mv7JVtew76/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/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 21:18:42 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 21:18:42 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 21:18:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Z4GDO3vMW6/Render', 21:18:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:18:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:18:42 INFO - 'HOME': '/Users/cltbld', 21:18:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:18:42 INFO - 'LOGNAME': 'cltbld', 21:18:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:18:42 INFO - 'MOZ_NO_REMOTE': '1', 21:18:42 INFO - 'NO_EM_RESTART': '1', 21:18:42 INFO - 'PAGER': '/bin/cat', 21:18:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:18:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:18:42 INFO - 'PWD': '/builds/slave/test', 21:18:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:18:42 INFO - 'SHELL': '/bin/bash', 21:18:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Mv7JVtew76/Listeners', 21:18:42 INFO - 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/', 21:18:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:18:42 INFO - 'USER': 'cltbld', 21:18:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:18:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:18:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:18:42 INFO - 'XPC_FLAGS': '0x0', 21:18:42 INFO - 'XPC_SERVICE_NAME': '0', 21:18:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:18:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:18:42 INFO - Downloading/unpacking flake8 21:18:42 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 21:18:42 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 21:18:42 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 21:18:42 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 21:18:42 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 21:18:42 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 21:18:45 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 21:18:45 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 21:18:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:18:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:18:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:18:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:18:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:18:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:18:45 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 21:18:45 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 21:18:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:18:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:18:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:18:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:18:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:18:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:18:46 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 21:18:46 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 21:18:46 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 21:18:46 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 21:18:46 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 21:18:46 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 21:18:46 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 21:18:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 21:18:46 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 21:18:46 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 21:18:46 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 21:18:46 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 21:18:46 INFO - no previously-included directories found matching 'docs/_build' 21:18:46 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 21:18:46 INFO - Running setup.py install for pep8 21:18:46 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 21:18:46 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 21:18:46 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 21:18:46 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 21:18:46 INFO - no previously-included directories found matching 'docs/_build' 21:18:46 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 21:18:46 INFO - Successfully installed flake8 mccabe pyflakes pep8 21:18:46 INFO - Cleaning up... 21:18:46 INFO - Return code: 0 21:18:46 INFO - Running post-action listener: _resource_record_post_action 21:18:46 INFO - Running post-action listener: _start_resource_monitoring 21:18:47 INFO - Starting resource monitoring. 21:18:47 INFO - ##### 21:18:47 INFO - ##### Finished create-virtualenv step (success) 21:18:47 INFO - ##### 21:18:47 INFO - ##### 21:18:47 INFO - ##### Running install step. 21:18:47 INFO - ##### 21:18:47 INFO - Running pre-action listener: _resource_record_pre_action 21:18:47 INFO - Running main action method: install 21:18:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:18:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:18:47 INFO - Reading from file tmpfile_stdout 21:18:47 INFO - Detecting whether we're running mozinstall >=1.0... 21:18:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:18:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:18:47 INFO - Reading from file tmpfile_stdout 21:18:47 INFO - Output received: 21:18:47 INFO - Usage: mozinstall [options] installer 21:18:47 INFO - Options: 21:18:47 INFO - -h, --help show this help message and exit 21:18:47 INFO - -d DEST, --destination=DEST 21:18:47 INFO - Directory to install application into. [default: 21:18:47 INFO - "/builds/slave/test"] 21:18:47 INFO - --app=APP Application being installed. [default: firefox] 21:18:47 INFO - mkdir: /builds/slave/test/build/application 21:18:47 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'] 21:18:47 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 21:19:21 INFO - Reading from file tmpfile_stdout 21:19:21 INFO - Output received: 21:19:21 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 21:19:21 INFO - Running post-action listener: _resource_record_post_action 21:19:21 INFO - ##### 21:19:21 INFO - ##### Finished install step (success) 21:19:21 INFO - ##### 21:19:21 INFO - ##### 21:19:21 INFO - ##### Running run-tests step. 21:19:21 INFO - ##### 21:19:21 INFO - Running pre-action listener: _resource_record_pre_action 21:19:21 INFO - Running main action method: run_tests 21:19:21 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:19:21 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:19:21 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:19:21 INFO - 2016-05-26 21:19:21.187 screenresolution[2246:16425] starting screenresolution argv=screenresolution get 21:19:21 INFO - 2016-05-26 21:19:21.190 screenresolution[2246:16425] Display 0: 1600x1200x32@60 21:19:21 INFO - 2016-05-26 21:19:21.196 screenresolution[2247:16429] starting screenresolution argv=screenresolution list 21:19:21 INFO - Available Modes on Display 0 21:19:21 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:19:21 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:19:21 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:19:21 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:19:21 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:19:21 INFO - Intel Iris: 21:19:21 INFO - Chipset Model: Intel Iris 21:19:21 INFO - Type: GPU 21:19:21 INFO - Bus: Built-In 21:19:21 INFO - VRAM (Dynamic, Max): 1536 MB 21:19:21 INFO - Vendor: Intel (0x8086) 21:19:21 INFO - Device ID: 0x0a2e 21:19:21 INFO - Revision ID: 0x0009 21:19:21 INFO - Displays: 21:19:21 INFO - Display: 21:19:21 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:19:21 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:19:21 INFO - Display Serial Number: 1600x1200 60 21:19:21 INFO - Main Display: Yes 21:19:21 INFO - Mirror: Off 21:19:21 INFO - Online: Yes 21:19:21 INFO - Rotation: Supported 21:19:21 INFO - Return code: 0 21:19:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:19:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:19:21 INFO - Python 2.7.3 21:19:21 INFO - Return code: 0 21:19:21 INFO - grabbing minidump binary from tooltool 21:19:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:19:21 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 0x1065a0d30>, '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 0x1063c4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106597b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:19:21 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 21:19:21 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 21:19:21 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 21:19:21 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:19:21 INFO - Return code: 0 21:19:21 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 21:19:21 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:19:21 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:19:21 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 21:19:21 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:19:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 21:19:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 21:19:22 INFO - Return code: 0 21:19:22 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-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HjX7zCRzTVWYUsE8TXQKSw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0148', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 21:19:22 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-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/HjX7zCRzTVWYUsE8TXQKSw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0148 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 21:19:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Z4GDO3vMW6/Render', 21:19:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:19:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:19:22 INFO - 'HOME': '/Users/cltbld', 21:19:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:19:22 INFO - 'LOGNAME': 'cltbld', 21:19:22 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:19:22 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 21:19:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:19:22 INFO - 'MOZ_NO_REMOTE': '1', 21:19:22 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:19:22 INFO - 'NO_EM_RESTART': '1', 21:19:22 INFO - 'PAGER': '/bin/cat', 21:19:22 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:19:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:19:22 INFO - 'PWD': '/builds/slave/test', 21:19:22 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:19:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:19:22 INFO - 'SHELL': '/bin/bash', 21:19:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Mv7JVtew76/Listeners', 21:19:22 INFO - 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/', 21:19:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:19:22 INFO - 'USER': 'cltbld', 21:19:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:19:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:19:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:19:22 INFO - 'XPC_FLAGS': '0x0', 21:19:22 INFO - 'XPC_SERVICE_NAME': '0', 21:19:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:19:22 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HjX7zCRzTVWYUsE8TXQKSw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0148', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 21:19:22 INFO - mozversion application_buildid: 20160526190243 21:19:22 INFO - mozversion application_changeset: f2125a888d23ff0a5b6df4c31bc66f0132610750 21:19:22 INFO - mozversion application_display_name: Nightly 21:19:22 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:19:22 INFO - mozversion application_name: Firefox 21:19:22 INFO - mozversion application_remotingname: firefox 21:19:22 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 21:19:22 INFO - mozversion application_vendor: Mozilla 21:19:22 INFO - mozversion application_version: 49.0a1 21:19:22 INFO - mozversion platform_buildid: 20160526190243 21:19:22 INFO - mozversion platform_changeset: f2125a888d23ff0a5b6df4c31bc66f0132610750 21:19:22 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 21:19:22 INFO - mozversion platform_version: 49.0a1 21:19:22 INFO - using testdate: 1464322762 21:19:22 INFO - actual date: 1464322762 21:19:22 INFO - starting webserver on 'localhost:51609' 21:19:22 INFO - SUITE-START | Running 2 tests 21:19:22 INFO - TEST-START | dromaeo_css 21:19:22 INFO - operating with platform_type : mac_ 21:19:22 INFO - Initialising browser for dromaeo_css test... 21:19:22 INFO - TEST-INFO | started process 2265 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/tmpvMlMiF/profile http://localhost:51609/getInfo.html) 21:19:25 INFO - PROCESS | 2265 | __metrics Screen width/height:1600/1200 21:19:25 INFO - PROCESS | 2265 | colorDepth:24 21:19:25 INFO - PROCESS | 2265 | Browser inner width/height: 1024/689 21:19:25 INFO - PROCESS | 2265 | __metrics 21:19:25 ERROR - PROCESS | 2265 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 21:19:26 INFO - TEST-INFO | 2265: exit 0 21:19:26 INFO - Browser initialized. 21:19:26 INFO - Running cycle 1/1 for dromaeo_css test... 21:19:26 INFO - TEST-INFO | started process 2269 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/tmpvMlMiF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:19:38 INFO - PROCESS | 2269 | RSS: Main: 252047360 21:19:38 INFO - PROCESS | 2269 | 21:20:28 INFO - PROCESS | 2269 | 1464322828234 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] 21:22:38 INFO - PROCESS | 2269 | Cycle 1(1): loaded http://localhost:51609/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51609/tests/dromaeo/cssquery-ext.html) 21:22:39 INFO - PROCESS | 2269 | RSS: Main: 264691712 21:22:39 INFO - PROCESS | 2269 | 21:25:42 INFO - PROCESS | 2269 | Cycle 1(1): loaded http://localhost:51609/tests/dromaeo/cssquery-ext.html (next: http://localhost:51609/tests/dromaeo/cssquery-jquery.html) 21:25:42 INFO - PROCESS | 2269 | RSS: Main: 276013056 21:25:42 INFO - PROCESS | 2269 | 21:28:44 INFO - PROCESS | 2269 | Cycle 1(1): loaded http://localhost:51609/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51609/tests/dromaeo/cssquery-mootools.html) 21:28:45 INFO - PROCESS | 2269 | RSS: Main: 278278144 21:28:45 INFO - PROCESS | 2269 | 21:31:47 INFO - PROCESS | 2269 | Cycle 1(1): loaded http://localhost:51609/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51609/tests/dromaeo/cssquery-prototype.html) 21:31:47 INFO - PROCESS | 2269 | RSS: Main: 280272896 21:31:47 INFO - PROCESS | 2269 | 21:34:50 INFO - PROCESS | 2269 | Cycle 1(1): loaded http://localhost:51609/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51609/tests/dromaeo/cssquery-yui.html) 21:34:51 INFO - PROCESS | 2269 | RSS: Main: 283213824 21:34:51 INFO - PROCESS | 2269 | 21:37:53 INFO - PROCESS | 2269 | Cycle 1(1): loaded http://localhost:51609/tests/dromaeo/cssquery-yui.html (next: http://localhost:51609/tests/dromaeo/cssquery-dojo.html) 21:37:53 INFO - PROCESS | 2269 | RSS: Main: 283541504 21:37:53 INFO - PROCESS | 2269 | 21:37:53 INFO - PROCESS | 2269 | __start_tp_report 21:37:53 INFO - PROCESS | 2269 | _x_x_mozilla_page_load 21:37:53 INFO - PROCESS | 2269 | _x_x_mozilla_page_load_details 21:37:53 INFO - PROCESS | 2269 | |i|pagename|runs| 21:37:53 INFO - PROCESS | 2269 | |0;dojo.html;3505.404081306178;3513.8770143045;3527.894163175105;3541.0439181421016;3570.910727231819;4986.825461108862;5881.705914704269;5916.0238560637445;5927.170196172534;6011.819645410639;5826.126081087839;5941.970290148554;5950;5993.88012239755;6015.939840601593;5318.282031925689;5358.732063396834;5425.104857698495;5449.809256676017;5471.972640136793;95.72915284438804;5438.048341540234;5448.400675925645;5458.099413596752;5489.917651235235;5329.280547126127;5346.973265133688;5372.650777699457;5391.218273350361;5415.214793854909;5324.254604355393;5378.2470454136455;5393.2179833923865;5420.24116623673;5443.5100840924115;5213.957208558266;5256.684598924076;5284.735763211824;5300.602454815904;5341.626086173969;4815.3499277597775;5720.170575266605;5734.340550836624;5765.7981970631245;5772.769089236425;6136.059583474755;6182.876342473125;6274;6280.277768056686;6317.431431171217;6292.685365731741;6370.490360771128;6381.329960354143;6381.840453988641;6382.393672601095;6107.6946152692635;6119.5410344224365;6122.26532816056;6128.877422451526;6130.938690613126;6162.352952939967;6340.778072767431;6372.2353317601255;6491.026346048036;6491.642959637265;6231.532635052389;6274.152989346379;6286.119943207955;6300.117983482316;6301.779437719658;4784.760761961888;5011.473795251446;6258.15514905491;6365.2679941806355;6395.904061439082;5645.7579332546775;5887.7056147193325;6170.660613666291;6204.658743769136;6243.625382477066;6194.915889719371;6223.782167624111;6226.097215903668;6230.875382492325;6241.126242326078;4304.225239456865;5089.236614507853;5108.974455127701;5117.820876269313;5161.767720452589;3788.9431658525145;4523.977380113079;4535.365048893158;4539.818407263739;4624.398828152319;5109.20807274873;5992.490638295706;6044.395560443921;6073;6081.908771368433;6326.367363263636;6341.492680585634;6342.207224096988;6346.873062538816;6352.4918006559365;4899.265110233422;5661.575381846295;5926.111083337448;6063.696815159225;6091.299500557404;5577.191307260506;5581.553475721932;5597.216389705444;5601;5613.915791263134;6534.771283005072;6578.703958321887;6605.8348541285895;6612.404883560406;6625.933740662628;6172.22847144107;6254.687265636609;6278.654673992792;6331.2085989251345;6404.551681382259;5432.29380180574;6561.606303621798;6574.506911981525;6593.703283352164;6601.636909969998;4770.833020844254;5483.698396588066;5506.705924107751;6146.723397447215;6300.873982520232;5722.998475266729;6197.659128747782;6213.720382582885;6316.084167795736;6353.745850165941;5715.199872017838;6250.406211409909;6252.781152659635;6259.906101408392;6280.466160376419;5011.747063234191;6182.28903676083;6244.937550624617;6258.092576576462;6258.843528911813;5630.465105814941;5870.412958704095;6079.300880398634;6117.571769976059;6175.104609831639;5327.121025030827;6185.392390811591;6214.316425193135;6240.469560087444;6287.591306565058;6087.330393656783;6152.876942461216;6192.845178870564;6194.473469755121;6216 21:37:53 INFO - PROCESS | 2269 | |1;ext.html;510.90548248574015;514.0592715330945;518.0700642346986;537.435692522851;544.9291592093027;33142.83428582857;41389.58610413895;41470;41491;41502;42210.36684449728;42377;42869.785651071776;42931.35602965958;43029.56970430295;9517.762055948604;9770.804583908317;9784.21726261901;9787.657431989881;9818.410895346302;9711.902880971205;9759.560819763092;9784.119429251366;9803.06870847272;9815.214782817358;9740.659076932308;9743.220542356594;9797.951010244975;9798.314118011742;9854.753631159207;9699.999999999965;9775.608975640966;9800.950995244979;9815.165710914582;9823.90176098237;9664.178544823699;9692.51537423126;9721.125098741148;9793.90206097937;9801.90198098017;39561.60438395608;39666.20667586632;39940.20119597621;40117.398239026435;40134.598654013374;3107.3008573071106;3414.3000684859794;3492.912677183065;3524.836803854722;3539.5398598182073;29070;29925.880326265604;30618.928337508303;30819.537706934414;30929;2620.2663254288827;3143.339898621269;3151.8108913465035;3257.153140183569;3261.1684020574603;411168;411582.99999999703;414861;415931;415977;33766.831165844014;33858.49212261818;33904;34055.659443405246;34135.317293653985;35197;35832;36254.82471051693;36293.27413451716;36350.8182459086;14695.632609184642;14783.482578109715;14986.850131498546;14989.700205996038;15015.924920375328;12570.811437828439;12622.747545049047;12710.110292279453;12715.93642031784;12724.300163490912;7767.1456139825705;7901.802454938673;7932.206779322137;7938.126806051329;8014.759557213293;3328.984659678821;3345.782524135972;3352.379809735207;3353.077903576536;3385.9153521162166;991.4101109839634;1002.0680766886719;1015.2284263959444;1047.5966752800075;1153.4463457540428;27202.45595088087;27353.86323068372;30806.69193308083;31202.843985779928;31439.685603143676;1073.0557109743377;1135.6763322453062;1154.8902854228834;1156.6819124740764;1181.285322379965;12286.385680715928;13827.79258311126;14649.802529620429;14726.631834204016;15590.84409155894;677556;688232;691328;692716;713080;54512.72743636256;55414.16878746822;55872.6031849207;56718;56772.148417773766;1426.0659268179359;1429.478240442252;1435.964100897465;1436.6695659998259;1439.7192547453355;29050;29065.273368165967;29172.124836254945;29492.705072949855;29618.703812961594;50149.997000060524;50366.4890053299;50863.237051445;50880.491195087576;50931.745341273054;641576;642639;644866;644914;645360;25049.49901001933;26020.739792602588;26313.21060368192;26386.4722705541;26498.7350126504;46643;46707;46901;47122.52877471319;47776.52223477721;38492.50091026467;38629.36447626303;38661.22677546489;38792;39098.02254943649;37066.81466592757;37213.44179837359;37570.436443452825;37901.24197516089;38296.61703382931;38356.42465363078;39369.01577460586;39431;40384.1923161541;41165.382519261635;34887.825560872036;34956.12609684778;35701;35714.46428303525;35727.82136089407;25208.621870672323;25823.225303240182;26059.348516287246;26095.608565871906;26381 21:37:53 INFO - PROCESS | 2269 | |2;jquery.html;339.65864306372094;381.5878850841092;388.59197842265615;390.9413587961806;396.5697218517908;19659.31192408264;20473.795262047373;20670.416197975253;21287.36137915861;21291.57416851662;16235;20816;21036;21419.250326238584;21432.8928355358;5354.5716342692685;5905.232319798431;6116.296625888013;6121.510279177677;6130.877382452349;5980.581359304851;5997.190379298804;6034.426729460716;6060.606060606046;6079.39206079393;5802.12968054791;5919.704014799265;5942.851428714295;5952.255968003999;5965.850853728648;5860.062390017577;5861.120831875211;5896.941030589704;5925.170476133345;5952.077427998667;5015.197568389077;5136.512031357015;5147.176451767737;5163.277141200235;5167.534921857051;25276.873615631986;25543.105991290216;26048.479030419476;26275.343116422242;26354.341141471425;2069.637813382667;2387.6060450025725;2546.7835234005133;2741.8217815842104;2876.8492602958777;12243.69390765229;15279;15543.766843497357;15876.12681302528;16152.515424537283;1859.191251805469;1872.8220819022172;1889.6598612249788;1937.9224831006743;2056.5269987902866;85778.57110714469;105926;105985.94014059838;106149.46925265402;106319;11586.826197607043;11602.361870097056;11670.183087183985;11759.941200294115;11849.526018959144;11912.642620721392;14683.853161468249;14709.264536773118;14721.631959200891;14815.851841481663;8171.67313307473;8362.749117526484;8618.396712230084;8687.869681954775;8695.217430431165;7010.509264351548;8474.618642161118;8496.745097647081;8525.701600443954;8568.614412351459;4473.463184417826;4502.617277531446;4560.726356418625;4583.816647334069;4657.74382408964;1706.9928742042255;1953.8143876331726;1958.265650381107;1964;2024.2712623455545;826.9049059358131;1029.52777528134;1032.6592224565875;1050.4852622215212;1051.018829506386;16375.76569203685;17197.312107515558;19928.70106948397;20878.478038048866;21372.35882923515;912.370464379576;940.5250348574064;946.6733976778;949.4730424614405;1081.1188881062067;10673.946630266953;11142.944285278683;11174.720631984266;11181.83227251592;11194.66416007521;513358;584247;597117;601336;603144;143735;148778;149484;151600.48399516084;154350;991.613270824379;1024.416082832793;1041.146260066738;1043.2332235806675;1046.3094357723867;13879.653008674864;14463.710725785635;17966.461006169837;18400.89594624327;19121.330753423565;37373;43491.782541087094;54579;57511;57717.13424298555;60237;76135.61932190304;77434;79796;80350.392992141;6554.885669436303;6571.178602674666;7014.649267536704;7473.51422157558;7802.765917022499;9107.59015844302;11123.888761112285;12547.811782823082;12878;13954.58136255914;8420.957895210484;11268.433638900222;11359.716007099889;11368.602098926829;11500.309981401142;11181.161412894227;11209.663710088711;11338.716532086764;11345.432728363383;11740.530378784753;11445.713857153638;11700.824487632523;11704.648860534198;11737.35444550558;11790.646280611596;6733.966330168318;7454.1800401956825;7466.9546263524235;7517.02278703765;7560.281773231534;10426.530806113591;11282.94358528202;11333.093352531943;11571.6528504145;11595.710107247387 21:37:53 INFO - PROCESS | 2269 | |3;mootools.html;1536.5313579358299;1576.550683055329;1797.928082876685;1896.1846406045404;1908.828205461508;7934.127246002935;8551.828963420743;8643.48139111653;9149.451032938021;9156.633734650613;8657;8714.259287960514;8863.46819190849;8915.019347871745;8926.73219803407;2340.8127349811975;2382.1424287256573;2383.2135395319588;2384.6899903012586;2390.581648211556;2343.765623437651;2345.870977096259;2375.8574485530835;2397.6643269942224;2399.8680072596;2267.1044937249776;2288.2563166970717;2313.780190881872;2376.0733314007553;2383.249276477904;2339.543788961153;2353.5292941411617;2363.9645405318934;2377.2511658827502;2378.0368950575044;1967.2229469359522;2347.8495537186827;2356.4697942962866;2357.446000189954;2360.3273067175946;8754.606042728092;8850.911490885073;8905.955470222672;8916.55417229136;8923.643054277822;5198.662086964335;5242.292290540767;5343.572514198893;5345.465453454663;5360.222767698662;3559.6796288333917;3669.743117981742;3730.198007428416;3737.4020156774786;3758.1732018955786;1063.9255252132355;1096.736783172041;1106.8505751723494;1107.058999850129;1134.5802053240272;10516.894831051668;11654.067674586098;11661.766764664744;11664.35846028468;11673.766524669543;3899.8440062397185;4696.7651617419;4811.182099043171;4825.662203645781;4832.855014349575;9914.305998580174;9932.354396964174;9940;9982.800343993225;9987.400755954666;5278.5513231375;5328.307320048447;5367.892642147135;5377.8117765878005;5400.351957765015;4396.010897548026;4691.108689349013;4905.626424601118;4906.803727850917;4909.435414927258;3974.4038394241097;4005.439238506611;4060.3706425504092;4149.087200815816;4180.059486615487;1311.8819306262483;1336.1582203212108;1337.993310033463;1345.1525538910423;1346.9865301347056;951.7573018880141;959.3907868503551;959.8032403357295;973.9220862330997;1008.8284991551454;5965.492933100648;5987.670678112659;6018.488428483541;6021.578489505692;6136.447719705248;445.9620932220733;455.2420220333174;455.9361689363492;901.7520181950016;946.5314669238771;3350.966490335114;3557.3952428087287;4153.356229784388;4308.5045219799495;4374.387585737999;9702.369345992487;9976.752905886764;10485.475726213812;10524.894751052392;10679.626213082505;10070.345427547187;10470.16238700902;10483.580656773796;10590;10673.786524269472;7250.021247131675;7652.387808975269;8174.427790054639;8198.508089514648;8213.753587392388;6005.789797357072;6127.816165515042;6141.293751218667;6164.198654174928;6209.192804935329;9790.216782657228;10007.499625018865;10037.297389182688;10077;10079.69760907174;9822.410655360702;9849.556769945513;10089.949550252202;10095.697129086138;10147.7463063424;6682.866342673216;6985.825354366181;7418.369438597564;7637.88543171864;7650.808729781577;8339.166083391612;8469.02606200295;8471.57642117904;8564.314854811724;8580.613872375883;7483.289087536676;7595.3164215220895;7619.08570971487;7655.540667559964;7723.961380193063;7332.633368331669;7340.229275925951;7441.962790186014;7518.173000969999;7524.172341042371;7063.364297213276;7098.3256590623805;7142.464315176278;7147.606881621561;7154.749583764543;4962.8759281018265;5078.441371449064;5151.613628977767;5160.922586161286;5173.896522069463;4504.662150338672;5106.6490655180605;5108.9233661495855;5148.562372198405;5206.73966301691 21:37:53 INFO - PROCESS | 2269 | |4;prototype.html;305.39226938392596;305.6301874731576;319.59411547334884;321.84390570573254;324.9918752031199;4282.743035417877;5329.760160792764;5473.698946557938;5556.638818476797;5593.804216852411;5131.153359695655;5294.338207724035;5554.2779438672915;5595.720213989304;5602.38373778885;3944.842206311758;4201.789910504478;4211.515675697304;4225.873223803291;4232.322828347457;3874.6319099685484;3916.7454115482547;4238.91522169558;4239.2581298272835;4256.2764330063965;4032.8185231664497;4102.056526998792;4109.958900410988;4145.626893579577;4150.522689890671;4028.8244348052017;4134.4625198724125;4163.770992595406;4178.1643671265865;4223.852165174235;3189.04328701391;3506.667466362766;3612.566492020948;3617.7467577269604;3638.6179451157514;5222.556082732972;5270.314859068297;5320.17537281722;5322.483092318709;5328.706921119337;4116.855910043134;4434.379186913835;4691.038337140878;4779.920734480477;4824.879378015543;4666.719996800203;4700.835470758507;5258.947410525884;5684.431556844283;5866.090755932837;3531.894043178683;3916.4125381192766;3946.921061578781;3956.090099277182;3963.4253033310297;5162.625192411458;5919.437653422918;6173.197484327009;6183.628982261079;6185.443310102112;3230.6284777250917;3356;3539.9292014159573;3997.8411657704514;4033.233685599727;2997.460457117696;3710.1466662667735;4053.5541090480024;4085.019595297107;4113.362428823537;4085.9182816343505;4113.8813511041835;4611.9392539716055;4754.881127971759;4763.666543342002;5353.0899747043095;5356;5564.666120032811;5865.941340586625;5948.405159484017;1147.7761836441814;1224.4367590908282;1371.4445649511963;1377.3251106957719;1381.8825399840925;759.9285008138609;819.9262066414051;827.4745536584312;852.1819053708385;853.8206976534873;4212.915741685149;4275.636570891509;4309.719868208556;4638.628909687217;4656.580907718321;4492.9550704493195;4566.589006989387;4966.1061009018;5138.511841375063;5145.665531740425;4182.971258939412;4603.654725895572;4616.538346165356;4620.5148459411275;4675.719456832601;2866.5556838690036;3183.2201110727556;3191.9680803192136;3205.406999705062;3229.224986003342;5833.82498525045;6123.969380153071;6382.20222472191;6396.808095757135;6429.099926010363;5237.580993520509;5269.894602107936;5944.762209511658;6146.156153154126;6199.039148931923;5292.788288468495;5604.495595396434;6237;6275.6548389839;6318.842028949313;4931.0630980113665;5548.945700316927;6041.18444010053;6070.48400885912;6099.756009759737;5221.582273418041;5337.359516858002;5757.96356655806;5849.976254155421;6000.459958603747;5147.099257629975;5654.547636189012;5940.188242586053;6344.936550634435;6373.558278529542;3383.5093911383206;3590.156313266328;3688.4836122943348;3823.241308997781;3834.9233015340096;5273.024490469275;5487.945120548743;5565.833025009256;5962.2249107615735;6060.848478788065;4853.927191092066;4933.692571468636;5620.297462817148;5925.348211696796;5928.407159284037;4988.42633097184;5014.7994080236385;5671.773129074956;5982;5992.520598352208;4615.930761038654;5148.867249205169;5577.609567330248;5807.970960145172;5811.4479124483105;4512.82666506704;5800.970995144998;5913.586048976703;5933.495652869555;5962.344142144274;4903.656744027884;5425.83722488326;5815.912761308668;5828.59199856006;5855.473007429352 21:37:53 INFO - PROCESS | 2269 | |5;yui.html;728.5337384074194;732.3408931961234;758.2190343945738;819.2135549872124;826.5495305058743;14894.404223831047;15360.539183824489;15404.768928466083;15498.845011549882;15584.220788960549;11822.527098916054;14079.5072172474;14136.575902722909;14243.572692819205;14290.356933937972;702.0522294901892;870.4603146049458;888.2094935507404;902.2736696958946;907.3240435875249;858.3305022082754;885.1856292211149;888.9822203555925;890.7862113092876;896.8565029595266;921.8156368726249;960.7790208252106;962.8266911955845;963.3497389262255;963.8168747937905;933.1042199488465;948.1656142594542;952.5903861339622;955.9235261179089;956.6412595276771;675.8377989282537;691.8305015271291;702.4134847402426;702.7751119641716;703.2721133626671;14160.575182744535;14451.205183714892;14475.855241447662;14482.203478808662;14508;11021.504032318566;13503.527376541871;14147.787783183261;14169.78745318821;14188.645283867883;11426.714332141762;11741.765164696744;11757.059435245159;11764.176507644468;12266.938665306705;3298.422776014212;3804.6575808177395;3848.8652897148745;3851.6340947609933;3915.099527108752;54204.72897635487;72745;72960;73175.63412182905;73677.26322736757;10410.635627752992;10812.837807432894;10829.512671929782;10993.175511836644;10997.61508347204;21369.252076177258;21415.250466233607;21416.89291553532;21436.678449823266;21449.785502145092;8024.719134830253;8100;8187.95906020478;8221.465604735793;8302.70940517079;4464.464264288306;4485.192665320274;4507.166174257773;4550.294704320836;4568.794404251817;3424.298018906118;3477.3219222251923;3522.436410174411;3527.6295988921306;3530.770499917497;665.6039656404431;670.2359310386164;676.1413005483009;677.1975209376905;681.6557638392679;476.3616753550259;514.4161376837317;624.2945471617044;639.7313128486047;663.8605892762478;9116.042815504408;9528.523573821281;9558.617655293849;9587.376820506644;9666.420014799134;554.919536667189;586.519054375408;619.8357435279654;642.5437939063224;674.3964152083955;3658.5609726832417;3793.4878791363367;4321.719088259253;4398.516163222043;4530.818767249339;84257;84282;85222;87854.68217976735;89348.5532572333;55021.44978550243;77403.22596774073;77573.06067348362;78801.21198788054;79919.60040199762;64554.99999999906;69165.30834691589;70563.2943670567;71011.64494177495;71601.28398716051;8908.73273801787;8917.73246802597;8951.73144805657;8974.955125224333;9044.186023257938;38917.80541097277;41902.580974191085;45065.54934450614;45157.32264016108;45539.316910247035;54934.90130197453;60226.39773602208;61831.07253391293;62808;62962.68518657378;11821.873659212892;11857.34784586856;11945.343006134399;11956.402179890796;12029.578964736193;12708.809367859305;12775.341385899424;12999.1550549214;13288.601341959757;13397.330133493091;11943.734649829994;11989.700257493632;12042.096842737004;12173.878261217274;12190.878091218974;11801.881981180079;11872.228305160137;11948.223365481215;12170.57402990891;12234.142668925264;11649.825252621047;12211.938940305241;13002.219866808022;13119.344032798514;13971.371288291848;11078;11268.830967535658;11354.432278385882;11372.20394572372;11429.771404572028;9355.970843207378;10927.562897484011;11266.155038371991;11441.828372574251;11551 21:37:53 INFO - PROCESS | 2269 | __end_tp_report 21:37:53 INFO - PROCESS | 2269 | __start_cc_report 21:37:53 INFO - PROCESS | 2269 | _x_x_mozilla_cycle_collect,412 21:37:53 INFO - PROCESS | 2269 | __end_cc_report 21:37:53 INFO - PROCESS | 2269 | __startTimestamp1464323873892__endTimestamp 21:37:53 INFO - PROCESS | 2269 | 21:37:53 INFO - PROCESS | 2269 | ------- Summary: start ------- 21:37:53 INFO - PROCESS | 2269 | Number of tests: 6 21:37:53 INFO - PROCESS | 2269 | 21:37:53 INFO - PROCESS | 2269 | [#0] dojo.html Cycles:165 Average:5765.52 Median:6081.91 stddev:802.81 (13.2%) stddev-sans-first:785.45 21:37:53 INFO - PROCESS | 2269 | Values: 3505.4 3513.9 3527.9 3541.0 3570.9 4986.8 5881.7 5916.0 5927.2 6011.8 5826.1 5942.0 5950.0 5993.9 6015.9 5318.3 5358.7 5425.1 5449.8 5472.0 95.7 5438.0 5448.4 5458.1 5489.9 5329.3 5347.0 5372.7 5391.2 5415.2 5324.3 5378.2 5393.2 5420.2 5443.5 5214.0 5256.7 5284.7 5300.6 5341.6 4815.3 5720.2 5734.3 5765.8 5772.8 6136.1 6182.9 6274.0 6280.3 6317.4 6292.7 6370.5 6381.3 6381.8 6382.4 6107.7 6119.5 6122.3 6128.9 6130.9 6162.4 6340.8 6372.2 6491.0 6491.6 6231.5 6274.2 6286.1 6300.1 6301.8 4784.8 5011.5 6258.2 6365.3 6395.9 5645.8 5887.7 6170.7 6204.7 6243.6 6194.9 6223.8 6226.1 6230.9 6241.1 4304.2 5089.2 5109.0 5117.8 5161.8 3788.9 4524.0 4535.4 4539.8 4624.4 5109.2 5992.5 6044.4 6073.0 6081.9 6326.4 6341.5 6342.2 6346.9 6352.5 4899.3 5661.6 5926.1 6063.7 6091.3 5577.2 5581.6 5597.2 5601.0 5613.9 6534.8 6578.7 6605.8 6612.4 6625.9 6172.2 6254.7 6278.7 6331.2 6404.6 5432.3 6561.6 6574.5 6593.7 6601.6 4770.8 5483.7 5506.7 6146.7 6300.9 5723.0 6197.7 6213.7 6316.1 6353.7 5715.2 6250.4 6252.8 6259.9 6280.5 5011.7 6182.3 6244.9 6258.1 6258.8 5630.5 5870.4 6079.3 6117.6 6175.1 5327.1 6185.4 6214.3 6240.5 6287.6 6087.3 6152.9 6192.8 6194.5 6216.0 21:37:53 INFO - PROCESS | 2269 | 21:37:53 INFO - PROCESS | 2269 | [#1] ext.html Cycles:180 Average:69480.17 Median:38424.46 stddev:160147.87 (416.8%) stddev-sans-first:160513.41 21:37:53 INFO - PROCESS | 2269 | Values: 510.9 514.1 518.1 537.4 544.9 33142.8 41389.6 41470.0 41491.0 41502.0 42210.4 42377.0 42869.8 42931.4 43029.6 9517.8 9770.8 9784.2 9787.7 9818.4 9711.9 9759.6 9784.1 9803.1 9815.2 9740.7 9743.2 9798.0 9798.3 9854.8 9700.0 9775.6 9801.0 9815.2 9823.9 9664.2 9692.5 9721.1 9793.9 9801.9 39561.6 39666.2 39940.2 40117.4 40134.6 3107.3 3414.3 3492.9 3524.8 3539.5 29070.0 29925.9 30618.9 30819.5 30929.0 2620.3 3143.3 3151.8 3257.2 3261.2 411168.0 411583.0 414861.0 415931.0 415977.0 33766.8 33858.5 33904.0 34055.7 34135.3 35197.0 35832.0 36254.8 36293.3 36350.8 14695.6 14783.5 14986.9 14989.7 15015.9 12570.8 12622.7 12710.1 12715.9 12724.3 7767.1 7901.8 7932.2 7938.1 8014.8 3329.0 3345.8 3352.4 3353.1 3385.9 991.4 1002.1 1015.2 1047.6 1153.4 27202.5 27353.9 30806.7 31202.8 31439.7 1073.1 1135.7 1154.9 1156.7 1181.3 12286.4 13827.8 14649.8 14726.6 15590.8 677556.0 688232.0 691328.0 692716.0 713080.0 54512.7 55414.2 55872.6 56718.0 56772.1 1426.1 1429.5 1436.0 1436.7 1439.7 29050.0 29065.3 29172.1 29492.7 29618.7 50150.0 50366.5 50863.2 50880.5 50931.7 641576.0 642639.0 644866.0 644914.0 645360.0 25049.5 26020.7 26313.2 26386.5 26498.7 46643.0 46707.0 46901.0 47122.5 47776.5 38492.5 38629.4 38661.2 38792.0 39098.0 37066.8 37213.4 37570.4 37901.2 38296.6 38356.4 39369.0 39431.0 40384.2 41165.4 34887.8 34956.1 35701.0 35714.5 35727.8 25208.6 25823.2 26059.3 26095.6 26381.0 21:37:53 INFO - PROCESS | 2269 | 21:37:53 INFO - PROCESS | 2269 | [#2] jquery.html Cycles:180 Average:34509.93 Median:21426.07 stddev:97388.62 (454.5%) stddev-sans-first:97628.02 21:37:53 INFO - PROCESS | 2269 | Values: 339.7 381.6 388.6 390.9 396.6 19659.3 20473.8 20670.4 21287.4 21291.6 16235.0 20816.0 21036.0 21419.3 21432.9 5354.6 5905.2 6116.3 6121.5 6130.9 5980.6 5997.2 6034.4 6060.6 6079.4 5802.1 5919.7 5942.9 5952.3 5965.9 5860.1 5861.1 5896.9 5925.2 5952.1 5015.2 5136.5 5147.2 5163.3 5167.5 25276.9 25543.1 26048.5 26275.3 26354.3 2069.6 2387.6 2546.8 2741.8 2876.8 12243.7 15279.0 15543.8 15876.1 16152.5 1859.2 1872.8 1889.7 1937.9 2056.5 85778.6 105926.0 105985.9 106149.5 106319.0 11586.8 11602.4 11670.2 11759.9 11849.5 11912.6 14683.9 14709.3 14721.6 14815.9 8171.7 8362.7 8618.4 8687.9 8695.2 7010.5 8474.6 8496.7 8525.7 8568.6 4473.5 4502.6 4560.7 4583.8 4657.7 1707.0 1953.8 1958.3 1964.0 2024.3 826.9 1029.5 1032.7 1050.5 1051.0 16375.8 17197.3 19928.7 20878.5 21372.4 912.4 940.5 946.7 949.5 1081.1 10673.9 11142.9 11174.7 11181.8 11194.7 513358.0 584247.0 597117.0 601336.0 603144.0 143735.0 148778.0 149484.0 151600.5 154350.0 991.6 1024.4 1041.1 1043.2 1046.3 13879.7 14463.7 17966.5 18400.9 19121.3 37373.0 43491.8 54579.0 57511.0 57717.1 60237.0 76135.6 77434.0 79796.0 80350.4 6554.9 6571.2 7014.6 7473.5 7802.8 9107.6 11123.9 12547.8 12878.0 13954.6 8421.0 11268.4 11359.7 11368.6 11500.3 11181.2 11209.7 11338.7 11345.4 11740.5 11445.7 11700.8 11704.6 11737.4 11790.6 6734.0 7454.2 7467.0 7517.0 7560.3 10426.5 11282.9 11333.1 11571.7 11595.7 21:37:53 INFO - PROCESS | 2269 | 21:37:53 INFO - PROCESS | 2269 | [#3] mootools.html Cycles:180 Average:5717.68 Median:5020.66 stddev:3165.71 (63.1%) stddev-sans-first:3159.00 21:37:53 INFO - PROCESS | 2269 | Values: 1536.5 1576.6 1797.9 1896.2 1908.8 7934.1 8551.8 8643.5 9149.5 9156.6 8657.0 8714.3 8863.5 8915.0 8926.7 2340.8 2382.1 2383.2 2384.7 2390.6 2343.8 2345.9 2375.9 2397.7 2399.9 2267.1 2288.3 2313.8 2376.1 2383.2 2339.5 2353.5 2364.0 2377.3 2378.0 1967.2 2347.8 2356.5 2357.4 2360.3 8754.6 8850.9 8906.0 8916.6 8923.6 5198.7 5242.3 5343.6 5345.5 5360.2 3559.7 3669.7 3730.2 3737.4 3758.2 1063.9 1096.7 1106.9 1107.1 1134.6 10516.9 11654.1 11661.8 11664.4 11673.8 3899.8 4696.8 4811.2 4825.7 4832.9 9914.3 9932.4 9940.0 9982.8 9987.4 5278.6 5328.3 5367.9 5377.8 5400.4 4396.0 4691.1 4905.6 4906.8 4909.4 3974.4 4005.4 4060.4 4149.1 4180.1 1311.9 1336.2 1338.0 1345.2 1347.0 951.8 959.4 959.8 973.9 1008.8 5965.5 5987.7 6018.5 6021.6 6136.4 446.0 455.2 455.9 901.8 946.5 3351.0 3557.4 4153.4 4308.5 4374.4 9702.4 9976.8 10485.5 10524.9 10679.6 10070.3 10470.2 10483.6 10590.0 10673.8 7250.0 7652.4 8174.4 8198.5 8213.8 6005.8 6127.8 6141.3 6164.2 6209.2 9790.2 10007.5 10037.3 10077.0 10079.7 9822.4 9849.6 10089.9 10095.7 10147.7 6682.9 6985.8 7418.4 7637.9 7650.8 8339.2 8469.0 8471.6 8564.3 8580.6 7483.3 7595.3 7619.1 7655.5 7724.0 7332.6 7340.2 7442.0 7518.2 7524.2 7063.4 7098.3 7142.5 7147.6 7154.7 4962.9 5078.4 5151.6 5160.9 5173.9 4504.7 5106.6 5108.9 5148.6 5206.7 21:37:53 INFO - PROCESS | 2269 | 21:37:53 INFO - PROCESS | 2269 | [#4] prototype.html Cycles:180 Average:4572.41 Median:4949.90 stddev:1441.52 (29.1%) stddev-sans-first:1409.54 21:37:53 INFO - PROCESS | 2269 | Values: 305.4 305.6 319.6 321.8 325.0 4282.7 5329.8 5473.7 5556.6 5593.8 5131.2 5294.3 5554.3 5595.7 5602.4 3944.8 4201.8 4211.5 4225.9 4232.3 3874.6 3916.7 4238.9 4239.3 4256.3 4032.8 4102.1 4110.0 4145.6 4150.5 4028.8 4134.5 4163.8 4178.2 4223.9 3189.0 3506.7 3612.6 3617.7 3638.6 5222.6 5270.3 5320.2 5322.5 5328.7 4116.9 4434.4 4691.0 4779.9 4824.9 4666.7 4700.8 5258.9 5684.4 5866.1 3531.9 3916.4 3946.9 3956.1 3963.4 5162.6 5919.4 6173.2 6183.6 6185.4 3230.6 3356.0 3539.9 3997.8 4033.2 2997.5 3710.1 4053.6 4085.0 4113.4 4085.9 4113.9 4611.9 4754.9 4763.7 5353.1 5356.0 5564.7 5865.9 5948.4 1147.8 1224.4 1371.4 1377.3 1381.9 759.9 819.9 827.5 852.2 853.8 4212.9 4275.6 4309.7 4638.6 4656.6 4493.0 4566.6 4966.1 5138.5 5145.7 4183.0 4603.7 4616.5 4620.5 4675.7 2866.6 3183.2 3192.0 3205.4 3229.2 5833.8 6124.0 6382.2 6396.8 6429.1 5237.6 5269.9 5944.8 6146.2 6199.0 5292.8 5604.5 6237.0 6275.7 6318.8 4931.1 5548.9 6041.2 6070.5 6099.8 5221.6 5337.4 5758.0 5850.0 6000.5 5147.1 5654.5 5940.2 6344.9 6373.6 3383.5 3590.2 3688.5 3823.2 3834.9 5273.0 5487.9 5565.8 5962.2 6060.8 4853.9 4933.7 5620.3 5925.3 5928.4 4988.4 5014.8 5671.8 5982.0 5992.5 4615.9 5148.9 5577.6 5808.0 5811.4 4512.8 5801.0 5913.6 5933.5 5962.3 4903.7 5425.8 5815.9 5828.6 5855.5 21:37:53 INFO - PROCESS | 2269 | 21:37:53 INFO - PROCESS | 2269 | [#5] yui.html Cycles:180 Average:17693.54 Median:4540.56 stddev:23507.90 (517.7%) stddev-sans-first:23539.33 21:37:53 INFO - PROCESS | 2269 | Values: 728.5 732.3 758.2 819.2 826.5 14894.4 15360.5 15404.8 15498.8 15584.2 11822.5 14079.5 14136.6 14243.6 14290.4 702.1 870.5 888.2 902.3 907.3 858.3 885.2 889.0 890.8 896.9 921.8 960.8 962.8 963.3 963.8 933.1 948.2 952.6 955.9 956.6 675.8 691.8 702.4 702.8 703.3 14160.6 14451.2 14475.9 14482.2 14508.0 11021.5 13503.5 14147.8 14169.8 14188.6 11426.7 11741.8 11757.1 11764.2 12266.9 3298.4 3804.7 3848.9 3851.6 3915.1 54204.7 72745.0 72960.0 73175.6 73677.3 10410.6 10812.8 10829.5 10993.2 10997.6 21369.3 21415.3 21416.9 21436.7 21449.8 8024.7 8100.0 8188.0 8221.5 8302.7 4464.5 4485.2 4507.2 4550.3 4568.8 3424.3 3477.3 3522.4 3527.6 3530.8 665.6 670.2 676.1 677.2 681.7 476.4 514.4 624.3 639.7 663.9 9116.0 9528.5 9558.6 9587.4 9666.4 554.9 586.5 619.8 642.5 674.4 3658.6 3793.5 4321.7 4398.5 4530.8 84257.0 84282.0 85222.0 87854.7 89348.6 55021.4 77403.2 77573.1 78801.2 79919.6 64555.0 69165.3 70563.3 71011.6 71601.3 8908.7 8917.7 8951.7 8975.0 9044.2 38917.8 41902.6 45065.5 45157.3 45539.3 54934.9 60226.4 61831.1 62808.0 62962.7 11821.9 11857.3 11945.3 11956.4 12029.6 12708.8 12775.3 12999.2 13288.6 13397.3 11943.7 11989.7 12042.1 12173.9 12190.9 11801.9 11872.2 11948.2 12170.6 12234.1 11649.8 12211.9 13002.2 13119.3 13971.4 11078.0 11268.8 11354.4 11372.2 11429.8 9356.0 10927.6 11266.2 11441.8 11551.0 21:37:53 INFO - PROCESS | 2269 | -------- Summary: end -------- 21:37:53 INFO - PROCESS | 2269 | 21:37:54 INFO - TEST-INFO | 2269: exit 0 21:37:54 INFO - TEST-OK | dromaeo_css | took 1111553ms 21:37:54 INFO - TEST-START | kraken 21:37:54 INFO - operating with platform_type : mac_ 21:37:54 INFO - Initialising browser for kraken test... 21:37:54 INFO - TEST-INFO | started process 2275 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/tmpjKvn1H/profile http://localhost:51609/getInfo.html) 21:37:57 INFO - PROCESS | 2275 | __metrics Screen width/height:1600/1200 21:37:57 INFO - PROCESS | 2275 | colorDepth:24 21:37:57 INFO - PROCESS | 2275 | Browser inner width/height: 1024/689 21:37:57 INFO - PROCESS | 2275 | __metrics 21:37:57 ERROR - PROCESS | 2275 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 21:37:58 INFO - TEST-INFO | 2275: exit 0 21:37:58 INFO - Browser initialized. 21:37:58 INFO - Running cycle 1/1 for kraken test... 21:37:58 INFO - TEST-INFO | started process 2277 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/tmpjKvn1H/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:38:10 INFO - PROCESS | 2277 | RSS: Main: 252219392 21:38:10 INFO - PROCESS | 2277 | 21:38:12 INFO - PROCESS | 2277 | Cycle 1(1): loaded http://localhost:51609/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51609/tests/kraken/driver.html?testName=audio-beat-detection) 21:38:13 INFO - PROCESS | 2277 | RSS: Main: 264617984 21:38:13 INFO - PROCESS | 2277 | 21:38:16 INFO - PROCESS | 2277 | Cycle 1(1): loaded http://localhost:51609/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51609/tests/kraken/driver.html?testName=audio-dft) 21:38:16 INFO - PROCESS | 2277 | RSS: Main: 268984320 21:38:16 INFO - PROCESS | 2277 | 21:38:20 INFO - PROCESS | 2277 | Cycle 1(1): loaded http://localhost:51609/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51609/tests/kraken/driver.html?testName=audio-fft) 21:38:20 INFO - PROCESS | 2277 | RSS: Main: 269393920 21:38:20 INFO - PROCESS | 2277 | 21:38:22 INFO - PROCESS | 2277 | Cycle 1(1): loaded http://localhost:51609/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51609/tests/kraken/driver.html?testName=audio-oscillator) 21:38:23 INFO - PROCESS | 2277 | RSS: Main: 270213120 21:38:23 INFO - PROCESS | 2277 | 21:38:25 INFO - PROCESS | 2277 | Cycle 1(1): loaded http://localhost:51609/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51609/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:38:26 INFO - PROCESS | 2277 | RSS: Main: 270966784 21:38:26 INFO - PROCESS | 2277 | 21:38:28 INFO - PROCESS | 2277 | Cycle 1(1): loaded http://localhost:51609/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51609/tests/kraken/driver.html?testName=imaging-darkroom) 21:38:29 INFO - PROCESS | 2277 | RSS: Main: 271351808 21:38:29 INFO - PROCESS | 2277 | 21:38:32 INFO - PROCESS | 2277 | Cycle 1(1): loaded http://localhost:51609/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51609/tests/kraken/driver.html?testName=imaging-desaturate) 21:38:33 INFO - PROCESS | 2277 | RSS: Main: 271474688 21:38:33 INFO - PROCESS | 2277 | 21:38:35 INFO - PROCESS | 2277 | Cycle 1(1): loaded http://localhost:51609/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51609/tests/kraken/driver.html?testName=json-parse-financial) 21:38:36 INFO - PROCESS | 2277 | RSS: Main: 271601664 21:38:36 INFO - PROCESS | 2277 | 21:38:38 INFO - PROCESS | 2277 | Cycle 1(1): loaded http://localhost:51609/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51609/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:38:38 INFO - PROCESS | 2277 | RSS: Main: 270737408 21:38:38 INFO - PROCESS | 2277 | 21:38:40 INFO - PROCESS | 2277 | Cycle 1(1): loaded http://localhost:51609/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51609/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:38:41 INFO - PROCESS | 2277 | RSS: Main: 270929920 21:38:41 INFO - PROCESS | 2277 | 21:38:43 INFO - PROCESS | 2277 | Cycle 1(1): loaded http://localhost:51609/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51609/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:38:44 INFO - PROCESS | 2277 | RSS: Main: 271278080 21:38:44 INFO - PROCESS | 2277 | 21:38:46 INFO - PROCESS | 2277 | Cycle 1(1): loaded http://localhost:51609/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51609/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:38:47 INFO - PROCESS | 2277 | RSS: Main: 271249408 21:38:47 INFO - PROCESS | 2277 | 21:38:50 INFO - PROCESS | 2277 | Cycle 1(1): loaded http://localhost:51609/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51609/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:38:51 INFO - PROCESS | 2277 | RSS: Main: 271163392 21:38:51 INFO - PROCESS | 2277 | 21:38:52 INFO - PROCESS | 2277 | Cycle 1(1): loaded http://localhost:51609/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51609/tests/kraken/driver.html?testName=ai-astar) 21:38:53 INFO - PROCESS | 2277 | RSS: Main: 272216064 21:38:53 INFO - PROCESS | 2277 | 21:38:53 INFO - PROCESS | 2277 | __start_tp_report 21:38:53 INFO - PROCESS | 2277 | _x_x_mozilla_page_load 21:38:53 INFO - PROCESS | 2277 | _x_x_mozilla_page_load_details 21:38:53 INFO - PROCESS | 2277 | |i|pagename|runs| 21:38:53 INFO - PROCESS | 2277 | |0;ai-astar;114;106;117;116;115;118;117;116;118;115 21:38:53 INFO - PROCESS | 2277 | |1;audio-beat-detection;156;124;144;169;120;143;144;135;171;107 21:38:53 INFO - PROCESS | 2277 | |2;audio-dft;198;219;178;221;269;275;244;199;194;197 21:38:53 INFO - PROCESS | 2277 | |3;audio-fft;65;107;100;144;71;58;57;60;127;58 21:38:53 INFO - PROCESS | 2277 | |4;audio-oscillator;75;104;151;93;71;80;113;70;76;116 21:38:53 INFO - PROCESS | 2277 | |5;imaging-gaussian-blur;89;85;88;85;107;105;89;86;89;84 21:38:53 INFO - PROCESS | 2277 | |6;imaging-darkroom;94;96;96;95;104;95;95;94;94;95 21:38:53 INFO - PROCESS | 2277 | |7;imaging-desaturate;90;92;91;88;91;91;90;94;89;111 21:38:53 INFO - PROCESS | 2277 | |8;json-parse-financial;56;54;57;57;57;57;55;57;57;55 21:38:53 INFO - PROCESS | 2277 | |9;json-stringify-tinderbox;67;89;108;70;77;82;75;73;72;72 21:38:53 INFO - PROCESS | 2277 | |10;stanford-crypto-aes;83;80;94;111;110;86;84;88;120;89 21:38:53 INFO - PROCESS | 2277 | |11;stanford-crypto-ccm;148;152;144;141;139;136;139;139;178;154 21:38:53 INFO - PROCESS | 2277 | |12;stanford-crypto-pbkdf2;164;168;167;167;168;166;167;170;171;221 21:38:53 INFO - PROCESS | 2277 | |13;stanford-crypto-sha256-iterative;63;70;65;67;65;67;66;68;66;66 21:38:53 INFO - PROCESS | 2277 | __end_tp_report 21:38:53 INFO - PROCESS | 2277 | __start_cc_report 21:38:53 INFO - PROCESS | 2277 | _x_x_mozilla_cycle_collect,1076 21:38:53 INFO - PROCESS | 2277 | __end_cc_report 21:38:53 INFO - PROCESS | 2277 | __startTimestamp1464323933037__endTimestamp 21:38:53 INFO - PROCESS | 2277 | 21:38:53 INFO - PROCESS | 2277 | ------- Summary: start ------- 21:38:53 INFO - PROCESS | 2277 | Number of tests: 14 21:38:53 INFO - PROCESS | 2277 | 21:38:53 INFO - PROCESS | 2277 | [#0] ai-astar Cycles:10 Average:115.20 Median:116.50 stddev:3.49 (3.0%) stddev-sans-first:3.67 21:38:53 INFO - PROCESS | 2277 | Values: 114.0 106.0 117.0 116.0 115.0 118.0 117.0 116.0 118.0 115.0 21:38:53 INFO - PROCESS | 2277 | 21:38:53 INFO - PROCESS | 2277 | [#1] audio-beat-detection Cycles:10 Average:141.30 Median:144.00 stddev:20.69 (14.4%) stddev-sans-first:21.25 21:38:53 INFO - PROCESS | 2277 | Values: 156.0 124.0 144.0 169.0 120.0 143.0 144.0 135.0 171.0 107.0 21:38:53 INFO - PROCESS | 2277 | 21:38:53 INFO - PROCESS | 2277 | [#2] audio-dft Cycles:10 Average:219.40 Median:220.00 stddev:33.16 (15.1%) stddev-sans-first:34.25 21:38:53 INFO - PROCESS | 2277 | Values: 198.0 219.0 178.0 221.0 269.0 275.0 244.0 199.0 194.0 197.0 21:38:53 INFO - PROCESS | 2277 | 21:38:53 INFO - PROCESS | 2277 | [#3] audio-fft Cycles:10 Average:84.70 Median:58.00 stddev:32.35 (55.8%) stddev-sans-first:33.51 21:38:53 INFO - PROCESS | 2277 | Values: 65.0 107.0 100.0 144.0 71.0 58.0 57.0 60.0 127.0 58.0 21:38:53 INFO - PROCESS | 2277 | 21:38:53 INFO - PROCESS | 2277 | [#4] audio-oscillator Cycles:10 Average:94.90 Median:73.00 stddev:26.19 (35.9%) stddev-sans-first:26.77 21:38:53 INFO - PROCESS | 2277 | Values: 75.0 104.0 151.0 93.0 71.0 80.0 113.0 70.0 76.0 116.0 21:38:53 INFO - PROCESS | 2277 | 21:38:53 INFO - PROCESS | 2277 | [#5] imaging-gaussian-blur Cycles:10 Average:90.70 Median:87.00 stddev:8.29 (9.5%) stddev-sans-first:8.77 21:38:53 INFO - PROCESS | 2277 | Values: 89.0 85.0 88.0 85.0 107.0 105.0 89.0 86.0 89.0 84.0 21:38:53 INFO - PROCESS | 2277 | 21:38:53 INFO - PROCESS | 2277 | [#6] imaging-darkroom Cycles:10 Average:95.80 Median:95.00 stddev:2.97 (3.1%) stddev-sans-first:3.08 21:38:53 INFO - PROCESS | 2277 | Values: 94.0 96.0 96.0 95.0 104.0 95.0 95.0 94.0 94.0 95.0 21:38:53 INFO - PROCESS | 2277 | 21:38:53 INFO - PROCESS | 2277 | [#7] imaging-desaturate Cycles:10 Average:92.70 Median:91.00 stddev:6.63 (7.3%) stddev-sans-first:6.96 21:38:53 INFO - PROCESS | 2277 | Values: 90.0 92.0 91.0 88.0 91.0 91.0 90.0 94.0 89.0 111.0 21:38:53 INFO - PROCESS | 2277 | 21:38:53 INFO - PROCESS | 2277 | [#8] json-parse-financial Cycles:10 Average:56.20 Median:57.00 stddev:1.14 (2.0%) stddev-sans-first:1.20 21:38:53 INFO - PROCESS | 2277 | Values: 56.0 54.0 57.0 57.0 57.0 57.0 55.0 57.0 57.0 55.0 21:38:53 INFO - PROCESS | 2277 | 21:38:53 INFO - PROCESS | 2277 | [#9] json-stringify-tinderbox Cycles:10 Average:78.50 Median:74.00 stddev:12.14 (16.4%) stddev-sans-first:12.14 21:38:53 INFO - PROCESS | 2277 | Values: 67.0 89.0 108.0 70.0 77.0 82.0 75.0 73.0 72.0 72.0 21:38:53 INFO - PROCESS | 2277 | 21:38:53 INFO - PROCESS | 2277 | [#10] stanford-crypto-aes Cycles:10 Average:94.50 Median:85.00 stddev:13.99 (16.5%) stddev-sans-first:14.20 21:38:53 INFO - PROCESS | 2277 | Values: 83.0 80.0 94.0 111.0 110.0 86.0 84.0 88.0 120.0 89.0 21:38:53 INFO - PROCESS | 2277 | 21:38:53 INFO - PROCESS | 2277 | [#11] stanford-crypto-ccm Cycles:10 Average:147.00 Median:146.00 stddev:12.45 (8.5%) stddev-sans-first:13.20 21:38:53 INFO - PROCESS | 2277 | Values: 148.0 152.0 144.0 141.0 139.0 136.0 139.0 139.0 178.0 154.0 21:38:53 INFO - PROCESS | 2277 | 21:38:53 INFO - PROCESS | 2277 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.90 Median:168.00 stddev:17.01 (10.1%) stddev-sans-first:17.74 21:38:53 INFO - PROCESS | 2277 | Values: 164.0 168.0 167.0 167.0 168.0 166.0 167.0 170.0 171.0 221.0 21:38:53 INFO - PROCESS | 2277 | 21:38:53 INFO - PROCESS | 2277 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.30 Median:66.50 stddev:1.89 (2.8%) stddev-sans-first:1.58 21:38:53 INFO - PROCESS | 2277 | Values: 63.0 70.0 65.0 67.0 65.0 67.0 66.0 68.0 66.0 66.0 21:38:53 INFO - PROCESS | 2277 | -------- Summary: end -------- 21:38:53 INFO - PROCESS | 2277 | 21:38:53 INFO - TEST-INFO | 2277: exit 0 21:38:53 INFO - TEST-OK | kraken | took 59531ms 21:38:53 INFO - SUITE-END | took 1171s 21:38:54 INFO - Completed test suite (00:19:31) 21:38:54 INFO - javascript benchmark 21:38:54 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7177.017491202563, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3505.404081306178, 3513.8770143045, 3527.894163175105, 3541.0439181421016, 3570.910727231819, 4986.825461108862, 5881.705914704269, 5916.0238560637445, 5927.170196172534, 6011.819645410639, 5826.126081087839, 5941.970290148554, 5950.0, 5993.88012239755, 6015.939840601593, 5318.282031925689, 5358.732063396834, 5425.104857698495, 5449.809256676017, 5471.972640136793, 95.72915284438804, 5438.048341540234, 5448.400675925645, 5458.099413596752, 5489.917651235235, 5329.280547126127, 5346.973265133688, 5372.650777699457, 5391.218273350361, 5415.214793854909, 5324.254604355393, 5378.2470454136455, 5393.2179833923865, 5420.24116623673, 5443.5100840924115, 5213.957208558266, 5256.684598924076, 5284.735763211824, 5300.602454815904, 5341.626086173969, 4815.3499277597775, 5720.170575266605, 5734.340550836624, 5765.7981970631245, 5772.769089236425, 6136.059583474755, 6182.876342473125, 6274.0, 6280.277768056686, 6317.431431171217, 6292.685365731741, 6370.490360771128, 6381.329960354143, 6381.840453988641, 6382.393672601095, 6107.6946152692635, 6119.5410344224365, 6122.26532816056, 6128.877422451526, 6130.938690613126, 6162.352952939967, 6340.778072767431, 6372.2353317601255, 6491.026346048036, 6491.642959637265, 6231.532635052389, 6274.152989346379, 6286.119943207955, 6300.117983482316, 6301.779437719658, 4784.760761961888, 5011.473795251446, 6258.15514905491, 6365.2679941806355, 6395.904061439082, 5645.7579332546775, 5887.7056147193325, 6170.660613666291, 6204.658743769136, 6243.625382477066, 6194.915889719371, 6223.782167624111, 6226.097215903668, 6230.875382492325, 6241.126242326078, 4304.225239456865, 5089.236614507853, 5108.974455127701, 5117.820876269313, 5161.767720452589, 3788.9431658525145, 4523.977380113079, 4535.365048893158, 4539.818407263739, 4624.398828152319, 5109.20807274873, 5992.490638295706, 6044.395560443921, 6073.0, 6081.908771368433, 6326.367363263636, 6341.492680585634, 6342.207224096988, 6346.873062538816, 6352.4918006559365, 4899.265110233422, 5661.575381846295, 5926.111083337448, 6063.696815159225, 6091.299500557404, 5577.191307260506, 5581.553475721932, 5597.216389705444, 5601.0, 5613.915791263134, 6534.771283005072, 6578.703958321887, 6605.8348541285895, 6612.404883560406, 6625.933740662628, 6172.22847144107, 6254.687265636609, 6278.654673992792, 6331.2085989251345, 6404.551681382259, 5432.29380180574, 6561.606303621798, 6574.506911981525, 6593.703283352164, 6601.636909969998, 4770.833020844254, 5483.698396588066, 5506.705924107751, 6146.723397447215, 6300.873982520232, 5722.998475266729, 6197.659128747782, 6213.720382582885, 6316.084167795736, 6353.745850165941, 5715.199872017838, 6250.406211409909, 6252.781152659635, 6259.906101408392, 6280.466160376419, 5011.747063234191, 6182.28903676083, 6244.937550624617, 6258.092576576462, 6258.843528911813, 5630.465105814941, 5870.412958704095, 6079.300880398634, 6117.571769976059, 6175.104609831639, 5327.121025030827, 6185.392390811591, 6214.316425193135, 6240.469560087444, 6287.591306565058, 6087.330393656783, 6152.876942461216, 6192.845178870564, 6194.473469755121, 6216.0], "value": 5722.265933603434, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [510.90548248574015, 514.0592715330945, 518.0700642346986, 537.435692522851, 544.9291592093027, 33142.83428582857, 41389.58610413895, 41470.0, 41491.0, 41502.0, 42210.36684449728, 42377.0, 42869.785651071776, 42931.35602965958, 43029.56970430295, 9517.762055948604, 9770.804583908317, 9784.21726261901, 9787.657431989881, 9818.410895346302, 9711.902880971205, 9759.560819763092, 9784.119429251366, 9803.06870847272, 9815.214782817358, 9740.659076932308, 9743.220542356594, 9797.951010244975, 9798.314118011742, 9854.753631159207, 9699.999999999965, 9775.608975640966, 9800.950995244979, 9815.165710914582, 9823.90176098237, 9664.178544823699, 9692.51537423126, 9721.125098741148, 9793.90206097937, 9801.90198098017, 39561.60438395608, 39666.20667586632, 39940.20119597621, 40117.398239026435, 40134.598654013374, 3107.3008573071106, 3414.3000684859794, 3492.912677183065, 3524.836803854722, 3539.5398598182073, 29070.0, 29925.880326265604, 30618.928337508303, 30819.537706934414, 30929.0, 2620.2663254288827, 3143.339898621269, 3151.8108913465035, 3257.153140183569, 3261.1684020574603, 411168.0, 411582.99999999703, 414861.0, 415931.0, 415977.0, 33766.831165844014, 33858.49212261818, 33904.0, 34055.659443405246, 34135.317293653985, 35197.0, 35832.0, 36254.82471051693, 36293.27413451716, 36350.8182459086, 14695.632609184642, 14783.482578109715, 14986.850131498546, 14989.700205996038, 15015.924920375328, 12570.811437828439, 12622.747545049047, 12710.110292279453, 12715.93642031784, 12724.300163490912, 7767.1456139825705, 7901.802454938673, 7932.206779322137, 7938.126806051329, 8014.759557213293, 3328.984659678821, 3345.782524135972, 3352.379809735207, 3353.077903576536, 3385.9153521162166, 991.4101109839634, 1002.0680766886719, 1015.2284263959444, 1047.5966752800075, 1153.4463457540428, 27202.45595088087, 27353.86323068372, 30806.69193308083, 31202.843985779928, 31439.685603143676, 1073.0557109743377, 1135.6763322453062, 1154.8902854228834, 1156.6819124740764, 1181.285322379965, 12286.385680715928, 13827.79258311126, 14649.802529620429, 14726.631834204016, 15590.84409155894, 677556.0, 688232.0, 691328.0, 692716.0, 713080.0, 54512.72743636256, 55414.16878746822, 55872.6031849207, 56718.0, 56772.148417773766, 1426.0659268179359, 1429.478240442252, 1435.964100897465, 1436.6695659998259, 1439.7192547453355, 29050.0, 29065.273368165967, 29172.124836254945, 29492.705072949855, 29618.703812961594, 50149.997000060524, 50366.4890053299, 50863.237051445, 50880.491195087576, 50931.745341273054, 641576.0, 642639.0, 644866.0, 644914.0, 645360.0, 25049.49901001933, 26020.739792602588, 26313.21060368192, 26386.4722705541, 26498.7350126504, 46643.0, 46707.0, 46901.0, 47122.52877471319, 47776.52223477721, 38492.50091026467, 38629.36447626303, 38661.22677546489, 38792.0, 39098.02254943649, 37066.81466592757, 37213.44179837359, 37570.436443452825, 37901.24197516089, 38296.61703382931, 38356.42465363078, 39369.01577460586, 39431.0, 40384.1923161541, 41165.382519261635, 34887.825560872036, 34956.12609684778, 35701.0, 35714.46428303525, 35727.82136089407, 25208.621870672323, 25823.225303240182, 26059.348516287246, 26095.608565871906, 26381.0], "value": 18531.278122786178, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [339.65864306372094, 381.5878850841092, 388.59197842265615, 390.9413587961806, 396.5697218517908, 19659.31192408264, 20473.795262047373, 20670.416197975253, 21287.36137915861, 21291.57416851662, 16235.0, 20816.0, 21036.0, 21419.250326238584, 21432.8928355358, 5354.5716342692685, 5905.232319798431, 6116.296625888013, 6121.510279177677, 6130.877382452349, 5980.581359304851, 5997.190379298804, 6034.426729460716, 6060.606060606046, 6079.39206079393, 5802.12968054791, 5919.704014799265, 5942.851428714295, 5952.255968003999, 5965.850853728648, 5860.062390017577, 5861.120831875211, 5896.941030589704, 5925.170476133345, 5952.077427998667, 5015.197568389077, 5136.512031357015, 5147.176451767737, 5163.277141200235, 5167.534921857051, 25276.873615631986, 25543.105991290216, 26048.479030419476, 26275.343116422242, 26354.341141471425, 2069.637813382667, 2387.6060450025725, 2546.7835234005133, 2741.8217815842104, 2876.8492602958777, 12243.69390765229, 15279.0, 15543.766843497357, 15876.12681302528, 16152.515424537283, 1859.191251805469, 1872.8220819022172, 1889.6598612249788, 1937.9224831006743, 2056.5269987902866, 85778.57110714469, 105926.0, 105985.94014059838, 106149.46925265402, 106319.0, 11586.826197607043, 11602.361870097056, 11670.183087183985, 11759.941200294115, 11849.526018959144, 11912.642620721392, 14683.853161468249, 14709.264536773118, 14721.631959200891, 14815.851841481663, 8171.67313307473, 8362.749117526484, 8618.396712230084, 8687.869681954775, 8695.217430431165, 7010.509264351548, 8474.618642161118, 8496.745097647081, 8525.701600443954, 8568.614412351459, 4473.463184417826, 4502.617277531446, 4560.726356418625, 4583.816647334069, 4657.74382408964, 1706.9928742042255, 1953.8143876331726, 1958.265650381107, 1964.0, 2024.2712623455545, 826.9049059358131, 1029.52777528134, 1032.6592224565875, 1050.4852622215212, 1051.018829506386, 16375.76569203685, 17197.312107515558, 19928.70106948397, 20878.478038048866, 21372.35882923515, 912.370464379576, 940.5250348574064, 946.6733976778, 949.4730424614405, 1081.1188881062067, 10673.946630266953, 11142.944285278683, 11174.720631984266, 11181.83227251592, 11194.66416007521, 513358.0, 584247.0, 597117.0, 601336.0, 603144.0, 143735.0, 148778.0, 149484.0, 151600.48399516084, 154350.0, 991.613270824379, 1024.416082832793, 1041.146260066738, 1043.2332235806675, 1046.3094357723867, 13879.653008674864, 14463.710725785635, 17966.461006169837, 18400.89594624327, 19121.330753423565, 37373.0, 43491.782541087094, 54579.0, 57511.0, 57717.13424298555, 60237.0, 76135.61932190304, 77434.0, 79796.0, 80350.392992141, 6554.885669436303, 6571.178602674666, 7014.649267536704, 7473.51422157558, 7802.765917022499, 9107.59015844302, 11123.888761112285, 12547.811782823082, 12878.0, 13954.58136255914, 8420.957895210484, 11268.433638900222, 11359.716007099889, 11368.602098926829, 11500.309981401142, 11181.161412894227, 11209.663710088711, 11338.716532086764, 11345.432728363383, 11740.530378784753, 11445.713857153638, 11700.824487632523, 11704.648860534198, 11737.35444550558, 11790.646280611596, 6733.966330168318, 7454.1800401956825, 7466.9546263524235, 7517.02278703765, 7560.281773231534, 10426.530806113591, 11282.94358528202, 11333.093352531943, 11571.6528504145, 11595.710107247387], "value": 9597.520860946968, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1536.5313579358299, 1576.550683055329, 1797.928082876685, 1896.1846406045404, 1908.828205461508, 7934.127246002935, 8551.828963420743, 8643.48139111653, 9149.451032938021, 9156.633734650613, 8657.0, 8714.259287960514, 8863.46819190849, 8915.019347871745, 8926.73219803407, 2340.8127349811975, 2382.1424287256573, 2383.2135395319588, 2384.6899903012586, 2390.581648211556, 2343.765623437651, 2345.870977096259, 2375.8574485530835, 2397.6643269942224, 2399.8680072596, 2267.1044937249776, 2288.2563166970717, 2313.780190881872, 2376.0733314007553, 2383.249276477904, 2339.543788961153, 2353.5292941411617, 2363.9645405318934, 2377.2511658827502, 2378.0368950575044, 1967.2229469359522, 2347.8495537186827, 2356.4697942962866, 2357.446000189954, 2360.3273067175946, 8754.606042728092, 8850.911490885073, 8905.955470222672, 8916.55417229136, 8923.643054277822, 5198.662086964335, 5242.292290540767, 5343.572514198893, 5345.465453454663, 5360.222767698662, 3559.6796288333917, 3669.743117981742, 3730.198007428416, 3737.4020156774786, 3758.1732018955786, 1063.9255252132355, 1096.736783172041, 1106.8505751723494, 1107.058999850129, 1134.5802053240272, 10516.894831051668, 11654.067674586098, 11661.766764664744, 11664.35846028468, 11673.766524669543, 3899.8440062397185, 4696.7651617419, 4811.182099043171, 4825.662203645781, 4832.855014349575, 9914.305998580174, 9932.354396964174, 9940.0, 9982.800343993225, 9987.400755954666, 5278.5513231375, 5328.307320048447, 5367.892642147135, 5377.8117765878005, 5400.351957765015, 4396.010897548026, 4691.108689349013, 4905.626424601118, 4906.803727850917, 4909.435414927258, 3974.4038394241097, 4005.439238506611, 4060.3706425504092, 4149.087200815816, 4180.059486615487, 1311.8819306262483, 1336.1582203212108, 1337.993310033463, 1345.1525538910423, 1346.9865301347056, 951.7573018880141, 959.3907868503551, 959.8032403357295, 973.9220862330997, 1008.8284991551454, 5965.492933100648, 5987.670678112659, 6018.488428483541, 6021.578489505692, 6136.447719705248, 445.9620932220733, 455.2420220333174, 455.9361689363492, 901.7520181950016, 946.5314669238771, 3350.966490335114, 3557.3952428087287, 4153.356229784388, 4308.5045219799495, 4374.387585737999, 9702.369345992487, 9976.752905886764, 10485.475726213812, 10524.894751052392, 10679.626213082505, 10070.345427547187, 10470.16238700902, 10483.580656773796, 10590.0, 10673.786524269472, 7250.021247131675, 7652.387808975269, 8174.427790054639, 8198.508089514648, 8213.753587392388, 6005.789797357072, 6127.816165515042, 6141.293751218667, 6164.198654174928, 6209.192804935329, 9790.216782657228, 10007.499625018865, 10037.297389182688, 10077.0, 10079.69760907174, 9822.410655360702, 9849.556769945513, 10089.949550252202, 10095.697129086138, 10147.7463063424, 6682.866342673216, 6985.825354366181, 7418.369438597564, 7637.88543171864, 7650.808729781577, 8339.166083391612, 8469.02606200295, 8471.57642117904, 8564.314854811724, 8580.613872375883, 7483.289087536676, 7595.3164215220895, 7619.08570971487, 7655.540667559964, 7723.961380193063, 7332.633368331669, 7340.229275925951, 7441.962790186014, 7518.173000969999, 7524.172341042371, 7063.364297213276, 7098.3256590623805, 7142.464315176278, 7147.606881621561, 7154.749583764543, 4962.8759281018265, 5078.441371449064, 5151.613628977767, 5160.922586161286, 5173.896522069463, 4504.662150338672, 5106.6490655180605, 5108.9233661495855, 5148.562372198405, 5206.73966301691], "value": 4585.348553056766, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [305.39226938392596, 305.6301874731576, 319.59411547334884, 321.84390570573254, 324.9918752031199, 4282.743035417877, 5329.760160792764, 5473.698946557938, 5556.638818476797, 5593.804216852411, 5131.153359695655, 5294.338207724035, 5554.2779438672915, 5595.720213989304, 5602.38373778885, 3944.842206311758, 4201.789910504478, 4211.515675697304, 4225.873223803291, 4232.322828347457, 3874.6319099685484, 3916.7454115482547, 4238.91522169558, 4239.2581298272835, 4256.2764330063965, 4032.8185231664497, 4102.056526998792, 4109.958900410988, 4145.626893579577, 4150.522689890671, 4028.8244348052017, 4134.4625198724125, 4163.770992595406, 4178.1643671265865, 4223.852165174235, 3189.04328701391, 3506.667466362766, 3612.566492020948, 3617.7467577269604, 3638.6179451157514, 5222.556082732972, 5270.314859068297, 5320.17537281722, 5322.483092318709, 5328.706921119337, 4116.855910043134, 4434.379186913835, 4691.038337140878, 4779.920734480477, 4824.879378015543, 4666.719996800203, 4700.835470758507, 5258.947410525884, 5684.431556844283, 5866.090755932837, 3531.894043178683, 3916.4125381192766, 3946.921061578781, 3956.090099277182, 3963.4253033310297, 5162.625192411458, 5919.437653422918, 6173.197484327009, 6183.628982261079, 6185.443310102112, 3230.6284777250917, 3356.0, 3539.9292014159573, 3997.8411657704514, 4033.233685599727, 2997.460457117696, 3710.1466662667735, 4053.5541090480024, 4085.019595297107, 4113.362428823537, 4085.9182816343505, 4113.8813511041835, 4611.9392539716055, 4754.881127971759, 4763.666543342002, 5353.0899747043095, 5356.0, 5564.666120032811, 5865.941340586625, 5948.405159484017, 1147.7761836441814, 1224.4367590908282, 1371.4445649511963, 1377.3251106957719, 1381.8825399840925, 759.9285008138609, 819.9262066414051, 827.4745536584312, 852.1819053708385, 853.8206976534873, 4212.915741685149, 4275.636570891509, 4309.719868208556, 4638.628909687217, 4656.580907718321, 4492.9550704493195, 4566.589006989387, 4966.1061009018, 5138.511841375063, 5145.665531740425, 4182.971258939412, 4603.654725895572, 4616.538346165356, 4620.5148459411275, 4675.719456832601, 2866.5556838690036, 3183.2201110727556, 3191.9680803192136, 3205.406999705062, 3229.224986003342, 5833.82498525045, 6123.969380153071, 6382.20222472191, 6396.808095757135, 6429.099926010363, 5237.580993520509, 5269.894602107936, 5944.762209511658, 6146.156153154126, 6199.039148931923, 5292.788288468495, 5604.495595396434, 6237.0, 6275.6548389839, 6318.842028949313, 4931.0630980113665, 5548.945700316927, 6041.18444010053, 6070.48400885912, 6099.756009759737, 5221.582273418041, 5337.359516858002, 5757.96356655806, 5849.976254155421, 6000.459958603747, 5147.099257629975, 5654.547636189012, 5940.188242586053, 6344.936550634435, 6373.558278529542, 3383.5093911383206, 3590.156313266328, 3688.4836122943348, 3823.241308997781, 3834.9233015340096, 5273.024490469275, 5487.945120548743, 5565.833025009256, 5962.2249107615735, 6060.848478788065, 4853.927191092066, 4933.692571468636, 5620.297462817148, 5925.348211696796, 5928.407159284037, 4988.42633097184, 5014.7994080236385, 5671.773129074956, 5982.0, 5992.520598352208, 4615.930761038654, 5148.867249205169, 5577.609567330248, 5807.970960145172, 5811.4479124483105, 4512.82666506704, 5800.970995144998, 5913.586048976703, 5933.495652869555, 5962.344142144274, 4903.656744027884, 5425.83722488326, 5815.912761308668, 5828.59199856006, 5855.473007429352], "value": 4114.128818534228, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [728.5337384074194, 732.3408931961234, 758.2190343945738, 819.2135549872124, 826.5495305058743, 14894.404223831047, 15360.539183824489, 15404.768928466083, 15498.845011549882, 15584.220788960549, 11822.527098916054, 14079.5072172474, 14136.575902722909, 14243.572692819205, 14290.356933937972, 702.0522294901892, 870.4603146049458, 888.2094935507404, 902.2736696958946, 907.3240435875249, 858.3305022082754, 885.1856292211149, 888.9822203555925, 890.7862113092876, 896.8565029595266, 921.8156368726249, 960.7790208252106, 962.8266911955845, 963.3497389262255, 963.8168747937905, 933.1042199488465, 948.1656142594542, 952.5903861339622, 955.9235261179089, 956.6412595276771, 675.8377989282537, 691.8305015271291, 702.4134847402426, 702.7751119641716, 703.2721133626671, 14160.575182744535, 14451.205183714892, 14475.855241447662, 14482.203478808662, 14508.0, 11021.504032318566, 13503.527376541871, 14147.787783183261, 14169.78745318821, 14188.645283867883, 11426.714332141762, 11741.765164696744, 11757.059435245159, 11764.176507644468, 12266.938665306705, 3298.422776014212, 3804.6575808177395, 3848.8652897148745, 3851.6340947609933, 3915.099527108752, 54204.72897635487, 72745.0, 72960.0, 73175.63412182905, 73677.26322736757, 10410.635627752992, 10812.837807432894, 10829.512671929782, 10993.175511836644, 10997.61508347204, 21369.252076177258, 21415.250466233607, 21416.89291553532, 21436.678449823266, 21449.785502145092, 8024.719134830253, 8100.0, 8187.95906020478, 8221.465604735793, 8302.70940517079, 4464.464264288306, 4485.192665320274, 4507.166174257773, 4550.294704320836, 4568.794404251817, 3424.298018906118, 3477.3219222251923, 3522.436410174411, 3527.6295988921306, 3530.770499917497, 665.6039656404431, 670.2359310386164, 676.1413005483009, 677.1975209376905, 681.6557638392679, 476.3616753550259, 514.4161376837317, 624.2945471617044, 639.7313128486047, 663.8605892762478, 9116.042815504408, 9528.523573821281, 9558.617655293849, 9587.376820506644, 9666.420014799134, 554.919536667189, 586.519054375408, 619.8357435279654, 642.5437939063224, 674.3964152083955, 3658.5609726832417, 3793.4878791363367, 4321.719088259253, 4398.516163222043, 4530.818767249339, 84257.0, 84282.0, 85222.0, 87854.68217976735, 89348.5532572333, 55021.44978550243, 77403.22596774073, 77573.06067348362, 78801.21198788054, 79919.60040199762, 64554.99999999906, 69165.30834691589, 70563.2943670567, 71011.64494177495, 71601.28398716051, 8908.73273801787, 8917.73246802597, 8951.73144805657, 8974.955125224333, 9044.186023257938, 38917.80541097277, 41902.580974191085, 45065.54934450614, 45157.32264016108, 45539.316910247035, 54934.90130197453, 60226.39773602208, 61831.07253391293, 62808.0, 62962.68518657378, 11821.873659212892, 11857.34784586856, 11945.343006134399, 11956.402179890796, 12029.578964736193, 12708.809367859305, 12775.341385899424, 12999.1550549214, 13288.601341959757, 13397.330133493091, 11943.734649829994, 11989.700257493632, 12042.096842737004, 12173.878261217274, 12190.878091218974, 11801.881981180079, 11872.228305160137, 11948.223365481215, 12170.57402990891, 12234.142668925264, 11649.825252621047, 12211.938940305241, 13002.219866808022, 13119.344032798514, 13971.371288291848, 11078.0, 11268.830967535658, 11354.432278385882, 11372.20394572372, 11429.771404572028, 9355.970843207378, 10927.562897484011, 11266.155038371991, 11441.828372574251, 11551.0], "value": 7117.681024178192, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1550.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 106.0, 117.0, 116.0, 115.0, 118.0, 117.0, 116.0, 118.0, 115.0], "value": 115.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 124.0, 144.0, 169.0, 120.0, 143.0, 144.0, 135.0, 171.0, 107.0], "value": 141.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 219.0, 178.0, 221.0, 269.0, 275.0, 244.0, 199.0, 194.0, 197.0], "value": 219.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 107.0, 100.0, 144.0, 71.0, 58.0, 57.0, 60.0, 127.0, 58.0], "value": 84.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 104.0, 151.0, 93.0, 71.0, 80.0, 113.0, 70.0, 76.0, 116.0], "value": 94.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 85.0, 88.0, 85.0, 107.0, 105.0, 89.0, 86.0, 89.0, 84.0], "value": 90.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 96.0, 96.0, 95.0, 104.0, 95.0, 95.0, 94.0, 94.0, 95.0], "value": 95.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 92.0, 91.0, 88.0, 91.0, 91.0, 90.0, 94.0, 89.0, 111.0], "value": 92.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 54.0, 57.0, 57.0, 57.0, 57.0, 55.0, 57.0, 57.0, 55.0], "value": 56.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 89.0, 108.0, 70.0, 77.0, 82.0, 75.0, 73.0, 72.0, 72.0], "value": 78.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 80.0, 94.0, 111.0, 110.0, 86.0, 84.0, 88.0, 120.0, 89.0], "value": 94.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 152.0, 144.0, 141.0, 139.0, 136.0, 139.0, 139.0, 178.0, 154.0], "value": 147.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 168.0, 167.0, 167.0, 168.0, 166.0, 167.0, 170.0, 171.0, 221.0], "value": 172.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 70.0, 65.0, 67.0, 65.0, 67.0, 66.0, 68.0, 66.0, 66.0], "value": 66.3, "unit": "score"}]}]} 21:38:54 INFO - Return code: 0 21:38:54 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 21:38:54 ERROR - # TBPL SUCCESS # 21:38:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:38:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:38:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:38:54 INFO - 2016-05-26 21:38:54.383 screenresolution[2282:19079] starting screenresolution argv=screenresolution get 21:38:54 INFO - 2016-05-26 21:38:54.386 screenresolution[2282:19079] Display 0: 1600x1200x32@60 21:38:54 INFO - 2016-05-26 21:38:54.392 screenresolution[2283:19082] starting screenresolution argv=screenresolution list 21:38:54 INFO - Available Modes on Display 0 21:38:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:38:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:38:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:38:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:38:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:38:54 INFO - Intel Iris: 21:38:54 INFO - Chipset Model: Intel Iris 21:38:54 INFO - Type: GPU 21:38:54 INFO - Bus: Built-In 21:38:54 INFO - VRAM (Dynamic, Max): 1536 MB 21:38:54 INFO - Vendor: Intel (0x8086) 21:38:54 INFO - Device ID: 0x0a2e 21:38:54 INFO - Revision ID: 0x0009 21:38:54 INFO - Displays: 21:38:54 INFO - Display: 21:38:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:38:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:38:54 INFO - Display Serial Number: 1600x1200 60 21:38:54 INFO - Main Display: Yes 21:38:54 INFO - Mirror: Off 21:38:54 INFO - Online: Yes 21:38:54 INFO - Rotation: Supported 21:38:54 INFO - Return code: 0 21:38:54 INFO - Running post-action listener: _resource_record_post_action 21:38:54 INFO - ##### 21:38:54 INFO - ##### Finished run-tests step (success) 21:38:54 INFO - ##### 21:38:54 INFO - Running post-run listener: _resource_record_post_run 21:38:54 INFO - Total resource usage - Wall time: 1207s; CPU: 54.0%; Read bytes: 5693440; Write bytes: 700639744; Read time: 121; Write time: 2148 21:38:54 INFO - TinderboxPrint: CPU usage
54.4% 21:38:54 INFO - TinderboxPrint: I/O read bytes / time
5,693,440 / 121 21:38:54 INFO - TinderboxPrint: I/O write bytes / time
700,639,744 / 2,148 21:38:54 INFO - TinderboxPrint: CPU idle
2,200.4 (45.6%) 21:38:54 INFO - TinderboxPrint: CPU system
974.3 (20.2%) 21:38:54 INFO - TinderboxPrint: CPU user
1,654.9 (34.3%) 21:38:54 INFO - TinderboxPrint: Swap in / out
403,357,696 / 0 21:38:54 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 398514688; Write bytes: 401740800; Read time: 30146; Write time: 766 21:38:54 INFO - run-tests - Wall time: 1173s; CPU: 54.0%; Read bytes: 5591040; Write bytes: 297883136; Read time: 118; Write time: 1377 21:38:55 INFO - Running post-run listener: _upload_blobber_files 21:38:55 INFO - Blob upload gear active. 21:38:55 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:38:55 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 21:38:55 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'] 21:38:55 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 21:38:55 INFO - (blobuploader) - INFO - Open directory for files ... 21:38:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 21:38:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:38:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:38:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 21:38:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:38:57 INFO - (blobuploader) - INFO - Done attempting. 21:38:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 21:38:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:38:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:38:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 21:38:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:38:58 INFO - (blobuploader) - INFO - Done attempting. 21:38:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:38:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:38:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:39:00 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:39:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:39:00 INFO - (blobuploader) - INFO - Done attempting. 21:39:00 INFO - (blobuploader) - INFO - Iteration through files over. 21:39:00 INFO - Return code: 0 21:39:00 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:39:00 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:39:00 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4b0981b97d96de0f03960efa90c69938715bbbba1d840724abc92fef19f42e92d95296ca956744f143fd07cafd61489c7914fcbf7c2f79d22e5ff1bc3d71f542", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a0f64439a3966aa1052596cad96d81a310c61cb84cf349b056ed0f6955f3c759ebc74ae1084a91a4c42fb05d3d9c64a1fb907fb6a8e7ea5c982d3206d5a5771f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ff647f6ed4a2352d980a7a165fe75f49f872a47b731a4ffbc7806b29cfce7d1b475f78f5ea6d195ef61caa1bb09caf06df3ea385b412e5eeaf0eff7dddcf551c"} 21:39:00 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:39:00 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:39:00 INFO - Contents: 21:39:00 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4b0981b97d96de0f03960efa90c69938715bbbba1d840724abc92fef19f42e92d95296ca956744f143fd07cafd61489c7914fcbf7c2f79d22e5ff1bc3d71f542", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a0f64439a3966aa1052596cad96d81a310c61cb84cf349b056ed0f6955f3c759ebc74ae1084a91a4c42fb05d3d9c64a1fb907fb6a8e7ea5c982d3206d5a5771f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ff647f6ed4a2352d980a7a165fe75f49f872a47b731a4ffbc7806b29cfce7d1b475f78f5ea6d195ef61caa1bb09caf06df3ea385b412e5eeaf0eff7dddcf551c"} 21:39:00 INFO - Running post-run listener: copy_logs_to_upload_dir 21:39:00 INFO - Copying logs to upload dir... 21:39:00 INFO - mkdir: /builds/slave/test/build/upload/logs 21:39:00 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1293.997138 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 34 secs) (at 2016-05-26 21:39:00.452630) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-26 21:39:00.457945) ========= 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.Z4GDO3vMW6/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.Mv7JVtew76/Listeners TMPDIR=/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4b0981b97d96de0f03960efa90c69938715bbbba1d840724abc92fef19f42e92d95296ca956744f143fd07cafd61489c7914fcbf7c2f79d22e5ff1bc3d71f542", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a0f64439a3966aa1052596cad96d81a310c61cb84cf349b056ed0f6955f3c759ebc74ae1084a91a4c42fb05d3d9c64a1fb907fb6a8e7ea5c982d3206d5a5771f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ff647f6ed4a2352d980a7a165fe75f49f872a47b731a4ffbc7806b29cfce7d1b475f78f5ea6d195ef61caa1bb09caf06df3ea385b412e5eeaf0eff7dddcf551c"} build_url:https://queue.taskcluster.net/v1/task/HjX7zCRzTVWYUsE8TXQKSw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011136 build_url: 'https://queue.taskcluster.net/v1/task/HjX7zCRzTVWYUsE8TXQKSw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4b0981b97d96de0f03960efa90c69938715bbbba1d840724abc92fef19f42e92d95296ca956744f143fd07cafd61489c7914fcbf7c2f79d22e5ff1bc3d71f542", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a0f64439a3966aa1052596cad96d81a310c61cb84cf349b056ed0f6955f3c759ebc74ae1084a91a4c42fb05d3d9c64a1fb907fb6a8e7ea5c982d3206d5a5771f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ff647f6ed4a2352d980a7a165fe75f49f872a47b731a4ffbc7806b29cfce7d1b475f78f5ea6d195ef61caa1bb09caf06df3ea385b412e5eeaf0eff7dddcf551c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-26 21:39:00.489199) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 21:39:00.489559) ========= 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.Z4GDO3vMW6/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.Mv7JVtew76/Listeners TMPDIR=/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/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.005179 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 21:39:00.538373) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-26 21:39:00.538693) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-26 21:39:00.539041) ========= ========= Total master_lag: 0.17 =========