builder: larch_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0029 starttime: 1464200290.42 results: success (0) buildid: 20160525091843 builduid: 6600d0aac85d4a879f886da3f75303bf revision: 2cfafbe60b75550e47fbc04aa87d43ccb856edcf ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-25 11:18:10.425210) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-25 11:18:10.425645) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-25 11:18:10.425921) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-25 11:18:10.455721) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 11:18:10.456374) ========= 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.AfXkI8tNfc/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.XewucawdYy/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-25 11:18:10-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 21.7M=0.001s 2016-05-25 11:18:11 (21.7 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.591316 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 11:18:11.067418) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 11:18:11.067700) ========= 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.AfXkI8tNfc/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.XewucawdYy/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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.029707 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 11:18:11.115792) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-25 11:18:11.116104) ========= bash -c 'python archiver_client.py mozharness --repo projects/larch --rev 2cfafbe60b75550e47fbc04aa87d43ccb856edcf --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/larch --rev 2cfafbe60b75550e47fbc04aa87d43ccb856edcf --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.AfXkI8tNfc/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.XewucawdYy/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-25 11:18:11,192 truncating revision to first 12 chars 2016-05-25 11:18:11,192 Setting DEBUG logging. 2016-05-25 11:18:11,192 attempt 1/10 2016-05-25 11:18:11,192 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/larch/2cfafbe60b75?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-25 11:18:12,298 unpacking tar archive at: larch-2cfafbe60b75/testing/mozharness/ program finished with exit code 0 elapsedTime=1.368897 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-25 11:18:12.504280) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-25 11:18:12.504565) ========= script_repo_revision: 2cfafbe60b75550e47fbc04aa87d43ccb856edcf ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-25 11:18:12.504941) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-25 11:18:12.505210) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-25 11:18:12.521443) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 39 secs) (at 2016-05-25 11:18:12.521739) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch 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', 'Larch', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Larch'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.AfXkI8tNfc/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.XewucawdYy/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 11:18:12 INFO - MultiFileLogger online at 20160525 11:18:12 in /builds/slave/test 11:18:12 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch 11:18:12 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:18:12 INFO - {'append_to_log': False, 11:18:12 INFO - 'base_work_dir': '/builds/slave/test', 11:18:12 INFO - 'blob_upload_branch': 'Larch', 11:18:12 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:18:12 INFO - 'branch': 'Larch', 11:18:12 INFO - 'buildbot_json_path': 'buildprops.json', 11:18:12 INFO - 'config_files': ('talos/mac_config.py',), 11:18:12 INFO - 'default_actions': ('clobber', 11:18:12 INFO - 'read-buildbot-config', 11:18:12 INFO - 'download-and-extract', 11:18:12 INFO - 'populate-webroot', 11:18:12 INFO - 'create-virtualenv', 11:18:12 INFO - 'install', 11:18:12 INFO - 'run-tests'), 11:18:12 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:18:12 INFO - 'download_minidump_stackwalk': True, 11:18:12 INFO - 'download_symbols': 'ondemand', 11:18:12 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:18:12 INFO - 'tooltool.py': '/tools/tooltool.py', 11:18:12 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:18:12 INFO - '/tools/misc-python/virtualenv.py')}, 11:18:12 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:18:12 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:18:12 INFO - 'installer_path': 'installer.exe', 11:18:12 INFO - 'log_level': 'info', 11:18:12 INFO - 'log_name': 'talos', 11:18:12 INFO - 'log_to_console': True, 11:18:12 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:18:12 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:18:12 INFO - 'opt_config_files': (), 11:18:12 INFO - 'pip_index': False, 11:18:12 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:18:12 INFO - 'cmd': ('bash', 11:18:12 INFO - '-c', 11:18:12 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:18:12 INFO - 'enabled': True, 11:18:12 INFO - 'halt_on_failure': False, 11:18:12 INFO - 'name': 'check_screen_resolution'},), 11:18:12 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:18:12 INFO - 'cmd': ('bash', 11:18:12 INFO - '-c', 11:18:12 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:18:12 INFO - 'enabled': True, 11:18:12 INFO - 'halt_on_failure': False, 11:18:12 INFO - 'name': 'check_screen_resolution'},), 11:18:12 INFO - 'run_cmd_checks_enabled': True, 11:18:12 INFO - 'sps_profile': False, 11:18:12 INFO - 'sps_profile_interval': 0, 11:18:12 INFO - 'suite': 'dromaeojs-e10s', 11:18:12 INFO - 'system_bits': '32', 11:18:12 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:18:12 INFO - 'title': 't-yosemite-r7-0029', 11:18:12 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:18:12 INFO - 'use_talos_json': True, 11:18:12 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:18:12 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:18:12 INFO - 'work_dir': 'build'} 11:18:12 INFO - ##### 11:18:12 INFO - ##### Running clobber step. 11:18:12 INFO - ##### 11:18:12 INFO - Running pre-action listener: _resource_record_pre_action 11:18:12 INFO - Running main action method: clobber 11:18:12 INFO - rmtree: /builds/slave/test/build 11:18:12 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:18:14 INFO - Running post-action listener: _resource_record_post_action 11:18:14 INFO - ##### 11:18:14 INFO - ##### Finished clobber step (success) 11:18:14 INFO - ##### 11:18:14 INFO - ##### 11:18:14 INFO - ##### Running read-buildbot-config step. 11:18:14 INFO - ##### 11:18:14 INFO - Running pre-action listener: _resource_record_pre_action 11:18:14 INFO - Running main action method: read_buildbot_config 11:18:14 INFO - Using buildbot properties: 11:18:14 INFO - { 11:18:14 INFO - "project": "", 11:18:14 INFO - "product": "firefox", 11:18:14 INFO - "script_repo_revision": "production", 11:18:14 INFO - "builddir": "larch_yosemite_r7_test-dromaeojs-e10s", 11:18:14 INFO - "repository": "", 11:18:14 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 larch talos dromaeojs-e10s", 11:18:14 INFO - "buildid": "20160525091843", 11:18:14 INFO - "pgo_build": "False", 11:18:14 INFO - "basedir": "/builds/slave/test", 11:18:14 INFO - "buildnumber": 0, 11:18:14 INFO - "slavename": "t-yosemite-r7-0029", 11:18:14 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 11:18:14 INFO - "platform": "yosemite_r7", 11:18:14 INFO - "scheduler": "tests-larch-macosx64-talos", 11:18:14 INFO - "branch": "larch", 11:18:14 INFO - "repo_path": "projects/larch", 11:18:14 INFO - "revision": "2cfafbe60b75550e47fbc04aa87d43ccb856edcf", 11:18:14 INFO - "stage_platform": "macosx64", 11:18:14 INFO - "builduid": "6600d0aac85d4a879f886da3f75303bf", 11:18:14 INFO - "slavebuilddir": "test" 11:18:14 INFO - } 11:18:14 INFO - Found installer url https://queue.taskcluster.net/v1/task/DCftajzTTwuCAyg-GMzplA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 11:18:14 INFO - Running post-action listener: _resource_record_post_action 11:18:14 INFO - ##### 11:18:14 INFO - ##### Finished read-buildbot-config step (success) 11:18:14 INFO - ##### 11:18:14 INFO - ##### 11:18:14 INFO - ##### Running download-and-extract step. 11:18:14 INFO - ##### 11:18:14 INFO - Running pre-action listener: _resource_record_pre_action 11:18:14 INFO - Running main action method: download_and_extract 11:18:14 INFO - mkdir: /builds/slave/test/build/tests 11:18:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:18:14 INFO - https://queue.taskcluster.net/v1/task/DCftajzTTwuCAyg-GMzplA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 11:18:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DCftajzTTwuCAyg-GMzplA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 11:18:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DCftajzTTwuCAyg-GMzplA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 11:18:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DCftajzTTwuCAyg-GMzplA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 11:18:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DCftajzTTwuCAyg-GMzplA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 11:18:43 INFO - Downloaded 1199 bytes. 11:18:43 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 11:18:43 INFO - Using the following test package requirements: 11:18:43 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 11:18:43 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 11:18:43 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 11:18:43 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 11:18:43 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 11:18:43 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 11:18:43 INFO - u'jsshell-mac.zip'], 11:18:43 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 11:18:43 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 11:18:43 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 11:18:43 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 11:18:43 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 11:18:43 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 11:18:43 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 11:18:43 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 11:18:43 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 11:18:43 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 11:18:43 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 11:18:43 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 11:18:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:18:43 INFO - https://queue.taskcluster.net/v1/task/DCftajzTTwuCAyg-GMzplA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 11:18:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DCftajzTTwuCAyg-GMzplA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 11:18:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DCftajzTTwuCAyg-GMzplA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 11:18:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DCftajzTTwuCAyg-GMzplA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 11:18:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DCftajzTTwuCAyg-GMzplA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 11:18:45 INFO - Downloaded 18634696 bytes. 11:18:45 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 11:18:45 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 11:18:49 INFO - Return code: 0 11:18:49 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 11:18:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:18:49 INFO - https://queue.taskcluster.net/v1/task/DCftajzTTwuCAyg-GMzplA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 11:18:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DCftajzTTwuCAyg-GMzplA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 11:18:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DCftajzTTwuCAyg-GMzplA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 11:18:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DCftajzTTwuCAyg-GMzplA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 11:18:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DCftajzTTwuCAyg-GMzplA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 11:18:50 INFO - Downloaded 18634696 bytes. 11:18:50 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 11:18:50 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 11:18:53 INFO - Return code: 0 11:18:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:18:53 INFO - https://queue.taskcluster.net/v1/task/DCftajzTTwuCAyg-GMzplA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 11:18:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DCftajzTTwuCAyg-GMzplA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 11:18:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DCftajzTTwuCAyg-GMzplA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 11:18:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DCftajzTTwuCAyg-GMzplA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 11:18:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DCftajzTTwuCAyg-GMzplA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 11:18:54 INFO - Downloaded 10834042 bytes. 11:18:54 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 11:18:54 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 11:18:55 INFO - Return code: 0 11:18:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:18:55 INFO - https://queue.taskcluster.net/v1/task/DCftajzTTwuCAyg-GMzplA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 11:18:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DCftajzTTwuCAyg-GMzplA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 11:18:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DCftajzTTwuCAyg-GMzplA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 11:18:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DCftajzTTwuCAyg-GMzplA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 11:18:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DCftajzTTwuCAyg-GMzplA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 11:19:00 INFO - Downloaded 110597814 bytes. 11:19:00 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DCftajzTTwuCAyg-GMzplA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 11:19:00 INFO - mkdir: /builds/slave/test/properties 11:19:00 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:19:00 INFO - Writing to file /builds/slave/test/properties/build_url 11:19:00 INFO - Contents: 11:19:00 INFO - build_url:https://queue.taskcluster.net/v1/task/DCftajzTTwuCAyg-GMzplA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 11:19:02 INFO - Running post-action listener: _resource_record_post_action 11:19:02 INFO - Running post-action listener: set_extra_try_arguments 11:19:02 INFO - ##### 11:19:02 INFO - ##### Finished download-and-extract step (success) 11:19:02 INFO - ##### 11:19:02 INFO - ##### 11:19:02 INFO - ##### Running populate-webroot step. 11:19:02 INFO - ##### 11:19:02 INFO - Running pre-action listener: _resource_record_pre_action 11:19:02 INFO - Running main action method: populate_webroot 11:19:02 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:19:02 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:19:02 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:19:02 INFO - '--tppagecycles', 11:19:02 INFO - '7'], 11:19:02 INFO - 'tests': ['tsvgm']}}, 11:19:02 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:19:02 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:19:02 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:19:02 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:19:02 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:19:02 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:19:02 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:19:02 INFO - 'talos_options': ['--disable-e10s'], 11:19:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:19:02 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:19:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:19:02 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:19:02 INFO - 'talos_options': ['--disable-e10s'], 11:19:02 INFO - 'tests': ['damp', 'tps']}, 11:19:02 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:19:02 INFO - 'tests': ['damp', 'tps']}, 11:19:02 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:19:02 INFO - 'tests': ['dromaeo_dom']}, 11:19:02 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:19:02 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:19:02 INFO - 'tests': ['a11yr', 11:19:02 INFO - 'ts_paint', 11:19:02 INFO - 'tpaint', 11:19:02 INFO - 'sessionrestore', 11:19:02 INFO - 'sessionrestore_no_auto_restore', 11:19:02 INFO - 'tabpaint']}, 11:19:02 INFO - 'other-e10s': {'tests': ['a11yr', 11:19:02 INFO - 'ts_paint', 11:19:02 INFO - 'tpaint', 11:19:02 INFO - 'sessionrestore', 11:19:02 INFO - 'sessionrestore_no_auto_restore', 11:19:02 INFO - 'tabpaint']}, 11:19:02 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:19:02 INFO - 'ts_paint', 11:19:02 INFO - 'tpaint', 11:19:02 INFO - 'sessionrestore', 11:19:02 INFO - 'sessionrestore_no_auto_restore', 11:19:02 INFO - 'tabpaint']}, 11:19:02 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:19:02 INFO - 'ts_paint', 11:19:02 INFO - 'tpaint', 11:19:02 INFO - 'sessionrestore', 11:19:02 INFO - 'sessionrestore_no_auto_restore', 11:19:02 INFO - 'tabpaint']}, 11:19:02 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:19:02 INFO - 'tests': ['a11yr', 11:19:02 INFO - 'ts_paint', 11:19:02 INFO - 'tpaint', 11:19:02 INFO - 'sessionrestore', 11:19:02 INFO - 'sessionrestore_no_auto_restore', 11:19:02 INFO - 'tabpaint']}, 11:19:02 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:19:02 INFO - 'tests': ['a11yr', 11:19:02 INFO - 'ts_paint', 11:19:02 INFO - 'tpaint', 11:19:02 INFO - 'sessionrestore', 11:19:02 INFO - 'sessionrestore_no_auto_restore', 11:19:02 INFO - 'tabpaint']}, 11:19:02 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:19:02 INFO - 'tests': ['tsvgx', 11:19:02 INFO - 'tsvgr_opacity', 11:19:02 INFO - 'tart', 11:19:02 INFO - 'tscrollx', 11:19:02 INFO - 'cart']}, 11:19:02 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:19:02 INFO - 'tsvgr_opacity', 11:19:02 INFO - 'tart', 11:19:02 INFO - 'tscrollx', 11:19:02 INFO - 'cart']}, 11:19:02 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:19:02 INFO - 'talos_options': ['--disable-e10s'], 11:19:02 INFO - 'tests': ['tp5o']}, 11:19:02 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:19:02 INFO - 'tests': ['tp5o']}, 11:19:02 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:19:02 INFO - 'talos_options': ['--disable-e10s', 11:19:02 INFO - '--xperf_path', 11:19:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:19:02 INFO - 'tests': ['tp5n']}, 11:19:02 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:19:02 INFO - 'talos_options': ['--xperf_path', 11:19:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:19:02 INFO - 'tests': ['tp5n']}}, 11:19:02 INFO - 'talos.zip': {'path': '', 11:19:02 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:19:02 INFO - Running post-action listener: _resource_record_post_action 11:19:02 INFO - ##### 11:19:02 INFO - ##### Finished populate-webroot step (success) 11:19:02 INFO - ##### 11:19:02 INFO - ##### 11:19:02 INFO - ##### Running create-virtualenv step. 11:19:02 INFO - ##### 11:19:02 INFO - Running pre-action listener: _resource_record_pre_action 11:19:02 INFO - Running main action method: create_virtualenv 11:19:02 INFO - Creating virtualenv /builds/slave/test/build/venv 11:19:02 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 11:19:02 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:19:02 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:19:02 INFO - Using real prefix '/tools/python27' 11:19:02 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:19:02 INFO - Installing distribute.............................................................................................................................................................................................done. 11:19:05 INFO - Installing pip.................done. 11:19:05 INFO - Return code: 0 11:19:05 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:19: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')]} 11:19:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:19:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:19: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')]} 11:19:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:19:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:19:05 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 0x104e53a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e59570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6425a4260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cf7d30>, '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 0x104b1bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104ceeb20>, '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.AfXkI8tNfc/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.XewucawdYy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:19:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 11:19:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 11:19:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AfXkI8tNfc/Render', 11:19:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:19:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:19:05 INFO - 'HOME': '/Users/cltbld', 11:19:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:19:05 INFO - 'LOGNAME': 'cltbld', 11:19:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:19:05 INFO - 'MOZ_NO_REMOTE': '1', 11:19:05 INFO - 'NO_EM_RESTART': '1', 11:19:05 INFO - 'PAGER': '/bin/cat', 11:19:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:19:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:19:05 INFO - 'PWD': '/builds/slave/test', 11:19:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:19:05 INFO - 'SHELL': '/bin/bash', 11:19:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XewucawdYy/Listeners', 11:19:05 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 11:19:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:19:05 INFO - 'USER': 'cltbld', 11:19:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:19:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:19:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:19:05 INFO - 'XPC_FLAGS': '0x0', 11:19:05 INFO - 'XPC_SERVICE_NAME': '0', 11:19:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:19:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:19:06 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 11:19:06 INFO - Cleaning up... 11:19:06 INFO - Return code: 0 11:19:06 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:19: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')]} 11:19:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:19:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:19: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')]} 11:19:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:19:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:19:06 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 0x104e53a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e59570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6425a4260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cf7d30>, '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 0x104b1bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104ceeb20>, '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.AfXkI8tNfc/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.XewucawdYy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:19:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 11:19:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 11:19:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AfXkI8tNfc/Render', 11:19:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:19:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:19:06 INFO - 'HOME': '/Users/cltbld', 11:19:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:19:06 INFO - 'LOGNAME': 'cltbld', 11:19:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:19:06 INFO - 'MOZ_NO_REMOTE': '1', 11:19:06 INFO - 'NO_EM_RESTART': '1', 11:19:06 INFO - 'PAGER': '/bin/cat', 11:19:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:19:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:19:06 INFO - 'PWD': '/builds/slave/test', 11:19:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:19:06 INFO - 'SHELL': '/bin/bash', 11:19:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XewucawdYy/Listeners', 11:19:06 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 11:19:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:19:06 INFO - 'USER': 'cltbld', 11:19:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:19:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:19:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:19:06 INFO - 'XPC_FLAGS': '0x0', 11:19:06 INFO - 'XPC_SERVICE_NAME': '0', 11:19:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:19:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:19:06 INFO - Downloading/unpacking psutil>=3.1.1 11:19:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:19:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:19:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:19:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:19:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:19:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:19:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:19:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:19:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:19:09 INFO - Installing collected packages: psutil 11:19:09 INFO - Running setup.py install for psutil 11:19:09 INFO - building 'psutil._psutil_osx' extension 11:19:09 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 11:19:09 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 11:19:09 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 11:19:09 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 11:19:09 INFO - building 'psutil._psutil_posix' extension 11:19:09 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 11:19:10 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:19:10 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:19:10 INFO - ^ 11:19:10 INFO - 1 warning generated. 11:19:10 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 11:19:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:19:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:19:10 INFO - Successfully installed psutil 11:19:10 INFO - Cleaning up... 11:19:10 INFO - Return code: 0 11:19:10 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:19:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:19:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:19:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:19:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:19:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:19:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:19:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104e53a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e59570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6425a4260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cf7d30>, '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 0x104b1bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104ceeb20>, '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.AfXkI8tNfc/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.XewucawdYy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:19:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 11:19:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 11:19:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AfXkI8tNfc/Render', 11:19:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:19:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:19:10 INFO - 'HOME': '/Users/cltbld', 11:19:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:19:10 INFO - 'LOGNAME': 'cltbld', 11:19:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:19:10 INFO - 'MOZ_NO_REMOTE': '1', 11:19:10 INFO - 'NO_EM_RESTART': '1', 11:19:10 INFO - 'PAGER': '/bin/cat', 11:19:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:19:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:19:10 INFO - 'PWD': '/builds/slave/test', 11:19:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:19:10 INFO - 'SHELL': '/bin/bash', 11:19:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XewucawdYy/Listeners', 11:19:10 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 11:19:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:19:10 INFO - 'USER': 'cltbld', 11:19:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:19:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:19:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:19:10 INFO - 'XPC_FLAGS': '0x0', 11:19:10 INFO - 'XPC_SERVICE_NAME': '0', 11:19:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:19:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:19:10 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:19:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:19:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:19:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:19:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:19:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:19:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:19:12 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:19:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:19:13 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:19:13 INFO - Installing collected packages: mozsystemmonitor 11:19:13 INFO - Running setup.py install for mozsystemmonitor 11:19:13 INFO - Successfully installed mozsystemmonitor 11:19:13 INFO - Cleaning up... 11:19:13 INFO - Return code: 0 11:19:13 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:19:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:19:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:19:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:19:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:19:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:19:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:19:13 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 0x104e53a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e59570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6425a4260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cf7d30>, '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 0x104b1bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104ceeb20>, '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.AfXkI8tNfc/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.XewucawdYy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:19:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 11:19:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 11:19:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AfXkI8tNfc/Render', 11:19:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:19:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:19:13 INFO - 'HOME': '/Users/cltbld', 11:19:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:19:13 INFO - 'LOGNAME': 'cltbld', 11:19:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:19:13 INFO - 'MOZ_NO_REMOTE': '1', 11:19:13 INFO - 'NO_EM_RESTART': '1', 11:19:13 INFO - 'PAGER': '/bin/cat', 11:19:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:19:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:19:13 INFO - 'PWD': '/builds/slave/test', 11:19:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:19:13 INFO - 'SHELL': '/bin/bash', 11:19:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XewucawdYy/Listeners', 11:19:13 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 11:19:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:19:13 INFO - 'USER': 'cltbld', 11:19:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:19:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:19:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:19:13 INFO - 'XPC_FLAGS': '0x0', 11:19:13 INFO - 'XPC_SERVICE_NAME': '0', 11:19:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:19:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:19:13 INFO - Downloading/unpacking blobuploader==1.2.4 11:19:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:19:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:19:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:19:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:19:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:19:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:19:16 INFO - Downloading blobuploader-1.2.4.tar.gz 11:19:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:19:16 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:19: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 11:19: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 11:19: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 11:19: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 11:19: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 11:19: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 11:19:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:19:17 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:19:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:19:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:19:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:19:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:19:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:19:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:19:17 INFO - Downloading docopt-0.6.1.tar.gz 11:19:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:19:17 INFO - Installing collected packages: blobuploader, requests, docopt 11:19:17 INFO - Running setup.py install for blobuploader 11:19:17 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:19:17 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:19:17 INFO - Running setup.py install for requests 11:19:18 INFO - Running setup.py install for docopt 11:19:18 INFO - Successfully installed blobuploader requests docopt 11:19:18 INFO - Cleaning up... 11:19:18 INFO - Return code: 0 11:19:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:19:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:19:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:19:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:19:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:19:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:19:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:19:18 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 0x104e53a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e59570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6425a4260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cf7d30>, '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 0x104b1bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104ceeb20>, '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.AfXkI8tNfc/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.XewucawdYy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:19:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 11:19:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:19:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AfXkI8tNfc/Render', 11:19:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:19:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:19:18 INFO - 'HOME': '/Users/cltbld', 11:19:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:19:18 INFO - 'LOGNAME': 'cltbld', 11:19:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:19:18 INFO - 'MOZ_NO_REMOTE': '1', 11:19:18 INFO - 'NO_EM_RESTART': '1', 11:19:18 INFO - 'PAGER': '/bin/cat', 11:19:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:19:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:19:18 INFO - 'PWD': '/builds/slave/test', 11:19:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:19:18 INFO - 'SHELL': '/bin/bash', 11:19:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XewucawdYy/Listeners', 11:19:18 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 11:19:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:19:18 INFO - 'USER': 'cltbld', 11:19:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:19:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:19:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:19:18 INFO - 'XPC_FLAGS': '0x0', 11:19:18 INFO - 'XPC_SERVICE_NAME': '0', 11:19:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:19:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:19:19 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-uvSmvy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:19:19 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-xbJhs9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:19:19 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-IKmWKP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:19:19 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-KdTk_q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:19:19 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-BIZofa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:19:19 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-LPrDrw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:19:19 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-mUKBLg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:19:19 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-shj532-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:19:20 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-OoT7cI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:19:20 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-B4RSqU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:19:20 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-UH6gZP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:19:20 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-1IlRq2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:19:20 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-I1E9na-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:19:20 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-eu08Z4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:19:20 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-npCLyz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:19:20 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-yOfBOl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:19:20 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-uArtl9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:19:20 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:19:20 INFO - Running setup.py install for manifestparser 11:19:21 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:19:21 INFO - Running setup.py install for mozcrash 11:19:21 INFO - Running setup.py install for mozdebug 11:19:21 INFO - Running setup.py install for mozdevice 11:19:21 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:19:21 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:19:21 INFO - Running setup.py install for mozfile 11:19:21 INFO - Running setup.py install for mozhttpd 11:19:21 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:19:21 INFO - Running setup.py install for mozinfo 11:19:21 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:19:22 INFO - Running setup.py install for mozInstall 11:19:22 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:19:22 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:19:22 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:19:22 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:19:22 INFO - Running setup.py install for mozleak 11:19:22 INFO - Running setup.py install for mozlog 11:19:22 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:19:22 INFO - Running setup.py install for moznetwork 11:19:22 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:19:22 INFO - Running setup.py install for mozprocess 11:19:22 INFO - Running setup.py install for mozprofile 11:19:22 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:19:22 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:19:22 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:19:22 INFO - Running setup.py install for mozrunner 11:19:23 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:19:23 INFO - Running setup.py install for mozscreenshot 11:19:23 INFO - Running setup.py install for moztest 11:19:23 INFO - Running setup.py install for mozversion 11:19:23 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:19:23 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:19:23 INFO - Cleaning up... 11:19:23 INFO - Return code: 0 11:19:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:19:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:19:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:19:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:19:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:19:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:19:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:19:23 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 0x104e53a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e59570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6425a4260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cf7d30>, '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 0x104b1bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104ceeb20>, '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.AfXkI8tNfc/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.XewucawdYy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:19:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 11:19:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:19:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AfXkI8tNfc/Render', 11:19:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:19:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:19:23 INFO - 'HOME': '/Users/cltbld', 11:19:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:19:23 INFO - 'LOGNAME': 'cltbld', 11:19:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:19:23 INFO - 'MOZ_NO_REMOTE': '1', 11:19:23 INFO - 'NO_EM_RESTART': '1', 11:19:23 INFO - 'PAGER': '/bin/cat', 11:19:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:19:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:19:23 INFO - 'PWD': '/builds/slave/test', 11:19:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:19:23 INFO - 'SHELL': '/bin/bash', 11:19:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XewucawdYy/Listeners', 11:19:23 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 11:19:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:19:23 INFO - 'USER': 'cltbld', 11:19:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:19:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:19:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:19:23 INFO - 'XPC_FLAGS': '0x0', 11:19:23 INFO - 'XPC_SERVICE_NAME': '0', 11:19:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:19:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:19:23 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-MZJYRO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:19:23 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 11:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:19:23 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-quiYtJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:19:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:19:24 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-Lp0PsZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:19:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 11:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:19:24 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-OjnqGV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:19:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:19:24 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-ukVjfv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:19:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 11:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:19:24 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-RFmH79-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:19:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 11:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:19:24 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-W4IxCR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:19:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 11:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:19:24 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-ZU2JiX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:19:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 11:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:19:24 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-XuY6bU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:19:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 11:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:19:24 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-NORX3F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:19:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:19:24 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-jvBCRH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:19:24 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 11:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:19:25 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-MeeJiN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:19:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 11:19:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:19:25 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-LQw96i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:19:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 11:19:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:19:25 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-qrI4kn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:19:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 11:19:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:19:25 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-MSFlgt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:19:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 11:19:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:19:25 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-3FxVef-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:19:25 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 11:19:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:19:25 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-0R1Rb_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:19:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 11:19:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:19:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:19:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 11:19:25 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:19:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:19:25 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:19:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:19:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:19:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:19:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:19:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:19:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:19:28 INFO - Downloading blessings-1.6.tar.gz 11:19:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:19:28 INFO - Installing collected packages: blessings 11:19:28 INFO - Running setup.py install for blessings 11:19:28 INFO - Successfully installed blessings 11:19:28 INFO - Cleaning up... 11:19:28 INFO - Return code: 0 11:19:28 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:19:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:19:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:19:28 INFO - Reading from file tmpfile_stdout 11:19:28 INFO - Current package versions: 11:19:28 INFO - blessings == 1.6 11:19:28 INFO - blobuploader == 1.2.4 11:19:28 INFO - docopt == 0.6.1 11:19:28 INFO - manifestparser == 1.1 11:19:28 INFO - mozInstall == 1.12 11:19:28 INFO - mozcrash == 0.17 11:19:28 INFO - mozdebug == 0.1 11:19:28 INFO - mozdevice == 0.48 11:19:28 INFO - mozfile == 1.2 11:19:28 INFO - mozhttpd == 0.7 11:19:28 INFO - mozinfo == 0.9 11:19:28 INFO - mozleak == 0.1 11:19:28 INFO - mozlog == 3.2 11:19:28 INFO - moznetwork == 0.27 11:19:28 INFO - mozprocess == 0.22 11:19:28 INFO - mozprofile == 0.28 11:19:28 INFO - mozrunner == 6.11 11:19:28 INFO - mozscreenshot == 0.1 11:19:28 INFO - mozsystemmonitor == 0.3 11:19:28 INFO - moztest == 0.7 11:19:28 INFO - mozversion == 1.4 11:19:28 INFO - psutil == 3.1.1 11:19:28 INFO - requests == 1.2.3 11:19:28 INFO - wsgiref == 0.1.2 11:19:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:19: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')]} 11:19:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:19:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:19: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')]} 11:19:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:19:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:19:28 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 0x104e53a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e59570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6425a4260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cf7d30>, '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 0x104b1bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104ceeb20>, '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.AfXkI8tNfc/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.XewucawdYy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:19:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 11:19:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:19:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AfXkI8tNfc/Render', 11:19:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:19:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:19:28 INFO - 'HOME': '/Users/cltbld', 11:19:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:19:28 INFO - 'LOGNAME': 'cltbld', 11:19:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:19:28 INFO - 'MOZ_NO_REMOTE': '1', 11:19:28 INFO - 'NO_EM_RESTART': '1', 11:19:28 INFO - 'PAGER': '/bin/cat', 11:19:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:19:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:19:28 INFO - 'PWD': '/builds/slave/test', 11:19:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:19:28 INFO - 'SHELL': '/bin/bash', 11:19:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XewucawdYy/Listeners', 11:19:28 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 11:19:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:19:28 INFO - 'USER': 'cltbld', 11:19:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:19:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:19:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:19:28 INFO - 'XPC_FLAGS': '0x0', 11:19:28 INFO - 'XPC_SERVICE_NAME': '0', 11:19:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:19:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:19:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 11:19:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 11:19:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 11:19:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 11:19:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 11:19:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 11:19:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 11:19:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 11:19:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 11:19:28 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 11:19:28 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 11:19:28 INFO - Cleaning up... 11:19:28 INFO - Return code: 0 11:19:28 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:19: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')]} 11:19:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:19:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:19: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')]} 11:19:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:19:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:19:29 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 0x104e53a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e59570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6425a4260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cf7d30>, '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 0x104b1bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104ceeb20>, '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.AfXkI8tNfc/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.XewucawdYy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:19:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 11:19:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 11:19:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AfXkI8tNfc/Render', 11:19:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:19:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:19:29 INFO - 'HOME': '/Users/cltbld', 11:19:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:19:29 INFO - 'LOGNAME': 'cltbld', 11:19:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:19:29 INFO - 'MOZ_NO_REMOTE': '1', 11:19:29 INFO - 'NO_EM_RESTART': '1', 11:19:29 INFO - 'PAGER': '/bin/cat', 11:19:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:19:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:19:29 INFO - 'PWD': '/builds/slave/test', 11:19:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:19:29 INFO - 'SHELL': '/bin/bash', 11:19:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XewucawdYy/Listeners', 11:19:29 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 11:19:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:19:29 INFO - 'USER': 'cltbld', 11:19:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:19:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:19:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:19:29 INFO - 'XPC_FLAGS': '0x0', 11:19:29 INFO - 'XPC_SERVICE_NAME': '0', 11:19:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:19:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:19:29 INFO - Downloading/unpacking jsonschema 11:19:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:19:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:19:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:19:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:19:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:19:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:19:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:19:31 INFO - Downloading/unpacking functools32 (from jsonschema) 11:19:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:19:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:19:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:19:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:19:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:19:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:19:32 INFO - Downloading functools32-3.2.3-2.tar.gz 11:19:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:19:32 INFO - warning: no files found matching '*.txt' 11:19:32 INFO - no previously-included directories found matching 'build' 11:19:32 INFO - no previously-included directories found matching 'dist' 11:19:32 INFO - no previously-included directories found matching '.git*' 11:19:32 INFO - Installing collected packages: jsonschema, functools32 11:19:32 INFO - Running setup.py install for functools32 11:19:32 INFO - warning: no files found matching '*.txt' 11:19:32 INFO - no previously-included directories found matching 'build' 11:19:32 INFO - no previously-included directories found matching 'dist' 11:19:32 INFO - no previously-included directories found matching '.git*' 11:19:32 INFO - Successfully installed jsonschema functools32 11:19:32 INFO - Cleaning up... 11:19:32 INFO - Return code: 0 11:19:32 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 11:19: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')]} 11:19:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:19:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:19: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')]} 11:19:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:19:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:19:32 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 0x104e53a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e59570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6425a4260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cf7d30>, '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 0x104b1bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104ceeb20>, '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.AfXkI8tNfc/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.XewucawdYy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:19:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 11:19:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 11:19:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AfXkI8tNfc/Render', 11:19:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:19:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:19:32 INFO - 'HOME': '/Users/cltbld', 11:19:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:19:32 INFO - 'LOGNAME': 'cltbld', 11:19:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:19:32 INFO - 'MOZ_NO_REMOTE': '1', 11:19:32 INFO - 'NO_EM_RESTART': '1', 11:19:32 INFO - 'PAGER': '/bin/cat', 11:19:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:19:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:19:32 INFO - 'PWD': '/builds/slave/test', 11:19:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:19:32 INFO - 'SHELL': '/bin/bash', 11:19:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XewucawdYy/Listeners', 11:19:32 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 11:19:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:19:32 INFO - 'USER': 'cltbld', 11:19:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:19:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:19:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:19:32 INFO - 'XPC_FLAGS': '0x0', 11:19:32 INFO - 'XPC_SERVICE_NAME': '0', 11:19:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:19:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:19:32 INFO - Downloading/unpacking flake8 11:19:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:19:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:19:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:19:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:19:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:19:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:19:35 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 11:19:35 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 11:19:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:19:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:19:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:19:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:19:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:19:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:19:35 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 11:19:35 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 11:19:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:19:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:19:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:19:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:19:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:19:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:19:36 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 11:19:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:19:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:19:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:19:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:19:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:19:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:19:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 11:19:36 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 11:19:36 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 11:19:36 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 11:19:36 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 11:19:36 INFO - no previously-included directories found matching 'docs/_build' 11:19:36 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 11:19:36 INFO - Running setup.py install for pep8 11:19:36 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 11:19:36 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 11:19:36 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 11:19:36 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 11:19:36 INFO - no previously-included directories found matching 'docs/_build' 11:19:36 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 11:19:36 INFO - Successfully installed flake8 mccabe pyflakes pep8 11:19:36 INFO - Cleaning up... 11:19:36 INFO - Return code: 0 11:19:36 INFO - Running post-action listener: _resource_record_post_action 11:19:36 INFO - Running post-action listener: _start_resource_monitoring 11:19:36 INFO - Starting resource monitoring. 11:19:36 INFO - ##### 11:19:36 INFO - ##### Finished create-virtualenv step (success) 11:19:36 INFO - ##### 11:19:36 INFO - ##### 11:19:36 INFO - ##### Running install step. 11:19:36 INFO - ##### 11:19:36 INFO - Running pre-action listener: _resource_record_pre_action 11:19:36 INFO - Running main action method: install 11:19:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:19:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:19:37 INFO - Reading from file tmpfile_stdout 11:19:37 INFO - Detecting whether we're running mozinstall >=1.0... 11:19:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:19:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:19:37 INFO - Reading from file tmpfile_stdout 11:19:37 INFO - Output received: 11:19:37 INFO - Usage: mozinstall [options] installer 11:19:37 INFO - Options: 11:19:37 INFO - -h, --help show this help message and exit 11:19:37 INFO - -d DEST, --destination=DEST 11:19:37 INFO - Directory to install application into. [default: 11:19:37 INFO - "/builds/slave/test"] 11:19:37 INFO - --app=APP Application being installed. [default: firefox] 11:19:37 INFO - mkdir: /builds/slave/test/build/application 11:19:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 11:19:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 11:20:10 INFO - Reading from file tmpfile_stdout 11:20:10 INFO - Output received: 11:20:10 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 11:20:10 INFO - Running post-action listener: _resource_record_post_action 11:20:10 INFO - ##### 11:20:10 INFO - ##### Finished install step (success) 11:20:10 INFO - ##### 11:20:10 INFO - ##### 11:20:10 INFO - ##### Running run-tests step. 11:20:10 INFO - ##### 11:20:10 INFO - Running pre-action listener: _resource_record_pre_action 11:20:10 INFO - Running main action method: run_tests 11:20:10 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:20:10 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:20:10 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:20:10 INFO - 2016-05-25 11:20:10.593 screenresolution[1649:8157] starting screenresolution argv=screenresolution get 11:20:10 INFO - 2016-05-25 11:20:10.596 screenresolution[1649:8157] Display 0: 1600x1200x32@60 11:20:10 INFO - 2016-05-25 11:20:10.602 screenresolution[1650:8159] starting screenresolution argv=screenresolution list 11:20:10 INFO - Available Modes on Display 0 11:20:10 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:20:10 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:20:10 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:20:10 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:20:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:20:10 INFO - Intel Iris: 11:20:10 INFO - Chipset Model: Intel Iris 11:20:10 INFO - Type: GPU 11:20:10 INFO - Bus: Built-In 11:20:10 INFO - VRAM (Dynamic, Max): 1536 MB 11:20:10 INFO - Vendor: Intel (0x8086) 11:20:10 INFO - Device ID: 0x0a2e 11:20:10 INFO - Revision ID: 0x0009 11:20:10 INFO - Displays: 11:20:10 INFO - Display: 11:20:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:20:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:20:10 INFO - Display Serial Number: 1600x1200 60 11:20:10 INFO - Main Display: Yes 11:20:10 INFO - Mirror: Off 11:20:10 INFO - Online: Yes 11:20:10 INFO - Rotation: Supported 11:20:10 INFO - Return code: 0 11:20:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:20:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:20:10 INFO - Python 2.7.3 11:20:10 INFO - Return code: 0 11:20:10 INFO - grabbing minidump binary from tooltool 11:20:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:20:10 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 0x104cf7d30>, '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 0x104b1bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104ceeb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:20:10 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 11:20:10 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 11:20:10 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 11:20:10 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:20:10 INFO - Return code: 0 11:20:10 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:20:10 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:20:10 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:20:10 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:20:10 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:20:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 11:20:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 11:20:12 INFO - Return code: 0 11:20:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DCftajzTTwuCAyg-GMzplA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0029', '--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 11:20:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Larch --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/DCftajzTTwuCAyg-GMzplA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0029 --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 11:20:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AfXkI8tNfc/Render', 11:20:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:20:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:20:12 INFO - 'HOME': '/Users/cltbld', 11:20:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:20:12 INFO - 'LOGNAME': 'cltbld', 11:20:12 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:20:12 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:20:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:20:12 INFO - 'MOZ_NO_REMOTE': '1', 11:20:12 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:20:12 INFO - 'NO_EM_RESTART': '1', 11:20:12 INFO - 'PAGER': '/bin/cat', 11:20:12 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:20:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:20:12 INFO - 'PWD': '/builds/slave/test', 11:20:12 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:20:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:20:12 INFO - 'SHELL': '/bin/bash', 11:20:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XewucawdYy/Listeners', 11:20:12 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 11:20:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:20:12 INFO - 'USER': 'cltbld', 11:20:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:20:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:20:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:20:12 INFO - 'XPC_FLAGS': '0x0', 11:20:12 INFO - 'XPC_SERVICE_NAME': '0', 11:20:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:20:12 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DCftajzTTwuCAyg-GMzplA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0029', '--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 11:20:12 INFO - mozversion application_buildid: 20160525091843 11:20:12 INFO - mozversion application_changeset: 2cfafbe60b75550e47fbc04aa87d43ccb856edcf 11:20:12 INFO - mozversion application_display_name: Nightly 11:20:12 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:20:12 INFO - mozversion application_name: Firefox 11:20:12 INFO - mozversion application_remotingname: firefox 11:20:12 INFO - mozversion application_repository: https://hg.mozilla.org/projects/larch 11:20:12 INFO - mozversion application_vendor: Mozilla 11:20:12 INFO - mozversion application_version: 49.0a1 11:20:12 INFO - mozversion platform_buildid: 20160525091843 11:20:12 INFO - mozversion platform_changeset: 2cfafbe60b75550e47fbc04aa87d43ccb856edcf 11:20:12 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/larch 11:20:12 INFO - mozversion platform_version: 49.0a1 11:20:12 INFO - using testdate: 1464200412 11:20:12 INFO - actual date: 1464200412 11:20:12 INFO - starting webserver on 'localhost:49276' 11:20:12 INFO - SUITE-START | Running 2 tests 11:20:12 INFO - TEST-START | dromaeo_css 11:20:12 INFO - operating with platform_type : mac_ 11:20:12 INFO - Initialising browser for dromaeo_css test... 11:20:12 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/tmpZvoRVP/profile http://localhost:49276/getInfo.html) 11:20:15 INFO - PROCESS | 1668 | __metrics Screen width/height:1600/1200 11:20:15 INFO - PROCESS | 1668 | colorDepth:24 11:20:15 INFO - PROCESS | 1668 | Browser inner width/height: 1024/689 11:20:15 INFO - PROCESS | 1668 | __metrics 11:20:15 ERROR - PROCESS | 1668 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 11:20:16 INFO - TEST-INFO | 1668: exit 0 11:20:16 INFO - Browser initialized. 11:20:16 INFO - Running cycle 1/1 for dromaeo_css test... 11:20:16 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/tmpZvoRVP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:20:28 INFO - PROCESS | 1672 | RSS: Main: 252370944 11:20:28 INFO - PROCESS | 1672 | 11:21:17 INFO - PROCESS | 1672 | 1464200477897 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 11:23:28 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49276/tests/dromaeo/cssquery-ext.html) 11:23:30 INFO - PROCESS | 1672 | RSS: Main: 264732672 11:23:30 INFO - PROCESS | 1672 | 11:26:32 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-ext.html (next: http://localhost:49276/tests/dromaeo/cssquery-jquery.html) 11:26:33 INFO - PROCESS | 1672 | RSS: Main: 276000768 11:26:33 INFO - PROCESS | 1672 | 11:29:34 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49276/tests/dromaeo/cssquery-mootools.html) 11:29:35 INFO - PROCESS | 1672 | RSS: Main: 277860352 11:29:35 INFO - PROCESS | 1672 | 11:32:37 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49276/tests/dromaeo/cssquery-prototype.html) 11:32:37 INFO - PROCESS | 1672 | RSS: Main: 279687168 11:32:37 INFO - PROCESS | 1672 | 11:35:40 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49276/tests/dromaeo/cssquery-yui.html) 11:35:41 INFO - PROCESS | 1672 | RSS: Main: 282529792 11:35:41 INFO - PROCESS | 1672 | 11:38:44 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-yui.html (next: http://localhost:49276/tests/dromaeo/cssquery-dojo.html) 11:38:44 INFO - PROCESS | 1672 | RSS: Main: 283516928 11:38:44 INFO - PROCESS | 1672 | 11:38:44 INFO - PROCESS | 1672 | __start_tp_report 11:38:44 INFO - PROCESS | 1672 | _x_x_mozilla_page_load 11:38:44 INFO - PROCESS | 1672 | _x_x_mozilla_page_load_details 11:38:44 INFO - PROCESS | 1672 | |i|pagename|runs| 11:38:44 INFO - PROCESS | 1672 | |0;dojo.html;3139.042592009437;3440.1399650087487;3459.9827000864993;3461.532693086434;3478.339115568042;5708.686022268779;5758.712064396785;5838.737256823442;5846.824595262138;5856.443637854397;5582.91625625616;5692.146178073291;5761.251037365148;5813.2152159458465;5861.325947516037;5155.226715992593;5188.896222075557;5190.377154741436;5294.52349288564;5329.600279978998;5227.895442091156;5323.680579165262;5324.893502129955;5376.892462150755;5398.703071331075;5109.795608175668;5160.458151894035;5174.637775355726;5240.816571420001;5262.0265250928505;5195.870103247411;5212.374515058179;5214.191800270926;5235.214717792323;5255.658382205144;5161.458046905096;5245.763940622643;5279.52484276417;5284.497972692588;5286.1013627683005;4753.9762301188275;5712.771489140429;5722.97138514309;5737.770489180428;5779;4893.898872753638;5975.820725378246;6238.563300568962;6246.0630905364105;6264;6145.754169833202;6206.2862770781485;6209.161763162006;6226.62640241587;6232.626042437469;5723.08430651098;5800.564957628195;5856.824295271149;5914.497267732291;5921.171036054956;6338.714757835908;6379.202599675041;6388.073729309224;6388.2653494847755;6399.520035997319;5329.934013197376;6068.75724971005;6084.6349219046115;6092.8781424372155;6099.878002440015;6102.489251635809;6224.688765561704;6226.750929962841;6234.563580549318;6256.718447669866;6041.305249896318;6069.575129740876;6069.666168360783;6125.448709616156;6158.261008678897;6034.24571928509;6072.392760723892;6078.665673387918;6149.631022138686;6156.199694039746;4890.437599676083;4939.654224204343;4939.802407903644;4942.20924652054;5006.4743201964;4415.4259946206785;4429.889252768642;4445.377647129404;4465.062336909221;4477.171723231213;5672.8865422691315;5823.330317013598;5893.5579831512805;5939.613925094855;5955.970220148872;5228.6339956203465;6136.304638670889;6146.139540464339;6154;6185.8144255672405;5864;5919.1713160157615;5934.6142500737315;5940.61386009908;5945.64326140433;4565.726056436624;4750.928736068961;5405.108157154104;5406.270153529302;5416.647917885325;4671.929921051187;5976;6106.908396374058;6362.95011323145;6449.161608990801;5239;5928.614640048383;6218.129461875251;6222.315545289924;6225.470834979077;6180.567360284737;6183.598066125687;6210.596311239755;6230.190075290183;6380;6027.156198132177;6054.939450605438;6080.72636731357;6106.9083963739695;6129;6065.2418447694035;6119.938800612115;6124.8775024500155;6125.10405399348;6129.662868542273;5911.881762364643;6002.519798416029;6043.365446628217;6052;6053.9091913622215;6050.697465126814;6058.939410605837;6062.666553339609;6079.513638908965;6123.632582045091;5908.734106965283;6032.4570788629235;6067.484263837623;6094.994325936171;6096.908546371719;5991.940080599138;6013.278406591237;6064.545159113172;6066.423689749467;6090;5981.820545383645;6011.819645410645;6040.1543783871175;6043.395660434097;6045.335013148638 11:38:44 INFO - PROCESS | 1672 | |1;ext.html;479.2858640625469;514.2183880501638;526.1213772999092;547.5510081732981;551.678729442984;32322.515162272557;40438.79780601097;40459.583914562994;40519.189616207696;40581.797091014574;41880.37179442311;42333.36499952496;42355;42546.36180457295;42554.574454255446;9409.811803763921;9579.568919398644;9612.90387096127;9625.470599117045;9638.084381983736;9525.857112143323;9579;9588.137067663909;9594.568244429018;9639.614415423375;9567.521623918776;9588.280878934072;9605.66380176694;9609.990950950154;9679.90320096797;9522.0954009369;9528.90471095294;9572.13850753432;9575.329726919119;9585.808283834354;9430.49112142054;9502.097300756417;9519.143277105093;9529.666461673809;9539.809203815954;39431.21137577233;39672;39676.60323396758;39835.800820996;39856.601433985576;3088.073577926613;3126.8592913318957;3303.4219011672863;3382.0868365541164;3457.2566898117025;28741.28146796347;28789.28026799347;29200.707992920223;30014.549781753292;30024.849875750482;2745.2999485131163;2772.7781777457753;2827.6748173960054;2860.4565132624734;2863.871125799344;443120;444383;518153;521759;522435;25804;29109.854450728035;33842;33944.79358841667;57417;34699.82650086733;35137;35274.64725352714;35294;35322.823385882904;14083.943704222223;14223.217723025125;14224.21766802815;14263.358148883326;14272.857271427152;12315.322657253937;12392.62822115338;12400.62798116058;12415.751684966432;12555.686107847378;7727.536347819148;7792.181820908836;7807.141214466405;7837.373010159174;7873.606319683993;2740.2464322311525;3260.918477038093;3265.9836700816345;3283.83580820958;3286.244163842282;927.3137877970254;1015.28422462164;1080.2762149359949;1085.9294145880492;1101.983470247947;28968.710312897023;29063;29326.413471730873;29534;29706.257343566584;1040.8178568750438;1098.0758806240449;1127.9210455268217;1160.048760016778;1178.6169494914186;11495.88504114965;13000.219986800823;13946.58160255194;14936.477223297132;15169.772453413208;663203;674110;674711;679150;684451;54483;54565;54817.62955926134;54881.9999999992;55065.17402238889;1401.2083173007218;1425.330094855437;1437.216716889298;1438.841727409984;1450.514077783933;24818.627720583845;26371;26398.07606733755;28720.712792871804;29302.41395172127;49462.50537494579;49922.75038624929;50009.749951250014;50072.74963625159;50266.748666257896;568594.0992065258;570927;595976;604911;612766;25942.35144121412;26014.86992565025;26292.21123366302;26345.15583909108;26350;44322.556774433135;45460.31809522926;45620.54379456163;48681.269780954026;48742;34691.13272168115;37456.81271593733;37878;37982.240355193295;38706.61293387143;32805.85179518705;33195.83402082974;35253.29493410169;36645.450318244715;37482.812585936896;35840.46239306465;36390.454143188406;36621;37249.627503724616;37377;32511.34977300488;33074;33303.00090997274;33405.16487087842;33499;18031.81968180354;24107.63838542388;24187.15344962918;24817.875910621053;25108.62337064982 11:38:44 INFO - PROCESS | 1672 | |2;jquery.html;354.6985062696708;388.24486374002566;388.34757607219876;402.09327965437933;411.5144129926687;18875.905620471895;21171.576568468623;21276.574468510622;21986;22007.119715211393;17970.730439043393;20270.39188824334;20790.688139677917;20870.791292087073;21256.043478043488;4556.3621093047;5465.535429488498;5951.940480595182;5990.670513121777;5991.2510936132985;5939.970300148493;5946.197263369455;5980.671063091529;6000.579959402844;6021.9698901505435;5584.720763961806;5810.244668193129;5820.068788993762;5857.26784151981;5875.1187321901625;5632.04255276604;5765.654060756368;5834.241548598698;5866.061430171152;5869;4989.052080104769;5071;5074.746262686888;5090.898182036375;5121.539061484447;13332.733345333136;23377.415564610852;23713.170039048724;24850.627240591406;25931.611025834627;1893.7727472703225;2025.7569091708942;2314.3866875278063;2371.6205407134776;2372.6441033845063;9918.206543476577;10029.04724051214;10064.043915827984;10151.695449136538;14493.492727754478;1692.8222536633723;1721.9483415497555;1747.8426941575192;1782.4563508130016;1786.9821301787076;96736.51631741866;119303;125747.37126314401;126520.73479265273;126918;11592;11592.6522204334;11629.127815413878;11692.590759323382;11764.411779410995;14395.208263545395;14561.344739486749;14564.49024284145;14605.269736513344;14653.34059967304;8475.915240847637;8523.616437260338;8545;8560.315174786003;8660.004099528634;7015.7722398580045;8504.277136443348;8546.31629469641;8565.871511927326;8650.394472386999;4489.281714925596;4501.662375321864;4526.343680166357;4550.47669518003;4560.931586026212;1918.9040547972545;1969.9901500492692;1974.5063734066484;1985.602879424121;1990.5919286546223;891.6299735609649;911.4303560274828;922.4649703172144;931.9580618872167;954.1269738189618;18870.905645471685;19975.800241997684;20133.395998119788;20329.288474903307;20386.388408347775;964.4406244378243;981.1660088924357;985.4579981010415;993.4982833668951;1040.0535512683423;10536.367817930948;10775.299605525615;10775.299605525615;10789.46052697362;10807.459627018618;588299;594177;597957;603509;608307;103231.96768032224;137966;140262.99999999796;141312;142759;857.8884744983112;881.3389957531723;918.320442872283;958.2812890332251;993.557866749304;14558.344874480463;17744.3789467368;17792.733109003635;18026;18235.54411139679;41803.7909810449;48563.75718121387;48596;49756;50356.74821625868;72003;75815.62092189504;75869.62065189639;76489.23510764822;78898;7680.385569154566;7756.961215193888;7949.244821741925;8125.3905957052275;8227.629756661085;10208.897911021091;12121.333326667118;12201.450934708137;12456.626301210978;12529.373531323216;10045.694059772182;10493.422861742678;10528.420936848546;10601.363918164934;10633.627823026158;9883.50582470888;10576.735581610214;10717.571297148026;10754.838677419688;10809.513571889127;9597.952010240184;10761;10948.233623646269;11174.888251117385;11207.991280784769;7665.348445382205;7794.844103118019;7851.254130857559;7916.881246781418;8217.465864718773;7888.881666775118;8872.24585910205;10460.790784184426;10673.41296228715;10761.408122553335 11:38:44 INFO - PROCESS | 1672 | |3;mootools.html;1449.7970284160217;1551.6198531359819;1781.2874850059975;1917.5397904502915;1955.3254127326068;8122.59387030649;8620.525871077096;8621;9103.453792772438;9142.680006199793;7449.553026818381;8700.608472618731;8742.213200811926;8883.64465421384;8916.420432671888;2376.0495801679335;2409.3976505873534;2410.4094496848297;2416.734159242491;2418.0327868852423;2371.739108698042;2388.8208384371155;2389.2712722619594;2406.8194885383577;2418.673479080328;1928.4600311912616;2255.334676270504;2278.54429114177;2290.919817806377;2299;1919;2293.5412917416584;2367.2188177901253;2382.654515095319;2386.451116243265;2283.4748007958265;2378.774016468433;2390.952180956371;2401.3396316013063;2402.1112188490197;7567.978322926389;8824.205821476035;8890.644374225023;8892.377533572588;8914.910850891472;5221.295125158131;5366.409694933554;5426.864328391822;5437.966786310589;5453.509184173444;2757.3244555084134;3486.6687664671817;3554.129238336624;3576.8926932192076;3618.457231415309;874.3704532736419;894.8523493623544;1020.7397113736024;1021.5913634546166;1025.9025392587691;10101.393916364968;10546.578136874516;11684.12369072323;11763.294202347886;11800.291982481078;4691.753636669153;4775.307580400892;4844.4186697596515;4853.296272040534;4886.828960986349;8677.783055423664;9624.422534647943;9901.7029489114;9961.950190249003;9989.450580218012;4814.86850590111;5213.348331458567;5315.229291752751;5399.973000134974;5404.78380864761;4395.604395604411;4724.102420540086;4769.856904292877;4861.368022157097;4882.12121818069;3951.2295102454746;3974.503187101612;4065.57311482296;4081.2449696806184;4145.419641250227;1211.025124774565;1246.1712960881093;1302.4074046308906;1323.1862404621088;1325.3638253638114;906.3383729877227;908.9727308180766;915.4873270968279;938.1134827588;943.565959658551;5069.771860266297;5746.109353050284;5821.94178058214;5856.0630299151935;5864.44287792659;441.3137571076942;444.9510553839075;450.7070404237272;957.4590356448584;1010.5402042070987;3889.7860617665733;3992.341263691458;4012.879613611596;4059.91880162401;4061.248668996245;10526.157907367393;10853.077488413415;10931.836022459669;10963.671089867317;10973.3964631946;10823.13414926804;10853.565857365775;10860.837087443695;10878.619248326271;10893.019628233496;8081.717139900193;8252.669893204205;8287.792805179919;8365.205305495969;8366.163383661586;5767.653940763567;5796.246487956539;5861.35525092248;5867.119932010062;5940.316863560746;9934.900650993397;10105;10141.898581014095;10146.391216527032;10147.797044059225;8863.734087977113;9911.942652902057;9984.60061597557;10311.948440257751;10331.793364132825;6567.671616419065;6967.965160174167;7082.504224704221;7147.1780745214965;7215.278472152742;8411.032731235742;8487.872681909652;8523.403361764616;8535.743927682179;8565.27195188416;7440.776776696708;7509.399248060251;7533.171351151478;7630.504017238862;7685.193054729173;7383;7405.518641288299;7438.702451901863;7485.625718714152;7534.35957943559;6961.164660240804;7006.614636195059;7033.964830176021;7044.36600705939;7054.259302773135;4508.661850361171;4644.883877903079;4787.712737235777;4861.732604706775;4979.601631869514;4868.123737727243;4890.682105663121;4930.383702037246;5012;5145.819896303611 11:38:44 INFO - PROCESS | 1672 | |4;prototype.html;272.63194687172313;317.14687490650147;319.70746766708464;322.9870805167795;326.1536313267071;5573.777048918044;5582.748776305076;5622.979569607724;5653.1237658162945;5676.716164191789;4570;5250.501202385777;5452.781888724447;5541.889162216763;5557.444255574441;3955.4660120883746;4091.8158682859344;4188.958110418903;4189.937150942723;4198.265303571863;3618.6019537850816;3869.922601547967;4140.60664236897;4177.665786737069;4183.790810459464;3420.5211270422037;4132.772697501636;4137.648299894513;4151;4157.376393540978;3133.944404205904;3543.9114022149392;3633.4731463937583;4121.9175616487655;4147.523034850986;3194.37709646619;3545.94681079784;3562.664000999625;3567.9643203567894;3570.0896271450615;4585.679002469828;5311.150215965427;5339.439358867341;5358.785648574052;5365.302510673588;3464.9653503464897;4313.892152696177;4729.408823897013;4731.5268473152755;4746.952530474686;5106.923396149061;5721.284839395075;5732.254806875079;5750.741216645261;5772.278465191851;3160.909486227271;3870.3420418528917;3875.4380614810975;3901.648851603341;3908.8045597720284;5248.895022099575;5714.885702285931;5973.7455134421825;6144.324124346318;6197.535184861108;3105.54969529417;3817.522809648794;3891.871357306406;3896.883093507199;3955.228730397545;3503.5270238517473;3513.8594456221977;3957.841686332572;3975.3043217436834;3981.343078392033;4121.4848143982;4610.308453731967;4650.790714417859;4666.696664716783;4672.275797251432;4876.731779752076;5520.972395137998;5771.59598828078;5830.883382332329;5836.490309167014;1148.8908553687386;1162.3607016141257;1340.1289162044554;1369.6849724563208;1379.8754015477355;676.3675962974635;685.3934268172703;736.9005184300158;753.5666991479922;888.8888888888889;4431.40176076232;5161.329027226437;5324;5388.54197393226;5426.321709786277;5204.245384419238;5577.828655982289;5598.776048957996;5917.260342457193;5968.1644569760265;5159.406668233126;5279.287296214962;5343.438938911436;5372.206452644603;5388.871395798716;2582.398912674154;3048.329367539138;3122.172624254574;3148.174059045748;3151.4327421064427;6140.478059364915;6149.661768602769;6197.380261973857;6203.960059570778;6210.130581718563;5427;5727.455891690282;6155.84610384744;6248;6270.090836828635;5618.325800903999;5985.371535988746;6156.476699480594;6368.5860419072615;6373.080555458359;5636;6050.21347224858;6055.364186760326;6063.666498342457;6064.363241859542;5514.972425137848;5709.771609135588;5947.821565353046;5956.463918247378;5973.342932277359;5760.395158508296;5780.364159942487;6249.218847644044;6277.74889004453;6378.904316435164;3383.424817780934;3403.5745531808525;3945.408188771707;3954.387070004154;3955.8022098895517;6063.514918806573;6066.059760736916;6070.696465176812;6072.514198863992;6074.3925607438905;4976.626752993612;5169.715665638426;5270.3661864822025;5432.360318586636;5888.234529511136;4642.837500687459;5572.164175373727;5832.679202643895;5850.853728656647;5906;4795.808167673394;5098.439171691037;5142.948570514247;5427.728613569227;5760.164776107357;4890.877728056685;5189.455107213839;5205.843824685266;5379.220013098157;5816.272965879265;4674.368960190467;5297.841064768063;5694.487496125369;5751.849630073918;5815.5929084963645 11:38:44 INFO - PROCESS | 1672 | |5;yui.html;636.8131568375582;719.647372787334;729.1614643160364;729.6436392594064;769.303780079028;14845;15201.619959500991;15245.466408675698;15362.001469904448;15366.385344586217;12944.482220711183;14149.646258843533;14156.221407822566;14159.29203539824;14223.075500092487;548.3529435266385;753.5629334985695;787.3307688464816;899.5457294066488;908.7955210077713;883.5493898111945;890.8930928288614;891.0287786312917;897.161154320709;902.2466240689032;804.9718259860906;935.96724114656;954.1746389373177;954.7088138117871;957.1911734584265;916.8670542771295;947.971560853172;952.2096659772373;953.2516974175239;959.0697023886853;691.9654017299115;702.9578225306498;707.971681132754;710.0556260173956;712.2521352579822;13402.46390144393;13546.59360219195;13598.252096134709;13642.454301827915;13668.248246346448;12605.180663256859;12703.364831758376;12712.809307860389;12746.490140394373;13036;10087.798244035079;10144.239182061376;10174.33866798663;10379;10506.579736810594;3442.2599141184764;3464.9653503464897;3539.4159963605975;3654.4152935530187;3699.5930447650735;65896.69361572369;83901;84171.3165736688;86987;87356;8803.911960880308;9103.317251206186;9442.339036267533;9631.181349585364;10422.426766527913;20278.493037674176;20281;20491.69262461064;20605.278815241396;20661.690074648894;7141.428685705131;7261.273872612696;7274.090738657668;7309.89035164473;7491.475596708287;3580.4220960908106;4002.6397624213378;4092.2020206059533;4092.7544347339253;4096.09885825118;3521.841517131735;3603.9819800900827;3662.322470342995;3667.1932174921476;3668.853245870135;635.3646353646353;636.9363063693594;640.1837656987285;653.3466533466534;672.5661948043485;508.6896992834368;509.7571477355382;560.9859753506113;596.3212025316387;608.4371955940737;7214.96392518034;7434.145073316529;7459.813504662318;7518.398528117737;7533.020707308125;521.9712915789668;572.9257641921397;575.65748379714;593.4955288005161;599.5263741644139;3019.456497830368;3665.8533658653355;3856.8071596420627;3871.6515513603913;3898.8830335089992;83017;85099;88222;88582;90193;74239;76340.61829690928;76602.61698691471;77813.61093194498;80314;56133.87732245332;68428.6578567104;69315.3068469319;69870.60258794898;74721.6263918677;6906.723731050903;8185.631646576037;8234.300084492885;8382.605569094158;8494.447860889022;44706.77646611746;44876;44942;45324.54675453335;45658;63692;64296.357036429035;64419.677901610194;64667.67666161639;65167.34832651613;10785.568577256821;10799.56801727922;10836.241463097824;11080.445977701243;11165.38590377537;10988.395638239974;11329.546818127183;11495.48270327854;11967.521299148284;11974.46114924813;11438.313701177956;11454.828177577176;11481.425928703364;11716.70708232301;11773.705657358292;11300.94349528247;11330.716732081764;11502.022328102204;11869.287842729464;11960.700982475508;10997.010269075821;11024.173187010845;11252.437378131224;11394.772104557696;12208.633740987785;9558.808823823623;9568.186704129948;9595.328327017041;9741.464219467714;9868.309218354647;8866.785220703614;9557.948625651034;9654;9713.125818676353;9779.315447918578 11:38:44 INFO - PROCESS | 1672 | __end_tp_report 11:38:44 INFO - PROCESS | 1672 | __start_cc_report 11:38:44 INFO - PROCESS | 1672 | _x_x_mozilla_cycle_collect,414 11:38:44 INFO - PROCESS | 1672 | __end_cc_report 11:38:44 INFO - PROCESS | 1672 | __startTimestamp1464201524336__endTimestamp 11:38:44 INFO - PROCESS | 1672 | 11:38:44 INFO - PROCESS | 1672 | ------- Summary: start ------- 11:38:44 INFO - PROCESS | 1672 | Number of tests: 6 11:38:44 INFO - PROCESS | 1672 | 11:38:44 INFO - PROCESS | 1672 | [#0] dojo.html Cycles:165 Average:5701.42 Median:5955.97 stddev:634.14 (10.6%) stddev-sans-first:603.39 11:38:44 INFO - PROCESS | 1672 | Values: 3139.0 3440.1 3460.0 3461.5 3478.3 5708.7 5758.7 5838.7 5846.8 5856.4 5582.9 5692.1 5761.3 5813.2 5861.3 5155.2 5188.9 5190.4 5294.5 5329.6 5227.9 5323.7 5324.9 5376.9 5398.7 5109.8 5160.5 5174.6 5240.8 5262.0 5195.9 5212.4 5214.2 5235.2 5255.7 5161.5 5245.8 5279.5 5284.5 5286.1 4754.0 5712.8 5723.0 5737.8 5779.0 4893.9 5975.8 6238.6 6246.1 6264.0 6145.8 6206.3 6209.2 6226.6 6232.6 5723.1 5800.6 5856.8 5914.5 5921.2 6338.7 6379.2 6388.1 6388.3 6399.5 5329.9 6068.8 6084.6 6092.9 6099.9 6102.5 6224.7 6226.8 6234.6 6256.7 6041.3 6069.6 6069.7 6125.4 6158.3 6034.2 6072.4 6078.7 6149.6 6156.2 4890.4 4939.7 4939.8 4942.2 5006.5 4415.4 4429.9 4445.4 4465.1 4477.2 5672.9 5823.3 5893.6 5939.6 5956.0 5228.6 6136.3 6146.1 6154.0 6185.8 5864.0 5919.2 5934.6 5940.6 5945.6 4565.7 4750.9 5405.1 5406.3 5416.6 4671.9 5976.0 6106.9 6363.0 6449.2 5239.0 5928.6 6218.1 6222.3 6225.5 6180.6 6183.6 6210.6 6230.2 6380.0 6027.2 6054.9 6080.7 6106.9 6129.0 6065.2 6119.9 6124.9 6125.1 6129.7 5911.9 6002.5 6043.4 6052.0 6053.9 6050.7 6058.9 6062.7 6079.5 6123.6 5908.7 6032.5 6067.5 6095.0 6096.9 5991.9 6013.3 6064.5 6066.4 6090.0 5981.8 6011.8 6040.2 6043.4 6045.3 11:38:44 INFO - PROCESS | 1672 | 11:38:44 INFO - PROCESS | 1672 | [#1] ext.html Cycles:180 Average:69053.43 Median:36633.23 stddev:158548.45 (432.8%) stddev-sans-first:158909.62 11:38:44 INFO - PROCESS | 1672 | Values: 479.3 514.2 526.1 547.6 551.7 32322.5 40438.8 40459.6 40519.2 40581.8 41880.4 42333.4 42355.0 42546.4 42554.6 9409.8 9579.6 9612.9 9625.5 9638.1 9525.9 9579.0 9588.1 9594.6 9639.6 9567.5 9588.3 9605.7 9610.0 9679.9 9522.1 9528.9 9572.1 9575.3 9585.8 9430.5 9502.1 9519.1 9529.7 9539.8 39431.2 39672.0 39676.6 39835.8 39856.6 3088.1 3126.9 3303.4 3382.1 3457.3 28741.3 28789.3 29200.7 30014.5 30024.8 2745.3 2772.8 2827.7 2860.5 2863.9 443120.0 444383.0 518153.0 521759.0 522435.0 25804.0 29109.9 33842.0 33944.8 57417.0 34699.8 35137.0 35274.6 35294.0 35322.8 14083.9 14223.2 14224.2 14263.4 14272.9 12315.3 12392.6 12400.6 12415.8 12555.7 7727.5 7792.2 7807.1 7837.4 7873.6 2740.2 3260.9 3266.0 3283.8 3286.2 927.3 1015.3 1080.3 1085.9 1102.0 28968.7 29063.0 29326.4 29534.0 29706.3 1040.8 1098.1 1127.9 1160.0 1178.6 11495.9 13000.2 13946.6 14936.5 15169.8 663203.0 674110.0 674711.0 679150.0 684451.0 54483.0 54565.0 54817.6 54882.0 55065.2 1401.2 1425.3 1437.2 1438.8 1450.5 24818.6 26371.0 26398.1 28720.7 29302.4 49462.5 49922.8 50009.7 50072.7 50266.7 568594.1 570927.0 595976.0 604911.0 612766.0 25942.4 26014.9 26292.2 26345.2 26350.0 44322.6 45460.3 45620.5 48681.3 48742.0 34691.1 37456.8 37878.0 37982.2 38706.6 32805.9 33195.8 35253.3 36645.5 37482.8 35840.5 36390.5 36621.0 37249.6 37377.0 32511.3 33074.0 33303.0 33405.2 33499.0 18031.8 24107.6 24187.2 24817.9 25108.6 11:38:44 INFO - PROCESS | 1672 | 11:38:44 INFO - PROCESS | 1672 | [#2] jquery.html Cycles:180 Average:34743.56 Median:371.47 stddev:100100.83 (26947.1%) stddev-sans-first:100348.34 11:38:44 INFO - PROCESS | 1672 | Values: 354.7 388.2 388.3 402.1 411.5 18875.9 21171.6 21276.6 21986.0 22007.1 17970.7 20270.4 20790.7 20870.8 21256.0 4556.4 5465.5 5951.9 5990.7 5991.3 5940.0 5946.2 5980.7 6000.6 6022.0 5584.7 5810.2 5820.1 5857.3 5875.1 5632.0 5765.7 5834.2 5866.1 5869.0 4989.1 5071.0 5074.7 5090.9 5121.5 13332.7 23377.4 23713.2 24850.6 25931.6 1893.8 2025.8 2314.4 2371.6 2372.6 9918.2 10029.0 10064.0 10151.7 14493.5 1692.8 1721.9 1747.8 1782.5 1787.0 96736.5 119303.0 125747.4 126520.7 126918.0 11592.0 11592.7 11629.1 11692.6 11764.4 14395.2 14561.3 14564.5 14605.3 14653.3 8475.9 8523.6 8545.0 8560.3 8660.0 7015.8 8504.3 8546.3 8565.9 8650.4 4489.3 4501.7 4526.3 4550.5 4560.9 1918.9 1970.0 1974.5 1985.6 1990.6 891.6 911.4 922.5 932.0 954.1 18870.9 19975.8 20133.4 20329.3 20386.4 964.4 981.2 985.5 993.5 1040.1 10536.4 10775.3 10775.3 10789.5 10807.5 588299.0 594177.0 597957.0 603509.0 608307.0 103232.0 137966.0 140263.0 141312.0 142759.0 857.9 881.3 918.3 958.3 993.6 14558.3 17744.4 17792.7 18026.0 18235.5 41803.8 48563.8 48596.0 49756.0 50356.7 72003.0 75815.6 75869.6 76489.2 78898.0 7680.4 7757.0 7949.2 8125.4 8227.6 10208.9 12121.3 12201.5 12456.6 12529.4 10045.7 10493.4 10528.4 10601.4 10633.6 9883.5 10576.7 10717.6 10754.8 10809.5 9598.0 10761.0 10948.2 11174.9 11208.0 7665.3 7794.8 7851.3 7916.9 8217.5 7888.9 8872.2 10460.8 10673.4 10761.4 11:38:44 INFO - PROCESS | 1672 | 11:38:44 INFO - PROCESS | 1672 | [#3] mootools.html Cycles:180 Average:5673.08 Median:4864.93 stddev:3193.13 (65.6%) stddev-sans-first:3186.32 11:38:44 INFO - PROCESS | 1672 | Values: 1449.8 1551.6 1781.3 1917.5 1955.3 8122.6 8620.5 8621.0 9103.5 9142.7 7449.6 8700.6 8742.2 8883.6 8916.4 2376.0 2409.4 2410.4 2416.7 2418.0 2371.7 2388.8 2389.3 2406.8 2418.7 1928.5 2255.3 2278.5 2290.9 2299.0 1919.0 2293.5 2367.2 2382.7 2386.5 2283.5 2378.8 2391.0 2401.3 2402.1 7568.0 8824.2 8890.6 8892.4 8914.9 5221.3 5366.4 5426.9 5438.0 5453.5 2757.3 3486.7 3554.1 3576.9 3618.5 874.4 894.9 1020.7 1021.6 1025.9 10101.4 10546.6 11684.1 11763.3 11800.3 4691.8 4775.3 4844.4 4853.3 4886.8 8677.8 9624.4 9901.7 9962.0 9989.5 4814.9 5213.3 5315.2 5400.0 5404.8 4395.6 4724.1 4769.9 4861.4 4882.1 3951.2 3974.5 4065.6 4081.2 4145.4 1211.0 1246.2 1302.4 1323.2 1325.4 906.3 909.0 915.5 938.1 943.6 5069.8 5746.1 5821.9 5856.1 5864.4 441.3 445.0 450.7 957.5 1010.5 3889.8 3992.3 4012.9 4059.9 4061.2 10526.2 10853.1 10931.8 10963.7 10973.4 10823.1 10853.6 10860.8 10878.6 10893.0 8081.7 8252.7 8287.8 8365.2 8366.2 5767.7 5796.2 5861.4 5867.1 5940.3 9934.9 10105.0 10141.9 10146.4 10147.8 8863.7 9911.9 9984.6 10311.9 10331.8 6567.7 6968.0 7082.5 7147.2 7215.3 8411.0 8487.9 8523.4 8535.7 8565.3 7440.8 7509.4 7533.2 7630.5 7685.2 7383.0 7405.5 7438.7 7485.6 7534.4 6961.2 7006.6 7034.0 7044.4 7054.3 4508.7 4644.9 4787.7 4861.7 4979.6 4868.1 4890.7 4930.4 5012.0 5145.8 11:38:44 INFO - PROCESS | 1672 | 11:38:44 INFO - PROCESS | 1672 | [#4] prototype.html Cycles:180 Average:4637.33 Median:5274.83 stddev:1496.51 (28.4%) stddev-sans-first:1464.41 11:38:44 INFO - PROCESS | 1672 | Values: 272.6 317.1 319.7 323.0 326.2 5573.8 5582.7 5623.0 5653.1 5676.7 4570.0 5250.5 5452.8 5541.9 5557.4 3955.5 4091.8 4189.0 4189.9 4198.3 3618.6 3869.9 4140.6 4177.7 4183.8 3420.5 4132.8 4137.6 4151.0 4157.4 3133.9 3543.9 3633.5 4121.9 4147.5 3194.4 3545.9 3562.7 3568.0 3570.1 4585.7 5311.2 5339.4 5358.8 5365.3 3465.0 4313.9 4729.4 4731.5 4747.0 5106.9 5721.3 5732.3 5750.7 5772.3 3160.9 3870.3 3875.4 3901.6 3908.8 5248.9 5714.9 5973.7 6144.3 6197.5 3105.5 3817.5 3891.9 3896.9 3955.2 3503.5 3513.9 3957.8 3975.3 3981.3 4121.5 4610.3 4650.8 4666.7 4672.3 4876.7 5521.0 5771.6 5830.9 5836.5 1148.9 1162.4 1340.1 1369.7 1379.9 676.4 685.4 736.9 753.6 888.9 4431.4 5161.3 5324.0 5388.5 5426.3 5204.2 5577.8 5598.8 5917.3 5968.2 5159.4 5279.3 5343.4 5372.2 5388.9 2582.4 3048.3 3122.2 3148.2 3151.4 6140.5 6149.7 6197.4 6204.0 6210.1 5427.0 5727.5 6155.8 6248.0 6270.1 5618.3 5985.4 6156.5 6368.6 6373.1 5636.0 6050.2 6055.4 6063.7 6064.4 5515.0 5709.8 5947.8 5956.5 5973.3 5760.4 5780.4 6249.2 6277.7 6378.9 3383.4 3403.6 3945.4 3954.4 3955.8 6063.5 6066.1 6070.7 6072.5 6074.4 4976.6 5169.7 5270.4 5432.4 5888.2 4642.8 5572.2 5832.7 5850.9 5906.0 4795.8 5098.4 5142.9 5427.7 5760.2 4890.9 5189.5 5205.8 5379.2 5816.3 4674.4 5297.8 5694.5 5751.8 5815.6 11:38:44 INFO - PROCESS | 1672 | 11:38:44 INFO - PROCESS | 1672 | [#5] yui.html Cycles:180 Average:17755.30 Median:597.92 stddev:24885.56 (4162.0%) stddev-sans-first:24922.17 11:38:44 INFO - PROCESS | 1672 | Values: 636.8 719.6 729.2 729.6 769.3 14845.0 15201.6 15245.5 15362.0 15366.4 12944.5 14149.6 14156.2 14159.3 14223.1 548.4 753.6 787.3 899.5 908.8 883.5 890.9 891.0 897.2 902.2 805.0 936.0 954.2 954.7 957.2 916.9 948.0 952.2 953.3 959.1 692.0 703.0 708.0 710.1 712.3 13402.5 13546.6 13598.3 13642.5 13668.2 12605.2 12703.4 12712.8 12746.5 13036.0 10087.8 10144.2 10174.3 10379.0 10506.6 3442.3 3465.0 3539.4 3654.4 3699.6 65896.7 83901.0 84171.3 86987.0 87356.0 8803.9 9103.3 9442.3 9631.2 10422.4 20278.5 20281.0 20491.7 20605.3 20661.7 7141.4 7261.3 7274.1 7309.9 7491.5 3580.4 4002.6 4092.2 4092.8 4096.1 3521.8 3604.0 3662.3 3667.2 3668.9 635.4 636.9 640.2 653.3 672.6 508.7 509.8 561.0 596.3 608.4 7215.0 7434.1 7459.8 7518.4 7533.0 522.0 572.9 575.7 593.5 599.5 3019.5 3665.9 3856.8 3871.7 3898.9 83017.0 85099.0 88222.0 88582.0 90193.0 74239.0 76340.6 76602.6 77813.6 80314.0 56133.9 68428.7 69315.3 69870.6 74721.6 6906.7 8185.6 8234.3 8382.6 8494.4 44706.8 44876.0 44942.0 45324.5 45658.0 63692.0 64296.4 64419.7 64667.7 65167.3 10785.6 10799.6 10836.2 11080.4 11165.4 10988.4 11329.5 11495.5 11967.5 11974.5 11438.3 11454.8 11481.4 11716.7 11773.7 11300.9 11330.7 11502.0 11869.3 11960.7 10997.0 11024.2 11252.4 11394.8 12208.6 9558.8 9568.2 9595.3 9741.5 9868.3 8866.8 9557.9 9654.0 9713.1 9779.3 11:38:44 INFO - PROCESS | 1672 | -------- Summary: end -------- 11:38:44 INFO - PROCESS | 1672 | 11:38:44 INFO - TEST-INFO | 1672: exit 0 11:38:44 INFO - TEST-OK | dromaeo_css | took 1112584ms 11:38:44 INFO - TEST-START | kraken 11:38:44 INFO - operating with platform_type : mac_ 11:38:44 INFO - Initialising browser for kraken test... 11:38:44 INFO - TEST-INFO | started process 1680 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/tmpbuRNeX/profile http://localhost:49276/getInfo.html) 11:38:47 INFO - PROCESS | 1680 | __metrics Screen width/height:1600/1200 11:38:47 INFO - PROCESS | 1680 | colorDepth:24 11:38:47 INFO - PROCESS | 1680 | Browser inner width/height: 1024/689 11:38:47 INFO - PROCESS | 1680 | __metrics 11:38:47 ERROR - PROCESS | 1680 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 11:38:48 INFO - TEST-INFO | 1680: exit 0 11:38:48 INFO - Browser initialized. 11:38:48 INFO - Running cycle 1/1 for kraken test... 11:38:48 INFO - TEST-INFO | started process 1682 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/tmpbuRNeX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:39:00 INFO - PROCESS | 1682 | RSS: Main: 252530688 11:39:00 INFO - PROCESS | 1682 | 11:39:03 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-beat-detection) 11:39:04 INFO - PROCESS | 1682 | RSS: Main: 264204288 11:39:04 INFO - PROCESS | 1682 | 11:39:06 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-dft) 11:39:07 INFO - PROCESS | 1682 | RSS: Main: 269438976 11:39:07 INFO - PROCESS | 1682 | 11:39:10 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-fft) 11:39:11 INFO - PROCESS | 1682 | RSS: Main: 269725696 11:39:11 INFO - PROCESS | 1682 | 11:39:13 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-oscillator) 11:39:14 INFO - PROCESS | 1682 | RSS: Main: 270143488 11:39:14 INFO - PROCESS | 1682 | 11:39:16 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:39:16 INFO - PROCESS | 1682 | RSS: Main: 270962688 11:39:16 INFO - PROCESS | 1682 | 11:39:19 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-darkroom) 11:39:20 INFO - PROCESS | 1682 | RSS: Main: 271302656 11:39:20 INFO - PROCESS | 1682 | 11:39:22 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-desaturate) 11:39:23 INFO - PROCESS | 1682 | RSS: Main: 271261696 11:39:23 INFO - PROCESS | 1682 | 11:39:26 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49276/tests/kraken/driver.html?testName=json-parse-financial) 11:39:27 INFO - PROCESS | 1682 | RSS: Main: 271077376 11:39:27 INFO - PROCESS | 1682 | 11:39:28 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49276/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:39:29 INFO - PROCESS | 1682 | RSS: Main: 271069184 11:39:29 INFO - PROCESS | 1682 | 11:39:31 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:39:32 INFO - PROCESS | 1682 | RSS: Main: 271118336 11:39:32 INFO - PROCESS | 1682 | 11:39:34 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:39:35 INFO - PROCESS | 1682 | RSS: Main: 270614528 11:39:35 INFO - PROCESS | 1682 | 11:39:38 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:39:38 INFO - PROCESS | 1682 | RSS: Main: 271245312 11:39:38 INFO - PROCESS | 1682 | 11:39:41 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:39:42 INFO - PROCESS | 1682 | RSS: Main: 271118336 11:39:42 INFO - PROCESS | 1682 | 11:39:44 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49276/tests/kraken/driver.html?testName=ai-astar) 11:39:44 INFO - PROCESS | 1682 | RSS: Main: 272670720 11:39:44 INFO - PROCESS | 1682 | 11:39:44 INFO - PROCESS | 1682 | __start_tp_report 11:39:44 INFO - PROCESS | 1682 | _x_x_mozilla_page_load 11:39:44 INFO - PROCESS | 1682 | _x_x_mozilla_page_load_details 11:39:44 INFO - PROCESS | 1682 | |i|pagename|runs| 11:39:44 INFO - PROCESS | 1682 | |0;ai-astar;115;108;118;148;169;115;115;116;115;117 11:39:44 INFO - PROCESS | 1682 | |1;audio-beat-detection;158;121;143;165;125;151;139;111;190;149 11:39:44 INFO - PROCESS | 1682 | |2;audio-dft;195;203;203;238;201;196;196;198;202;196 11:39:44 INFO - PROCESS | 1682 | |3;audio-fft;72;81;77;142;59;58;60;59;128;57 11:39:44 INFO - PROCESS | 1682 | |4;audio-oscillator;70;75;131;71;72;77;111;70;75;113 11:39:44 INFO - PROCESS | 1682 | |5;imaging-gaussian-blur;89;87;88;84;88;84;110;76;136;131 11:39:44 INFO - PROCESS | 1682 | |6;imaging-darkroom;93;95;95;95;94;99;94;97;94;94 11:39:44 INFO - PROCESS | 1682 | |7;imaging-desaturate;92;96;90;90;92;92;79;140;149;91 11:39:44 INFO - PROCESS | 1682 | |8;json-parse-financial;60;59;60;69;77;91;87;98;96;86 11:39:44 INFO - PROCESS | 1682 | |9;json-stringify-tinderbox;68;69;68;83;82;87;60;85;109;89 11:39:44 INFO - PROCESS | 1682 | |10;stanford-crypto-aes;100;116;118;86;81;82;78;76;106;111 11:39:44 INFO - PROCESS | 1682 | |11;stanford-crypto-ccm;150;136;138;145;147;156;140;140;184;144 11:39:44 INFO - PROCESS | 1682 | |12;stanford-crypto-pbkdf2;159;211;167;169;205;226;187;166;238;180 11:39:44 INFO - PROCESS | 1682 | |13;stanford-crypto-sha256-iterative;68;65;64;60;84;85;82;59;71;81 11:39:44 INFO - PROCESS | 1682 | __end_tp_report 11:39:44 INFO - PROCESS | 1682 | __start_cc_report 11:39:44 INFO - PROCESS | 1682 | _x_x_mozilla_cycle_collect,1080 11:39:44 INFO - PROCESS | 1682 | __end_cc_report 11:39:44 INFO - PROCESS | 1682 | __startTimestamp1464201584465__endTimestamp 11:39:44 INFO - PROCESS | 1682 | 11:39:44 INFO - PROCESS | 1682 | ------- Summary: start ------- 11:39:44 INFO - PROCESS | 1682 | Number of tests: 14 11:39:44 INFO - PROCESS | 1682 | 11:39:44 INFO - PROCESS | 1682 | [#0] ai-astar Cycles:10 Average:123.60 Median:116.50 stddev:19.23 (16.5%) stddev-sans-first:20.14 11:39:44 INFO - PROCESS | 1682 | Values: 115.0 108.0 118.0 148.0 169.0 115.0 115.0 116.0 115.0 117.0 11:39:44 INFO - PROCESS | 1682 | 11:39:44 INFO - PROCESS | 1682 | [#1] audio-beat-detection Cycles:10 Average:145.20 Median:150.00 stddev:23.14 (15.4%) stddev-sans-first:24.07 11:39:44 INFO - PROCESS | 1682 | Values: 158.0 121.0 143.0 165.0 125.0 151.0 139.0 111.0 190.0 149.0 11:39:44 INFO - PROCESS | 1682 | 11:39:44 INFO - PROCESS | 1682 | [#2] audio-dft Cycles:10 Average:202.80 Median:201.50 stddev:12.76 (6.3%) stddev-sans-first:13.22 11:39:44 INFO - PROCESS | 1682 | Values: 195.0 203.0 203.0 238.0 201.0 196.0 196.0 198.0 202.0 196.0 11:39:44 INFO - PROCESS | 1682 | 11:39:44 INFO - PROCESS | 1682 | [#3] audio-fft Cycles:10 Average:79.30 Median:59.50 stddev:30.75 (51.7%) stddev-sans-first:32.51 11:39:44 INFO - PROCESS | 1682 | Values: 72.0 81.0 77.0 142.0 59.0 58.0 60.0 59.0 128.0 57.0 11:39:44 INFO - PROCESS | 1682 | 11:39:44 INFO - PROCESS | 1682 | [#4] audio-oscillator Cycles:10 Average:86.50 Median:71.50 stddev:22.69 (31.7%) stddev-sans-first:23.26 11:39:44 INFO - PROCESS | 1682 | Values: 70.0 75.0 131.0 71.0 72.0 77.0 111.0 70.0 75.0 113.0 11:39:44 INFO - PROCESS | 1682 | 11:39:44 INFO - PROCESS | 1682 | [#5] imaging-gaussian-blur Cycles:10 Average:97.30 Median:85.50 stddev:20.95 (24.5%) stddev-sans-first:22.00 11:39:44 INFO - PROCESS | 1682 | Values: 89.0 87.0 88.0 84.0 88.0 84.0 110.0 76.0 136.0 131.0 11:39:44 INFO - PROCESS | 1682 | 11:39:44 INFO - PROCESS | 1682 | [#6] imaging-darkroom Cycles:10 Average:95.00 Median:95.00 stddev:1.76 (1.9%) stddev-sans-first:1.72 11:39:44 INFO - PROCESS | 1682 | Values: 93.0 95.0 95.0 95.0 94.0 99.0 94.0 97.0 94.0 94.0 11:39:44 INFO - PROCESS | 1682 | 11:39:44 INFO - PROCESS | 1682 | [#7] imaging-desaturate Cycles:10 Average:101.10 Median:91.50 stddev:23.38 (25.6%) stddev-sans-first:24.56 11:39:44 INFO - PROCESS | 1682 | Values: 92.0 96.0 90.0 90.0 92.0 92.0 79.0 140.0 149.0 91.0 11:39:44 INFO - PROCESS | 1682 | 11:39:44 INFO - PROCESS | 1682 | [#8] json-parse-financial Cycles:10 Average:78.30 Median:86.50 stddev:15.38 (17.8%) stddev-sans-first:14.82 11:39:44 INFO - PROCESS | 1682 | Values: 60.0 59.0 60.0 69.0 77.0 91.0 87.0 98.0 96.0 86.0 11:39:44 INFO - PROCESS | 1682 | 11:39:44 INFO - PROCESS | 1682 | [#9] json-stringify-tinderbox Cycles:10 Average:80.00 Median:82.50 stddev:14.21 (17.2%) stddev-sans-first:14.40 11:39:44 INFO - PROCESS | 1682 | Values: 68.0 69.0 68.0 83.0 82.0 87.0 60.0 85.0 109.0 89.0 11:39:44 INFO - PROCESS | 1682 | 11:39:44 INFO - PROCESS | 1682 | [#10] stanford-crypto-aes Cycles:10 Average:95.40 Median:77.00 stddev:16.55 (21.5%) stddev-sans-first:17.47 11:39:44 INFO - PROCESS | 1682 | Values: 100.0 116.0 118.0 86.0 81.0 82.0 78.0 76.0 106.0 111.0 11:39:44 INFO - PROCESS | 1682 | 11:39:44 INFO - PROCESS | 1682 | [#11] stanford-crypto-ccm Cycles:10 Average:148.00 Median:146.00 stddev:13.99 (9.6%) stddev-sans-first:14.82 11:39:44 INFO - PROCESS | 1682 | Values: 150.0 136.0 138.0 145.0 147.0 156.0 140.0 140.0 184.0 144.0 11:39:44 INFO - PROCESS | 1682 | 11:39:44 INFO - PROCESS | 1682 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:190.80 Median:196.00 stddev:27.64 (14.1%) stddev-sans-first:26.81 11:39:44 INFO - PROCESS | 1682 | Values: 159.0 211.0 167.0 169.0 205.0 226.0 187.0 166.0 238.0 180.0 11:39:44 INFO - PROCESS | 1682 | 11:39:44 INFO - PROCESS | 1682 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.90 Median:76.00 stddev:10.20 (13.4%) stddev-sans-first:10.72 11:39:44 INFO - PROCESS | 1682 | Values: 68.0 65.0 64.0 60.0 84.0 85.0 82.0 59.0 71.0 81.0 11:39:44 INFO - PROCESS | 1682 | -------- Summary: end -------- 11:39:44 INFO - PROCESS | 1682 | 11:39:45 INFO - TEST-INFO | 1682: exit 0 11:39:45 INFO - TEST-OK | kraken | took 60407ms 11:39:45 INFO - SUITE-END | took 1172s 11:39:45 INFO - Completed test suite (00:19:33) 11:39:45 INFO - javascript benchmark 11:39:45 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7034.677063669115, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3139.042592009437, 3440.1399650087487, 3459.9827000864993, 3461.532693086434, 3478.339115568042, 5708.686022268779, 5758.712064396785, 5838.737256823442, 5846.824595262138, 5856.443637854397, 5582.91625625616, 5692.146178073291, 5761.251037365148, 5813.2152159458465, 5861.325947516037, 5155.226715992593, 5188.896222075557, 5190.377154741436, 5294.52349288564, 5329.600279978998, 5227.895442091156, 5323.680579165262, 5324.893502129955, 5376.892462150755, 5398.703071331075, 5109.795608175668, 5160.458151894035, 5174.637775355726, 5240.816571420001, 5262.0265250928505, 5195.870103247411, 5212.374515058179, 5214.191800270926, 5235.214717792323, 5255.658382205144, 5161.458046905096, 5245.763940622643, 5279.52484276417, 5284.497972692588, 5286.1013627683005, 4753.9762301188275, 5712.771489140429, 5722.97138514309, 5737.770489180428, 5779.0, 4893.898872753638, 5975.820725378246, 6238.563300568962, 6246.0630905364105, 6264.0, 6145.754169833202, 6206.2862770781485, 6209.161763162006, 6226.62640241587, 6232.626042437469, 5723.08430651098, 5800.564957628195, 5856.824295271149, 5914.497267732291, 5921.171036054956, 6338.714757835908, 6379.202599675041, 6388.073729309224, 6388.2653494847755, 6399.520035997319, 5329.934013197376, 6068.75724971005, 6084.6349219046115, 6092.8781424372155, 6099.878002440015, 6102.489251635809, 6224.688765561704, 6226.750929962841, 6234.563580549318, 6256.718447669866, 6041.305249896318, 6069.575129740876, 6069.666168360783, 6125.448709616156, 6158.261008678897, 6034.24571928509, 6072.392760723892, 6078.665673387918, 6149.631022138686, 6156.199694039746, 4890.437599676083, 4939.654224204343, 4939.802407903644, 4942.20924652054, 5006.4743201964, 4415.4259946206785, 4429.889252768642, 4445.377647129404, 4465.062336909221, 4477.171723231213, 5672.8865422691315, 5823.330317013598, 5893.5579831512805, 5939.613925094855, 5955.970220148872, 5228.6339956203465, 6136.304638670889, 6146.139540464339, 6154.0, 6185.8144255672405, 5864.0, 5919.1713160157615, 5934.6142500737315, 5940.61386009908, 5945.64326140433, 4565.726056436624, 4750.928736068961, 5405.108157154104, 5406.270153529302, 5416.647917885325, 4671.929921051187, 5976.0, 6106.908396374058, 6362.95011323145, 6449.161608990801, 5239.0, 5928.614640048383, 6218.129461875251, 6222.315545289924, 6225.470834979077, 6180.567360284737, 6183.598066125687, 6210.596311239755, 6230.190075290183, 6380.0, 6027.156198132177, 6054.939450605438, 6080.72636731357, 6106.9083963739695, 6129.0, 6065.2418447694035, 6119.938800612115, 6124.8775024500155, 6125.10405399348, 6129.662868542273, 5911.881762364643, 6002.519798416029, 6043.365446628217, 6052.0, 6053.9091913622215, 6050.697465126814, 6058.939410605837, 6062.666553339609, 6079.513638908965, 6123.632582045091, 5908.734106965283, 6032.4570788629235, 6067.484263837623, 6094.994325936171, 6096.908546371719, 5991.940080599138, 6013.278406591237, 6064.545159113172, 6066.423689749467, 6090.0, 5981.820545383645, 6011.819645410645, 6040.1543783871175, 6043.395660434097, 6045.335013148638], "value": 5664.059967394778, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [479.2858640625469, 514.2183880501638, 526.1213772999092, 547.5510081732981, 551.678729442984, 32322.515162272557, 40438.79780601097, 40459.583914562994, 40519.189616207696, 40581.797091014574, 41880.37179442311, 42333.36499952496, 42355.0, 42546.36180457295, 42554.574454255446, 9409.811803763921, 9579.568919398644, 9612.90387096127, 9625.470599117045, 9638.084381983736, 9525.857112143323, 9579.0, 9588.137067663909, 9594.568244429018, 9639.614415423375, 9567.521623918776, 9588.280878934072, 9605.66380176694, 9609.990950950154, 9679.90320096797, 9522.0954009369, 9528.90471095294, 9572.13850753432, 9575.329726919119, 9585.808283834354, 9430.49112142054, 9502.097300756417, 9519.143277105093, 9529.666461673809, 9539.809203815954, 39431.21137577233, 39672.0, 39676.60323396758, 39835.800820996, 39856.601433985576, 3088.073577926613, 3126.8592913318957, 3303.4219011672863, 3382.0868365541164, 3457.2566898117025, 28741.28146796347, 28789.28026799347, 29200.707992920223, 30014.549781753292, 30024.849875750482, 2745.2999485131163, 2772.7781777457753, 2827.6748173960054, 2860.4565132624734, 2863.871125799344, 443120.0, 444383.0, 518153.0, 521759.0, 522435.0, 25804.0, 29109.854450728035, 33842.0, 33944.79358841667, 57417.0, 34699.82650086733, 35137.0, 35274.64725352714, 35294.0, 35322.823385882904, 14083.943704222223, 14223.217723025125, 14224.21766802815, 14263.358148883326, 14272.857271427152, 12315.322657253937, 12392.62822115338, 12400.62798116058, 12415.751684966432, 12555.686107847378, 7727.536347819148, 7792.181820908836, 7807.141214466405, 7837.373010159174, 7873.606319683993, 2740.2464322311525, 3260.918477038093, 3265.9836700816345, 3283.83580820958, 3286.244163842282, 927.3137877970254, 1015.28422462164, 1080.2762149359949, 1085.9294145880492, 1101.983470247947, 28968.710312897023, 29063.0, 29326.413471730873, 29534.0, 29706.257343566584, 1040.8178568750438, 1098.0758806240449, 1127.9210455268217, 1160.048760016778, 1178.6169494914186, 11495.88504114965, 13000.219986800823, 13946.58160255194, 14936.477223297132, 15169.772453413208, 663203.0, 674110.0, 674711.0, 679150.0, 684451.0, 54483.0, 54565.0, 54817.62955926134, 54881.9999999992, 55065.17402238889, 1401.2083173007218, 1425.330094855437, 1437.216716889298, 1438.841727409984, 1450.514077783933, 24818.627720583845, 26371.0, 26398.07606733755, 28720.712792871804, 29302.41395172127, 49462.50537494579, 49922.75038624929, 50009.749951250014, 50072.74963625159, 50266.748666257896, 568594.0992065258, 570927.0, 595976.0, 604911.0, 612766.0, 25942.35144121412, 26014.86992565025, 26292.21123366302, 26345.15583909108, 26350.0, 44322.556774433135, 45460.31809522926, 45620.54379456163, 48681.269780954026, 48742.0, 34691.13272168115, 37456.81271593733, 37878.0, 37982.240355193295, 38706.61293387143, 32805.85179518705, 33195.83402082974, 35253.29493410169, 36645.450318244715, 37482.812585936896, 35840.46239306465, 36390.454143188406, 36621.0, 37249.627503724616, 37377.0, 32511.34977300488, 33074.0, 33303.00090997274, 33405.16487087842, 33499.0, 18031.81968180354, 24107.63838542388, 24187.15344962918, 24817.875910621053, 25108.62337064982], "value": 18078.080609571734, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [354.6985062696708, 388.24486374002566, 388.34757607219876, 402.09327965437933, 411.5144129926687, 18875.905620471895, 21171.576568468623, 21276.574468510622, 21986.0, 22007.119715211393, 17970.730439043393, 20270.39188824334, 20790.688139677917, 20870.791292087073, 21256.043478043488, 4556.3621093047, 5465.535429488498, 5951.940480595182, 5990.670513121777, 5991.2510936132985, 5939.970300148493, 5946.197263369455, 5980.671063091529, 6000.579959402844, 6021.9698901505435, 5584.720763961806, 5810.244668193129, 5820.068788993762, 5857.26784151981, 5875.1187321901625, 5632.04255276604, 5765.654060756368, 5834.241548598698, 5866.061430171152, 5869.0, 4989.052080104769, 5071.0, 5074.746262686888, 5090.898182036375, 5121.539061484447, 13332.733345333136, 23377.415564610852, 23713.170039048724, 24850.627240591406, 25931.611025834627, 1893.7727472703225, 2025.7569091708942, 2314.3866875278063, 2371.6205407134776, 2372.6441033845063, 9918.206543476577, 10029.04724051214, 10064.043915827984, 10151.695449136538, 14493.492727754478, 1692.8222536633723, 1721.9483415497555, 1747.8426941575192, 1782.4563508130016, 1786.9821301787076, 96736.51631741866, 119303.0, 125747.37126314401, 126520.73479265273, 126918.0, 11592.0, 11592.6522204334, 11629.127815413878, 11692.590759323382, 11764.411779410995, 14395.208263545395, 14561.344739486749, 14564.49024284145, 14605.269736513344, 14653.34059967304, 8475.915240847637, 8523.616437260338, 8545.0, 8560.315174786003, 8660.004099528634, 7015.7722398580045, 8504.277136443348, 8546.31629469641, 8565.871511927326, 8650.394472386999, 4489.281714925596, 4501.662375321864, 4526.343680166357, 4550.47669518003, 4560.931586026212, 1918.9040547972545, 1969.9901500492692, 1974.5063734066484, 1985.602879424121, 1990.5919286546223, 891.6299735609649, 911.4303560274828, 922.4649703172144, 931.9580618872167, 954.1269738189618, 18870.905645471685, 19975.800241997684, 20133.395998119788, 20329.288474903307, 20386.388408347775, 964.4406244378243, 981.1660088924357, 985.4579981010415, 993.4982833668951, 1040.0535512683423, 10536.367817930948, 10775.299605525615, 10775.299605525615, 10789.46052697362, 10807.459627018618, 588299.0, 594177.0, 597957.0, 603509.0, 608307.0, 103231.96768032224, 137966.0, 140262.99999999796, 141312.0, 142759.0, 857.8884744983112, 881.3389957531723, 918.320442872283, 958.2812890332251, 993.557866749304, 14558.344874480463, 17744.3789467368, 17792.733109003635, 18026.0, 18235.54411139679, 41803.7909810449, 48563.75718121387, 48596.0, 49756.0, 50356.74821625868, 72003.0, 75815.62092189504, 75869.62065189639, 76489.23510764822, 78898.0, 7680.385569154566, 7756.961215193888, 7949.244821741925, 8125.3905957052275, 8227.629756661085, 10208.897911021091, 12121.333326667118, 12201.450934708137, 12456.626301210978, 12529.373531323216, 10045.694059772182, 10493.422861742678, 10528.420936848546, 10601.363918164934, 10633.627823026158, 9883.50582470888, 10576.735581610214, 10717.571297148026, 10754.838677419688, 10809.513571889127, 9597.952010240184, 10761.0, 10948.233623646269, 11174.888251117385, 11207.991280784769, 7665.348445382205, 7794.844103118019, 7851.254130857559, 7916.881246781418, 8217.465864718773, 7888.881666775118, 8872.24585910205, 10460.790784184426, 10673.41296228715, 10761.408122553335], "value": 9344.394162621495, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1449.7970284160217, 1551.6198531359819, 1781.2874850059975, 1917.5397904502915, 1955.3254127326068, 8122.59387030649, 8620.525871077096, 8621.0, 9103.453792772438, 9142.680006199793, 7449.553026818381, 8700.608472618731, 8742.213200811926, 8883.64465421384, 8916.420432671888, 2376.0495801679335, 2409.3976505873534, 2410.4094496848297, 2416.734159242491, 2418.0327868852423, 2371.739108698042, 2388.8208384371155, 2389.2712722619594, 2406.8194885383577, 2418.673479080328, 1928.4600311912616, 2255.334676270504, 2278.54429114177, 2290.919817806377, 2299.0, 1919.0, 2293.5412917416584, 2367.2188177901253, 2382.654515095319, 2386.451116243265, 2283.4748007958265, 2378.774016468433, 2390.952180956371, 2401.3396316013063, 2402.1112188490197, 7567.978322926389, 8824.205821476035, 8890.644374225023, 8892.377533572588, 8914.910850891472, 5221.295125158131, 5366.409694933554, 5426.864328391822, 5437.966786310589, 5453.509184173444, 2757.3244555084134, 3486.6687664671817, 3554.129238336624, 3576.8926932192076, 3618.457231415309, 874.3704532736419, 894.8523493623544, 1020.7397113736024, 1021.5913634546166, 1025.9025392587691, 10101.393916364968, 10546.578136874516, 11684.12369072323, 11763.294202347886, 11800.291982481078, 4691.753636669153, 4775.307580400892, 4844.4186697596515, 4853.296272040534, 4886.828960986349, 8677.783055423664, 9624.422534647943, 9901.7029489114, 9961.950190249003, 9989.450580218012, 4814.86850590111, 5213.348331458567, 5315.229291752751, 5399.973000134974, 5404.78380864761, 4395.604395604411, 4724.102420540086, 4769.856904292877, 4861.368022157097, 4882.12121818069, 3951.2295102454746, 3974.503187101612, 4065.57311482296, 4081.2449696806184, 4145.419641250227, 1211.025124774565, 1246.1712960881093, 1302.4074046308906, 1323.1862404621088, 1325.3638253638114, 906.3383729877227, 908.9727308180766, 915.4873270968279, 938.1134827588, 943.565959658551, 5069.771860266297, 5746.109353050284, 5821.94178058214, 5856.0630299151935, 5864.44287792659, 441.3137571076942, 444.9510553839075, 450.7070404237272, 957.4590356448584, 1010.5402042070987, 3889.7860617665733, 3992.341263691458, 4012.879613611596, 4059.91880162401, 4061.248668996245, 10526.157907367393, 10853.077488413415, 10931.836022459669, 10963.671089867317, 10973.3964631946, 10823.13414926804, 10853.565857365775, 10860.837087443695, 10878.619248326271, 10893.019628233496, 8081.717139900193, 8252.669893204205, 8287.792805179919, 8365.205305495969, 8366.163383661586, 5767.653940763567, 5796.246487956539, 5861.35525092248, 5867.119932010062, 5940.316863560746, 9934.900650993397, 10105.0, 10141.898581014095, 10146.391216527032, 10147.797044059225, 8863.734087977113, 9911.942652902057, 9984.60061597557, 10311.948440257751, 10331.793364132825, 6567.671616419065, 6967.965160174167, 7082.504224704221, 7147.1780745214965, 7215.278472152742, 8411.032731235742, 8487.872681909652, 8523.403361764616, 8535.743927682179, 8565.27195188416, 7440.776776696708, 7509.399248060251, 7533.171351151478, 7630.504017238862, 7685.193054729173, 7383.0, 7405.518641288299, 7438.702451901863, 7485.625718714152, 7534.35957943559, 6961.164660240804, 7006.614636195059, 7033.964830176021, 7044.36600705939, 7054.259302773135, 4508.661850361171, 4644.883877903079, 4787.712737235777, 4861.732604706775, 4979.601631869514, 4868.123737727243, 4890.682105663121, 4930.383702037246, 5012.0, 5145.819896303611], "value": 4522.581744309759, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [272.63194687172313, 317.14687490650147, 319.70746766708464, 322.9870805167795, 326.1536313267071, 5573.777048918044, 5582.748776305076, 5622.979569607724, 5653.1237658162945, 5676.716164191789, 4570.0, 5250.501202385777, 5452.781888724447, 5541.889162216763, 5557.444255574441, 3955.4660120883746, 4091.8158682859344, 4188.958110418903, 4189.937150942723, 4198.265303571863, 3618.6019537850816, 3869.922601547967, 4140.60664236897, 4177.665786737069, 4183.790810459464, 3420.5211270422037, 4132.772697501636, 4137.648299894513, 4151.0, 4157.376393540978, 3133.944404205904, 3543.9114022149392, 3633.4731463937583, 4121.9175616487655, 4147.523034850986, 3194.37709646619, 3545.94681079784, 3562.664000999625, 3567.9643203567894, 3570.0896271450615, 4585.679002469828, 5311.150215965427, 5339.439358867341, 5358.785648574052, 5365.302510673588, 3464.9653503464897, 4313.892152696177, 4729.408823897013, 4731.5268473152755, 4746.952530474686, 5106.923396149061, 5721.284839395075, 5732.254806875079, 5750.741216645261, 5772.278465191851, 3160.909486227271, 3870.3420418528917, 3875.4380614810975, 3901.648851603341, 3908.8045597720284, 5248.895022099575, 5714.885702285931, 5973.7455134421825, 6144.324124346318, 6197.535184861108, 3105.54969529417, 3817.522809648794, 3891.871357306406, 3896.883093507199, 3955.228730397545, 3503.5270238517473, 3513.8594456221977, 3957.841686332572, 3975.3043217436834, 3981.343078392033, 4121.4848143982, 4610.308453731967, 4650.790714417859, 4666.696664716783, 4672.275797251432, 4876.731779752076, 5520.972395137998, 5771.59598828078, 5830.883382332329, 5836.490309167014, 1148.8908553687386, 1162.3607016141257, 1340.1289162044554, 1369.6849724563208, 1379.8754015477355, 676.3675962974635, 685.3934268172703, 736.9005184300158, 753.5666991479922, 888.8888888888889, 4431.40176076232, 5161.329027226437, 5324.0, 5388.54197393226, 5426.321709786277, 5204.245384419238, 5577.828655982289, 5598.776048957996, 5917.260342457193, 5968.1644569760265, 5159.406668233126, 5279.287296214962, 5343.438938911436, 5372.206452644603, 5388.871395798716, 2582.398912674154, 3048.329367539138, 3122.172624254574, 3148.174059045748, 3151.4327421064427, 6140.478059364915, 6149.661768602769, 6197.380261973857, 6203.960059570778, 6210.130581718563, 5427.0, 5727.455891690282, 6155.84610384744, 6248.0, 6270.090836828635, 5618.325800903999, 5985.371535988746, 6156.476699480594, 6368.5860419072615, 6373.080555458359, 5636.0, 6050.21347224858, 6055.364186760326, 6063.666498342457, 6064.363241859542, 5514.972425137848, 5709.771609135588, 5947.821565353046, 5956.463918247378, 5973.342932277359, 5760.395158508296, 5780.364159942487, 6249.218847644044, 6277.74889004453, 6378.904316435164, 3383.424817780934, 3403.5745531808525, 3945.408188771707, 3954.387070004154, 3955.8022098895517, 6063.514918806573, 6066.059760736916, 6070.696465176812, 6072.514198863992, 6074.3925607438905, 4976.626752993612, 5169.715665638426, 5270.3661864822025, 5432.360318586636, 5888.234529511136, 4642.837500687459, 5572.164175373727, 5832.679202643895, 5850.853728656647, 5906.0, 4795.808167673394, 5098.439171691037, 5142.948570514247, 5427.728613569227, 5760.164776107357, 4890.877728056685, 5189.455107213839, 5205.843824685266, 5379.220013098157, 5816.272965879265, 4674.368960190467, 5297.841064768063, 5694.487496125369, 5751.849630073918, 5815.5929084963645], "value": 4146.376518519995, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [636.8131568375582, 719.647372787334, 729.1614643160364, 729.6436392594064, 769.303780079028, 14845.0, 15201.619959500991, 15245.466408675698, 15362.001469904448, 15366.385344586217, 12944.482220711183, 14149.646258843533, 14156.221407822566, 14159.29203539824, 14223.075500092487, 548.3529435266385, 753.5629334985695, 787.3307688464816, 899.5457294066488, 908.7955210077713, 883.5493898111945, 890.8930928288614, 891.0287786312917, 897.161154320709, 902.2466240689032, 804.9718259860906, 935.96724114656, 954.1746389373177, 954.7088138117871, 957.1911734584265, 916.8670542771295, 947.971560853172, 952.2096659772373, 953.2516974175239, 959.0697023886853, 691.9654017299115, 702.9578225306498, 707.971681132754, 710.0556260173956, 712.2521352579822, 13402.46390144393, 13546.59360219195, 13598.252096134709, 13642.454301827915, 13668.248246346448, 12605.180663256859, 12703.364831758376, 12712.809307860389, 12746.490140394373, 13036.0, 10087.798244035079, 10144.239182061376, 10174.33866798663, 10379.0, 10506.579736810594, 3442.2599141184764, 3464.9653503464897, 3539.4159963605975, 3654.4152935530187, 3699.5930447650735, 65896.69361572369, 83901.0, 84171.3165736688, 86987.0, 87356.0, 8803.911960880308, 9103.317251206186, 9442.339036267533, 9631.181349585364, 10422.426766527913, 20278.493037674176, 20281.0, 20491.69262461064, 20605.278815241396, 20661.690074648894, 7141.428685705131, 7261.273872612696, 7274.090738657668, 7309.89035164473, 7491.475596708287, 3580.4220960908106, 4002.6397624213378, 4092.2020206059533, 4092.7544347339253, 4096.09885825118, 3521.841517131735, 3603.9819800900827, 3662.322470342995, 3667.1932174921476, 3668.853245870135, 635.3646353646353, 636.9363063693594, 640.1837656987285, 653.3466533466534, 672.5661948043485, 508.6896992834368, 509.7571477355382, 560.9859753506113, 596.3212025316387, 608.4371955940737, 7214.96392518034, 7434.145073316529, 7459.813504662318, 7518.398528117737, 7533.020707308125, 521.9712915789668, 572.9257641921397, 575.65748379714, 593.4955288005161, 599.5263741644139, 3019.456497830368, 3665.8533658653355, 3856.8071596420627, 3871.6515513603913, 3898.8830335089992, 83017.0, 85099.0, 88222.0, 88582.0, 90193.0, 74239.0, 76340.61829690928, 76602.61698691471, 77813.61093194498, 80314.0, 56133.87732245332, 68428.6578567104, 69315.3068469319, 69870.60258794898, 74721.6263918677, 6906.723731050903, 8185.631646576037, 8234.300084492885, 8382.605569094158, 8494.447860889022, 44706.77646611746, 44876.0, 44942.0, 45324.54675453335, 45658.0, 63692.0, 64296.357036429035, 64419.677901610194, 64667.67666161639, 65167.34832651613, 10785.568577256821, 10799.56801727922, 10836.241463097824, 11080.445977701243, 11165.38590377537, 10988.395638239974, 11329.546818127183, 11495.48270327854, 11967.521299148284, 11974.46114924813, 11438.313701177956, 11454.828177577176, 11481.425928703364, 11716.70708232301, 11773.705657358292, 11300.94349528247, 11330.716732081764, 11502.022328102204, 11869.287842729464, 11960.700982475508, 10997.010269075821, 11024.173187010845, 11252.437378131224, 11394.772104557696, 12208.633740987785, 9558.808823823623, 9568.186704129948, 9595.328327017041, 9741.464219467714, 9868.309218354647, 8866.785220703614, 9557.948625651034, 9654.0, 9713.125818676353, 9779.315447918578], "value": 6753.621219182852, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1595.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 108.0, 118.0, 148.0, 169.0, 115.0, 115.0, 116.0, 115.0, 117.0], "value": 123.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 121.0, 143.0, 165.0, 125.0, 151.0, 139.0, 111.0, 190.0, 149.0], "value": 145.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 203.0, 203.0, 238.0, 201.0, 196.0, 196.0, 198.0, 202.0, 196.0], "value": 202.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 81.0, 77.0, 142.0, 59.0, 58.0, 60.0, 59.0, 128.0, 57.0], "value": 79.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 75.0, 131.0, 71.0, 72.0, 77.0, 111.0, 70.0, 75.0, 113.0], "value": 86.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 88.0, 84.0, 88.0, 84.0, 110.0, 76.0, 136.0, 131.0], "value": 97.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 95.0, 95.0, 95.0, 94.0, 99.0, 94.0, 97.0, 94.0, 94.0], "value": 95.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 96.0, 90.0, 90.0, 92.0, 92.0, 79.0, 140.0, 149.0, 91.0], "value": 101.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 59.0, 60.0, 69.0, 77.0, 91.0, 87.0, 98.0, 96.0, 86.0], "value": 78.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 68.0, 83.0, 82.0, 87.0, 60.0, 85.0, 109.0, 89.0], "value": 80.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 116.0, 118.0, 86.0, 81.0, 82.0, 78.0, 76.0, 106.0, 111.0], "value": 95.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 136.0, 138.0, 145.0, 147.0, 156.0, 140.0, 140.0, 184.0, 144.0], "value": 148.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 211.0, 167.0, 169.0, 205.0, 226.0, 187.0, 166.0, 238.0, 180.0], "value": 190.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 65.0, 64.0, 60.0, 84.0, 85.0, 82.0, 59.0, 71.0, 81.0], "value": 71.9, "unit": "score"}]}]} 11:39:45 INFO - Return code: 0 11:39:45 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 11:39:45 ERROR - # TBPL SUCCESS # 11:39:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:39:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:39:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:39:45 INFO - 2016-05-25 11:39:45.762 screenresolution[1687:10891] starting screenresolution argv=screenresolution get 11:39:45 INFO - 2016-05-25 11:39:45.765 screenresolution[1687:10891] Display 0: 1600x1200x32@60 11:39:45 INFO - 2016-05-25 11:39:45.771 screenresolution[1688:10894] starting screenresolution argv=screenresolution list 11:39:45 INFO - Available Modes on Display 0 11:39:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:39:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:39:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:39:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:39:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:39:46 INFO - Intel Iris: 11:39:46 INFO - Chipset Model: Intel Iris 11:39:46 INFO - Type: GPU 11:39:46 INFO - Bus: Built-In 11:39:46 INFO - VRAM (Dynamic, Max): 1536 MB 11:39:46 INFO - Vendor: Intel (0x8086) 11:39:46 INFO - Device ID: 0x0a2e 11:39:46 INFO - Revision ID: 0x0009 11:39:46 INFO - Displays: 11:39:46 INFO - Display: 11:39:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:39:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:39:46 INFO - Display Serial Number: 1600x1200 60 11:39:46 INFO - Main Display: Yes 11:39:46 INFO - Mirror: Off 11:39:46 INFO - Online: Yes 11:39:46 INFO - Rotation: Supported 11:39:46 INFO - Return code: 0 11:39:46 INFO - Running post-action listener: _resource_record_post_action 11:39:46 INFO - ##### 11:39:46 INFO - ##### Finished run-tests step (success) 11:39:46 INFO - ##### 11:39:46 INFO - Running post-run listener: _resource_record_post_run 11:39:46 INFO - Total resource usage - Wall time: 1208s; CPU: 54.0%; Read bytes: 32734720; Write bytes: 698105856; Read time: 324; Write time: 2152 11:39:46 INFO - TinderboxPrint: CPU usage
54.4% 11:39:46 INFO - TinderboxPrint: I/O read bytes / time
32,734,720 / 324 11:39:46 INFO - TinderboxPrint: I/O write bytes / time
698,105,856 / 2,152 11:39:46 INFO - TinderboxPrint: CPU idle
2,203.6 (45.6%) 11:39:46 INFO - TinderboxPrint: CPU system
974.6 (20.2%) 11:39:46 INFO - TinderboxPrint: CPU user
1,655.5 (34.2%) 11:39:46 INFO - TinderboxPrint: Swap in / out
426,356,736 / 0 11:39:46 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 387570176; Write bytes: 381474304; Read time: 28402; Write time: 741 11:39:46 INFO - run-tests - Wall time: 1175s; CPU: 54.0%; Read bytes: 30977536; Write bytes: 297122304; Read time: 307; Write time: 1365 11:39:46 INFO - Running post-run listener: _upload_blobber_files 11:39:46 INFO - Blob upload gear active. 11:39:46 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:39:46 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 11:39:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Larch', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:39:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Larch -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:39:46 INFO - (blobuploader) - INFO - Open directory for files ... 11:39:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 11:39:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:39:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:39:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 11:39:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:39:48 INFO - (blobuploader) - INFO - Done attempting. 11:39:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 11:39:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:39:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:39:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 11:39:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:39:49 INFO - (blobuploader) - INFO - Done attempting. 11:39:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:39:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:39:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:39:51 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:39:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:39:51 INFO - (blobuploader) - INFO - Done attempting. 11:39:51 INFO - (blobuploader) - INFO - Iteration through files over. 11:39:51 INFO - Return code: 0 11:39:51 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:39:51 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:39:51 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/a40a02e1f583a53504a5642054e24557486dfe07893b90554d707aafa7dd10abf9f1fdf10249e422f8431ac4c4bfc693ad4d3ff033b1ae5f3571e339d17ab88b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/d9b00e61c033c7e2966bdcb8a813c4dc351041bf01bf82944897985ac6abae60bc1412ea0abcef4647d880baa3e13da9ba2575fd617d2a19b7aa4bda1f57d229", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/baab3285e14ee5f80f66d071f84616e0b994edb114a93cdc9c6460d862c3d5eaa8df666e9699c7efe816f127a77a96dbc956644d6276a65fcd845fba214daed9"} 11:39:51 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:39:51 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:39:51 INFO - Contents: 11:39:51 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/a40a02e1f583a53504a5642054e24557486dfe07893b90554d707aafa7dd10abf9f1fdf10249e422f8431ac4c4bfc693ad4d3ff033b1ae5f3571e339d17ab88b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/d9b00e61c033c7e2966bdcb8a813c4dc351041bf01bf82944897985ac6abae60bc1412ea0abcef4647d880baa3e13da9ba2575fd617d2a19b7aa4bda1f57d229", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/baab3285e14ee5f80f66d071f84616e0b994edb114a93cdc9c6460d862c3d5eaa8df666e9699c7efe816f127a77a96dbc956644d6276a65fcd845fba214daed9"} 11:39:51 INFO - Running post-run listener: copy_logs_to_upload_dir 11:39:51 INFO - Copying logs to upload dir... 11:39:51 INFO - mkdir: /builds/slave/test/build/upload/logs 11:39:51 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1299.259378 ========= master_lag: 0.41 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 39 secs) (at 2016-05-25 11:39:52.194243) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-25 11:39:52.198921) ========= 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.AfXkI8tNfc/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.XewucawdYy/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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/Larch/sha512/a40a02e1f583a53504a5642054e24557486dfe07893b90554d707aafa7dd10abf9f1fdf10249e422f8431ac4c4bfc693ad4d3ff033b1ae5f3571e339d17ab88b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/d9b00e61c033c7e2966bdcb8a813c4dc351041bf01bf82944897985ac6abae60bc1412ea0abcef4647d880baa3e13da9ba2575fd617d2a19b7aa4bda1f57d229", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/baab3285e14ee5f80f66d071f84616e0b994edb114a93cdc9c6460d862c3d5eaa8df666e9699c7efe816f127a77a96dbc956644d6276a65fcd845fba214daed9"} build_url:https://queue.taskcluster.net/v1/task/DCftajzTTwuCAyg-GMzplA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011344 build_url: 'https://queue.taskcluster.net/v1/task/DCftajzTTwuCAyg-GMzplA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/a40a02e1f583a53504a5642054e24557486dfe07893b90554d707aafa7dd10abf9f1fdf10249e422f8431ac4c4bfc693ad4d3ff033b1ae5f3571e339d17ab88b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/d9b00e61c033c7e2966bdcb8a813c4dc351041bf01bf82944897985ac6abae60bc1412ea0abcef4647d880baa3e13da9ba2575fd617d2a19b7aa4bda1f57d229", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/baab3285e14ee5f80f66d071f84616e0b994edb114a93cdc9c6460d862c3d5eaa8df666e9699c7efe816f127a77a96dbc956644d6276a65fcd845fba214daed9"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-25 11:39:52.237282) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 11:39:52.237839) ========= 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.AfXkI8tNfc/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.XewucawdYy/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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.005146 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 11:39:52.286355) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-25 11:39:52.289791) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-25 11:39:52.290232) ========= ========= Total master_lag: 0.54 =========