builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0027 starttime: 1464240311.43 results: success (0) buildid: 20160525204851 builduid: 5d679e607a424656ad6003a26e637ab7 revision: 25566a14a7ac98674bfe9b6e73edc59fcc286c22 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-25 22:25:11.433038) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-25 22:25:11.433483) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-25 22:25:11.433860) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-25 22:25:11.458621) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 22:25:11.458981) ========= 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.pCLH78Ko2X/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.89ywlbnuOM/Listeners TMPDIR=/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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 22:25:11-- 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% 16.1M=0.001s 2016-05-25 22:25:12 (16.1 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.678673 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 22:25:12.158300) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 22:25:12.158704) ========= 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.pCLH78Ko2X/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.89ywlbnuOM/Listeners TMPDIR=/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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.034682 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 22:25:12.219153) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-25 22:25:12.219658) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 25566a14a7ac98674bfe9b6e73edc59fcc286c22 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 25566a14a7ac98674bfe9b6e73edc59fcc286c22 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.pCLH78Ko2X/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.89ywlbnuOM/Listeners TMPDIR=/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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 22:25:12,304 truncating revision to first 12 chars 2016-05-25 22:25:12,304 Setting DEBUG logging. 2016-05-25 22:25:12,305 attempt 1/10 2016-05-25 22:25:12,305 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/25566a14a7ac?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-25 22:25:13,344 unpacking tar archive at: fx-team-25566a14a7ac/testing/mozharness/ program finished with exit code 0 elapsedTime=1.293697 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-25 22:25:13.541966) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-25 22:25:13.542306) ========= script_repo_revision: 25566a14a7ac98674bfe9b6e73edc59fcc286c22 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-25 22:25:13.546897) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-25 22:25:13.547223) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-25 22:25:13.573861) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 43 secs) (at 2016-05-25 22:25:13.574247) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.pCLH78Ko2X/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.89ywlbnuOM/Listeners TMPDIR=/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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 22:25:13 INFO - MultiFileLogger online at 20160525 22:25:13 in /builds/slave/test 22:25:13 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 22:25:13 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:25:13 INFO - {'append_to_log': False, 22:25:13 INFO - 'base_work_dir': '/builds/slave/test', 22:25:13 INFO - 'blob_upload_branch': 'Fx-Team', 22:25:13 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:25:13 INFO - 'branch': 'Fx-Team', 22:25:13 INFO - 'buildbot_json_path': 'buildprops.json', 22:25:13 INFO - 'config_files': ('talos/mac_config.py',), 22:25:13 INFO - 'default_actions': ('clobber', 22:25:13 INFO - 'read-buildbot-config', 22:25:13 INFO - 'download-and-extract', 22:25:13 INFO - 'populate-webroot', 22:25:13 INFO - 'create-virtualenv', 22:25:13 INFO - 'install', 22:25:13 INFO - 'run-tests'), 22:25:13 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:25:13 INFO - 'download_minidump_stackwalk': True, 22:25:13 INFO - 'download_symbols': 'ondemand', 22:25:13 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:25:13 INFO - 'tooltool.py': '/tools/tooltool.py', 22:25:13 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:25:13 INFO - '/tools/misc-python/virtualenv.py')}, 22:25:13 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:25:13 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:25:13 INFO - 'installer_path': 'installer.exe', 22:25:13 INFO - 'log_level': 'info', 22:25:13 INFO - 'log_name': 'talos', 22:25:13 INFO - 'log_to_console': True, 22:25:13 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 22:25:13 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 22:25:13 INFO - 'opt_config_files': (), 22:25:13 INFO - 'pip_index': False, 22:25:13 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:25:13 INFO - 'cmd': ('bash', 22:25:13 INFO - '-c', 22:25:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:25:13 INFO - 'enabled': True, 22:25:13 INFO - 'halt_on_failure': False, 22:25:13 INFO - 'name': 'check_screen_resolution'},), 22:25:13 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:25:13 INFO - 'cmd': ('bash', 22:25:13 INFO - '-c', 22:25:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:25:13 INFO - 'enabled': True, 22:25:13 INFO - 'halt_on_failure': False, 22:25:13 INFO - 'name': 'check_screen_resolution'},), 22:25:13 INFO - 'run_cmd_checks_enabled': True, 22:25:13 INFO - 'sps_profile': False, 22:25:13 INFO - 'sps_profile_interval': 0, 22:25:13 INFO - 'suite': 'dromaeojs', 22:25:13 INFO - 'system_bits': '32', 22:25:13 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:25:13 INFO - 'title': 't-yosemite-r7-0027', 22:25:13 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:25:13 INFO - 'use_talos_json': True, 22:25:13 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:25:13 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:25:13 INFO - 'work_dir': 'build'} 22:25:13 INFO - ##### 22:25:13 INFO - ##### Running clobber step. 22:25:13 INFO - ##### 22:25:13 INFO - Running pre-action listener: _resource_record_pre_action 22:25:13 INFO - Running main action method: clobber 22:25:13 INFO - rmtree: /builds/slave/test/build 22:25:13 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:25:16 INFO - Running post-action listener: _resource_record_post_action 22:25:16 INFO - ##### 22:25:16 INFO - ##### Finished clobber step (success) 22:25:16 INFO - ##### 22:25:16 INFO - ##### 22:25:16 INFO - ##### Running read-buildbot-config step. 22:25:16 INFO - ##### 22:25:16 INFO - Running pre-action listener: _resource_record_pre_action 22:25:16 INFO - Running main action method: read_buildbot_config 22:25:16 INFO - Using buildbot properties: 22:25:16 INFO - { 22:25:16 INFO - "project": "", 22:25:16 INFO - "product": "firefox", 22:25:16 INFO - "script_repo_revision": "production", 22:25:16 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 22:25:16 INFO - "repository": "", 22:25:16 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 22:25:16 INFO - "buildid": "20160525204851", 22:25:16 INFO - "pgo_build": "False", 22:25:16 INFO - "basedir": "/builds/slave/test", 22:25:16 INFO - "buildnumber": 23, 22:25:16 INFO - "slavename": "t-yosemite-r7-0027", 22:25:16 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 22:25:16 INFO - "platform": "yosemite_r7", 22:25:16 INFO - "scheduler": "tests-fx-team-macosx64-talos", 22:25:16 INFO - "branch": "fx-team", 22:25:16 INFO - "repo_path": "integration/fx-team", 22:25:16 INFO - "revision": "25566a14a7ac98674bfe9b6e73edc59fcc286c22", 22:25:16 INFO - "stage_platform": "macosx64", 22:25:16 INFO - "builduid": "5d679e607a424656ad6003a26e637ab7", 22:25:16 INFO - "slavebuilddir": "test" 22:25:16 INFO - } 22:25:16 INFO - Found installer url https://queue.taskcluster.net/v1/task/R7anaQXgTwKgFY0a0bDjjA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 22:25:16 INFO - Running post-action listener: _resource_record_post_action 22:25:16 INFO - ##### 22:25:16 INFO - ##### Finished read-buildbot-config step (success) 22:25:16 INFO - ##### 22:25:16 INFO - ##### 22:25:16 INFO - ##### Running download-and-extract step. 22:25:16 INFO - ##### 22:25:16 INFO - Running pre-action listener: _resource_record_pre_action 22:25:16 INFO - Running main action method: download_and_extract 22:25:16 INFO - mkdir: /builds/slave/test/build/tests 22:25:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:25:16 INFO - https://queue.taskcluster.net/v1/task/R7anaQXgTwKgFY0a0bDjjA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 22:25:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R7anaQXgTwKgFY0a0bDjjA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 22:25:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R7anaQXgTwKgFY0a0bDjjA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 22:25:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R7anaQXgTwKgFY0a0bDjjA/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 22:25:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R7anaQXgTwKgFY0a0bDjjA/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 22:25:29 INFO - Downloaded 1199 bytes. 22:25:29 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 22:25:29 INFO - Using the following test package requirements: 22:25:29 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 22:25:29 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 22:25:29 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 22:25:29 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 22:25:29 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 22:25:29 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 22:25:29 INFO - u'jsshell-mac.zip'], 22:25:29 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 22:25:29 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 22:25:29 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 22:25:29 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 22:25:29 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 22:25:29 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 22:25:29 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 22:25:29 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 22:25:29 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 22:25:29 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 22:25:29 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 22:25:29 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 22:25:29 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')]} 22:25:29 INFO - https://queue.taskcluster.net/v1/task/R7anaQXgTwKgFY0a0bDjjA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 22:25:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R7anaQXgTwKgFY0a0bDjjA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 22:25:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R7anaQXgTwKgFY0a0bDjjA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 22:25:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R7anaQXgTwKgFY0a0bDjjA/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 22:25:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R7anaQXgTwKgFY0a0bDjjA/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 22:25:37 INFO - Downloaded 18622344 bytes. 22:25:37 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:25:37 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 22:25:40 INFO - Return code: 0 22:25:40 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 22:25:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:25:40 INFO - https://queue.taskcluster.net/v1/task/R7anaQXgTwKgFY0a0bDjjA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 22:25:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R7anaQXgTwKgFY0a0bDjjA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 22:25:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R7anaQXgTwKgFY0a0bDjjA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 22:25:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R7anaQXgTwKgFY0a0bDjjA/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 22:25:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R7anaQXgTwKgFY0a0bDjjA/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 22:25:50 INFO - Downloaded 18622344 bytes. 22:25: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'] 22:25: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 22:25:54 INFO - Return code: 0 22:25:54 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')]} 22:25:54 INFO - https://queue.taskcluster.net/v1/task/R7anaQXgTwKgFY0a0bDjjA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 22:25:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R7anaQXgTwKgFY0a0bDjjA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 22:25:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R7anaQXgTwKgFY0a0bDjjA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 22:25:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R7anaQXgTwKgFY0a0bDjjA/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 22:25:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R7anaQXgTwKgFY0a0bDjjA/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 22:25:55 INFO - Downloaded 10834034 bytes. 22:25:55 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'] 22:25:55 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 22:25:55 INFO - Return code: 0 22:25: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')]} 22:25:55 INFO - https://queue.taskcluster.net/v1/task/R7anaQXgTwKgFY0a0bDjjA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 22:25:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R7anaQXgTwKgFY0a0bDjjA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 22:25:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R7anaQXgTwKgFY0a0bDjjA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 22:25:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R7anaQXgTwKgFY0a0bDjjA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 22:25:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R7anaQXgTwKgFY0a0bDjjA/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 22:26:03 INFO - Downloaded 110500519 bytes. 22:26:03 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/R7anaQXgTwKgFY0a0bDjjA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 22:26:03 INFO - mkdir: /builds/slave/test/properties 22:26:03 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:26:03 INFO - Writing to file /builds/slave/test/properties/build_url 22:26:03 INFO - Contents: 22:26:03 INFO - build_url:https://queue.taskcluster.net/v1/task/R7anaQXgTwKgFY0a0bDjjA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 22:26:04 INFO - Running post-action listener: _resource_record_post_action 22:26:04 INFO - Running post-action listener: set_extra_try_arguments 22:26:04 INFO - ##### 22:26:04 INFO - ##### Finished download-and-extract step (success) 22:26:04 INFO - ##### 22:26:04 INFO - ##### 22:26:04 INFO - ##### Running populate-webroot step. 22:26:04 INFO - ##### 22:26:04 INFO - Running pre-action listener: _resource_record_pre_action 22:26:04 INFO - Running main action method: populate_webroot 22:26:04 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:26:04 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:26:04 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:26:04 INFO - '--tppagecycles', 22:26:04 INFO - '7'], 22:26:04 INFO - 'tests': ['tsvgm']}}, 22:26:04 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:26:04 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:26:04 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:26:04 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:26:04 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:26:04 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:26:04 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:26:04 INFO - 'talos_options': ['--disable-e10s'], 22:26:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:26:04 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:26:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:26:04 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:26:04 INFO - 'talos_options': ['--disable-e10s'], 22:26:04 INFO - 'tests': ['damp', 'tps']}, 22:26:04 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:26:04 INFO - 'tests': ['damp', 'tps']}, 22:26:04 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:26:04 INFO - 'tests': ['dromaeo_dom']}, 22:26:04 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:26:04 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:26:04 INFO - 'tests': ['a11yr', 22:26:04 INFO - 'ts_paint', 22:26:04 INFO - 'tpaint', 22:26:04 INFO - 'sessionrestore', 22:26:04 INFO - 'sessionrestore_no_auto_restore', 22:26:04 INFO - 'tabpaint']}, 22:26:04 INFO - 'other-e10s': {'tests': ['a11yr', 22:26:04 INFO - 'ts_paint', 22:26:04 INFO - 'tpaint', 22:26:04 INFO - 'sessionrestore', 22:26:04 INFO - 'sessionrestore_no_auto_restore', 22:26:04 INFO - 'tabpaint']}, 22:26:04 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:26:04 INFO - 'ts_paint', 22:26:04 INFO - 'tpaint', 22:26:04 INFO - 'sessionrestore', 22:26:04 INFO - 'sessionrestore_no_auto_restore', 22:26:04 INFO - 'tabpaint']}, 22:26:04 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:26:04 INFO - 'ts_paint', 22:26:04 INFO - 'tpaint', 22:26:04 INFO - 'sessionrestore', 22:26:04 INFO - 'sessionrestore_no_auto_restore', 22:26:04 INFO - 'tabpaint']}, 22:26:04 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:26:04 INFO - 'tests': ['a11yr', 22:26:04 INFO - 'ts_paint', 22:26:04 INFO - 'tpaint', 22:26:04 INFO - 'sessionrestore', 22:26:04 INFO - 'sessionrestore_no_auto_restore', 22:26:04 INFO - 'tabpaint']}, 22:26:04 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:26:04 INFO - 'tests': ['a11yr', 22:26:04 INFO - 'ts_paint', 22:26:04 INFO - 'tpaint', 22:26:04 INFO - 'sessionrestore', 22:26:04 INFO - 'sessionrestore_no_auto_restore', 22:26:04 INFO - 'tabpaint']}, 22:26:04 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:26:04 INFO - 'tests': ['tsvgx', 22:26:04 INFO - 'tsvgr_opacity', 22:26:04 INFO - 'tart', 22:26:04 INFO - 'tscrollx', 22:26:04 INFO - 'cart']}, 22:26:04 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:26:04 INFO - 'tsvgr_opacity', 22:26:04 INFO - 'tart', 22:26:04 INFO - 'tscrollx', 22:26:04 INFO - 'cart']}, 22:26:04 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:26:04 INFO - 'talos_options': ['--disable-e10s'], 22:26:04 INFO - 'tests': ['tp5o']}, 22:26:04 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:26:04 INFO - 'tests': ['tp5o']}, 22:26:04 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:26:04 INFO - 'talos_options': ['--disable-e10s', 22:26:04 INFO - '--xperf_path', 22:26:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:26:04 INFO - 'tests': ['tp5n']}, 22:26:04 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:26:04 INFO - 'talos_options': ['--xperf_path', 22:26:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:26:04 INFO - 'tests': ['tp5n']}}, 22:26:04 INFO - 'talos.zip': {'path': '', 22:26:04 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:26:04 INFO - Running post-action listener: _resource_record_post_action 22:26:04 INFO - ##### 22:26:04 INFO - ##### Finished populate-webroot step (success) 22:26:04 INFO - ##### 22:26:04 INFO - ##### 22:26:04 INFO - ##### Running create-virtualenv step. 22:26:04 INFO - ##### 22:26:04 INFO - Running pre-action listener: _resource_record_pre_action 22:26:04 INFO - Running main action method: create_virtualenv 22:26:04 INFO - Creating virtualenv /builds/slave/test/build/venv 22:26:04 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 22:26:04 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:26:04 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:26:04 INFO - Using real prefix '/tools/python27' 22:26:04 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:26:05 INFO - Installing distribute.............................................................................................................................................................................................done. 22:26:08 INFO - Installing pip.................done. 22:26:08 INFO - Return code: 0 22:26:08 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:26:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:26:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:26:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:26:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:26:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:26:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:26:08 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 0x105f28a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105f2e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97324bd010>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105dccd30>, '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 0x105bf0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105dc3b20>, '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.pCLH78Ko2X/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.89ywlbnuOM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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 22:26:08 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 22:26:08 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 22:26:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pCLH78Ko2X/Render', 22:26:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:26:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:26:08 INFO - 'HOME': '/Users/cltbld', 22:26:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:26:08 INFO - 'LOGNAME': 'cltbld', 22:26:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:26:08 INFO - 'MOZ_NO_REMOTE': '1', 22:26:08 INFO - 'NO_EM_RESTART': '1', 22:26:08 INFO - 'PAGER': '/bin/cat', 22:26:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:26:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:26:08 INFO - 'PWD': '/builds/slave/test', 22:26:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:26:08 INFO - 'SHELL': '/bin/bash', 22:26:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.89ywlbnuOM/Listeners', 22:26:08 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 22:26:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:26:08 INFO - 'USER': 'cltbld', 22:26:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:26:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:26:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:26:08 INFO - 'XPC_FLAGS': '0x0', 22:26:08 INFO - 'XPC_SERVICE_NAME': '0', 22:26:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:26:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:26:08 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 22:26:08 INFO - Cleaning up... 22:26:08 INFO - Return code: 0 22:26:08 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:26:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:26:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:26:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:26:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:26:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:26:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:26:08 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 0x105f28a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105f2e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97324bd010>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105dccd30>, '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 0x105bf0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105dc3b20>, '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.pCLH78Ko2X/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.89ywlbnuOM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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 22:26:08 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 22:26:08 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 22:26:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pCLH78Ko2X/Render', 22:26:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:26:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:26:08 INFO - 'HOME': '/Users/cltbld', 22:26:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:26:08 INFO - 'LOGNAME': 'cltbld', 22:26:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:26:08 INFO - 'MOZ_NO_REMOTE': '1', 22:26:08 INFO - 'NO_EM_RESTART': '1', 22:26:08 INFO - 'PAGER': '/bin/cat', 22:26:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:26:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:26:08 INFO - 'PWD': '/builds/slave/test', 22:26:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:26:08 INFO - 'SHELL': '/bin/bash', 22:26:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.89ywlbnuOM/Listeners', 22:26:08 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 22:26:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:26:08 INFO - 'USER': 'cltbld', 22:26:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:26:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:26:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:26:08 INFO - 'XPC_FLAGS': '0x0', 22:26:08 INFO - 'XPC_SERVICE_NAME': '0', 22:26:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:26:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:26:08 INFO - Downloading/unpacking psutil>=3.1.1 22:26:08 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 22:26:08 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 22:26:08 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 22:26:08 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 22:26:08 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 22:26:08 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 22:26:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:26:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:26:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:26:12 INFO - Installing collected packages: psutil 22:26:12 INFO - Running setup.py install for psutil 22:26:12 INFO - building 'psutil._psutil_osx' extension 22:26:12 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 22:26:13 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 22:26:13 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 22:26:13 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 22:26:13 INFO - building 'psutil._psutil_posix' extension 22:26:13 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 22:26:13 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 22:26:13 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 22:26:13 INFO - ^ 22:26:13 INFO - 1 warning generated. 22:26:13 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 22:26:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:26:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:26:13 INFO - Successfully installed psutil 22:26:13 INFO - Cleaning up... 22:26:13 INFO - Return code: 0 22:26:13 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:26: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')]} 22:26:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:26:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:26: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')]} 22:26:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:26:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:26: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105f28a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105f2e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97324bd010>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105dccd30>, '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 0x105bf0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105dc3b20>, '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.pCLH78Ko2X/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.89ywlbnuOM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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 22:26: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 22:26: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 mozsystemmonitor==0.3 22:26:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pCLH78Ko2X/Render', 22:26:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:26:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:26:13 INFO - 'HOME': '/Users/cltbld', 22:26:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:26:13 INFO - 'LOGNAME': 'cltbld', 22:26:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:26:13 INFO - 'MOZ_NO_REMOTE': '1', 22:26:13 INFO - 'NO_EM_RESTART': '1', 22:26:13 INFO - 'PAGER': '/bin/cat', 22:26:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:26:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:26:13 INFO - 'PWD': '/builds/slave/test', 22:26:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:26:13 INFO - 'SHELL': '/bin/bash', 22:26:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.89ywlbnuOM/Listeners', 22:26:13 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 22:26:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:26:13 INFO - 'USER': 'cltbld', 22:26:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:26:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:26:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:26:13 INFO - 'XPC_FLAGS': '0x0', 22:26:13 INFO - 'XPC_SERVICE_NAME': '0', 22:26:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:26:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:26:13 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:26: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 22:26: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 22:26: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 22:26: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 22:26: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 22:26: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 22:26:16 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:26:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:26:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:26:16 INFO - Installing collected packages: mozsystemmonitor 22:26:16 INFO - Running setup.py install for mozsystemmonitor 22:26:16 INFO - Successfully installed mozsystemmonitor 22:26:16 INFO - Cleaning up... 22:26:16 INFO - Return code: 0 22:26:16 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:26:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:26:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:26:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:26:17 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')]} 22:26:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:26:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:26:17 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 0x105f28a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105f2e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97324bd010>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105dccd30>, '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 0x105bf0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105dc3b20>, '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.pCLH78Ko2X/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.89ywlbnuOM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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 22:26:17 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 22:26:17 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 22:26:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pCLH78Ko2X/Render', 22:26:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:26:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:26:17 INFO - 'HOME': '/Users/cltbld', 22:26:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:26:17 INFO - 'LOGNAME': 'cltbld', 22:26:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:26:17 INFO - 'MOZ_NO_REMOTE': '1', 22:26:17 INFO - 'NO_EM_RESTART': '1', 22:26:17 INFO - 'PAGER': '/bin/cat', 22:26:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:26:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:26:17 INFO - 'PWD': '/builds/slave/test', 22:26:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:26:17 INFO - 'SHELL': '/bin/bash', 22:26:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.89ywlbnuOM/Listeners', 22:26:17 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 22:26:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:26:17 INFO - 'USER': 'cltbld', 22:26:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:26:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:26:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:26:17 INFO - 'XPC_FLAGS': '0x0', 22:26:17 INFO - 'XPC_SERVICE_NAME': '0', 22:26:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:26:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:26:17 INFO - Downloading/unpacking blobuploader==1.2.4 22:26: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 22:26: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 22:26: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 22:26: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 22:26: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 22:26: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 22:26:19 INFO - Downloading blobuploader-1.2.4.tar.gz 22:26:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:26:19 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:26:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:26:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:26:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:26:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:26:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:26:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:26:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:26:20 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:26:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:26:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:26:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:26:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:26:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:26:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:26:20 INFO - Downloading docopt-0.6.1.tar.gz 22:26:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:26:20 INFO - Installing collected packages: blobuploader, requests, docopt 22:26:20 INFO - Running setup.py install for blobuploader 22:26:21 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:26:21 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:26:21 INFO - Running setup.py install for requests 22:26:21 INFO - Running setup.py install for docopt 22:26:21 INFO - Successfully installed blobuploader requests docopt 22:26:21 INFO - Cleaning up... 22:26:21 INFO - Return code: 0 22:26:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:26:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:26:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:26:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:26:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:26:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:26:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:26:21 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 0x105f28a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105f2e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97324bd010>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105dccd30>, '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 0x105bf0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105dc3b20>, '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.pCLH78Ko2X/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.89ywlbnuOM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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 22:26:21 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 22:26:21 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 22:26:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pCLH78Ko2X/Render', 22:26:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:26:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:26:21 INFO - 'HOME': '/Users/cltbld', 22:26:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:26:21 INFO - 'LOGNAME': 'cltbld', 22:26:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:26:21 INFO - 'MOZ_NO_REMOTE': '1', 22:26:21 INFO - 'NO_EM_RESTART': '1', 22:26:21 INFO - 'PAGER': '/bin/cat', 22:26:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:26:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:26:21 INFO - 'PWD': '/builds/slave/test', 22:26:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:26:21 INFO - 'SHELL': '/bin/bash', 22:26:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.89ywlbnuOM/Listeners', 22:26:21 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 22:26:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:26:21 INFO - 'USER': 'cltbld', 22:26:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:26:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:26:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:26:21 INFO - 'XPC_FLAGS': '0x0', 22:26:21 INFO - 'XPC_SERVICE_NAME': '0', 22:26:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:26:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:26:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:26:22 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-4rIyTR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:26:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:26:22 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-Wlm9nO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:26:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:26:22 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-5QoOkY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:26:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:26:22 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-5t3I3V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:26:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:26:22 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-frRuJ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:26:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:26:22 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-dmIcKC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:26:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:26:22 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-I1Ibxd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:26:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:26:22 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-SctPK8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:26:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:26:22 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-oET9qm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:26:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:26:23 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-GvSoMF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:26:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:26:23 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-EO76G7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:26:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:26:23 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-ZD_MGl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:26:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:26:23 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-qTQFun-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:26:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:26:23 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-fKC4az-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:26:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:26:23 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-R_Z0IY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:26:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:26:23 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-AkHcML-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:26:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:26:23 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-KkG2k4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:26:23 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:26:23 INFO - Running setup.py install for manifestparser 22:26:23 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:26:23 INFO - Running setup.py install for mozcrash 22:26:24 INFO - Running setup.py install for mozdebug 22:26:24 INFO - Running setup.py install for mozdevice 22:26:24 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:26:24 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:26:24 INFO - Running setup.py install for mozfile 22:26:24 INFO - Running setup.py install for mozhttpd 22:26:24 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:26:24 INFO - Running setup.py install for mozinfo 22:26:24 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:26:24 INFO - Running setup.py install for mozInstall 22:26:24 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:26:24 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:26:24 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:26:24 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:26:25 INFO - Running setup.py install for mozleak 22:26:25 INFO - Running setup.py install for mozlog 22:26:25 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:26:25 INFO - Running setup.py install for moznetwork 22:26:25 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:26:25 INFO - Running setup.py install for mozprocess 22:26:25 INFO - Running setup.py install for mozprofile 22:26:25 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:26:25 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:26:25 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:26:25 INFO - Running setup.py install for mozrunner 22:26:25 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:26:25 INFO - Running setup.py install for mozscreenshot 22:26:26 INFO - Running setup.py install for moztest 22:26:26 INFO - Running setup.py install for mozversion 22:26:26 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:26:26 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:26:26 INFO - Cleaning up... 22:26:26 INFO - Return code: 0 22:26:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:26:26 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')]} 22:26:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:26:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:26:26 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')]} 22:26:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:26:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:26:26 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 0x105f28a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105f2e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97324bd010>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105dccd30>, '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 0x105bf0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105dc3b20>, '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.pCLH78Ko2X/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.89ywlbnuOM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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 22:26:26 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 22:26:26 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 22:26:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pCLH78Ko2X/Render', 22:26:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:26:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:26:26 INFO - 'HOME': '/Users/cltbld', 22:26:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:26:26 INFO - 'LOGNAME': 'cltbld', 22:26:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:26:26 INFO - 'MOZ_NO_REMOTE': '1', 22:26:26 INFO - 'NO_EM_RESTART': '1', 22:26:26 INFO - 'PAGER': '/bin/cat', 22:26:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:26:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:26:26 INFO - 'PWD': '/builds/slave/test', 22:26:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:26:26 INFO - 'SHELL': '/bin/bash', 22:26:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.89ywlbnuOM/Listeners', 22:26:26 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 22:26:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:26:26 INFO - 'USER': 'cltbld', 22:26:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:26:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:26:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:26:26 INFO - 'XPC_FLAGS': '0x0', 22:26:26 INFO - 'XPC_SERVICE_NAME': '0', 22:26:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:26:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:26:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:26:26 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-X0tcvz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:26:26 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)) 22:26:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:26:26 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-Fa4GUf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:26:26 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)) 22:26:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:26:26 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-rUEbyl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:26:26 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)) 22:26:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:26:27 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-AQPFDI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:26:27 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)) 22:26:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:26:27 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-LFNGk7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:26:27 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)) 22:26:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:26:27 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-qqZwQm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:26:27 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)) 22:26:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:26:27 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-urFI4A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:26:27 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)) 22:26:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:26:27 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-7kALbG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:26:27 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)) 22:26:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:26:27 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-ADSflt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:26:27 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)) 22:26:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:26:27 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-ycgZEf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:26:27 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)) 22:26:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:26:27 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-uNM_sX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:26:27 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)) 22:26:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:26:27 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-ycFZEw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:26:27 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)) 22:26:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:26:27 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-OrjtGZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:26:28 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)) 22:26:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:26:28 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-KMrQgN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:26:28 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)) 22:26:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:26:28 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-ToffKR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:26:28 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)) 22:26:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:26:28 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-dbQQXw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:26:28 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)) 22:26:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:26:28 INFO - Running setup.py (path:/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/pip-mwpRRE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:26:28 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)) 22:26:28 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)) 22:26:28 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)) 22:26:28 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)) 22:26:28 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)) 22:26:28 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)) 22:26:28 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:26:28 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 22:26:28 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 22:26:28 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 22:26:28 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 22:26:28 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 22:26:28 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 22:26:30 INFO - Downloading blessings-1.6.tar.gz 22:26:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:26:31 INFO - Installing collected packages: blessings 22:26:31 INFO - Running setup.py install for blessings 22:26:31 INFO - Successfully installed blessings 22:26:31 INFO - Cleaning up... 22:26:31 INFO - Return code: 0 22:26:31 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:26:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:26:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:26:31 INFO - Reading from file tmpfile_stdout 22:26:31 INFO - Current package versions: 22:26:31 INFO - blessings == 1.6 22:26:31 INFO - blobuploader == 1.2.4 22:26:31 INFO - docopt == 0.6.1 22:26:31 INFO - manifestparser == 1.1 22:26:31 INFO - mozInstall == 1.12 22:26:31 INFO - mozcrash == 0.17 22:26:31 INFO - mozdebug == 0.1 22:26:31 INFO - mozdevice == 0.48 22:26:31 INFO - mozfile == 1.2 22:26:31 INFO - mozhttpd == 0.7 22:26:31 INFO - mozinfo == 0.9 22:26:31 INFO - mozleak == 0.1 22:26:31 INFO - mozlog == 3.2 22:26:31 INFO - moznetwork == 0.27 22:26:31 INFO - mozprocess == 0.22 22:26:31 INFO - mozprofile == 0.28 22:26:31 INFO - mozrunner == 6.11 22:26:31 INFO - mozscreenshot == 0.1 22:26:31 INFO - mozsystemmonitor == 0.3 22:26:31 INFO - moztest == 0.7 22:26:31 INFO - mozversion == 1.4 22:26:31 INFO - psutil == 3.1.1 22:26:31 INFO - requests == 1.2.3 22:26:31 INFO - wsgiref == 0.1.2 22:26:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:26:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:26:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:26:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:26:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:26:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:26:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:26:31 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 0x105f28a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105f2e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97324bd010>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105dccd30>, '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 0x105bf0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105dc3b20>, '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.pCLH78Ko2X/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.89ywlbnuOM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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 22:26:31 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 22:26:31 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 22:26:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pCLH78Ko2X/Render', 22:26:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:26:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:26:31 INFO - 'HOME': '/Users/cltbld', 22:26:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:26:31 INFO - 'LOGNAME': 'cltbld', 22:26:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:26:31 INFO - 'MOZ_NO_REMOTE': '1', 22:26:31 INFO - 'NO_EM_RESTART': '1', 22:26:31 INFO - 'PAGER': '/bin/cat', 22:26:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:26:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:26:31 INFO - 'PWD': '/builds/slave/test', 22:26:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:26:31 INFO - 'SHELL': '/bin/bash', 22:26:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.89ywlbnuOM/Listeners', 22:26:31 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 22:26:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:26:31 INFO - 'USER': 'cltbld', 22:26:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:26:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:26:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:26:31 INFO - 'XPC_FLAGS': '0x0', 22:26:31 INFO - 'XPC_SERVICE_NAME': '0', 22:26:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:26:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:26:31 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)) 22:26:31 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)) 22:26:31 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)) 22:26:31 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)) 22:26:31 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)) 22:26:31 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)) 22:26:31 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)) 22:26:31 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)) 22:26:31 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)) 22:26:31 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)) 22:26:31 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)) 22:26:31 INFO - Cleaning up... 22:26:31 INFO - Return code: 0 22:26:31 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:26:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:26:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:26:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:26:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:26:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:26:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:26:31 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 0x105f28a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105f2e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97324bd010>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105dccd30>, '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 0x105bf0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105dc3b20>, '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.pCLH78Ko2X/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.89ywlbnuOM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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 22:26:31 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 22:26:31 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 22:26:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pCLH78Ko2X/Render', 22:26:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:26:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:26:31 INFO - 'HOME': '/Users/cltbld', 22:26:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:26:31 INFO - 'LOGNAME': 'cltbld', 22:26:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:26:31 INFO - 'MOZ_NO_REMOTE': '1', 22:26:31 INFO - 'NO_EM_RESTART': '1', 22:26:31 INFO - 'PAGER': '/bin/cat', 22:26:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:26:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:26:31 INFO - 'PWD': '/builds/slave/test', 22:26:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:26:31 INFO - 'SHELL': '/bin/bash', 22:26:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.89ywlbnuOM/Listeners', 22:26:31 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 22:26:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:26:31 INFO - 'USER': 'cltbld', 22:26:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:26:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:26:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:26:31 INFO - 'XPC_FLAGS': '0x0', 22:26:31 INFO - 'XPC_SERVICE_NAME': '0', 22:26:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:26:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:26:32 INFO - Downloading/unpacking jsonschema 22:26: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 22:26: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 22:26: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 22:26: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 22:26: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 22:26: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 22:26:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:26:34 INFO - Downloading/unpacking functools32 (from jsonschema) 22:26:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:26:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:26:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:26:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:26:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:26:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:26:34 INFO - Downloading functools32-3.2.3-2.tar.gz 22:26:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:26:35 INFO - warning: no files found matching '*.txt' 22:26:35 INFO - no previously-included directories found matching 'build' 22:26:35 INFO - no previously-included directories found matching 'dist' 22:26:35 INFO - no previously-included directories found matching '.git*' 22:26:35 INFO - Installing collected packages: jsonschema, functools32 22:26:35 INFO - Running setup.py install for functools32 22:26:35 INFO - warning: no files found matching '*.txt' 22:26:35 INFO - no previously-included directories found matching 'build' 22:26:35 INFO - no previously-included directories found matching 'dist' 22:26:35 INFO - no previously-included directories found matching '.git*' 22:26:35 INFO - Successfully installed jsonschema functools32 22:26:35 INFO - Cleaning up... 22:26:35 INFO - Return code: 0 22:26:35 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 22:26:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:26:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:26:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:26:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:26:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:26:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:26:35 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 0x105f28a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105f2e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f97324bd010>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105dccd30>, '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 0x105bf0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105dc3b20>, '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.pCLH78Ko2X/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.89ywlbnuOM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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 22:26:35 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 22:26:35 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 22:26:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pCLH78Ko2X/Render', 22:26:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:26:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:26:35 INFO - 'HOME': '/Users/cltbld', 22:26:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:26:35 INFO - 'LOGNAME': 'cltbld', 22:26:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:26:35 INFO - 'MOZ_NO_REMOTE': '1', 22:26:35 INFO - 'NO_EM_RESTART': '1', 22:26:35 INFO - 'PAGER': '/bin/cat', 22:26:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:26:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:26:35 INFO - 'PWD': '/builds/slave/test', 22:26:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:26:35 INFO - 'SHELL': '/bin/bash', 22:26:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.89ywlbnuOM/Listeners', 22:26:35 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 22:26:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:26:35 INFO - 'USER': 'cltbld', 22:26:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:26:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:26:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:26:35 INFO - 'XPC_FLAGS': '0x0', 22:26:35 INFO - 'XPC_SERVICE_NAME': '0', 22:26:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:26:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:26:35 INFO - Downloading/unpacking flake8 22:26: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 22:26: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 22:26: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 22:26: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 22:26: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 22:26: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 22:26:37 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 22:26:38 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 22:26:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:26:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:26:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:26:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:26:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:26:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:26:38 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 22:26:38 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 22:26:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:26:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:26:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:26:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:26:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:26:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:26:38 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 22:26:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:26:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:26:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:26:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:26:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:26:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:26:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 22:26:39 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:26:39 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:26:39 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:26:39 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:26:39 INFO - no previously-included directories found matching 'docs/_build' 22:26:39 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 22:26:39 INFO - Running setup.py install for pep8 22:26:39 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:26:39 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:26:39 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:26:39 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:26:39 INFO - no previously-included directories found matching 'docs/_build' 22:26:39 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 22:26:39 INFO - Successfully installed flake8 mccabe pyflakes pep8 22:26:39 INFO - Cleaning up... 22:26:39 INFO - Return code: 0 22:26:39 INFO - Running post-action listener: _resource_record_post_action 22:26:39 INFO - Running post-action listener: _start_resource_monitoring 22:26:39 INFO - Starting resource monitoring. 22:26:39 INFO - ##### 22:26:39 INFO - ##### Finished create-virtualenv step (success) 22:26:39 INFO - ##### 22:26:39 INFO - ##### 22:26:39 INFO - ##### Running install step. 22:26:39 INFO - ##### 22:26:39 INFO - Running pre-action listener: _resource_record_pre_action 22:26:39 INFO - Running main action method: install 22:26:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:26:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:26:39 INFO - Reading from file tmpfile_stdout 22:26:39 INFO - Detecting whether we're running mozinstall >=1.0... 22:26:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:26:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:26:40 INFO - Reading from file tmpfile_stdout 22:26:40 INFO - Output received: 22:26:40 INFO - Usage: mozinstall [options] installer 22:26:40 INFO - Options: 22:26:40 INFO - -h, --help show this help message and exit 22:26:40 INFO - -d DEST, --destination=DEST 22:26:40 INFO - Directory to install application into. [default: 22:26:40 INFO - "/builds/slave/test"] 22:26:40 INFO - --app=APP Application being installed. [default: firefox] 22:26:40 INFO - mkdir: /builds/slave/test/build/application 22:26:40 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'] 22:26:40 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 22:27:13 INFO - Reading from file tmpfile_stdout 22:27:13 INFO - Output received: 22:27:13 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 22:27:13 INFO - Running post-action listener: _resource_record_post_action 22:27:13 INFO - ##### 22:27:13 INFO - ##### Finished install step (success) 22:27:13 INFO - ##### 22:27:13 INFO - ##### 22:27:13 INFO - ##### Running run-tests step. 22:27:13 INFO - ##### 22:27:13 INFO - Running pre-action listener: _resource_record_pre_action 22:27:13 INFO - Running main action method: run_tests 22:27:13 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:27:13 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:27:13 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:27:13 INFO - 2016-05-25 22:27:13.409 screenresolution[1643:8320] starting screenresolution argv=screenresolution get 22:27:13 INFO - 2016-05-25 22:27:13.412 screenresolution[1643:8320] Display 0: 1600x1200x32@60 22:27:13 INFO - 2016-05-25 22:27:13.418 screenresolution[1644:8323] starting screenresolution argv=screenresolution list 22:27:13 INFO - Available Modes on Display 0 22:27:13 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:27:13 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:27:13 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:27:13 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:27:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:27:13 INFO - Intel Iris: 22:27:13 INFO - Chipset Model: Intel Iris 22:27:13 INFO - Type: GPU 22:27:13 INFO - Bus: Built-In 22:27:13 INFO - VRAM (Dynamic, Max): 1536 MB 22:27:13 INFO - Vendor: Intel (0x8086) 22:27:13 INFO - Device ID: 0x0a2e 22:27:13 INFO - Revision ID: 0x0009 22:27:13 INFO - Displays: 22:27:13 INFO - Display: 22:27:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:27:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:27:13 INFO - Display Serial Number: 1600x1200 60 22:27:13 INFO - Main Display: Yes 22:27:13 INFO - Mirror: Off 22:27:13 INFO - Online: Yes 22:27:13 INFO - Rotation: Supported 22:27:13 INFO - Return code: 0 22:27:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:27:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:27:13 INFO - Python 2.7.3 22:27:13 INFO - Return code: 0 22:27:13 INFO - grabbing minidump binary from tooltool 22:27: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')]} 22:27:13 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 0x105dccd30>, '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 0x105bf0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105dc3b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:27:13 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 22:27:13 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 22:27:13 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 22:27:13 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:27:13 INFO - Return code: 0 22:27:13 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 22:27:13 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:27:13 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:27:13 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 22:27:13 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:27:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 22:27:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 22:27:14 INFO - Return code: 0 22:27:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/R7anaQXgTwKgFY0a0bDjjA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0027', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 22:27:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/R7anaQXgTwKgFY0a0bDjjA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0027 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 22:27:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pCLH78Ko2X/Render', 22:27:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:27:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:27:14 INFO - 'HOME': '/Users/cltbld', 22:27:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:27:14 INFO - 'LOGNAME': 'cltbld', 22:27:14 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:27:14 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 22:27:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:27:14 INFO - 'MOZ_NO_REMOTE': '1', 22:27:14 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:27:14 INFO - 'NO_EM_RESTART': '1', 22:27:14 INFO - 'PAGER': '/bin/cat', 22:27:14 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:27:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:27:14 INFO - 'PWD': '/builds/slave/test', 22:27:14 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:27:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:27:14 INFO - 'SHELL': '/bin/bash', 22:27:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.89ywlbnuOM/Listeners', 22:27:14 INFO - 'TMPDIR': '/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/', 22:27:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:27:14 INFO - 'USER': 'cltbld', 22:27:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:27:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:27:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:27:14 INFO - 'XPC_FLAGS': '0x0', 22:27:14 INFO - 'XPC_SERVICE_NAME': '0', 22:27:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:27:14 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/R7anaQXgTwKgFY0a0bDjjA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0027', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 22:27:15 INFO - mozversion application_buildid: 20160525204851 22:27:15 INFO - mozversion application_changeset: 25566a14a7ac98674bfe9b6e73edc59fcc286c22 22:27:15 INFO - mozversion application_display_name: Nightly 22:27:15 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:27:15 INFO - mozversion application_name: Firefox 22:27:15 INFO - mozversion application_remotingname: firefox 22:27:15 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 22:27:15 INFO - mozversion application_vendor: Mozilla 22:27:15 INFO - mozversion application_version: 49.0a1 22:27:15 INFO - mozversion platform_buildid: 20160525204851 22:27:15 INFO - mozversion platform_changeset: 25566a14a7ac98674bfe9b6e73edc59fcc286c22 22:27:15 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 22:27:15 INFO - mozversion platform_version: 49.0a1 22:27:15 INFO - using testdate: 1464240435 22:27:15 INFO - actual date: 1464240435 22:27:15 INFO - starting webserver on 'localhost:49277' 22:27:15 INFO - SUITE-START | Running 2 tests 22:27:15 INFO - TEST-START | dromaeo_css 22:27:15 INFO - operating with platform_type : mac_ 22:27:15 INFO - Initialising browser for dromaeo_css test... 22:27:15 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/tmp9kVsxM/profile http://localhost:49277/getInfo.html) 22:27:17 INFO - PROCESS | 1662 | __metrics Screen width/height:1600/1200 22:27:17 INFO - PROCESS | 1662 | colorDepth:24 22:27:17 INFO - PROCESS | 1662 | Browser inner width/height: 1024/689 22:27:17 INFO - PROCESS | 1662 | __metrics 22:27:17 INFO - PROCESS | 1662 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 22:27:17 ERROR - PROCESS | 1662 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 22:27:18 INFO - TEST-INFO | 1662: exit 0 22:27:18 INFO - Browser initialized. 22:27:18 INFO - Running cycle 1/1 for dromaeo_css test... 22:27:18 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/tmp9kVsxM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:27:30 INFO - PROCESS | 1665 | RSS: Main: 241586176 22:27:30 INFO - PROCESS | 1665 | 22:28:22 INFO - PROCESS | 1665 | 1464240502120 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] 22:30:33 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49277/tests/dromaeo/cssquery-ext.html) 22:30:34 INFO - PROCESS | 1665 | RSS: Main: 374501376 22:30:34 INFO - PROCESS | 1665 | 22:33:37 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-ext.html (next: http://localhost:49277/tests/dromaeo/cssquery-jquery.html) 22:33:38 INFO - PROCESS | 1665 | RSS: Main: 377098240 22:33:38 INFO - PROCESS | 1665 | 22:36:40 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49277/tests/dromaeo/cssquery-mootools.html) 22:36:40 INFO - PROCESS | 1665 | RSS: Main: 376471552 22:36:40 INFO - PROCESS | 1665 | 22:39:43 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49277/tests/dromaeo/cssquery-prototype.html) 22:39:43 INFO - PROCESS | 1665 | RSS: Main: 372719616 22:39:43 INFO - PROCESS | 1665 | 22:42:47 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49277/tests/dromaeo/cssquery-yui.html) 22:42:48 INFO - PROCESS | 1665 | RSS: Main: 344055808 22:42:48 INFO - PROCESS | 1665 | 22:45:50 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-yui.html (next: http://localhost:49277/tests/dromaeo/cssquery-dojo.html) 22:45:51 INFO - PROCESS | 1665 | RSS: Main: 379830272 22:45:51 INFO - PROCESS | 1665 | 22:45:51 INFO - PROCESS | 1665 | __start_tp_report 22:45:51 INFO - PROCESS | 1665 | _x_x_mozilla_page_load 22:45:51 INFO - PROCESS | 1665 | _x_x_mozilla_page_load_details 22:45:51 INFO - PROCESS | 1665 | |i|pagename|runs| 22:45:51 INFO - PROCESS | 1665 | |0;dojo.html;3490.1449144959474;3496.772709773865;3519.982400088;3527.7001454876367;3572.3034008368363;5640.858978525539;5646.096624540074;5657.321121465425;5753.079507278836;5765.538756899448;5694.145878118285;5790.247267855183;5815.185873977636;5825.533957283418;5863.003289440795;5146.099432599299;5158.742062896859;5191.584673226152;5230.450802665722;5342;5286.814961476349;5322.441143679896;5329.546988505982;5358.866028349284;5372.785088596471;5141.82003629873;5148.665336753099;5168.948310516885;5218.295530103444;5256.789728410859;5186.5850731941355;5202.557782588485;5204.895902081975;5218.921716174261;5231.9476805232225;5179.922301165486;5208.427073021964;5252.054630166568;5278.841634750964;5305.602079843989;5573.303337082864;5619.634723742944;5678.006348888969;5711.371749107595;5716.428357164292;6224.066390041485;6278.215223097113;6281.309056003882;6303.5902666327;6305.653189074599;5210;6245.687715614201;6250.7812226572305;6255.812325630239;6271.8745625087695;5565.2486914266465;5778.162166485878;5851.210086638334;5903.055511118243;5912.497437717756;6331.113644089831;6353.650549219838;6370;6376.7130479128555;6387.520935929824;6015.248093988252;6192.0711893215475;6205.069239614094;6206.627602343874;6214.782482613087;6142.692865356715;6194.38056194377;6234.781782637586;6243.719032643451;6257.155284036688;6138.235132557318;6145.740123274718;6194.4115309045565;6223;6259.6870156492905;6241.625502469867;6250.999840025665;6256.593321434092;6274.529410294246;6289.433950944437;4805.8077676892535;4908.779104940286;4939.382577177853;4949.381327334083;4977.278294647328;4412.117576484716;4422.823087076481;4501.167284052461;4501.482329532146;4508.278675411918;5736.426357364314;5746.88506229873;5773.220615216976;5804.158397032491;5810.709464526757;6188.938110618837;6234.438900498977;6241.781537646251;6258.530610204162;6264.780732674334;5785.652860828363;5808.854778630568;5809.186713860063;5831.329397119301;5888.352281249059;5294.258803767476;5372.543333816591;5380.219868119101;5436.782528698808;5437.374701909252;6245.594036387621;6343.238811342669;6396.200474940632;6402.967985160044;6493.577917435352;6168.938310616836;6230.096635987666;6236.03341482071;6244.968775156095;6245.28179259391;6224.346443623354;6488.416042556004;6489.8702025960165;6490.286068532552;6495.675216239264;6038;6099.725512352044;6111.633302001894;6146.955017646921;6150.554619664285;6144.754209831735;6145.539084568586;6159.81520554384;6173.012318029094;6179.752809887734;5924.940750592439;5936.465718085393;5987.820365389045;5996.280446346292;6001.36985616504;6019.879602408015;6116.3883611638485;6126.387361263838;6143.38905964972;6216.906746398718;6059.21230240066;6081.1486391906055;6094.605511174926;6120.510359171167;6134.263888333428;6014.789482368096;6183.072539119168;6195.070739388947;6220.160278362544;6230.501559875109;5844.444777746107;5848.53211743068;5969.910451343147;6034.456898879122;6041.6375017499095 22:45:51 INFO - PROCESS | 1665 | |1;ext.html;505.45158503024214;516.2591680937852;519.4675457655904;529.7774934527498;559.9920143741266;30796.84601576992;41995;43237.35143972843;43239.35140972888;43286.56713432869;43953.68138955828;44601.776991115;44618.55381446185;44651.77674111633;44884.775576122156;8624.137586241388;9473.242140628767;9705.02949705031;9707.223422126248;9709.223262139047;8667.69663061793;8800.603972821238;8806.911930880673;8829.735107946739;11599;11512.13658975576;11889.940550297237;11891;11891.286522808616;11926.165168438212;11940.820887686692;12076.516939322417;12083.697907552294;12091.45588448513;12092.697682557919;8686.78283042929;11470.369129697863;11721.94139029308;11724.706882327924;11881.702957426047;41166.38250426246;41603;41685.37471937924;41770.373444398356;41781;3103.8137711737368;3171.9682803172136;3203.0711093782775;3218.903432897017;3362.478815783533;28458.857705711547;28672;28931.56602650962;29242.85378573115;29618.555721663975;2576.9613455798176;2700.6894207166233;2730.890764369423;2757.993332433647;2858.428314337141;506689;508487.20294789877;508940;509400;509458;62031.449213770014;63148.05277920835;63266.68366658229;63357.683211583644;63381.68309158517;66349.66825165843;66424;66497;66555.66722166455;66750.33249667441;16301.347946082262;16859.83140168607;16863.915680421764;16868.24092915822;16869.746953795704;10784.676459706221;12942.223466592035;12983.610491685076;12988.285644289655;12997.480100795863;14783.482578109715;15137.167455790037;16663.16684165787;17050.868641419824;17109.23008464622;5994.07091900756;7067.858642827217;7084.822879427952;7127.92872071283;7163.10461192351;729.5882467425548;981.7349315684725;994.8955359687274;1003.6487229469627;1032.1988130711957;29136.83452661912;29379.412411751644;29467.263318417212;30290.394192116033;30298.69701302959;1079.7516571188671;1096.0464395975393;1104.3539529375405;1149.166854030821;1150.775598758234;14686.192259425834;15180.089194648357;15208;15283.61790955235;15617.375304987674;184268;184453;185141;185193;185700.07149964164;61990;62006.68996654988;62039.37960620426;62047.6897615509;62248.06627900585;1285.7428514297178;1327.2633688303085;1428.221619217529;1436.2100844535464;1447.9203643799692;29344.26639334034;30081.69918300789;30649.54025689661;30665;32139.35721285608;60034.7993040128;61215;61273.38726612677;61328;61366.63600367984;626036;654134;654292;654705.7264713837;655778;37413.625863742105;41868.3719744198;42569;43895;44263.336049958634;45706.771466142454;46169;47789.80525486891;47854.521454785005;47916.28125578189;37187.25625487421;38134;39232.80383598064;39863.2027359457;40068.7996560027;37621;38130.23739525139;38134.42798358083;38711.6128838708;38783.612163878;37568.81215593904;37819.43270850994;37997.81001094977;38472;38519.42220866633;31746.365072698878;33351.66648333583;34142;34178.82910585531;34293.82853085719;20621.484462888548;24923.252302430956;24958;24984.875075625234;25146 22:45:51 INFO - PROCESS | 1665 | |2;jquery.html;381.59550876071364;383.4631515877771;384.40417353102686;402.3602472069409;410.4581951823594;19827.603447931033;21875.453113672167;22153.66769498459;22286.665700014513;22320.88839555802;20966.05652745626;21093.15627374907;21640.675389869164;21667.241646542374;22008.88995555024;4239.385289133066;6027.578069535134;6178.567500274982;6188.226471691039;6211.937880621181;4222.577742225783;4465.218586747323;6046.21399218101;6432.13166222561;6782.694778734969;6664.966675166617;6776.322367763233;6780.694868730919;6791.898121528181;6796.524243302971;6574.276829548746;6752.864942701119;6764.0530325754435;6766.661666916659;6779.796606101825;5617.719114044281;5853.88292234153;5901.262342207224;5901.557383196277;5909.084091965752;26081.60877586838;26440.47119057608;26637.46725065507;27074.593881091798;27382.178534643994;2034.3185033013958;2065.979340206594;2126.6597344424817;2247.0674670011927;2310.849794763335;6835.692393842289;6907.965460172667;14679.339429725687;14797.632378819444;14893.702125957529;1686.8819182657219;1733.9306427742874;1741.7126174181371;1825.4249911277905;1829.5791967847338;106604;108122.45938770335;110030.44984775105;116145;117804.41097794542;9241.537923103819;9838.557264923096;9943.204543636492;10046.894841567422;11470.426478676032;12317.692057698629;12323.69190770238;12380.00959923222;12424.813627795591;12433.626991190278;7531.397488200931;7570.924290757132;7589.620518974029;7669.079710434784;7710.036245469317;6501.544891857525;6601.339866013456;6973.6750017496515;7630.580318082448;7723.497972631873;3887.1253967857006;3907.5897030811357;3909.6481316681634;3958.129211573449;3965.9603403966166;1762;1784.1334036408189;1819.672458957374;1903.676375016223;1978.3768113044346;890.6036813618007;900.8873890763655;962.0956301077105;976.092234222166;1005.5676059294427;19717.211311547377;20659;20918.957277766316;20950;20957.476063098544;924.9676261330821;930.3812964378612;953.7472569769017;965.232640051161;965.6571916969515;10844.837327440095;10932.572308215185;11022.952819482136;11037.779244415067;11097.500612472459;576577;581610;587686;596366;605992;124699;126956;126970;127863;131511;941.4868896451451;972.2062794144791;985.1478471122571;998.825205589019;1001.329109496639;17831.10844457746;18097.547561310545;18106.547336316697;18176.545586360447;18376.081195940416;40378.59621403866;42128.57871421369;51284.435778210194;52151;52519.21221181609;53396.73301633467;73346.53306933725;74561;74932.25067749253;75119.36604754775;8062.879056814035;8113.959430202811;8145.877811832709;8258.132896045829;8462.746117616482;11483.770324593293;12509.937450312691;12511.687207819878;12766.553170638983;12900.483980640669;10825.350478971286;10928.453577321261;11012.669619911416;11083.168762342695;11292.209545331747;8890.511021893599;10794.892051079389;10885.183611229031;10894.455277235948;11041.889581104086;10884.476173335897;11061.059809916247;11091.33451992883;11526.07791376671;11563.30620162793;7055.682494287872;7930.444868859356;8139.959300203461;8160.18398160203;8178.345732341518;10652.62715804943;10898.746644136027;11009.31091302553;11012.889871101506;11169 22:45:51 INFO - PROCESS | 1665 | |3;mootools.html;1380.9930950345247;1534.7928029715988;1558.8051493563305;1668.2826384654602;1787.8748487605865;7704.499207551512;8503.914960850398;8721.258693011087;8735.519546424945;8844.159804818531;8462.746117616465;8665.263452606536;8667.306615470763;8670.219680228794;8674.175953284428;2098.90554925028;2166.9133234670594;2170.370592528165;2337.5909215887195;2379.2862141357523;1829.6798060339454;1905.4569447707406;2209.3924170853065;2407.277816655005;2439.609662454008;2327.1622216002224;2348.178137651825;2350.236173243694;2358.292512246328;2358.5282943411303;1934.7388102606108;2340.871252081135;2344.6834677318598;2345.1088586337255;2350.1421980976993;2092.3618296419736;2169.5877783221135;2173.076442511926;2176.673498975151;2297.689811875409;8519.659213631447;8701.651933922636;8702.912970870337;8709.433886797338;8729.388942774009;5189.42916279209;5338.572914166857;5338.62629615927;5347.786088556453;5353;2991.2521869532616;3102.239951211945;3390.8982730518123;3415.8121303328307;3419.2135808764124;942.4627962061628;956.6460409648405;959.0217977662815;962.7352478068588;973.3916302311055;11376.886231137665;11488.597899073577;11492.770144597062;11504.827427588592;11513.884861151366;4527.954720452819;4812.614990800727;4817.518248175154;4817.735024573612;4820.831270905502;10025.298229123891;10123.443210623487;10130.037646423578;10132.848007279898;10136.391816491034;5165.070287348313;5350.197470379474;5350.438203988603;5370.060239458079;5375.16684913839;4157.64660003897;4891.755412229374;4892.241702536113;4895.559399654048;4900.044491324229;3426;3931.744436611611;3964.682825373963;3995.6603688686205;4005.7396269242404;1272.8281681973;1279.1429742072648;1284.8843604075678;1293.5084667826166;1294.851092124399;884.9867251991225;896.9192772650492;898.5507246376811;912.1927094521396;944.4174106477839;5646.943530564642;5870.324912635102;5911.674857882857;5913.881722365614;6146.446819786241;893.9329550283754;917.7934964632905;957.4733896356976;960.9183219426287;970.8203982263162;3587.7667951583585;3889.3388124018984;3894.6300101490315;4041.1715598302867;4069.2471892699946;10313.226508011929;10727.40999245049;10730.570777168981;10737.946310268399;10762.300450470695;8236.382271329674;10515.789684206273;10521.368717876952;10661.62684306061;10667;8045.919540804634;8191.139930307351;8260.669573217125;8272.296854767292;8283.751487455385;5693.146028095819;5704.686242256716;5748.137779333133;5804.158397032491;6001.129836173817;9581.425114493153;9923.65267215644;9925.057119573628;9950.35322704022;9953.502324883582;9443.291753118681;9872.457014863965;9894.406335619886;10036.89963100389;10046.447445390573;6870.038194652652;7057.117860267466;7080.04419403374;7363.7422690205585;7587.165411804807;7743;7777.961110194413;7868.763937081897;8223.835523289381;8299.751007469786;7223.097112860893;7393.704251829867;7413.184549699421;7435.925640743523;7586.848263034819;7212.963935180291;7250.02124713157;7279.053723015974;7331.670074846751;7349.04462419882;6733.326667333227;6765.391114799691;6884.105066341343;6894.827629309107;6900.516963812485;3949.249642568018;4770.809167633256;4772.451168115711;4929.383827021622;4989;4872.123017856819;4893.241547559992;4894.094592500399;4894.632902532396;5030.622703297194 22:45:51 INFO - PROCESS | 1665 | |4;prototype.html;298.37192709346823;299.5596473184419;313.5328360742493;316.21105342171256;316.239444136851;5096.974515127419;5150.819721309755;5170.276161337416;5198.714070726109;5200.843974680756;4219.367094935761;4244.278472659647;4357.498887627924;4650.4186976627925;4917.680350777197;3361.940988588599;3479.652034796525;3967.9801600991955;3987.9800600996955;3999.940000899974;3939.98030009851;3972.5232972043395;3973.5231772187235;3974.8608798692053;4000.5599384067727;3131.405033043726;3712.83292251848;3795.4496597993275;3853.7880416577077;3913.6477717005464;3753.2868754936476;3911.3350730375905;3918.549366822809;3926.3901800261756;3935.4490371347893;3024.8221280677876;3429.6913277805115;3432.9313413731834;3444.7071998880124;3457.619661837196;4613.792379342937;4724.480307166209;4902.877428064291;4916.287138364953;4924.507549245082;4334.745932115283;4495.752733599684;4519.751413672247;4529.411176547061;4540.659450541222;4662.417197850269;5332.76002579881;5419.078756611346;5421.780099477625;5445.809396671137;3809.590451532923;3812.5234345706785;3831.731778775487;3834.6225340289197;3843.7117216208617;4597;5034.0686972910125;5667.319921609392;5733.741981610838;5734.913976290359;3428.6571342866014;3501.072215862798;3704.4258139988347;3759.5112635357227;3765.4728338032696;3539.1859872229015;3547.2728090741334;3792.791396473165;3832.3101841668768;3833.8083095845095;3936.303632012081;4289.335153051282;4298.505671847715;4365.563443655609;4488.236999710057;5447.972760136174;5451.536619387317;5457.645253058538;5501.312335958005;5511.58663100269;1262.6275248801626;1275.6747029507417;1322.444573279225;1322.6296636941672;1328.518701647656;789.2659826361539;795.777182388932;798.4490701416005;809.29591255608;828.2462958707547;4306.009617787926;4633.513481084505;4957.925631115536;5087.26234695967;5171.81898633546;4482.21561226784;4883.560479556857;5136.048301645351;5529.889402211933;5538.86152846174;4645.6980296280635;4880.95119048805;5054.09026375256;5067.885065285631;5085.720285384339;2445.792107670832;2460.5940019896875;2866.784991125674;2886.4515742008953;2917.0519581136214;5274.81095345435;5740.273370149229;5748.5688573357165;5754.367019627837;5805.970970145207;4875.269279405831;5404.540614047772;5657.20799088128;5835.679037652885;5852.970735146297;4988.002399520183;5092.439831618445;5146.511081447256;5599.720013999284;5761.135829625589;4987.486324875993;5313.946860531345;5353.785848566014;5401.972990135024;5684.431556844448;5575.581831362583;5576.741419725018;5582.972085139549;5584.776608935597;5618.747156378055;4770.783450220171;5174.8965020700125;5213.374395072616;5410.215518749838;5942.435468630473;3326.850291736926;3517.613062563065;3573.821308934491;3606.891793246207;3823.942640860445;4591.724496530219;5558.888822223452;5573.749181286771;5632.2678051853;5633.521150702072;5460.116259890331;5460.25271912984;5460.781568737206;5463.9180412294645;5528.143137813645;5178.818741344035;5288.651393894686;5324.520793128482;5432;5465.125579907196;4492.921698792332;5122.871928201825;5276.736163191902;5296.629235953446;5361.839144825662;4656.323723459484;5254.89490210186;5511.283533140666;5522.502974732293;5554.944450555605;4752.52474752522;4904.901901962012;5089.6691715038405;5199.584033277404;5368.3557973041925 22:45:51 INFO - PROCESS | 1665 | |5;yui.html;621.6332363905295;720.7801620505747;728.9817754556132;734.1557209209409;736.970521179153;12691.36543172841;14533.128012319256;15100.244987750624;15208.391664333427;15631.765523517128;14342.498012569562;14492.202928838937;14545.272736363166;14546;14569.053011554242;727.7052793618567;749.722602637025;906.2115959115562;909.295296145486;933.3886304470583;919.7792529792869;919.9632014719404;926.703454894434;927.661403587689;930.9162175404213;985.7239972807589;987.5407935310079;987.9555420006117;990.3810118675827;992.910638042576;764.8393837294147;940.6002448959233;968.9224862011022;971.6793458158861;977.2426369563574;728.3990707666144;728.5410191579324;729.2270193594807;731.2322061835105;731.3052600029939;14888.553343399608;14930.70138597222;14934.551963441114;14946.551603451913;14947;12055.397230138546;12089.214201076902;12177;12186.8753667001;12226.694332641666;10722.571097156182;10797.83803242952;10807.243492955497;11150.052245559056;11342.48958796848;3963.4649322341693;3963.54419241788;3974.82113304902;4000.5399379071196;4144.316187829005;69249;71035.93446098313;73456.63271683661;84442.31115377719;84796;9295.814083718287;9305.302102342352;9352.672656457127;9380.90619093814;10057.245706572035;20866.89566552188;20889.477763056046;20898.059587318607;20920.79079209219;20927.372178834354;7238.0952380952385;7280.817979550554;7313.780586582411;7488.438367122488;7546;3929.7249192556246;3985.92028159441;3995.0212198011673;3996.9200615988098;4030.2141082488633;3407.4037043517287;3503.386907291214;3503.7722548034294;3504.7546671732734;3505.386557352453;570.4124751505956;609.3997412548636;625.8623102917351;630.9558330916792;648.9837754056186;490.6344773143959;555.4836590246191;571.948524632785;576.2510779783338;591.0484120565885;6495.577787443801;7986.960065199637;7994.760157195294;8106.594670266463;8194.713185038614;556.6632187526569;574.968376739275;578.4649199490455;591.6864062047122;600.906859436788;3657.908552286214;3846.1538461538416;3921.450996860442;3925.646691797695;3986.6151558996607;76959.61520192475;79340.60329698314;79662;89181.6359282266;94403;75505;79451.8082228767;80359;80514.59742701249;81156.59421702854;69606.65196674086;69662;71491;72885.6355718218;73953;8078.798030049295;8098.595070246582;8244.54654993981;8340.958295208486;8357.414980951518;45333.32000019936;45778.73544542287;45800.39698610666;46074.53925460702;47046.76476617595;59842.40157598542;60449.69775151096;62428;63715.4071148225;63729;10637;10824.24230303871;11079.335239885633;11101.333919964827;11106.444677766242;9939.652112176329;11812.881871181178;11961.940190298992;12074;12196.512139514674;11180.832287515856;11341.149413793833;11354.26197297173;11495.712607184887;11587.768244635228;9092.454452732858;11207.551697931991;11216.887831121585;11217.607383741857;11533.077353811843;10781.083607893417;10919.071878890383;11080.390578517936;11128.44357782124;11292;9117.179453849185;9261.53692315368;9267.582958766738;9339;9573.234141268542;9033.548322583976;9238.214751745909;9589.328746987923;9727;9864.753381165528 22:45:51 INFO - PROCESS | 1665 | __end_tp_report 22:45:51 INFO - PROCESS | 1665 | __start_cc_report 22:45:51 INFO - PROCESS | 1665 | _x_x_mozilla_cycle_collect,1280 22:45:51 INFO - PROCESS | 1665 | __end_cc_report 22:45:51 INFO - PROCESS | 1665 | __startTimestamp1464241551251__endTimestamp 22:45:51 INFO - PROCESS | 1665 | 22:45:51 INFO - PROCESS | 1665 | ------- Summary: start ------- 22:45:51 INFO - PROCESS | 1665 | Number of tests: 6 22:45:51 INFO - PROCESS | 1665 | 22:45:51 INFO - PROCESS | 1665 | [#0] dojo.html Cycles:165 Average:5770.03 Median:5996.28 stddev:624.39 (10.4%) stddev-sans-first:600.14 22:45:51 INFO - PROCESS | 1665 | Values: 3490.1 3496.8 3520.0 3527.7 3572.3 5640.9 5646.1 5657.3 5753.1 5765.5 5694.1 5790.2 5815.2 5825.5 5863.0 5146.1 5158.7 5191.6 5230.5 5342.0 5286.8 5322.4 5329.5 5358.9 5372.8 5141.8 5148.7 5168.9 5218.3 5256.8 5186.6 5202.6 5204.9 5218.9 5231.9 5179.9 5208.4 5252.1 5278.8 5305.6 5573.3 5619.6 5678.0 5711.4 5716.4 6224.1 6278.2 6281.3 6303.6 6305.7 5210.0 6245.7 6250.8 6255.8 6271.9 5565.2 5778.2 5851.2 5903.1 5912.5 6331.1 6353.7 6370.0 6376.7 6387.5 6015.2 6192.1 6205.1 6206.6 6214.8 6142.7 6194.4 6234.8 6243.7 6257.2 6138.2 6145.7 6194.4 6223.0 6259.7 6241.6 6251.0 6256.6 6274.5 6289.4 4805.8 4908.8 4939.4 4949.4 4977.3 4412.1 4422.8 4501.2 4501.5 4508.3 5736.4 5746.9 5773.2 5804.2 5810.7 6188.9 6234.4 6241.8 6258.5 6264.8 5785.7 5808.9 5809.2 5831.3 5888.4 5294.3 5372.5 5380.2 5436.8 5437.4 6245.6 6343.2 6396.2 6403.0 6493.6 6168.9 6230.1 6236.0 6245.0 6245.3 6224.3 6488.4 6489.9 6490.3 6495.7 6038.0 6099.7 6111.6 6147.0 6150.6 6144.8 6145.5 6159.8 6173.0 6179.8 5924.9 5936.5 5987.8 5996.3 6001.4 6019.9 6116.4 6126.4 6143.4 6216.9 6059.2 6081.1 6094.6 6120.5 6134.3 6014.8 6183.1 6195.1 6220.2 6230.5 5844.4 5848.5 5969.9 6034.5 6041.6 22:45:51 INFO - PROCESS | 1665 | 22:45:51 INFO - PROCESS | 1665 | [#1] ext.html Cycles:180 Average:61231.42 Median:37594.91 stddev:131063.04 (348.6%) stddev-sans-first:131351.40 22:45:51 INFO - PROCESS | 1665 | Values: 505.5 516.3 519.5 529.8 560.0 30796.8 41995.0 43237.4 43239.4 43286.6 43953.7 44601.8 44618.6 44651.8 44884.8 8624.1 9473.2 9705.0 9707.2 9709.2 8667.7 8800.6 8806.9 8829.7 11599.0 11512.1 11889.9 11891.0 11891.3 11926.2 11940.8 12076.5 12083.7 12091.5 12092.7 8686.8 11470.4 11721.9 11724.7 11881.7 41166.4 41603.0 41685.4 41770.4 41781.0 3103.8 3172.0 3203.1 3218.9 3362.5 28458.9 28672.0 28931.6 29242.9 29618.6 2577.0 2700.7 2730.9 2758.0 2858.4 506689.0 508487.2 508940.0 509400.0 509458.0 62031.4 63148.1 63266.7 63357.7 63381.7 66349.7 66424.0 66497.0 66555.7 66750.3 16301.3 16859.8 16863.9 16868.2 16869.7 10784.7 12942.2 12983.6 12988.3 12997.5 14783.5 15137.2 16663.2 17050.9 17109.2 5994.1 7067.9 7084.8 7127.9 7163.1 729.6 981.7 994.9 1003.6 1032.2 29136.8 29379.4 29467.3 30290.4 30298.7 1079.8 1096.0 1104.4 1149.2 1150.8 14686.2 15180.1 15208.0 15283.6 15617.4 184268.0 184453.0 185141.0 185193.0 185700.1 61990.0 62006.7 62039.4 62047.7 62248.1 1285.7 1327.3 1428.2 1436.2 1447.9 29344.3 30081.7 30649.5 30665.0 32139.4 60034.8 61215.0 61273.4 61328.0 61366.6 626036.0 654134.0 654292.0 654705.7 655778.0 37413.6 41868.4 42569.0 43895.0 44263.3 45706.8 46169.0 47789.8 47854.5 47916.3 37187.3 38134.0 39232.8 39863.2 40068.8 37621.0 38130.2 38134.4 38711.6 38783.6 37568.8 37819.4 37997.8 38472.0 38519.4 31746.4 33351.7 34142.0 34178.8 34293.8 20621.5 24923.3 24958.0 24984.9 25146.0 22:45:51 INFO - PROCESS | 1665 | 22:45:51 INFO - PROCESS | 1665 | [#2] jquery.html Cycles:180 Average:34202.29 Median:13881.89 stddev:98282.17 (708.0%) stddev-sans-first:98525.07 22:45:51 INFO - PROCESS | 1665 | Values: 381.6 383.5 384.4 402.4 410.5 19827.6 21875.5 22153.7 22286.7 22320.9 20966.1 21093.2 21640.7 21667.2 22008.9 4239.4 6027.6 6178.6 6188.2 6211.9 4222.6 4465.2 6046.2 6432.1 6782.7 6665.0 6776.3 6780.7 6791.9 6796.5 6574.3 6752.9 6764.1 6766.7 6779.8 5617.7 5853.9 5901.3 5901.6 5909.1 26081.6 26440.5 26637.5 27074.6 27382.2 2034.3 2066.0 2126.7 2247.1 2310.8 6835.7 6908.0 14679.3 14797.6 14893.7 1686.9 1733.9 1741.7 1825.4 1829.6 106604.0 108122.5 110030.4 116145.0 117804.4 9241.5 9838.6 9943.2 10046.9 11470.4 12317.7 12323.7 12380.0 12424.8 12433.6 7531.4 7570.9 7589.6 7669.1 7710.0 6501.5 6601.3 6973.7 7630.6 7723.5 3887.1 3907.6 3909.6 3958.1 3966.0 1762.0 1784.1 1819.7 1903.7 1978.4 890.6 900.9 962.1 976.1 1005.6 19717.2 20659.0 20919.0 20950.0 20957.5 925.0 930.4 953.7 965.2 965.7 10844.8 10932.6 11023.0 11037.8 11097.5 576577.0 581610.0 587686.0 596366.0 605992.0 124699.0 126956.0 126970.0 127863.0 131511.0 941.5 972.2 985.1 998.8 1001.3 17831.1 18097.5 18106.5 18176.5 18376.1 40378.6 42128.6 51284.4 52151.0 52519.2 53396.7 73346.5 74561.0 74932.3 75119.4 8062.9 8114.0 8145.9 8258.1 8462.7 11483.8 12509.9 12511.7 12766.6 12900.5 10825.4 10928.5 11012.7 11083.2 11292.2 8890.5 10794.9 10885.2 10894.5 11041.9 10884.5 11061.1 11091.3 11526.1 11563.3 7055.7 7930.4 8140.0 8160.2 8178.3 10652.6 10898.7 11009.3 11012.9 11169.0 22:45:51 INFO - PROCESS | 1665 | 22:45:51 INFO - PROCESS | 1665 | [#3] mootools.html Cycles:180 Average:5614.39 Median:5097.85 stddev:3169.85 (62.2%) stddev-sans-first:3162.78 22:45:51 INFO - PROCESS | 1665 | Values: 1381.0 1534.8 1558.8 1668.3 1787.9 7704.5 8503.9 8721.3 8735.5 8844.2 8462.7 8665.3 8667.3 8670.2 8674.2 2098.9 2166.9 2170.4 2337.6 2379.3 1829.7 1905.5 2209.4 2407.3 2439.6 2327.2 2348.2 2350.2 2358.3 2358.5 1934.7 2340.9 2344.7 2345.1 2350.1 2092.4 2169.6 2173.1 2176.7 2297.7 8519.7 8701.7 8702.9 8709.4 8729.4 5189.4 5338.6 5338.6 5347.8 5353.0 2991.3 3102.2 3390.9 3415.8 3419.2 942.5 956.6 959.0 962.7 973.4 11376.9 11488.6 11492.8 11504.8 11513.9 4528.0 4812.6 4817.5 4817.7 4820.8 10025.3 10123.4 10130.0 10132.8 10136.4 5165.1 5350.2 5350.4 5370.1 5375.2 4157.6 4891.8 4892.2 4895.6 4900.0 3426.0 3931.7 3964.7 3995.7 4005.7 1272.8 1279.1 1284.9 1293.5 1294.9 885.0 896.9 898.6 912.2 944.4 5646.9 5870.3 5911.7 5913.9 6146.4 893.9 917.8 957.5 960.9 970.8 3587.8 3889.3 3894.6 4041.2 4069.2 10313.2 10727.4 10730.6 10737.9 10762.3 8236.4 10515.8 10521.4 10661.6 10667.0 8045.9 8191.1 8260.7 8272.3 8283.8 5693.1 5704.7 5748.1 5804.2 6001.1 9581.4 9923.7 9925.1 9950.4 9953.5 9443.3 9872.5 9894.4 10036.9 10046.4 6870.0 7057.1 7080.0 7363.7 7587.2 7743.0 7778.0 7868.8 8223.8 8299.8 7223.1 7393.7 7413.2 7435.9 7586.8 7213.0 7250.0 7279.1 7331.7 7349.0 6733.3 6765.4 6884.1 6894.8 6900.5 3949.2 4770.8 4772.5 4929.4 4989.0 4872.1 4893.2 4894.1 4894.6 5030.6 22:45:51 INFO - PROCESS | 1665 | 22:45:51 INFO - PROCESS | 1665 | [#4] prototype.html Cycles:180 Average:4365.31 Median:4903.89 stddev:1344.49 (27.4%) stddev-sans-first:1313.15 22:45:51 INFO - PROCESS | 1665 | Values: 298.4 299.6 313.5 316.2 316.2 5097.0 5150.8 5170.3 5198.7 5200.8 4219.4 4244.3 4357.5 4650.4 4917.7 3361.9 3479.7 3968.0 3988.0 3999.9 3940.0 3972.5 3973.5 3974.9 4000.6 3131.4 3712.8 3795.4 3853.8 3913.6 3753.3 3911.3 3918.5 3926.4 3935.4 3024.8 3429.7 3432.9 3444.7 3457.6 4613.8 4724.5 4902.9 4916.3 4924.5 4334.7 4495.8 4519.8 4529.4 4540.7 4662.4 5332.8 5419.1 5421.8 5445.8 3809.6 3812.5 3831.7 3834.6 3843.7 4597.0 5034.1 5667.3 5733.7 5734.9 3428.7 3501.1 3704.4 3759.5 3765.5 3539.2 3547.3 3792.8 3832.3 3833.8 3936.3 4289.3 4298.5 4365.6 4488.2 5448.0 5451.5 5457.6 5501.3 5511.6 1262.6 1275.7 1322.4 1322.6 1328.5 789.3 795.8 798.4 809.3 828.2 4306.0 4633.5 4957.9 5087.3 5171.8 4482.2 4883.6 5136.0 5529.9 5538.9 4645.7 4881.0 5054.1 5067.9 5085.7 2445.8 2460.6 2866.8 2886.5 2917.1 5274.8 5740.3 5748.6 5754.4 5806.0 4875.3 5404.5 5657.2 5835.7 5853.0 4988.0 5092.4 5146.5 5599.7 5761.1 4987.5 5313.9 5353.8 5402.0 5684.4 5575.6 5576.7 5583.0 5584.8 5618.7 4770.8 5174.9 5213.4 5410.2 5942.4 3326.9 3517.6 3573.8 3606.9 3823.9 4591.7 5558.9 5573.7 5632.3 5633.5 5460.1 5460.3 5460.8 5463.9 5528.1 5178.8 5288.7 5324.5 5432.0 5465.1 4492.9 5122.9 5276.7 5296.6 5361.8 4656.3 5254.9 5511.3 5522.5 5554.9 4752.5 4904.9 5089.7 5199.6 5368.4 22:45:51 INFO - PROCESS | 1665 | 22:45:51 INFO - PROCESS | 1665 | [#5] yui.html Cycles:180 Average:17700.76 Median:30525.30 stddev:24565.59 (80.5%) stddev-sans-first:24601.03 22:45:51 INFO - PROCESS | 1665 | Values: 621.6 720.8 729.0 734.2 737.0 12691.4 14533.1 15100.2 15208.4 15631.8 14342.5 14492.2 14545.3 14546.0 14569.1 727.7 749.7 906.2 909.3 933.4 919.8 920.0 926.7 927.7 930.9 985.7 987.5 988.0 990.4 992.9 764.8 940.6 968.9 971.7 977.2 728.4 728.5 729.2 731.2 731.3 14888.6 14930.7 14934.6 14946.6 14947.0 12055.4 12089.2 12177.0 12186.9 12226.7 10722.6 10797.8 10807.2 11150.1 11342.5 3963.5 3963.5 3974.8 4000.5 4144.3 69249.0 71035.9 73456.6 84442.3 84796.0 9295.8 9305.3 9352.7 9380.9 10057.2 20866.9 20889.5 20898.1 20920.8 20927.4 7238.1 7280.8 7313.8 7488.4 7546.0 3929.7 3985.9 3995.0 3996.9 4030.2 3407.4 3503.4 3503.8 3504.8 3505.4 570.4 609.4 625.9 631.0 649.0 490.6 555.5 571.9 576.3 591.0 6495.6 7987.0 7994.8 8106.6 8194.7 556.7 575.0 578.5 591.7 600.9 3657.9 3846.2 3921.5 3925.6 3986.6 76959.6 79340.6 79662.0 89181.6 94403.0 75505.0 79451.8 80359.0 80514.6 81156.6 69606.7 69662.0 71491.0 72885.6 73953.0 8078.8 8098.6 8244.5 8341.0 8357.4 45333.3 45778.7 45800.4 46074.5 47046.8 59842.4 60449.7 62428.0 63715.4 63729.0 10637.0 10824.2 11079.3 11101.3 11106.4 9939.7 11812.9 11961.9 12074.0 12196.5 11180.8 11341.1 11354.3 11495.7 11587.8 9092.5 11207.6 11216.9 11217.6 11533.1 10781.1 10919.1 11080.4 11128.4 11292.0 9117.2 9261.5 9267.6 9339.0 9573.2 9033.5 9238.2 9589.3 9727.0 9864.8 22:45:51 INFO - PROCESS | 1665 | -------- Summary: end -------- 22:45:51 INFO - PROCESS | 1665 | 22:45:51 INFO - TEST-INFO | 1665: exit 0 22:45:51 INFO - TEST-OK | dromaeo_css | took 1116755ms 22:45:51 INFO - TEST-START | kraken 22:45:51 INFO - operating with platform_type : mac_ 22:45:51 INFO - Initialising browser for kraken test... 22:45:51 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/tmpkmvuTF/profile http://localhost:49277/getInfo.html) 22:45:54 INFO - PROCESS | 1671 | __metrics Screen width/height:1600/1200 22:45:54 INFO - PROCESS | 1671 | colorDepth:24 22:45:54 INFO - PROCESS | 1671 | Browser inner width/height: 1024/689 22:45:54 INFO - PROCESS | 1671 | __metrics 22:45:54 INFO - PROCESS | 1671 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 22:45:54 ERROR - PROCESS | 1671 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 22:45:54 INFO - TEST-INFO | 1671: exit 0 22:45:54 INFO - Browser initialized. 22:45:54 INFO - Running cycle 1/1 for kraken test... 22:45:54 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/tmpkmvuTF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:46:06 INFO - PROCESS | 1672 | RSS: Main: 241270784 22:46:06 INFO - PROCESS | 1672 | 22:46:09 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection) 22:46:10 INFO - PROCESS | 1672 | RSS: Main: 288555008 22:46:10 INFO - PROCESS | 1672 | 22:46:13 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-dft) 22:46:13 INFO - PROCESS | 1672 | RSS: Main: 297799680 22:46:13 INFO - PROCESS | 1672 | 22:46:17 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-fft) 22:46:17 INFO - PROCESS | 1672 | RSS: Main: 388198400 22:46:17 INFO - PROCESS | 1672 | 22:46:19 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator) 22:46:20 INFO - PROCESS | 1672 | RSS: Main: 286826496 22:46:20 INFO - PROCESS | 1672 | 22:46:22 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:46:23 INFO - PROCESS | 1672 | RSS: Main: 291930112 22:46:23 INFO - PROCESS | 1672 | 22:46:25 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom) 22:46:26 INFO - PROCESS | 1672 | RSS: Main: 326991872 22:46:26 INFO - PROCESS | 1672 | 22:46:29 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate) 22:46:30 INFO - PROCESS | 1672 | RSS: Main: 328769536 22:46:30 INFO - PROCESS | 1672 | 22:46:32 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial) 22:46:33 INFO - PROCESS | 1672 | RSS: Main: 329646080 22:46:33 INFO - PROCESS | 1672 | 22:46:35 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:46:35 INFO - PROCESS | 1672 | RSS: Main: 314740736 22:46:35 INFO - PROCESS | 1672 | 22:46:37 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:46:38 INFO - PROCESS | 1672 | RSS: Main: 322547712 22:46:38 INFO - PROCESS | 1672 | 22:46:40 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:46:41 INFO - PROCESS | 1672 | RSS: Main: 325234688 22:46:41 INFO - PROCESS | 1672 | 22:46:43 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:46:44 INFO - PROCESS | 1672 | RSS: Main: 328916992 22:46:44 INFO - PROCESS | 1672 | 22:46:47 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:46:48 INFO - PROCESS | 1672 | RSS: Main: 326848512 22:46:48 INFO - PROCESS | 1672 | 22:46:49 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49277/tests/kraken/driver.html?testName=ai-astar) 22:46:50 INFO - PROCESS | 1672 | RSS: Main: 373510144 22:46:50 INFO - PROCESS | 1672 | 22:46:50 INFO - PROCESS | 1672 | __start_tp_report 22:46:50 INFO - PROCESS | 1672 | _x_x_mozilla_page_load 22:46:50 INFO - PROCESS | 1672 | _x_x_mozilla_page_load_details 22:46:50 INFO - PROCESS | 1672 | |i|pagename|runs| 22:46:50 INFO - PROCESS | 1672 | |0;ai-astar;112;105;112;112;112;112;112;113;111;111 22:46:50 INFO - PROCESS | 1672 | |1;audio-beat-detection;161;142;140;149;120;139;142;118;167;118 22:46:50 INFO - PROCESS | 1672 | |2;audio-dft;184;185;184;183;184;183;180;178;181;182 22:46:50 INFO - PROCESS | 1672 | |3;audio-fft;81;60;50;86;74;81;85;89;109;56 22:46:50 INFO - PROCESS | 1672 | |4;audio-oscillator;90;69;101;72;74;69;94;70;68;103 22:46:50 INFO - PROCESS | 1672 | |5;imaging-gaussian-blur;86;84;87;84;86;83;87;86;87;83 22:46:50 INFO - PROCESS | 1672 | |6;imaging-darkroom;93;93;93;108;150;150;93;93;92;92 22:46:50 INFO - PROCESS | 1672 | |7;imaging-desaturate;86;83;84;84;85;84;85;86;85;90 22:46:50 INFO - PROCESS | 1672 | |8;json-parse-financial;57;57;59;56;56;57;57;78;57;55 22:46:50 INFO - PROCESS | 1672 | |9;json-stringify-tinderbox;66;77;68;77;71;69;70;70;69;70 22:46:50 INFO - PROCESS | 1672 | |10;stanford-crypto-aes;87;87;84;84;85;84;86;87;88;87 22:46:50 INFO - PROCESS | 1672 | |11;stanford-crypto-ccm;145;165;140;132;122;122;167;149;198;147 22:46:50 INFO - PROCESS | 1672 | |12;stanford-crypto-pbkdf2;152;165;170;224;225;166;167;165;161;208 22:46:50 INFO - PROCESS | 1672 | |13;stanford-crypto-sha256-iterative;67;68;68;67;67;65;66;65;66;64 22:46:50 INFO - PROCESS | 1672 | __end_tp_report 22:46:50 INFO - PROCESS | 1672 | __start_cc_report 22:46:50 INFO - PROCESS | 1672 | _x_x_mozilla_cycle_collect,2014 22:46:50 INFO - PROCESS | 1672 | __end_cc_report 22:46:50 INFO - PROCESS | 1672 | __startTimestamp1464241610191__endTimestamp 22:46:50 INFO - PROCESS | 1672 | 22:46:50 INFO - PROCESS | 1672 | ------- Summary: start ------- 22:46:50 INFO - PROCESS | 1672 | Number of tests: 14 22:46:50 INFO - PROCESS | 1672 | 22:46:50 INFO - PROCESS | 1672 | [#0] ai-astar Cycles:10 Average:111.20 Median:112.00 stddev:2.25 (2.0%) stddev-sans-first:2.37 22:46:50 INFO - PROCESS | 1672 | Values: 112.0 105.0 112.0 112.0 112.0 112.0 112.0 113.0 111.0 111.0 22:46:50 INFO - PROCESS | 1672 | 22:46:50 INFO - PROCESS | 1672 | [#1] audio-beat-detection Cycles:10 Average:139.60 Median:142.00 stddev:17.08 (12.0%) stddev-sans-first:16.27 22:46:50 INFO - PROCESS | 1672 | Values: 161.0 142.0 140.0 149.0 120.0 139.0 142.0 118.0 167.0 118.0 22:46:50 INFO - PROCESS | 1672 | 22:46:50 INFO - PROCESS | 1672 | [#2] audio-dft Cycles:10 Average:182.40 Median:183.50 stddev:2.17 (1.2%) stddev-sans-first:2.22 22:46:50 INFO - PROCESS | 1672 | Values: 184.0 185.0 184.0 183.0 184.0 183.0 180.0 178.0 181.0 182.0 22:46:50 INFO - PROCESS | 1672 | 22:46:50 INFO - PROCESS | 1672 | [#3] audio-fft Cycles:10 Average:77.10 Median:81.00 stddev:17.68 (21.8%) stddev-sans-first:18.69 22:46:50 INFO - PROCESS | 1672 | Values: 81.0 60.0 50.0 86.0 74.0 81.0 85.0 89.0 109.0 56.0 22:46:50 INFO - PROCESS | 1672 | 22:46:50 INFO - PROCESS | 1672 | [#4] audio-oscillator Cycles:10 Average:81.00 Median:71.00 stddev:14.31 (20.1%) stddev-sans-first:14.80 22:46:50 INFO - PROCESS | 1672 | Values: 90.0 69.0 101.0 72.0 74.0 69.0 94.0 70.0 68.0 103.0 22:46:50 INFO - PROCESS | 1672 | 22:46:50 INFO - PROCESS | 1672 | [#5] imaging-gaussian-blur Cycles:10 Average:85.30 Median:86.00 stddev:1.64 (1.9%) stddev-sans-first:1.72 22:46:50 INFO - PROCESS | 1672 | Values: 86.0 84.0 87.0 84.0 86.0 83.0 87.0 86.0 87.0 83.0 22:46:50 INFO - PROCESS | 1672 | 22:46:50 INFO - PROCESS | 1672 | [#6] imaging-darkroom Cycles:10 Average:105.70 Median:93.00 stddev:23.83 (25.6%) stddev-sans-first:24.83 22:46:50 INFO - PROCESS | 1672 | Values: 93.0 93.0 93.0 108.0 150.0 150.0 93.0 93.0 92.0 92.0 22:46:50 INFO - PROCESS | 1672 | 22:46:50 INFO - PROCESS | 1672 | [#7] imaging-desaturate Cycles:10 Average:85.20 Median:85.00 stddev:1.93 (2.3%) stddev-sans-first:2.03 22:46:50 INFO - PROCESS | 1672 | Values: 86.0 83.0 84.0 84.0 85.0 84.0 85.0 86.0 85.0 90.0 22:46:50 INFO - PROCESS | 1672 | 22:46:50 INFO - PROCESS | 1672 | [#8] json-parse-financial Cycles:10 Average:58.90 Median:57.00 stddev:6.79 (11.9%) stddev-sans-first:7.17 22:46:50 INFO - PROCESS | 1672 | Values: 57.0 57.0 59.0 56.0 56.0 57.0 57.0 78.0 57.0 55.0 22:46:50 INFO - PROCESS | 1672 | 22:46:50 INFO - PROCESS | 1672 | [#9] json-stringify-tinderbox Cycles:10 Average:70.70 Median:70.00 stddev:3.59 (5.1%) stddev-sans-first:3.38 22:46:50 INFO - PROCESS | 1672 | Values: 66.0 77.0 68.0 77.0 71.0 69.0 70.0 70.0 69.0 70.0 22:46:50 INFO - PROCESS | 1672 | 22:46:50 INFO - PROCESS | 1672 | [#10] stanford-crypto-aes Cycles:10 Average:85.90 Median:87.00 stddev:1.52 (1.8%) stddev-sans-first:1.56 22:46:50 INFO - PROCESS | 1672 | Values: 87.0 87.0 84.0 84.0 85.0 84.0 86.0 87.0 88.0 87.0 22:46:50 INFO - PROCESS | 1672 | 22:46:50 INFO - PROCESS | 1672 | [#11] stanford-crypto-ccm Cycles:10 Average:148.70 Median:148.00 stddev:23.16 (15.6%) stddev-sans-first:24.53 22:46:50 INFO - PROCESS | 1672 | Values: 145.0 165.0 140.0 132.0 122.0 122.0 167.0 149.0 198.0 147.0 22:46:50 INFO - PROCESS | 1672 | 22:46:50 INFO - PROCESS | 1672 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:180.30 Median:168.50 stddev:27.50 (16.3%) stddev-sans-first:27.19 22:46:50 INFO - PROCESS | 1672 | Values: 152.0 165.0 170.0 224.0 225.0 166.0 167.0 165.0 161.0 208.0 22:46:50 INFO - PROCESS | 1672 | 22:46:50 INFO - PROCESS | 1672 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.30 Median:67.00 stddev:1.34 (2.0%) stddev-sans-first:1.39 22:46:50 INFO - PROCESS | 1672 | Values: 67.0 68.0 68.0 67.0 67.0 65.0 66.0 65.0 66.0 64.0 22:46:50 INFO - PROCESS | 1672 | -------- Summary: end -------- 22:46:50 INFO - PROCESS | 1672 | 22:46:50 INFO - TEST-INFO | 1672: exit 0 22:46:51 INFO - TEST-OK | kraken | took 59168ms 22:46:51 INFO - SUITE-END | took 1175s 22:46:51 INFO - Completed test suite (00:19:36) 22:46:51 INFO - javascript benchmark 22:46:51 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3490.1449144959474, 3496.772709773865, 3519.982400088, 3527.7001454876367, 3572.3034008368363, 5640.858978525539, 5646.096624540074, 5657.321121465425, 5753.079507278836, 5765.538756899448, 5694.145878118285, 5790.247267855183, 5815.185873977636, 5825.533957283418, 5863.003289440795, 5146.099432599299, 5158.742062896859, 5191.584673226152, 5230.450802665722, 5342.0, 5286.814961476349, 5322.441143679896, 5329.546988505982, 5358.866028349284, 5372.785088596471, 5141.82003629873, 5148.665336753099, 5168.948310516885, 5218.295530103444, 5256.789728410859, 5186.5850731941355, 5202.557782588485, 5204.895902081975, 5218.921716174261, 5231.9476805232225, 5179.922301165486, 5208.427073021964, 5252.054630166568, 5278.841634750964, 5305.602079843989, 5573.303337082864, 5619.634723742944, 5678.006348888969, 5711.371749107595, 5716.428357164292, 6224.066390041485, 6278.215223097113, 6281.309056003882, 6303.5902666327, 6305.653189074599, 5210.0, 6245.687715614201, 6250.7812226572305, 6255.812325630239, 6271.8745625087695, 5565.2486914266465, 5778.162166485878, 5851.210086638334, 5903.055511118243, 5912.497437717756, 6331.113644089831, 6353.650549219838, 6370.0, 6376.7130479128555, 6387.520935929824, 6015.248093988252, 6192.0711893215475, 6205.069239614094, 6206.627602343874, 6214.782482613087, 6142.692865356715, 6194.38056194377, 6234.781782637586, 6243.719032643451, 6257.155284036688, 6138.235132557318, 6145.740123274718, 6194.4115309045565, 6223.0, 6259.6870156492905, 6241.625502469867, 6250.999840025665, 6256.593321434092, 6274.529410294246, 6289.433950944437, 4805.8077676892535, 4908.779104940286, 4939.382577177853, 4949.381327334083, 4977.278294647328, 4412.117576484716, 4422.823087076481, 4501.167284052461, 4501.482329532146, 4508.278675411918, 5736.426357364314, 5746.88506229873, 5773.220615216976, 5804.158397032491, 5810.709464526757, 6188.938110618837, 6234.438900498977, 6241.781537646251, 6258.530610204162, 6264.780732674334, 5785.652860828363, 5808.854778630568, 5809.186713860063, 5831.329397119301, 5888.352281249059, 5294.258803767476, 5372.543333816591, 5380.219868119101, 5436.782528698808, 5437.374701909252, 6245.594036387621, 6343.238811342669, 6396.200474940632, 6402.967985160044, 6493.577917435352, 6168.938310616836, 6230.096635987666, 6236.03341482071, 6244.968775156095, 6245.28179259391, 6224.346443623354, 6488.416042556004, 6489.8702025960165, 6490.286068532552, 6495.675216239264, 6038.0, 6099.725512352044, 6111.633302001894, 6146.955017646921, 6150.554619664285, 6144.754209831735, 6145.539084568586, 6159.81520554384, 6173.012318029094, 6179.752809887734, 5924.940750592439, 5936.465718085393, 5987.820365389045, 5996.280446346292, 6001.36985616504, 6019.879602408015, 6116.3883611638485, 6126.387361263838, 6143.38905964972, 6216.906746398718, 6059.21230240066, 6081.1486391906055, 6094.605511174926, 6120.510359171167, 6134.263888333428, 6014.789482368096, 6183.072539119168, 6195.070739388947, 6220.160278362544, 6230.501559875109, 5844.444777746107, 5848.53211743068, 5969.910451343147, 6034.456898879122, 6041.6375017499095], "value": 5731.571673643193, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [505.45158503024214, 516.2591680937852, 519.4675457655904, 529.7774934527498, 559.9920143741266, 30796.84601576992, 41995.0, 43237.35143972843, 43239.35140972888, 43286.56713432869, 43953.68138955828, 44601.776991115, 44618.55381446185, 44651.77674111633, 44884.775576122156, 8624.137586241388, 9473.242140628767, 9705.02949705031, 9707.223422126248, 9709.223262139047, 8667.69663061793, 8800.603972821238, 8806.911930880673, 8829.735107946739, 11599.0, 11512.13658975576, 11889.940550297237, 11891.0, 11891.286522808616, 11926.165168438212, 11940.820887686692, 12076.516939322417, 12083.697907552294, 12091.45588448513, 12092.697682557919, 8686.78283042929, 11470.369129697863, 11721.94139029308, 11724.706882327924, 11881.702957426047, 41166.38250426246, 41603.0, 41685.37471937924, 41770.373444398356, 41781.0, 3103.8137711737368, 3171.9682803172136, 3203.0711093782775, 3218.903432897017, 3362.478815783533, 28458.857705711547, 28672.0, 28931.56602650962, 29242.85378573115, 29618.555721663975, 2576.9613455798176, 2700.6894207166233, 2730.890764369423, 2757.993332433647, 2858.428314337141, 506689.0, 508487.20294789877, 508940.0, 509400.0, 509458.0, 62031.449213770014, 63148.05277920835, 63266.68366658229, 63357.683211583644, 63381.68309158517, 66349.66825165843, 66424.0, 66497.0, 66555.66722166455, 66750.33249667441, 16301.347946082262, 16859.83140168607, 16863.915680421764, 16868.24092915822, 16869.746953795704, 10784.676459706221, 12942.223466592035, 12983.610491685076, 12988.285644289655, 12997.480100795863, 14783.482578109715, 15137.167455790037, 16663.16684165787, 17050.868641419824, 17109.23008464622, 5994.07091900756, 7067.858642827217, 7084.822879427952, 7127.92872071283, 7163.10461192351, 729.5882467425548, 981.7349315684725, 994.8955359687274, 1003.6487229469627, 1032.1988130711957, 29136.83452661912, 29379.412411751644, 29467.263318417212, 30290.394192116033, 30298.69701302959, 1079.7516571188671, 1096.0464395975393, 1104.3539529375405, 1149.166854030821, 1150.775598758234, 14686.192259425834, 15180.089194648357, 15208.0, 15283.61790955235, 15617.375304987674, 184268.0, 184453.0, 185141.0, 185193.0, 185700.07149964164, 61990.0, 62006.68996654988, 62039.37960620426, 62047.6897615509, 62248.06627900585, 1285.7428514297178, 1327.2633688303085, 1428.221619217529, 1436.2100844535464, 1447.9203643799692, 29344.26639334034, 30081.69918300789, 30649.54025689661, 30665.0, 32139.35721285608, 60034.7993040128, 61215.0, 61273.38726612677, 61328.0, 61366.63600367984, 626036.0, 654134.0, 654292.0, 654705.7264713837, 655778.0, 37413.625863742105, 41868.3719744198, 42569.0, 43895.0, 44263.336049958634, 45706.771466142454, 46169.0, 47789.80525486891, 47854.521454785005, 47916.28125578189, 37187.25625487421, 38134.0, 39232.80383598064, 39863.2027359457, 40068.7996560027, 37621.0, 38130.23739525139, 38134.42798358083, 38711.6128838708, 38783.612163878, 37568.81215593904, 37819.43270850994, 37997.81001094977, 38472.0, 38519.42220866633, 31746.365072698878, 33351.66648333583, 34142.0, 34178.82910585531, 34293.82853085719, 20621.484462888548, 24923.252302430956, 24958.0, 24984.875075625234, 25146.0], "value": 19910.353418375274, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [381.59550876071364, 383.4631515877771, 384.40417353102686, 402.3602472069409, 410.4581951823594, 19827.603447931033, 21875.453113672167, 22153.66769498459, 22286.665700014513, 22320.88839555802, 20966.05652745626, 21093.15627374907, 21640.675389869164, 21667.241646542374, 22008.88995555024, 4239.385289133066, 6027.578069535134, 6178.567500274982, 6188.226471691039, 6211.937880621181, 4222.577742225783, 4465.218586747323, 6046.21399218101, 6432.13166222561, 6782.694778734969, 6664.966675166617, 6776.322367763233, 6780.694868730919, 6791.898121528181, 6796.524243302971, 6574.276829548746, 6752.864942701119, 6764.0530325754435, 6766.661666916659, 6779.796606101825, 5617.719114044281, 5853.88292234153, 5901.262342207224, 5901.557383196277, 5909.084091965752, 26081.60877586838, 26440.47119057608, 26637.46725065507, 27074.593881091798, 27382.178534643994, 2034.3185033013958, 2065.979340206594, 2126.6597344424817, 2247.0674670011927, 2310.849794763335, 6835.692393842289, 6907.965460172667, 14679.339429725687, 14797.632378819444, 14893.702125957529, 1686.8819182657219, 1733.9306427742874, 1741.7126174181371, 1825.4249911277905, 1829.5791967847338, 106604.0, 108122.45938770335, 110030.44984775105, 116145.0, 117804.41097794542, 9241.537923103819, 9838.557264923096, 9943.204543636492, 10046.894841567422, 11470.426478676032, 12317.692057698629, 12323.69190770238, 12380.00959923222, 12424.813627795591, 12433.626991190278, 7531.397488200931, 7570.924290757132, 7589.620518974029, 7669.079710434784, 7710.036245469317, 6501.544891857525, 6601.339866013456, 6973.6750017496515, 7630.580318082448, 7723.497972631873, 3887.1253967857006, 3907.5897030811357, 3909.6481316681634, 3958.129211573449, 3965.9603403966166, 1762.0, 1784.1334036408189, 1819.672458957374, 1903.676375016223, 1978.3768113044346, 890.6036813618007, 900.8873890763655, 962.0956301077105, 976.092234222166, 1005.5676059294427, 19717.211311547377, 20659.0, 20918.957277766316, 20950.0, 20957.476063098544, 924.9676261330821, 930.3812964378612, 953.7472569769017, 965.232640051161, 965.6571916969515, 10844.837327440095, 10932.572308215185, 11022.952819482136, 11037.779244415067, 11097.500612472459, 576577.0, 581610.0, 587686.0, 596366.0, 605992.0, 124699.0, 126956.0, 126970.0, 127863.0, 131511.0, 941.4868896451451, 972.2062794144791, 985.1478471122571, 998.825205589019, 1001.329109496639, 17831.10844457746, 18097.547561310545, 18106.547336316697, 18176.545586360447, 18376.081195940416, 40378.59621403866, 42128.57871421369, 51284.435778210194, 52151.0, 52519.21221181609, 53396.73301633467, 73346.53306933725, 74561.0, 74932.25067749253, 75119.36604754775, 8062.879056814035, 8113.959430202811, 8145.877811832709, 8258.132896045829, 8462.746117616482, 11483.770324593293, 12509.937450312691, 12511.687207819878, 12766.553170638983, 12900.483980640669, 10825.350478971286, 10928.453577321261, 11012.669619911416, 11083.168762342695, 11292.209545331747, 8890.511021893599, 10794.892051079389, 10885.183611229031, 10894.455277235948, 11041.889581104086, 10884.476173335897, 11061.059809916247, 11091.33451992883, 11526.07791376671, 11563.30620162793, 7055.682494287872, 7930.444868859356, 8139.959300203461, 8160.18398160203, 8178.345732341518, 10652.62715804943, 10898.746644136027, 11009.31091302553, 11012.889871101506, 11169.0], "value": 9396.843612926943, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1380.9930950345247, 1534.7928029715988, 1558.8051493563305, 1668.2826384654602, 1787.8748487605865, 7704.499207551512, 8503.914960850398, 8721.258693011087, 8735.519546424945, 8844.159804818531, 8462.746117616465, 8665.263452606536, 8667.306615470763, 8670.219680228794, 8674.175953284428, 2098.90554925028, 2166.9133234670594, 2170.370592528165, 2337.5909215887195, 2379.2862141357523, 1829.6798060339454, 1905.4569447707406, 2209.3924170853065, 2407.277816655005, 2439.609662454008, 2327.1622216002224, 2348.178137651825, 2350.236173243694, 2358.292512246328, 2358.5282943411303, 1934.7388102606108, 2340.871252081135, 2344.6834677318598, 2345.1088586337255, 2350.1421980976993, 2092.3618296419736, 2169.5877783221135, 2173.076442511926, 2176.673498975151, 2297.689811875409, 8519.659213631447, 8701.651933922636, 8702.912970870337, 8709.433886797338, 8729.388942774009, 5189.42916279209, 5338.572914166857, 5338.62629615927, 5347.786088556453, 5353.0, 2991.2521869532616, 3102.239951211945, 3390.8982730518123, 3415.8121303328307, 3419.2135808764124, 942.4627962061628, 956.6460409648405, 959.0217977662815, 962.7352478068588, 973.3916302311055, 11376.886231137665, 11488.597899073577, 11492.770144597062, 11504.827427588592, 11513.884861151366, 4527.954720452819, 4812.614990800727, 4817.518248175154, 4817.735024573612, 4820.831270905502, 10025.298229123891, 10123.443210623487, 10130.037646423578, 10132.848007279898, 10136.391816491034, 5165.070287348313, 5350.197470379474, 5350.438203988603, 5370.060239458079, 5375.16684913839, 4157.64660003897, 4891.755412229374, 4892.241702536113, 4895.559399654048, 4900.044491324229, 3426.0, 3931.744436611611, 3964.682825373963, 3995.6603688686205, 4005.7396269242404, 1272.8281681973, 1279.1429742072648, 1284.8843604075678, 1293.5084667826166, 1294.851092124399, 884.9867251991225, 896.9192772650492, 898.5507246376811, 912.1927094521396, 944.4174106477839, 5646.943530564642, 5870.324912635102, 5911.674857882857, 5913.881722365614, 6146.446819786241, 893.9329550283754, 917.7934964632905, 957.4733896356976, 960.9183219426287, 970.8203982263162, 3587.7667951583585, 3889.3388124018984, 3894.6300101490315, 4041.1715598302867, 4069.2471892699946, 10313.226508011929, 10727.40999245049, 10730.570777168981, 10737.946310268399, 10762.300450470695, 8236.382271329674, 10515.789684206273, 10521.368717876952, 10661.62684306061, 10667.0, 8045.919540804634, 8191.139930307351, 8260.669573217125, 8272.296854767292, 8283.751487455385, 5693.146028095819, 5704.686242256716, 5748.137779333133, 5804.158397032491, 6001.129836173817, 9581.425114493153, 9923.65267215644, 9925.057119573628, 9950.35322704022, 9953.502324883582, 9443.291753118681, 9872.457014863965, 9894.406335619886, 10036.89963100389, 10046.447445390573, 6870.038194652652, 7057.117860267466, 7080.04419403374, 7363.7422690205585, 7587.165411804807, 7743.0, 7777.961110194413, 7868.763937081897, 8223.835523289381, 8299.751007469786, 7223.097112860893, 7393.704251829867, 7413.184549699421, 7435.925640743523, 7586.848263034819, 7212.963935180291, 7250.02124713157, 7279.053723015974, 7331.670074846751, 7349.04462419882, 6733.326667333227, 6765.391114799691, 6884.105066341343, 6894.827629309107, 6900.516963812485, 3949.249642568018, 4770.809167633256, 4772.451168115711, 4929.383827021622, 4989.0, 4872.123017856819, 4893.241547559992, 4894.094592500399, 4894.632902532396, 5030.622703297194], "value": 4493.165955387695, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [298.37192709346823, 299.5596473184419, 313.5328360742493, 316.21105342171256, 316.239444136851, 5096.974515127419, 5150.819721309755, 5170.276161337416, 5198.714070726109, 5200.843974680756, 4219.367094935761, 4244.278472659647, 4357.498887627924, 4650.4186976627925, 4917.680350777197, 3361.940988588599, 3479.652034796525, 3967.9801600991955, 3987.9800600996955, 3999.940000899974, 3939.98030009851, 3972.5232972043395, 3973.5231772187235, 3974.8608798692053, 4000.5599384067727, 3131.405033043726, 3712.83292251848, 3795.4496597993275, 3853.7880416577077, 3913.6477717005464, 3753.2868754936476, 3911.3350730375905, 3918.549366822809, 3926.3901800261756, 3935.4490371347893, 3024.8221280677876, 3429.6913277805115, 3432.9313413731834, 3444.7071998880124, 3457.619661837196, 4613.792379342937, 4724.480307166209, 4902.877428064291, 4916.287138364953, 4924.507549245082, 4334.745932115283, 4495.752733599684, 4519.751413672247, 4529.411176547061, 4540.659450541222, 4662.417197850269, 5332.76002579881, 5419.078756611346, 5421.780099477625, 5445.809396671137, 3809.590451532923, 3812.5234345706785, 3831.731778775487, 3834.6225340289197, 3843.7117216208617, 4597.0, 5034.0686972910125, 5667.319921609392, 5733.741981610838, 5734.913976290359, 3428.6571342866014, 3501.072215862798, 3704.4258139988347, 3759.5112635357227, 3765.4728338032696, 3539.1859872229015, 3547.2728090741334, 3792.791396473165, 3832.3101841668768, 3833.8083095845095, 3936.303632012081, 4289.335153051282, 4298.505671847715, 4365.563443655609, 4488.236999710057, 5447.972760136174, 5451.536619387317, 5457.645253058538, 5501.312335958005, 5511.58663100269, 1262.6275248801626, 1275.6747029507417, 1322.444573279225, 1322.6296636941672, 1328.518701647656, 789.2659826361539, 795.777182388932, 798.4490701416005, 809.29591255608, 828.2462958707547, 4306.009617787926, 4633.513481084505, 4957.925631115536, 5087.26234695967, 5171.81898633546, 4482.21561226784, 4883.560479556857, 5136.048301645351, 5529.889402211933, 5538.86152846174, 4645.6980296280635, 4880.95119048805, 5054.09026375256, 5067.885065285631, 5085.720285384339, 2445.792107670832, 2460.5940019896875, 2866.784991125674, 2886.4515742008953, 2917.0519581136214, 5274.81095345435, 5740.273370149229, 5748.5688573357165, 5754.367019627837, 5805.970970145207, 4875.269279405831, 5404.540614047772, 5657.20799088128, 5835.679037652885, 5852.970735146297, 4988.002399520183, 5092.439831618445, 5146.511081447256, 5599.720013999284, 5761.135829625589, 4987.486324875993, 5313.946860531345, 5353.785848566014, 5401.972990135024, 5684.431556844448, 5575.581831362583, 5576.741419725018, 5582.972085139549, 5584.776608935597, 5618.747156378055, 4770.783450220171, 5174.8965020700125, 5213.374395072616, 5410.215518749838, 5942.435468630473, 3326.850291736926, 3517.613062563065, 3573.821308934491, 3606.891793246207, 3823.942640860445, 4591.724496530219, 5558.888822223452, 5573.749181286771, 5632.2678051853, 5633.521150702072, 5460.116259890331, 5460.25271912984, 5460.781568737206, 5463.9180412294645, 5528.143137813645, 5178.818741344035, 5288.651393894686, 5324.520793128482, 5432.0, 5465.125579907196, 4492.921698792332, 5122.871928201825, 5276.736163191902, 5296.629235953446, 5361.839144825662, 4656.323723459484, 5254.89490210186, 5511.283533140666, 5522.502974732293, 5554.944450555605, 4752.52474752522, 4904.901901962012, 5089.6691715038405, 5199.584033277404, 5368.3557973041925], "value": 3937.115825314583, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [621.6332363905295, 720.7801620505747, 728.9817754556132, 734.1557209209409, 736.970521179153, 12691.36543172841, 14533.128012319256, 15100.244987750624, 15208.391664333427, 15631.765523517128, 14342.498012569562, 14492.202928838937, 14545.272736363166, 14546.0, 14569.053011554242, 727.7052793618567, 749.722602637025, 906.2115959115562, 909.295296145486, 933.3886304470583, 919.7792529792869, 919.9632014719404, 926.703454894434, 927.661403587689, 930.9162175404213, 985.7239972807589, 987.5407935310079, 987.9555420006117, 990.3810118675827, 992.910638042576, 764.8393837294147, 940.6002448959233, 968.9224862011022, 971.6793458158861, 977.2426369563574, 728.3990707666144, 728.5410191579324, 729.2270193594807, 731.2322061835105, 731.3052600029939, 14888.553343399608, 14930.70138597222, 14934.551963441114, 14946.551603451913, 14947.0, 12055.397230138546, 12089.214201076902, 12177.0, 12186.8753667001, 12226.694332641666, 10722.571097156182, 10797.83803242952, 10807.243492955497, 11150.052245559056, 11342.48958796848, 3963.4649322341693, 3963.54419241788, 3974.82113304902, 4000.5399379071196, 4144.316187829005, 69249.0, 71035.93446098313, 73456.63271683661, 84442.31115377719, 84796.0, 9295.814083718287, 9305.302102342352, 9352.672656457127, 9380.90619093814, 10057.245706572035, 20866.89566552188, 20889.477763056046, 20898.059587318607, 20920.79079209219, 20927.372178834354, 7238.0952380952385, 7280.817979550554, 7313.780586582411, 7488.438367122488, 7546.0, 3929.7249192556246, 3985.92028159441, 3995.0212198011673, 3996.9200615988098, 4030.2141082488633, 3407.4037043517287, 3503.386907291214, 3503.7722548034294, 3504.7546671732734, 3505.386557352453, 570.4124751505956, 609.3997412548636, 625.8623102917351, 630.9558330916792, 648.9837754056186, 490.6344773143959, 555.4836590246191, 571.948524632785, 576.2510779783338, 591.0484120565885, 6495.577787443801, 7986.960065199637, 7994.760157195294, 8106.594670266463, 8194.713185038614, 556.6632187526569, 574.968376739275, 578.4649199490455, 591.6864062047122, 600.906859436788, 3657.908552286214, 3846.1538461538416, 3921.450996860442, 3925.646691797695, 3986.6151558996607, 76959.61520192475, 79340.60329698314, 79662.0, 89181.6359282266, 94403.0, 75505.0, 79451.8082228767, 80359.0, 80514.59742701249, 81156.59421702854, 69606.65196674086, 69662.0, 71491.0, 72885.6355718218, 73953.0, 8078.798030049295, 8098.595070246582, 8244.54654993981, 8340.958295208486, 8357.414980951518, 45333.32000019936, 45778.73544542287, 45800.39698610666, 46074.53925460702, 47046.76476617595, 59842.40157598542, 60449.69775151096, 62428.0, 63715.4071148225, 63729.0, 10637.0, 10824.24230303871, 11079.335239885633, 11101.333919964827, 11106.444677766242, 9939.652112176329, 11812.881871181178, 11961.940190298992, 12074.0, 12196.512139514674, 11180.832287515856, 11341.149413793833, 11354.26197297173, 11495.712607184887, 11587.768244635228, 9092.454452732858, 11207.551697931991, 11216.887831121585, 11217.607383741857, 11533.077353811843, 10781.083607893417, 10919.071878890383, 11080.390578517936, 11128.44357782124, 11292.0, 9117.179453849185, 9261.53692315368, 9267.582958766738, 9339.0, 9573.234141268542, 9033.548322583976, 9238.214751745909, 9589.328746987923, 9727.0, 9864.753381165528], "value": 6814.163136075912, "unit": "score"}], "name": "dromaeo_css", "value": 7110.841283325484, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 105.0, 112.0, 112.0, 112.0, 112.0, 112.0, 113.0, 111.0, 111.0], "value": 111.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 142.0, 140.0, 149.0, 120.0, 139.0, 142.0, 118.0, 167.0, 118.0], "value": 139.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 185.0, 184.0, 183.0, 184.0, 183.0, 180.0, 178.0, 181.0, 182.0], "value": 182.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 60.0, 50.0, 86.0, 74.0, 81.0, 85.0, 89.0, 109.0, 56.0], "value": 77.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 69.0, 101.0, 72.0, 74.0, 69.0, 94.0, 70.0, 68.0, 103.0], "value": 81.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 87.0, 84.0, 86.0, 83.0, 87.0, 86.0, 87.0, 83.0], "value": 85.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 93.0, 108.0, 150.0, 150.0, 93.0, 93.0, 92.0, 92.0], "value": 105.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 83.0, 84.0, 84.0, 85.0, 84.0, 85.0, 86.0, 85.0, 90.0], "value": 85.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 57.0, 59.0, 56.0, 56.0, 57.0, 57.0, 78.0, 57.0, 55.0], "value": 58.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 77.0, 68.0, 77.0, 71.0, 69.0, 70.0, 70.0, 69.0, 70.0], "value": 70.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 87.0, 84.0, 84.0, 85.0, 84.0, 86.0, 87.0, 88.0, 87.0], "value": 85.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 165.0, 140.0, 132.0, 122.0, 122.0, 167.0, 149.0, 198.0, 147.0], "value": 148.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 165.0, 170.0, 224.0, 225.0, 166.0, 167.0, 165.0, 161.0, 208.0], "value": 180.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 68.0, 67.0, 67.0, 65.0, 66.0, 65.0, 66.0, 64.0], "value": 66.3, "unit": "score"}], "name": "kraken", "value": 1478.3000000000002, "alertThreshold": 2.0}]} 22:46:51 INFO - Return code: 0 22:46:51 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 22:46:51 ERROR - # TBPL SUCCESS # 22:46:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:46:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:46:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:46:51 INFO - 2016-05-25 22:46:51.517 screenresolution[1676:10366] starting screenresolution argv=screenresolution get 22:46:51 INFO - 2016-05-25 22:46:51.519 screenresolution[1676:10366] Display 0: 1600x1200x32@60 22:46:51 INFO - 2016-05-25 22:46:51.525 screenresolution[1677:10369] starting screenresolution argv=screenresolution list 22:46:51 INFO - Available Modes on Display 0 22:46:51 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:46:51 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:46:51 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:46:51 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:46:51 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:46:51 INFO - Intel Iris: 22:46:51 INFO - Chipset Model: Intel Iris 22:46:51 INFO - Type: GPU 22:46:51 INFO - Bus: Built-In 22:46:51 INFO - VRAM (Dynamic, Max): 1536 MB 22:46:51 INFO - Vendor: Intel (0x8086) 22:46:51 INFO - Device ID: 0x0a2e 22:46:51 INFO - Revision ID: 0x0009 22:46:51 INFO - Displays: 22:46:51 INFO - Display: 22:46:51 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:46:51 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:46:51 INFO - Display Serial Number: 1600x1200 60 22:46:51 INFO - Main Display: Yes 22:46:51 INFO - Mirror: Off 22:46:51 INFO - Online: Yes 22:46:51 INFO - Rotation: Supported 22:46:51 INFO - Return code: 0 22:46:51 INFO - Running post-action listener: _resource_record_post_action 22:46:51 INFO - ##### 22:46:51 INFO - ##### Finished run-tests step (success) 22:46:51 INFO - ##### 22:46:51 INFO - Running post-run listener: _resource_record_post_run 22:46:51 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 32689664; Write bytes: 696830464; Read time: 324; Write time: 1969 22:46:51 INFO - TinderboxPrint: CPU usage
53.6% 22:46:51 INFO - TinderboxPrint: I/O read bytes / time
32,689,664 / 324 22:46:51 INFO - TinderboxPrint: I/O write bytes / time
696,830,464 / 1,969 22:46:51 INFO - TinderboxPrint: CPU idle
2,248.9 (46.4%) 22:46:51 INFO - TinderboxPrint: CPU system
964.2 (19.9%) 22:46:51 INFO - TinderboxPrint: CPU user
1,632.6 (33.7%) 22:46:51 INFO - TinderboxPrint: Swap in / out
425,668,608 / 0 22:46:51 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 382824960; Write bytes: 377789952; Read time: 28251; Write time: 757 22:46:51 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 31763968; Write bytes: 296184832; Read time: 310; Write time: 1162 22:46:52 INFO - Running post-run listener: _upload_blobber_files 22:46:52 INFO - Blob upload gear active. 22:46:52 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:46:52 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 22:46:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:46:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:46:52 INFO - (blobuploader) - INFO - Open directory for files ... 22:46:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:46:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:46:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:46:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:46:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:46:53 INFO - (blobuploader) - INFO - Done attempting. 22:46:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:46:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:46:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:46:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:46:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:46:54 INFO - (blobuploader) - INFO - Done attempting. 22:46:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:46:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:46:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:46:57 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:46:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:46:57 INFO - (blobuploader) - INFO - Done attempting. 22:46:57 INFO - (blobuploader) - INFO - Iteration through files over. 22:46:57 INFO - Return code: 0 22:46:57 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:46:57 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:46:57 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9931e79fb7ace27fb1c4d8a5989241b1f75d0552ae43e39fac4cef6374b5d79398930cd5436dd5227aa1f78f575fc027639ca08532a5eecf9ae236ee52a5f2e1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4a86e08f83da097684f87c01d59a011df4159edbafe2e8daf3d514002e4b0d7290ddefeedc2c7539f94a84fb956844a324c1333edbe114ba72ce88e20e654cb2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/80f1798663d4c8e3c53c332280c07a6177d01dfaea3f4facec0308d5f4df2c212a424bb9a00977193f9ac488c65afbd6c42fd93f7ca83da284c7fa73c22627b5"} 22:46:57 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:46:57 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:46:57 INFO - Contents: 22:46:57 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9931e79fb7ace27fb1c4d8a5989241b1f75d0552ae43e39fac4cef6374b5d79398930cd5436dd5227aa1f78f575fc027639ca08532a5eecf9ae236ee52a5f2e1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4a86e08f83da097684f87c01d59a011df4159edbafe2e8daf3d514002e4b0d7290ddefeedc2c7539f94a84fb956844a324c1333edbe114ba72ce88e20e654cb2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/80f1798663d4c8e3c53c332280c07a6177d01dfaea3f4facec0308d5f4df2c212a424bb9a00977193f9ac488c65afbd6c42fd93f7ca83da284c7fa73c22627b5"} 22:46:57 INFO - Running post-run listener: copy_logs_to_upload_dir 22:46:57 INFO - Copying logs to upload dir... 22:46:57 INFO - mkdir: /builds/slave/test/build/upload/logs 22:46:57 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1303.603150 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 43 secs) (at 2016-05-25 22:46:57.223000) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-25 22:46:57.226854) ========= 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.pCLH78Ko2X/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.89ywlbnuOM/Listeners TMPDIR=/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9931e79fb7ace27fb1c4d8a5989241b1f75d0552ae43e39fac4cef6374b5d79398930cd5436dd5227aa1f78f575fc027639ca08532a5eecf9ae236ee52a5f2e1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4a86e08f83da097684f87c01d59a011df4159edbafe2e8daf3d514002e4b0d7290ddefeedc2c7539f94a84fb956844a324c1333edbe114ba72ce88e20e654cb2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/80f1798663d4c8e3c53c332280c07a6177d01dfaea3f4facec0308d5f4df2c212a424bb9a00977193f9ac488c65afbd6c42fd93f7ca83da284c7fa73c22627b5"} build_url:https://queue.taskcluster.net/v1/task/R7anaQXgTwKgFY0a0bDjjA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010926 build_url: 'https://queue.taskcluster.net/v1/task/R7anaQXgTwKgFY0a0bDjjA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9931e79fb7ace27fb1c4d8a5989241b1f75d0552ae43e39fac4cef6374b5d79398930cd5436dd5227aa1f78f575fc027639ca08532a5eecf9ae236ee52a5f2e1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4a86e08f83da097684f87c01d59a011df4159edbafe2e8daf3d514002e4b0d7290ddefeedc2c7539f94a84fb956844a324c1333edbe114ba72ce88e20e654cb2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/80f1798663d4c8e3c53c332280c07a6177d01dfaea3f4facec0308d5f4df2c212a424bb9a00977193f9ac488c65afbd6c42fd93f7ca83da284c7fa73c22627b5"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-25 22:46:57.260285) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 22:46:57.261021) ========= 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.pCLH78Ko2X/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.89ywlbnuOM/Listeners TMPDIR=/var/folders/gp/w0knbrzj5_v59wx_4sx69slw00000w/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.005370 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 22:46:57.313330) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-25 22:46:57.314109) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-25 22:46:57.314849) ========= ========= Total master_lag: 0.19 =========