builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0115 starttime: 1464616918.41 results: success (0) buildid: 20160530060539 builduid: 06c33e7266294a8297960f765f604397 revision: 0c7956a1bfca3065bfc6d55c006f4ab725d0ab45 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-30 07:01:58.411836) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-30 07:01:58.412425) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-30 07:01:58.412730) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-30 07:01:58.481736) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 07:01:58.482021) ========= 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.62ipePxby1/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.VOR95Nd9N4/Listeners TMPDIR=/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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-30 07:01:58-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 15.3M=0.001s 2016-05-30 07:01:59 (15.3 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.532589 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 07:01:59.034605) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 07:01:59.034899) ========= 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.62ipePxby1/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.VOR95Nd9N4/Listeners TMPDIR=/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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.027748 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 07:01:59.082268) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-30 07:01:59.082568) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 0c7956a1bfca3065bfc6d55c006f4ab725d0ab45 --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 0c7956a1bfca3065bfc6d55c006f4ab725d0ab45 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.62ipePxby1/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.VOR95Nd9N4/Listeners TMPDIR=/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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-30 07:01:59,150 truncating revision to first 12 chars 2016-05-30 07:01:59,150 Setting DEBUG logging. 2016-05-30 07:01:59,150 attempt 1/10 2016-05-30 07:01:59,150 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/0c7956a1bfca?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-30 07:02:00,327 unpacking tar archive at: fx-team-0c7956a1bfca/testing/mozharness/ program finished with exit code 0 elapsedTime=1.432911 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-30 07:02:00.535151) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-30 07:02:00.535455) ========= script_repo_revision: 0c7956a1bfca3065bfc6d55c006f4ab725d0ab45 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-30 07:02:00.535825) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-30 07:02:00.536202) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-30 07:02:00.552424) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-05-30 07:02:00.552781) ========= /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.62ipePxby1/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.VOR95Nd9N4/Listeners TMPDIR=/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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 07:02:00 INFO - MultiFileLogger online at 20160530 07:02:00 in /builds/slave/test 07:02:00 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 07:02:00 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:02:00 INFO - {'append_to_log': False, 07:02:00 INFO - 'base_work_dir': '/builds/slave/test', 07:02:00 INFO - 'blob_upload_branch': 'Fx-Team', 07:02:00 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:02:00 INFO - 'branch': 'Fx-Team', 07:02:00 INFO - 'buildbot_json_path': 'buildprops.json', 07:02:00 INFO - 'config_files': ('talos/mac_config.py',), 07:02:00 INFO - 'default_actions': ('clobber', 07:02:00 INFO - 'read-buildbot-config', 07:02:00 INFO - 'download-and-extract', 07:02:00 INFO - 'populate-webroot', 07:02:00 INFO - 'create-virtualenv', 07:02:00 INFO - 'install', 07:02:00 INFO - 'run-tests'), 07:02:00 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:02:00 INFO - 'download_minidump_stackwalk': True, 07:02:00 INFO - 'download_symbols': 'ondemand', 07:02:00 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:02:00 INFO - 'tooltool.py': '/tools/tooltool.py', 07:02:00 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:02:00 INFO - '/tools/misc-python/virtualenv.py')}, 07:02:00 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:02:00 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:02:00 INFO - 'installer_path': 'installer.exe', 07:02:00 INFO - 'log_level': 'info', 07:02:00 INFO - 'log_name': 'talos', 07:02:00 INFO - 'log_to_console': True, 07:02:00 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:02:00 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:02:00 INFO - 'opt_config_files': (), 07:02:00 INFO - 'pip_index': False, 07:02:00 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:02:00 INFO - 'cmd': ('bash', 07:02:00 INFO - '-c', 07:02:00 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:02:00 INFO - 'enabled': True, 07:02:00 INFO - 'halt_on_failure': False, 07:02:00 INFO - 'name': 'check_screen_resolution'},), 07:02:00 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:02:00 INFO - 'cmd': ('bash', 07:02:00 INFO - '-c', 07:02:00 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:02:00 INFO - 'enabled': True, 07:02:00 INFO - 'halt_on_failure': False, 07:02:00 INFO - 'name': 'check_screen_resolution'},), 07:02:00 INFO - 'run_cmd_checks_enabled': True, 07:02:00 INFO - 'sps_profile': False, 07:02:00 INFO - 'sps_profile_interval': 0, 07:02:00 INFO - 'suite': 'dromaeojs', 07:02:00 INFO - 'system_bits': '32', 07:02:00 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:02:00 INFO - 'title': 't-yosemite-r7-0115', 07:02:00 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:02:00 INFO - 'use_talos_json': True, 07:02:00 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:02:00 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:02:00 INFO - 'work_dir': 'build'} 07:02:00 INFO - ##### 07:02:00 INFO - ##### Running clobber step. 07:02:00 INFO - ##### 07:02:00 INFO - Running pre-action listener: _resource_record_pre_action 07:02:00 INFO - Running main action method: clobber 07:02:00 INFO - rmtree: /builds/slave/test/build 07:02:00 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:02:02 INFO - Running post-action listener: _resource_record_post_action 07:02:02 INFO - ##### 07:02:02 INFO - ##### Finished clobber step (success) 07:02:02 INFO - ##### 07:02:02 INFO - ##### 07:02:02 INFO - ##### Running read-buildbot-config step. 07:02:02 INFO - ##### 07:02:02 INFO - Running pre-action listener: _resource_record_pre_action 07:02:02 INFO - Running main action method: read_buildbot_config 07:02:02 INFO - Using buildbot properties: 07:02:02 INFO - { 07:02:02 INFO - "project": "", 07:02:02 INFO - "product": "firefox", 07:02:02 INFO - "script_repo_revision": "production", 07:02:02 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 07:02:02 INFO - "repository": "", 07:02:02 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 07:02:02 INFO - "buildid": "20160530060539", 07:02:02 INFO - "pgo_build": "False", 07:02:02 INFO - "basedir": "/builds/slave/test", 07:02:02 INFO - "buildnumber": 28, 07:02:02 INFO - "slavename": "t-yosemite-r7-0115", 07:02:02 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 07:02:02 INFO - "platform": "yosemite_r7", 07:02:02 INFO - "scheduler": "tests-fx-team-macosx64-talos", 07:02:02 INFO - "branch": "fx-team", 07:02:02 INFO - "repo_path": "integration/fx-team", 07:02:02 INFO - "revision": "0c7956a1bfca3065bfc6d55c006f4ab725d0ab45", 07:02:02 INFO - "stage_platform": "macosx64", 07:02:02 INFO - "builduid": "06c33e7266294a8297960f765f604397", 07:02:02 INFO - "slavebuilddir": "test" 07:02:02 INFO - } 07:02:02 INFO - Found installer url https://queue.taskcluster.net/v1/task/PrwcUSx8QcWaKGouc0DjRA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 07:02:02 INFO - Running post-action listener: _resource_record_post_action 07:02:02 INFO - ##### 07:02:02 INFO - ##### Finished read-buildbot-config step (success) 07:02:02 INFO - ##### 07:02:02 INFO - ##### 07:02:02 INFO - ##### Running download-and-extract step. 07:02:02 INFO - ##### 07:02:02 INFO - Running pre-action listener: _resource_record_pre_action 07:02:02 INFO - Running main action method: download_and_extract 07:02:02 INFO - mkdir: /builds/slave/test/build/tests 07:02:02 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')]} 07:02:02 INFO - https://queue.taskcluster.net/v1/task/PrwcUSx8QcWaKGouc0DjRA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 07:02:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PrwcUSx8QcWaKGouc0DjRA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 07:02:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PrwcUSx8QcWaKGouc0DjRA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 07:02:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PrwcUSx8QcWaKGouc0DjRA/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 07:02:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PrwcUSx8QcWaKGouc0DjRA/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 07:02:07 INFO - Downloaded 1199 bytes. 07:02:07 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 07:02:07 INFO - Using the following test package requirements: 07:02:07 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 07:02:07 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:02:07 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 07:02:07 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:02:07 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 07:02:07 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:02:07 INFO - u'jsshell-mac.zip'], 07:02:07 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:02:07 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 07:02:07 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 07:02:07 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:02:07 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 07:02:07 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:02:07 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 07:02:07 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:02:07 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 07:02:07 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:02:07 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 07:02:07 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 07:02:07 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')]} 07:02:07 INFO - https://queue.taskcluster.net/v1/task/PrwcUSx8QcWaKGouc0DjRA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 07:02:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PrwcUSx8QcWaKGouc0DjRA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 07:02:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PrwcUSx8QcWaKGouc0DjRA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 07:02:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PrwcUSx8QcWaKGouc0DjRA/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 07:02:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PrwcUSx8QcWaKGouc0DjRA/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 07:02:08 INFO - Downloaded 18685211 bytes. 07:02:08 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'] 07:02:08 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 07:02:11 INFO - Return code: 0 07:02:11 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 07:02:11 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')]} 07:02:11 INFO - https://queue.taskcluster.net/v1/task/PrwcUSx8QcWaKGouc0DjRA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 07:02:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PrwcUSx8QcWaKGouc0DjRA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 07:02:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PrwcUSx8QcWaKGouc0DjRA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 07:02:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PrwcUSx8QcWaKGouc0DjRA/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 07:02:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PrwcUSx8QcWaKGouc0DjRA/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 07:02:15 INFO - Downloaded 18685211 bytes. 07:02:15 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'] 07:02:15 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 07:02:19 INFO - Return code: 0 07:02:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:02:19 INFO - https://queue.taskcluster.net/v1/task/PrwcUSx8QcWaKGouc0DjRA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 07:02:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PrwcUSx8QcWaKGouc0DjRA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 07:02:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PrwcUSx8QcWaKGouc0DjRA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 07:02:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PrwcUSx8QcWaKGouc0DjRA/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 07:02:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PrwcUSx8QcWaKGouc0DjRA/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 07:02:20 INFO - Downloaded 10834034 bytes. 07:02:20 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'] 07:02:20 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 07:02:20 INFO - Return code: 0 07:02:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:02:20 INFO - https://queue.taskcluster.net/v1/task/PrwcUSx8QcWaKGouc0DjRA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 07:02:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PrwcUSx8QcWaKGouc0DjRA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 07:02:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PrwcUSx8QcWaKGouc0DjRA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 07:02:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PrwcUSx8QcWaKGouc0DjRA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 07:02:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PrwcUSx8QcWaKGouc0DjRA/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 07:02:23 INFO - Downloaded 110491310 bytes. 07:02:23 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PrwcUSx8QcWaKGouc0DjRA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 07:02:23 INFO - mkdir: /builds/slave/test/properties 07:02:23 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:02:23 INFO - Writing to file /builds/slave/test/properties/build_url 07:02:23 INFO - Contents: 07:02:23 INFO - build_url:https://queue.taskcluster.net/v1/task/PrwcUSx8QcWaKGouc0DjRA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 07:02:24 INFO - Running post-action listener: _resource_record_post_action 07:02:24 INFO - Running post-action listener: set_extra_try_arguments 07:02:24 INFO - ##### 07:02:24 INFO - ##### Finished download-and-extract step (success) 07:02:24 INFO - ##### 07:02:24 INFO - ##### 07:02:24 INFO - ##### Running populate-webroot step. 07:02:24 INFO - ##### 07:02:24 INFO - Running pre-action listener: _resource_record_pre_action 07:02:24 INFO - Running main action method: populate_webroot 07:02:24 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:02:24 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:02:24 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:02:24 INFO - '--tppagecycles', 07:02:24 INFO - '7'], 07:02:24 INFO - 'tests': ['tsvgm']}}, 07:02:24 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:02:24 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:02:24 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:02:24 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:02:24 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:02:24 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:02:24 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:02:24 INFO - 'talos_options': ['--disable-e10s'], 07:02:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:02:24 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:02:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:02:24 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:02:24 INFO - 'talos_options': ['--disable-e10s'], 07:02:24 INFO - 'tests': ['damp', 'tps']}, 07:02:24 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:02:24 INFO - 'tests': ['damp', 'tps']}, 07:02:24 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:02:24 INFO - 'tests': ['dromaeo_dom']}, 07:02:24 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:02:24 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:02:24 INFO - 'tests': ['a11yr', 07:02:24 INFO - 'ts_paint', 07:02:24 INFO - 'tpaint', 07:02:24 INFO - 'sessionrestore', 07:02:24 INFO - 'sessionrestore_no_auto_restore', 07:02:24 INFO - 'tabpaint']}, 07:02:24 INFO - 'other-e10s': {'tests': ['a11yr', 07:02:24 INFO - 'ts_paint', 07:02:24 INFO - 'tpaint', 07:02:24 INFO - 'sessionrestore', 07:02:24 INFO - 'sessionrestore_no_auto_restore', 07:02:24 INFO - 'tabpaint']}, 07:02:24 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:02:24 INFO - 'ts_paint', 07:02:24 INFO - 'tpaint', 07:02:24 INFO - 'sessionrestore', 07:02:24 INFO - 'sessionrestore_no_auto_restore', 07:02:24 INFO - 'tabpaint']}, 07:02:24 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:02:24 INFO - 'ts_paint', 07:02:24 INFO - 'tpaint', 07:02:24 INFO - 'sessionrestore', 07:02:24 INFO - 'sessionrestore_no_auto_restore', 07:02:24 INFO - 'tabpaint']}, 07:02:24 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:02:24 INFO - 'tests': ['a11yr', 07:02:24 INFO - 'ts_paint', 07:02:24 INFO - 'tpaint', 07:02:24 INFO - 'sessionrestore', 07:02:24 INFO - 'sessionrestore_no_auto_restore', 07:02:24 INFO - 'tabpaint']}, 07:02:24 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:02:24 INFO - 'tests': ['a11yr', 07:02:24 INFO - 'ts_paint', 07:02:24 INFO - 'tpaint', 07:02:24 INFO - 'sessionrestore', 07:02:24 INFO - 'sessionrestore_no_auto_restore', 07:02:24 INFO - 'tabpaint']}, 07:02:24 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:02:24 INFO - 'tests': ['tsvgx', 07:02:24 INFO - 'tsvgr_opacity', 07:02:24 INFO - 'tart', 07:02:24 INFO - 'tscrollx', 07:02:24 INFO - 'cart']}, 07:02:24 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:02:24 INFO - 'tsvgr_opacity', 07:02:24 INFO - 'tart', 07:02:24 INFO - 'tscrollx', 07:02:24 INFO - 'cart']}, 07:02:24 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:02:24 INFO - 'talos_options': ['--disable-e10s'], 07:02:24 INFO - 'tests': ['tp5o']}, 07:02:24 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:02:24 INFO - 'tests': ['tp5o']}, 07:02:24 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:02:24 INFO - 'talos_options': ['--disable-e10s', 07:02:24 INFO - '--xperf_path', 07:02:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:02:24 INFO - 'tests': ['tp5n']}, 07:02:24 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:02:24 INFO - 'talos_options': ['--xperf_path', 07:02:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:02:24 INFO - 'tests': ['tp5n']}}, 07:02:24 INFO - 'talos.zip': {'path': '', 07:02:24 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:02:24 INFO - Running post-action listener: _resource_record_post_action 07:02:24 INFO - ##### 07:02:24 INFO - ##### Finished populate-webroot step (success) 07:02:24 INFO - ##### 07:02:24 INFO - ##### 07:02:24 INFO - ##### Running create-virtualenv step. 07:02:24 INFO - ##### 07:02:24 INFO - Running pre-action listener: _resource_record_pre_action 07:02:24 INFO - Running main action method: create_virtualenv 07:02:24 INFO - Creating virtualenv /builds/slave/test/build/venv 07:02:24 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 07:02:24 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:02:25 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:02:25 INFO - Using real prefix '/tools/python27' 07:02:25 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:02:25 INFO - Installing distribute.............................................................................................................................................................................................done. 07:02:28 INFO - Installing pip.................done. 07:02:28 INFO - Return code: 0 07:02:28 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:02:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:02:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:02:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:02:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:02:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:02:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:02:28 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 0x100b94a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b9a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa468cc4880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a38d30>, '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 0x10085cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100a2fb20>, '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.62ipePxby1/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.VOR95Nd9N4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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 07:02:28 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 07:02:28 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 07:02:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.62ipePxby1/Render', 07:02:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:02:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:02:28 INFO - 'HOME': '/Users/cltbld', 07:02:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:02:28 INFO - 'LOGNAME': 'cltbld', 07:02:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:02:28 INFO - 'MOZ_NO_REMOTE': '1', 07:02:28 INFO - 'NO_EM_RESTART': '1', 07:02:28 INFO - 'PAGER': '/bin/cat', 07:02:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:02:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:02:28 INFO - 'PWD': '/builds/slave/test', 07:02:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:02:28 INFO - 'SHELL': '/bin/bash', 07:02:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VOR95Nd9N4/Listeners', 07:02:28 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 07:02:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:02:28 INFO - 'USER': 'cltbld', 07:02:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:02:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:02:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:02:28 INFO - 'XPC_FLAGS': '0x0', 07:02:28 INFO - 'XPC_SERVICE_NAME': '0', 07:02:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:02:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:02:28 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 07:02:28 INFO - Cleaning up... 07:02:28 INFO - Return code: 0 07:02:28 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:02:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:02:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:02:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:02:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:02:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:02:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:02:28 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 0x100b94a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b9a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa468cc4880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a38d30>, '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 0x10085cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100a2fb20>, '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.62ipePxby1/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.VOR95Nd9N4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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 07:02:28 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 07:02:28 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 07:02:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.62ipePxby1/Render', 07:02:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:02:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:02:28 INFO - 'HOME': '/Users/cltbld', 07:02:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:02:28 INFO - 'LOGNAME': 'cltbld', 07:02:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:02:28 INFO - 'MOZ_NO_REMOTE': '1', 07:02:28 INFO - 'NO_EM_RESTART': '1', 07:02:28 INFO - 'PAGER': '/bin/cat', 07:02:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:02:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:02:28 INFO - 'PWD': '/builds/slave/test', 07:02:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:02:28 INFO - 'SHELL': '/bin/bash', 07:02:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VOR95Nd9N4/Listeners', 07:02:28 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 07:02:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:02:28 INFO - 'USER': 'cltbld', 07:02:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:02:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:02:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:02:28 INFO - 'XPC_FLAGS': '0x0', 07:02:28 INFO - 'XPC_SERVICE_NAME': '0', 07:02:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:02:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:02:29 INFO - Downloading/unpacking psutil>=3.1.1 07:02:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:02:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:02:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:02:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:02:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:02:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:02:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:02:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:02:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:02:31 INFO - Installing collected packages: psutil 07:02:31 INFO - Running setup.py install for psutil 07:02:31 INFO - building 'psutil._psutil_osx' extension 07:02:31 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 07:02:32 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 07:02:32 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 07:02:32 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 07:02:32 INFO - building 'psutil._psutil_posix' extension 07:02:32 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 07:02:32 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:02:32 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:02:32 INFO - ^ 07:02:32 INFO - 1 warning generated. 07:02:32 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 07:02:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:02:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:02:32 INFO - Successfully installed psutil 07:02:32 INFO - Cleaning up... 07:02:33 INFO - Return code: 0 07:02:33 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:02:33 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')]} 07:02:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:02:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:02:33 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')]} 07:02:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:02:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:02:33 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 0x100b94a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b9a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa468cc4880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a38d30>, '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 0x10085cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100a2fb20>, '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.62ipePxby1/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.VOR95Nd9N4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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 07:02:33 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 07:02:33 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 07:02:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.62ipePxby1/Render', 07:02:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:02:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:02:33 INFO - 'HOME': '/Users/cltbld', 07:02:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:02:33 INFO - 'LOGNAME': 'cltbld', 07:02:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:02:33 INFO - 'MOZ_NO_REMOTE': '1', 07:02:33 INFO - 'NO_EM_RESTART': '1', 07:02:33 INFO - 'PAGER': '/bin/cat', 07:02:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:02:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:02:33 INFO - 'PWD': '/builds/slave/test', 07:02:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:02:33 INFO - 'SHELL': '/bin/bash', 07:02:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VOR95Nd9N4/Listeners', 07:02:33 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 07:02:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:02:33 INFO - 'USER': 'cltbld', 07:02:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:02:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:02:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:02:33 INFO - 'XPC_FLAGS': '0x0', 07:02:33 INFO - 'XPC_SERVICE_NAME': '0', 07:02:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:02:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:02:33 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:02:33 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 07:02:33 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 07:02:33 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 07:02:33 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 07:02:33 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 07:02:33 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 07:02:35 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:02:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:02:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:02:35 INFO - Installing collected packages: mozsystemmonitor 07:02:35 INFO - Running setup.py install for mozsystemmonitor 07:02:35 INFO - Successfully installed mozsystemmonitor 07:02:35 INFO - Cleaning up... 07:02:36 INFO - Return code: 0 07:02:36 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:02:36 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')]} 07:02:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:02:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:02:36 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')]} 07:02:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:02:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:02:36 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 0x100b94a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b9a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa468cc4880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a38d30>, '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 0x10085cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100a2fb20>, '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.62ipePxby1/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.VOR95Nd9N4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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 07:02:36 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 07:02:36 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 07:02:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.62ipePxby1/Render', 07:02:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:02:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:02:36 INFO - 'HOME': '/Users/cltbld', 07:02:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:02:36 INFO - 'LOGNAME': 'cltbld', 07:02:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:02:36 INFO - 'MOZ_NO_REMOTE': '1', 07:02:36 INFO - 'NO_EM_RESTART': '1', 07:02:36 INFO - 'PAGER': '/bin/cat', 07:02:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:02:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:02:36 INFO - 'PWD': '/builds/slave/test', 07:02:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:02:36 INFO - 'SHELL': '/bin/bash', 07:02:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VOR95Nd9N4/Listeners', 07:02:36 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 07:02:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:02:36 INFO - 'USER': 'cltbld', 07:02:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:02:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:02:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:02:36 INFO - 'XPC_FLAGS': '0x0', 07:02:36 INFO - 'XPC_SERVICE_NAME': '0', 07:02:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:02:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:02:36 INFO - Downloading/unpacking blobuploader==1.2.4 07:02:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:02:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:02:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:02:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:02:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:02:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:02:38 INFO - Downloading blobuploader-1.2.4.tar.gz 07:02:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:02:38 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:02: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 07:02: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 07:02: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 07:02: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 07:02: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 07:02: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 07:02:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:02:39 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:02:39 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 07:02:39 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 07:02:39 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 07:02:39 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 07:02:39 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 07:02:39 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 07:02:39 INFO - Downloading docopt-0.6.1.tar.gz 07:02:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:02:39 INFO - Installing collected packages: blobuploader, requests, docopt 07:02:39 INFO - Running setup.py install for blobuploader 07:02:40 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:02:40 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:02:40 INFO - Running setup.py install for requests 07:02:40 INFO - Running setup.py install for docopt 07:02:40 INFO - Successfully installed blobuploader requests docopt 07:02:40 INFO - Cleaning up... 07:02:40 INFO - Return code: 0 07:02:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:02: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')]} 07:02:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:02:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:02: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')]} 07:02:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:02:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:02:40 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 0x100b94a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b9a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa468cc4880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a38d30>, '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 0x10085cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100a2fb20>, '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.62ipePxby1/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.VOR95Nd9N4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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 07:02:40 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 07:02:40 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 07:02:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.62ipePxby1/Render', 07:02:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:02:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:02:40 INFO - 'HOME': '/Users/cltbld', 07:02:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:02:40 INFO - 'LOGNAME': 'cltbld', 07:02:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:02:40 INFO - 'MOZ_NO_REMOTE': '1', 07:02:40 INFO - 'NO_EM_RESTART': '1', 07:02:40 INFO - 'PAGER': '/bin/cat', 07:02:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:02:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:02:40 INFO - 'PWD': '/builds/slave/test', 07:02:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:02:40 INFO - 'SHELL': '/bin/bash', 07:02:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VOR95Nd9N4/Listeners', 07:02:40 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 07:02:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:02:40 INFO - 'USER': 'cltbld', 07:02:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:02:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:02:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:02:40 INFO - 'XPC_FLAGS': '0x0', 07:02:40 INFO - 'XPC_SERVICE_NAME': '0', 07:02:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:02:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:02:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:02:41 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-m1mbRE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:02:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:02:41 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-OCd1YU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:02:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:02:41 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-kS5MrG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:02:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:02:41 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-HxQA6p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:02:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:02:41 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-HW_BWx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:02:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:02:41 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-LxGQhc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:02:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:02:41 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-BQ91ig-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:02:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:02:41 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-66UHgV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:02:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:02:41 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-J819g8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:02:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:02:42 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-84otad-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:02:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:02:42 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-hXzwaD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:02:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:02:42 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-CryZ0s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:02:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:02:42 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-rwgrNc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:02:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:02:42 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-B2Lqgt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:02:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:02:42 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-kshFC1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:02:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:02:42 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-eE0SiJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:02:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:02:42 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-DYFgqa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:02:42 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:02:42 INFO - Running setup.py install for manifestparser 07:02:43 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:02:43 INFO - Running setup.py install for mozcrash 07:02:43 INFO - Running setup.py install for mozdebug 07:02:43 INFO - Running setup.py install for mozdevice 07:02:43 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:02:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:02:43 INFO - Running setup.py install for mozfile 07:02:43 INFO - Running setup.py install for mozhttpd 07:02:43 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:02:43 INFO - Running setup.py install for mozinfo 07:02:43 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:02:43 INFO - Running setup.py install for mozInstall 07:02:44 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:02:44 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:02:44 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:02:44 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:02:44 INFO - Running setup.py install for mozleak 07:02:44 INFO - Running setup.py install for mozlog 07:02:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:02:44 INFO - Running setup.py install for moznetwork 07:02:44 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:02:44 INFO - Running setup.py install for mozprocess 07:02:44 INFO - Running setup.py install for mozprofile 07:02:44 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:02:44 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:02:44 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:02:44 INFO - Running setup.py install for mozrunner 07:02:44 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:02:44 INFO - Running setup.py install for mozscreenshot 07:02:45 INFO - Running setup.py install for moztest 07:02:45 INFO - Running setup.py install for mozversion 07:02:45 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:02:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:02:45 INFO - Cleaning up... 07:02:45 INFO - Return code: 0 07:02:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:02:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:02:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:02:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:02:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:02:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:02:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:02:45 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 0x100b94a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b9a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa468cc4880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a38d30>, '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 0x10085cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100a2fb20>, '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.62ipePxby1/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.VOR95Nd9N4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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 07:02:45 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 07:02:45 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 07:02:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.62ipePxby1/Render', 07:02:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:02:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:02:45 INFO - 'HOME': '/Users/cltbld', 07:02:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:02:45 INFO - 'LOGNAME': 'cltbld', 07:02:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:02:45 INFO - 'MOZ_NO_REMOTE': '1', 07:02:45 INFO - 'NO_EM_RESTART': '1', 07:02:45 INFO - 'PAGER': '/bin/cat', 07:02:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:02:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:02:45 INFO - 'PWD': '/builds/slave/test', 07:02:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:02:45 INFO - 'SHELL': '/bin/bash', 07:02:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VOR95Nd9N4/Listeners', 07:02:45 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 07:02:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:02:45 INFO - 'USER': 'cltbld', 07:02:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:02:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:02:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:02:45 INFO - 'XPC_FLAGS': '0x0', 07:02:45 INFO - 'XPC_SERVICE_NAME': '0', 07:02:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:02:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:02:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:02:45 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-DpS1lj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:02:45 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)) 07:02:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:02:45 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-n8SnTy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:02:45 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)) 07:02:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:02:45 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-7Bscu0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:02:46 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)) 07:02:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:02:46 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-c5qy60-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:02:46 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)) 07:02:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:02:46 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-fugRkD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:02:46 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)) 07:02:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:02:46 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-2TN1uK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:02:46 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)) 07:02:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:02:46 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-NPU9wz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:02:46 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)) 07:02:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:02:46 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-AXvLd6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:02:46 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)) 07:02:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:02:46 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-Z4rYYr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:02:46 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)) 07:02:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:02:46 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-dx4BxI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:02:46 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)) 07:02:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:02:46 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-mrHbsh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:02:46 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)) 07:02:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:02:46 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-OEn3Rm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:02:46 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)) 07:02:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:02:46 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-u7prYU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:02:47 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)) 07:02:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:02:47 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-MxOl_U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:02:47 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)) 07:02:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:02:47 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-ywZCHO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:02:47 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)) 07:02:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:02:47 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-XKzfRr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:02:47 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)) 07:02:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:02:47 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-7vr9_1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:02:47 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)) 07:02:47 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)) 07:02:47 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)) 07:02:47 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)) 07:02:47 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)) 07:02:47 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)) 07:02:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:02:47 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 07:02:47 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 07:02:47 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 07:02:47 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 07:02:47 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 07:02:47 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 07:02:49 INFO - Downloading blessings-1.6.tar.gz 07:02:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:02:50 INFO - Installing collected packages: blessings 07:02:50 INFO - Running setup.py install for blessings 07:02:50 INFO - Successfully installed blessings 07:02:50 INFO - Cleaning up... 07:02:50 INFO - Return code: 0 07:02:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:02:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:02:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:02:50 INFO - Reading from file tmpfile_stdout 07:02:50 INFO - Current package versions: 07:02:50 INFO - blessings == 1.6 07:02:50 INFO - blobuploader == 1.2.4 07:02:50 INFO - docopt == 0.6.1 07:02:50 INFO - manifestparser == 1.1 07:02:50 INFO - mozInstall == 1.12 07:02:50 INFO - mozcrash == 0.17 07:02:50 INFO - mozdebug == 0.1 07:02:50 INFO - mozdevice == 0.48 07:02:50 INFO - mozfile == 1.2 07:02:50 INFO - mozhttpd == 0.7 07:02:50 INFO - mozinfo == 0.9 07:02:50 INFO - mozleak == 0.1 07:02:50 INFO - mozlog == 3.2 07:02:50 INFO - moznetwork == 0.27 07:02:50 INFO - mozprocess == 0.22 07:02:50 INFO - mozprofile == 0.28 07:02:50 INFO - mozrunner == 6.11 07:02:50 INFO - mozscreenshot == 0.1 07:02:50 INFO - mozsystemmonitor == 0.3 07:02:50 INFO - moztest == 0.7 07:02:50 INFO - mozversion == 1.4 07:02:50 INFO - psutil == 3.1.1 07:02:50 INFO - requests == 1.2.3 07:02:50 INFO - wsgiref == 0.1.2 07:02:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:02:50 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')]} 07:02:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:02:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:02:50 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')]} 07:02:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:02:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:02:50 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 0x100b94a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b9a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa468cc4880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a38d30>, '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 0x10085cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100a2fb20>, '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.62ipePxby1/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.VOR95Nd9N4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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 07:02:50 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 07:02:50 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 07:02:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.62ipePxby1/Render', 07:02:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:02:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:02:50 INFO - 'HOME': '/Users/cltbld', 07:02:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:02:50 INFO - 'LOGNAME': 'cltbld', 07:02:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:02:50 INFO - 'MOZ_NO_REMOTE': '1', 07:02:50 INFO - 'NO_EM_RESTART': '1', 07:02:50 INFO - 'PAGER': '/bin/cat', 07:02:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:02:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:02:50 INFO - 'PWD': '/builds/slave/test', 07:02:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:02:50 INFO - 'SHELL': '/bin/bash', 07:02:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VOR95Nd9N4/Listeners', 07:02:50 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 07:02:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:02:50 INFO - 'USER': 'cltbld', 07:02:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:02:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:02:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:02:50 INFO - 'XPC_FLAGS': '0x0', 07:02:50 INFO - 'XPC_SERVICE_NAME': '0', 07:02:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:02:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:02:50 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)) 07:02:50 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)) 07:02:50 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)) 07:02:50 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)) 07:02:50 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)) 07:02:50 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)) 07:02:50 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)) 07:02:50 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)) 07:02:50 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)) 07:02:50 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)) 07:02:50 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)) 07:02:50 INFO - Cleaning up... 07:02:50 INFO - Return code: 0 07:02:50 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:02:50 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')]} 07:02:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:02:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:02:50 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')]} 07:02:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:02:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:02:50 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 0x100b94a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b9a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa468cc4880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a38d30>, '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 0x10085cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100a2fb20>, '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.62ipePxby1/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.VOR95Nd9N4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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 07:02:50 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 07:02:50 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 07:02:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.62ipePxby1/Render', 07:02:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:02:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:02:50 INFO - 'HOME': '/Users/cltbld', 07:02:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:02:50 INFO - 'LOGNAME': 'cltbld', 07:02:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:02:50 INFO - 'MOZ_NO_REMOTE': '1', 07:02:50 INFO - 'NO_EM_RESTART': '1', 07:02:50 INFO - 'PAGER': '/bin/cat', 07:02:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:02:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:02:50 INFO - 'PWD': '/builds/slave/test', 07:02:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:02:50 INFO - 'SHELL': '/bin/bash', 07:02:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VOR95Nd9N4/Listeners', 07:02:50 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 07:02:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:02:50 INFO - 'USER': 'cltbld', 07:02:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:02:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:02:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:02:50 INFO - 'XPC_FLAGS': '0x0', 07:02:50 INFO - 'XPC_SERVICE_NAME': '0', 07:02:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:02:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:02:51 INFO - Downloading/unpacking jsonschema 07:02:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:02:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:02:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:02:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:02:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:02:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:02:53 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:02:53 INFO - Downloading/unpacking functools32 (from jsonschema) 07:02:53 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 07:02:53 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 07:02:53 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 07:02:53 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 07:02:53 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 07:02:53 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 07:02:53 INFO - Downloading functools32-3.2.3-2.tar.gz 07:02:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:02:53 INFO - warning: no files found matching '*.txt' 07:02:53 INFO - no previously-included directories found matching 'build' 07:02:53 INFO - no previously-included directories found matching 'dist' 07:02:53 INFO - no previously-included directories found matching '.git*' 07:02:54 INFO - Installing collected packages: jsonschema, functools32 07:02:54 INFO - Running setup.py install for functools32 07:02:54 INFO - warning: no files found matching '*.txt' 07:02:54 INFO - no previously-included directories found matching 'build' 07:02:54 INFO - no previously-included directories found matching 'dist' 07:02:54 INFO - no previously-included directories found matching '.git*' 07:02:54 INFO - Successfully installed jsonschema functools32 07:02:54 INFO - Cleaning up... 07:02:54 INFO - Return code: 0 07:02:54 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 07:02: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')]} 07:02:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:02:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:02: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')]} 07:02:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:02:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:02:54 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 0x100b94a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b9a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa468cc4880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a38d30>, '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 0x10085cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100a2fb20>, '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.62ipePxby1/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.VOR95Nd9N4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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 07:02:54 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 07:02:54 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 07:02:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.62ipePxby1/Render', 07:02:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:02:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:02:54 INFO - 'HOME': '/Users/cltbld', 07:02:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:02:54 INFO - 'LOGNAME': 'cltbld', 07:02:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:02:54 INFO - 'MOZ_NO_REMOTE': '1', 07:02:54 INFO - 'NO_EM_RESTART': '1', 07:02:54 INFO - 'PAGER': '/bin/cat', 07:02:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:02:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:02:54 INFO - 'PWD': '/builds/slave/test', 07:02:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:02:54 INFO - 'SHELL': '/bin/bash', 07:02:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VOR95Nd9N4/Listeners', 07:02:54 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 07:02:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:02:54 INFO - 'USER': 'cltbld', 07:02:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:02:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:02:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:02:54 INFO - 'XPC_FLAGS': '0x0', 07:02:54 INFO - 'XPC_SERVICE_NAME': '0', 07:02:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:02:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:02:54 INFO - Downloading/unpacking flake8 07:02:54 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 07:02:54 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 07:02:54 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 07:02:54 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 07:02:54 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 07:02:54 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 07:02:57 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 07:02:57 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 07:02:57 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 07:02:57 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 07:02:57 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 07:02:57 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 07:02:57 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 07:02:57 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 07:02:57 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 07:02:57 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 07:02:57 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 07:02:57 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 07:02:57 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 07:02:57 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 07:02:57 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 07:02:57 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 07:02:57 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 07:02:57 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 07:02:57 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 07:02:57 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 07:02:57 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 07:02:57 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 07:02:57 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 07:02:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 07:02:58 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:02:58 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:02:58 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:02:58 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:02:58 INFO - no previously-included directories found matching 'docs/_build' 07:02:58 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 07:02:58 INFO - Running setup.py install for pep8 07:02:58 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:02:58 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:02:58 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:02:58 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:02:58 INFO - no previously-included directories found matching 'docs/_build' 07:02:58 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 07:02:58 INFO - Successfully installed flake8 mccabe pyflakes pep8 07:02:58 INFO - Cleaning up... 07:02:58 INFO - Return code: 0 07:02:58 INFO - Running post-action listener: _resource_record_post_action 07:02:58 INFO - Running post-action listener: _start_resource_monitoring 07:02:58 INFO - Starting resource monitoring. 07:02:58 INFO - ##### 07:02:58 INFO - ##### Finished create-virtualenv step (success) 07:02:58 INFO - ##### 07:02:58 INFO - ##### 07:02:58 INFO - ##### Running install step. 07:02:58 INFO - ##### 07:02:58 INFO - Running pre-action listener: _resource_record_pre_action 07:02:58 INFO - Running main action method: install 07:02:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:02:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:02:58 INFO - Reading from file tmpfile_stdout 07:02:58 INFO - Detecting whether we're running mozinstall >=1.0... 07:02:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:02:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:02:59 INFO - Reading from file tmpfile_stdout 07:02:59 INFO - Output received: 07:02:59 INFO - Usage: mozinstall [options] installer 07:02:59 INFO - Options: 07:02:59 INFO - -h, --help show this help message and exit 07:02:59 INFO - -d DEST, --destination=DEST 07:02:59 INFO - Directory to install application into. [default: 07:02:59 INFO - "/builds/slave/test"] 07:02:59 INFO - --app=APP Application being installed. [default: firefox] 07:02:59 INFO - mkdir: /builds/slave/test/build/application 07:02:59 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'] 07:02:59 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 07:03:32 INFO - Reading from file tmpfile_stdout 07:03:32 INFO - Output received: 07:03:32 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 07:03:32 INFO - Running post-action listener: _resource_record_post_action 07:03:32 INFO - ##### 07:03:32 INFO - ##### Finished install step (success) 07:03:32 INFO - ##### 07:03:32 INFO - ##### 07:03:32 INFO - ##### Running run-tests step. 07:03:32 INFO - ##### 07:03:32 INFO - Running pre-action listener: _resource_record_pre_action 07:03:32 INFO - Running main action method: run_tests 07:03:32 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:03:32 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:03:32 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:03:32 INFO - 2016-05-30 07:03:32.992 screenresolution[1647:7654] starting screenresolution argv=screenresolution get 07:03:32 INFO - 2016-05-30 07:03:32.994 screenresolution[1647:7654] Display 0: 1600x1200x32@60 07:03:33 INFO - 2016-05-30 07:03:33.000 screenresolution[1648:7657] starting screenresolution argv=screenresolution list 07:03:33 INFO - Available Modes on Display 0 07:03:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:03:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:03:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:03:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:03:33 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:03:33 INFO - Intel Iris: 07:03:33 INFO - Chipset Model: Intel Iris 07:03:33 INFO - Type: GPU 07:03:33 INFO - Bus: Built-In 07:03:33 INFO - VRAM (Dynamic, Max): 1536 MB 07:03:33 INFO - Vendor: Intel (0x8086) 07:03:33 INFO - Device ID: 0x0a2e 07:03:33 INFO - Revision ID: 0x0009 07:03:33 INFO - Displays: 07:03:33 INFO - Display: 07:03:33 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:03:33 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:03:33 INFO - Display Serial Number: 1600x1200 60 07:03:33 INFO - Main Display: Yes 07:03:33 INFO - Mirror: Off 07:03:33 INFO - Online: Yes 07:03:33 INFO - Rotation: Supported 07:03:33 INFO - Return code: 0 07:03:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:03:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:03:33 INFO - Python 2.7.3 07:03:33 INFO - Return code: 0 07:03:33 INFO - grabbing minidump binary from tooltool 07:03:33 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')]} 07:03:33 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 0x100a38d30>, '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 0x10085cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100a2fb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:03:33 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 07:03:33 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 07:03:33 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 07:03:33 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:03:33 INFO - Return code: 0 07:03:33 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:03:33 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:03:33 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:03:33 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:03:33 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:03:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 07:03:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 07:03:34 INFO - Return code: 0 07:03:34 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/PrwcUSx8QcWaKGouc0DjRA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0115', '--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 07:03:34 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/PrwcUSx8QcWaKGouc0DjRA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0115 --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 07:03:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.62ipePxby1/Render', 07:03:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:03:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:03:34 INFO - 'HOME': '/Users/cltbld', 07:03:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:03:34 INFO - 'LOGNAME': 'cltbld', 07:03:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:03:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:03:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:03:34 INFO - 'MOZ_NO_REMOTE': '1', 07:03:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:03:34 INFO - 'NO_EM_RESTART': '1', 07:03:34 INFO - 'PAGER': '/bin/cat', 07:03:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:03:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:03:34 INFO - 'PWD': '/builds/slave/test', 07:03:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:03:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:03:34 INFO - 'SHELL': '/bin/bash', 07:03:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VOR95Nd9N4/Listeners', 07:03:34 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 07:03:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:03:34 INFO - 'USER': 'cltbld', 07:03:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:03:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:03:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:03:34 INFO - 'XPC_FLAGS': '0x0', 07:03:34 INFO - 'XPC_SERVICE_NAME': '0', 07:03:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:03:34 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/PrwcUSx8QcWaKGouc0DjRA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0115', '--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 07:03:34 INFO - mozversion application_buildid: 20160530060539 07:03:34 INFO - mozversion application_changeset: 0c7956a1bfca3065bfc6d55c006f4ab725d0ab45 07:03:34 INFO - mozversion application_display_name: Nightly 07:03:34 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:03:34 INFO - mozversion application_name: Firefox 07:03:34 INFO - mozversion application_remotingname: firefox 07:03:34 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 07:03:34 INFO - mozversion application_vendor: Mozilla 07:03:34 INFO - mozversion application_version: 49.0a1 07:03:34 INFO - mozversion platform_buildid: 20160530060539 07:03:34 INFO - mozversion platform_changeset: 0c7956a1bfca3065bfc6d55c006f4ab725d0ab45 07:03:34 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 07:03:34 INFO - mozversion platform_version: 49.0a1 07:03:34 INFO - using testdate: 1464617014 07:03:34 INFO - actual date: 1464617014 07:03:34 INFO - starting webserver on 'localhost:49275' 07:03:34 INFO - SUITE-START | Running 2 tests 07:03:34 INFO - TEST-START | dromaeo_css 07:03:34 INFO - operating with platform_type : mac_ 07:03:34 INFO - Initialising browser for dromaeo_css test... 07:03:34 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/tmp1onTAs/profile http://localhost:49275/getInfo.html) 07:03:37 INFO - PROCESS | 1666 | __metrics Screen width/height:1600/1200 07:03:37 INFO - PROCESS | 1666 | colorDepth:24 07:03:37 INFO - PROCESS | 1666 | Browser inner width/height: 1024/689 07:03:37 INFO - PROCESS | 1666 | __metrics 07:03:37 INFO - PROCESS | 1666 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:03:37 ERROR - PROCESS | 1666 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 07:03:37 INFO - TEST-INFO | 1666: exit 0 07:03:37 INFO - Browser initialized. 07:03:37 INFO - Running cycle 1/1 for dromaeo_css test... 07:03:37 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/tmp1onTAs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:03:50 INFO - PROCESS | 1669 | RSS: Main: 241664000 07:03:50 INFO - PROCESS | 1669 | 07:04:41 INFO - PROCESS | 1669 | 1464617081779 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] 07:06:52 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 07:06:53 INFO - PROCESS | 1669 | RSS: Main: 409944064 07:06:53 INFO - PROCESS | 1669 | 07:09:56 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 07:09:57 INFO - PROCESS | 1669 | RSS: Main: 381075456 07:09:57 INFO - PROCESS | 1669 | 07:12:59 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 07:13:00 INFO - PROCESS | 1669 | RSS: Main: 376680448 07:13:00 INFO - PROCESS | 1669 | 07:16:02 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 07:16:03 INFO - PROCESS | 1669 | RSS: Main: 371208192 07:16:03 INFO - PROCESS | 1669 | 07:19:06 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 07:19:07 INFO - PROCESS | 1669 | RSS: Main: 343674880 07:19:07 INFO - PROCESS | 1669 | 07:22:10 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 07:22:10 INFO - PROCESS | 1669 | RSS: Main: 381988864 07:22:10 INFO - PROCESS | 1669 | 07:22:10 INFO - PROCESS | 1669 | __start_tp_report 07:22:10 INFO - PROCESS | 1669 | _x_x_mozilla_page_load 07:22:10 INFO - PROCESS | 1669 | _x_x_mozilla_page_load_details 07:22:10 INFO - PROCESS | 1669 | |i|pagename|runs| 07:22:10 INFO - PROCESS | 1669 | |0;dojo.html;3006.2484378905274;3398.9490157647638;3401.608814986276;3405.625381208068;3445.2248244145053;5469.507744303012;5476.972615136924;5585.720713964306;5597.944020559793;5599.104143337062;4658.510856360084;5545.195946587743;5561.58288128391;5660.122680984454;5749.856253593663;5016.949830501703;5070.112730272206;5124.103281925648;5130.9743451282875;5262.2632831403635;5173.922391164136;5215.426303106655;5224.45143259958;5225.190095535198;5293.947060529384;4186.49762028557;5012.1980483122525;5068.543831055186;5073.949260507385;5193.480651934814;5115.283860259567;5134.974325128388;5136.972605478919;5142.691438513663;5143.151380022292;4986.775595098229;5026.673266237683;5091.032703786306;5123.410807757118;5133.589312854962;5380.381256155553;5399.919001214985;5421.349438067457;5482.2873026506695;5559.833205003856;6066.617731664256;6112.050940411485;6124.234470691164;6127.325994140641;6136.23297087864;6040.818775436744;6098.129681996337;6126.609281623656;6167.383261673842;6239.469645080174;5763.164341170549;5797.594168408171;5802.274715660543;5803.332616749086;5805.245318108618;6132.95739724248;6314.273858506239;6318.810435686937;6319.715612797435;6321.304656487875;5857.355690874;5960.910586341209;5964.373740757158;5982.341942386334;5990.101484777319;6097.464185086671;6106.206193194945;6118.265808103056;6120.538559592075;6124.777645458507;6114.205153330038;6124.5100391968535;6139.430809034379;6141.455323060903;6142.682951206388;6108.680338522356;6146.385361463817;6147.969260153671;6152;6163.660998645028;4756.072565849695;4896.2655601660035;4902.362692849978;4902.656814023056;4935.358403407534;4274.5939135782055;4389.604935555815;4397.494288156839;4411.757353345597;4423.314386270134;5508.228847961289;5510.641808282448;5520.889582208333;5522.392536820947;5529.30883639545;4900.975495122573;6038.094285857069;6048.123022161762;6055.122007308915;6057.757689692363;5503.147012213113;5511.090670039399;5512.3385193777;5515.834524964257;5520.641158324696;4414.933775993362;5218.139007063868;5251.343582052244;5279.44565820591;5331.386890507563;6260.718267677966;6275.811725648238;6276.058591211355;6276.152719382916;6286.937130628727;6166.568340216142;6173.876522469615;6193.473554747897;6196.690165491797;6203.4416902479;6146.200993870947;6226.844328891632;6262.09199666055;6272.090546870587;6289.7484100635465;6077.392260774064;6091.0558863376245;6101.120797011208;6103.481204097701;6107.450329470369;6142.49757021464;6161.84595385119;6174.845628859314;6186.505079593711;6190.473809726223;5645.040343141593;5684.090545512863;5834.212381328466;5926.733297001731;5930.881382372415;5847.035239185487;5853.502452291603;5885.941140588539;5897.144913987534;5930.673812940329;5808.738606762621;5811.767529298781;5835.183074369506;5862.589618726648;5893.145493903446;5968.31364393083;5974.342822289458;5980.282366116094;5994.370591088047;6032.306284777307;5666.15007748841;5683.857903552444;5728.3412407572;5818.592698511064;5822.592418530662 07:22:10 INFO - PROCESS | 1669 | |1;ext.html;492.2272032908334;507.48490282363406;528.4187393866745;528.434575004745;538.4211972129962;30646.233844153878;31821;43401.91495212617;44583.77708111463;44715.552844471546;41988.37017444741;43753.7812310938;46136.769316153455;46578.068438631206;47025.529744702544;9543.188828949547;9758;9767.75580610487;9770.120689137977;9772.657956971507;8702.695405660803;8934.463932164059;8936.910630893673;9053.275737940981;9055.411398259092;11681.941590292037;11911.642650720492;11995.7600847983;12096.758064838697;12097.213681110743;11733.823992640117;11814.29114253139;11850.881491185108;11854;11861.584844530485;8641.006284277326;10101.343412678152;11734.94132529332;11737.823932641017;11838.348890811001;42239.366409503884;42731.57268427338;42807.785961069996;42825;42946;3498.2828520153307;3575.249197668493;3708.87018954338;3897.5170640023684;4036.2129384769983;28056.018039368526;31871.84064079688;32437.189070273194;32519.18702032468;33348.66651333504;3331.583552055993;3409.5567576214935;3437.862485500577;3455.1016735648914;3485.8431370588382;517148;517262;517409;518062.4096879529;518478;66865;66882.33117668859;67547;67566.66216668885;67590.986135208;57052;70105.64947175334;70105.64947175334;70214.64892675607;70224.6488767553;17201.7419738704;17459.21433535494;17488.950662960262;17490.56273593145;17552.03463809503;11812.52749890012;12412.379381030913;13087.607371778862;13160.934195329153;13165.93417032928;16670.916390434584;17029.574260643334;17525.484936518635;18943.43169704911;19326.613467730567;5979.252593425822;6983.266756990544;7018.613976231254;7105.538140020882;7109.92890071103;897.3359713811863;1043.5512729526376;1094.1848322999258;1120.2158489057692;1128.0298942909255;22749;27021.72978270192;31518.054458366285;31617.683823161933;32076.679233207837;1060.4273692206277;1086.5327908999047;1091.67795500326;1107.6621630402803;1125.6341688951125;15214.934954553075;16222.439296678676;16396.508104756875;16666.83333166677;16698.83301166997;668293;671942;672884;673824;673831;63590;63725;63861;63893;64184.35815641784;1389.7220555888862;1433.5770947570493;1446.4503488674236;1453.055513915963;1455.745244582194;30229;31233.375332492767;31234.531482027334;31752.460370374276;32089.67910320867;56421.153682695614;56680.149797752245;57165;57286;57352.1397179051;431130;440840;444046;444134;445321;33477.66522334745;36833.447498288086;44577.331340029275;44959.55040449554;45172.548274516834;38803.223935521695;39702.808915732574;42277.78861105675;51241.48758512367;53742.73128634332;41031.58968410278;41851.67454570949;42500.36249456199;42603.7869810649;42658.14683706371;40636.390454142624;40949.79525102475;41687.791561042;41855.7907210462;41910;39295.41056884206;39803.99173027925;40438;40610.79694601508;40720;34007.659923400446;35229.64770352366;36466.63533364632;36561.90314290576;37423.43864841975;26081.08716195;26285.60571591466;26300.473990520466;26460.470790584466;27005.054823081096 07:22:10 INFO - PROCESS | 1669 | |2;jquery.html;303.9635243770748;371.40389674572316;375.41810194198985;377.7091639437635;399.6183644619389;15393.846061539381;15742.370305187793;18025.4592362229;18052.729209061876;18476.260949562;19576.510587235327;20400.387988360337;21214.151433942625;21224.938753062324;21414.357569272906;5869.295684517864;6029.728662210211;6074.5747797654185;6146.815595532141;6155.261368635747;5853.824385268449;5974.312954010279;5999.370066143058;6016.338202797689;6030.246219222598;5893.44012318829;5970.1940238067955;5980.491658209057;5994.220751302347;6003.6697981611;5910.408959104098;5949.732262048218;5954.106883967418;5966.045432730764;5970.253718285215;4967.875803104916;5079.1365467870555;5254.7635356409055;5289.761960711778;5303.575713942914;12752.426140823685;12858.485660573566;12933.805992910115;12982.675433114153;13035.478580856754;2109.546447513792;2143.6462983607685;2144.0994782191515;2185.136870935987;2856.5857950597256;9329.766755831159;9633.470159141245;9673.758156046084;9827.557759900821;9865.802683946353;1596.5609457399307;1613.5804690780365;1741.5297869575145;1822.6081392500676;1877.9906100469411;54870.17694734582;75771;76618.61690691566;120311;120698.39650801777;11456.599019034295;11604;11605.88394116065;11636;11815.172937894436;14504.709905802036;14621.853781462261;14652;14656.340464679115;14665.926670366793;6906.274841141708;8414.242718155396;8560.229579337889;8666.350023748244;8686.95656521726;8473.279771219515;8496.405251632326;8530.616122274512;8650.048494665582;8671.21959023691;3777.905552361158;4431.313146462304;4531.501534831166;4559.658025648089;4583.152116858392;1640.8031036275484;1879.2201236486978;1897.6658709787039;1921.4427815933502;1925.2299080367684;817.3175398542165;928.3640706116332;940.6237504998055;1013.2704452793977;1028.3803010258873;17954.281828726966;20132.89933550323;21079.8946005269;21308.680369794467;21762.12951481923;959.5442164971722;968.5931908598405;1000.4997501249376;1006.9345492542961;1032.5869652139054;9106.86339704905;9754.317197796228;10618.097461715686;11004.779904401867;11435.656930292105;579368;587152;597996;598230;600973;101492.6990758072;105399;133631;133661;135626;964.1852634523816;996.1731762636994;1022.6062965758236;1024.893843152329;1029.8146333659863;19487.512812179808;19802.801971980673;19809.504762381057;19837.801621983595;19842.603147936672;41152.97117571965;51903.48096518986;51936.74031629818;53415.46584534105;53879;71893;75571.62214189114;75837.9184603956;76021;76625.61687191528;8036;8215.219554142346;8271.421000529905;8286.502809831429;8443.282321002784;12938.805917911428;13082.476700932237;13105.606831795061;13204.339783011003;13313.86686133165;7751.844963100819;11137.164712646421;11318.434078296217;11322.490487928228;11379.260348077349;11139.554417823196;11188.552457901593;11233.438328083399;11460.312381257152;11498.712532186762;10453.790924181625;11406.20156589064;11546;11647.534098636299;11847.696753356951;7656.885146722692;8131.878021829559;8213.83572328562;8236.094029656853;8299.29455996247;11320.433978300887;11459.942700286445;11464.828027579426;11470.483828227583;11498.712532186428 07:22:10 INFO - PROCESS | 1669 | |3;mootools.html;1409.8519655436173;1543.76843473479;1575.4328441760963;1809.375765360951;1962.5486138188226;7694.23057694231;8491.660333586657;8670.26302764265;8731.25784308333;8789.077146899579;8392.538410387426;8641.611127499262;8651.178138076888;8651.567421628924;8667.17661822126;2324.721033475981;2355.8350915435926;2359.327591636384;2359.7994170495526;2362.2795047510504;2110.419634600482;2130.084063852543;2275.6586512023246;2301.585714571385;2344.22640528626;2260.2880092770806;2272.8977196026135;2286.3598192506042;2327.511222643247;2332.9066837326486;1782.5454509100225;2103.8001389867936;2299.7125359330084;2311.768823117683;2325.2559181062065;2309.8383113182085;2316.9189078382174;2319.501307218956;2322.790948814598;2323.453988312745;7175.210726820045;8409.411341206118;8615.526146061964;8621;8633.697820576312;5123.590112791005;5308;5314.60140489461;5316.362036555638;5320.281761962163;3455.913602159941;3502.355721430329;3569.8572057117685;3616.992569124135;3622.456631505269;1062.9362238265728;1067.9359238445718;1070.903618674323;1071.785642871421;1075.1237741240864;11229.213955023151;11469.369184694839;11474.082073434189;11475.713107172303;11483.655490335219;4611.700239484489;4635.096156249567;4755.762211889391;4781.2111001685025;4787.760611969387;9961.900380996241;9966.651167209258;9969.003099690119;9971.252156088321;9987.750306242257;5319.28189694394;5319.5744340452675;5322.494363035506;5324.787008519694;5325.254464374991;4055.2444942142592;4361.956380436219;4491.73049617024;4501.7974191161475;4744.122337367598;3399.453248771803;3888.8833334999995;3985.860494882665;4049.8582549610624;4077.1448990709155;1013.6806905824642;1095.70963694621;1242.9993854946854;1287.1118927939692;1290.593463059152;831.9708810191614;877.903430622631;882.1046637662778;946.5787724462685;968.3366893677853;5689.943100569024;5757.625754325955;5976.163337132805;6011.489023433057;6021.57848950578;968.0899954043177;977.5600979559226;984.3601658921615;987.9922479071307;1025.0620682075817;3934.7639141651593;3995.9200815983518;4043.8786836394956;4050.797460126982;4058.8579399721457;10240.078392944672;10422.426766527913;10556.26106172576;10562.577496900038;10585.894141058645;9891.35706179096;10329.276950613385;10331.48342582868;10475.895241047492;10479.895201048044;7862.646180921873;7908.130105688369;8144.633491492897;8173.141820108803;8178.959105204436;5978.133170690138;5980.581359304807;5996.820095397145;6030.577859549965;6064.757409703562;9554.044595540669;9766.169875560658;9798.363106398061;9899.752506187115;9906.504674766089;9558.617655293989;9788.85316720264;9841.45871977048;10028.84668263159;10039.4478303694;7828.45200835936;8164.632591533514;8168.060673022441;8175.67297308101;8183.83632327362;7914.327282180849;7982.840343192987;8121.390895682729;8131.634076466691;8133.024037115584;7396.741114060982;7403.259674032553;7453.664585093576;7587.051618547682;7657.042869641295;7260.310270524292;7339.6330183489545;7357.5217610855125;7430.92569074324;7494.213107623839;6902.413294869993;6990.475714321548;7051.929480705127;7054.188768291507;7059.752908648173;4794.856154315376;4936.358273424432;4996.125678006261;5001.399832020035;5021.874453138701;4373.759443230653;4504.954950450454;4839.491853355347;4886.780094895667;5025.195968644999 07:22:10 INFO - PROCESS | 1669 | |4;prototype.html;290.5220911600417;293.6886899886121;310.24145963120174;311.67274361920005;312.4719224510577;4891.804327826887;5154.226865970107;5261.684298942067;5363.678179309244;5436.75534600943;4914.778834952427;4915.975420122894;5118.51374119459;5360.035193665139;5388.353397592295;3562.71498280137;4124.257633625946;4129.9174016519655;4144.564820693814;4154.8130334134885;3944.526656801188;4099.938500922489;4142.2958097123565;4152.650388623694;4164.958350416502;3654.506641603389;4005.8798236052967;4111.170529114545;4125;4152.937705934413;3764.736468447196;4069.1861627674584;4073.30753771858;4083.1425400665894;4109.445224894631;3606.1165014571334;3608.3478643197227;3616.4756110364115;3623.7644553103964;3633.800140992271;4341.45731783527;4910.558049775537;5156.458571849977;5175.7670904809;5238.73806309683;4237.300845360512;4284.335927931175;4342.478902531717;4669.603083737887;4714.996126646161;4690;4939.481354457766;5253.4221235664045;5661.433856614347;5694.430556944314;3582.659647333499;3875.6047822783776;3880.611938806125;3880.941785873214;3895.2014836958356;5682.374938756692;5776.335721392094;5901.468867801918;5911.822645320647;5979.910301345483;2883.8176347697345;3412.436947903568;3824.6557809796695;3936.9212615747524;3945.5659877413464;3471.600765911902;3997.7801220932547;4012.6388625023887;4014.41790940312;4015.819288132041;4265.253580623378;4463.799129039201;4629.814807407666;4652.395188625457;4692.976535117371;4155.688323375759;5762;5768.567357448208;5775.335836378787;5782.797602083907;1106.756513567014;1346.696993176547;1346.9057165998286;1365.487942021742;1367.9452821887212;735.1398863329916;777.8332501248128;790.4229912164037;816.2368185746346;839.5592314035058;4062.939055914164;4443.667074785763;4448.7108337957925;4484.484284307281;4528.298113792367;4709.882252943636;4834.4529750479505;4951.950480495221;4960.77676504551;4968.627352948543;3959.208158368338;4437.356583295468;4459.50267850005;4460.687751857339;4479.664025198123;2146.905078409991;2212.1970593965866;2392.641103834404;2425.8180636452335;2659.2421159969317;4471.105778844244;4919.827806026772;4929.950700493021;4952.900941981212;5118.59051275897;4434.937753944431;4687.671862969628;4826.889815342491;5051.055343692705;5085.135526960352;4654.277917170669;4657.464391594942;5146.408163061295;5176.482351764868;5219.564619729605;4223.184030866757;4535.31970204472;4713.363695901009;4726.834560790356;4933.383327084114;4053.858114965962;4199.501296728442;4559.742927250899;4902.950970490392;4942.950570494249;4153.605407486284;4763.21379482694;4811.903761924812;5490.780368785204;5885.3820348862755;3163.813569911283;3385.610654774732;3761.962380376161;3792.829322680431;3928.8821335359985;4955.677880937872;5842.2405087338375;5861.6189947653265;5923.052311630118;5946.205283516741;4918.188498897642;5299.33758280215;5644.209810626598;5700.201971724045;5749.367569567428;5020.221865610836;5653.265075540318;5678.488935995781;5688.516476099578;5699.6865172414255;4705.976470117627;5037.420696619935;5155.664881782672;5507.504324610804;5570.944290557042;5325.653832500875;5607.635503692247;5609.242752228397;5667.801626943037;5687.630304030225;4787.784549695341;4799.640026997919;5211.6710238890355;5479.369872464558;5553.555715542828 07:22:10 INFO - PROCESS | 1669 | |5;yui.html;712.6472396163898;827.9296259817916;851.4295422067219;853.5689476814208;860.0797147052652;13565;14555.417783288669;14796.112233265998;15514.767278490832;15516;14165.92917035416;14226.71546569068;14241.643958901033;14259.643508912282;14262.786058209134;706.8268274272798;734.4675110544865;870.9520976346298;902.62992173209;903.9367244292902;895.8387490251752;899.4468401932797;900.522722956834;902.264654306738;903.6249956268174;947.261136313673;949.8812648418948;955.4840386191448;955.9187469065103;956.0964888180672;927.8469052606346;946.6544711180438;946.6781294359881;947.1712251779693;947.7914858731102;557.2393682623231;685.4345165238698;707.723987644819;708.9007538944552;709.5458906299972;14804;14849.777253341208;14871.107733535915;14879.925600372037;14890.925545372203;11992.820107698391;12605.30670813105;13395.86604133956;13944.233067181302;14059.156450612996;10693;12456.75086498274;12890.613281601472;12965.675858103528;13388.799168012487;4574.908501829945;4723.645726570487;4815.686980346266;4888.388951381077;4888.388951381077;82960.9999999994;84203;85092.57453712754;85093.72359414551;85182;10926;11139.944300278446;11675;11725.41372931367;11759.412029398496;21292.36122916315;21398.465038374165;21437.78562214358;21444.571108578053;21454.356369308945;7832.530048197126;8015.318697910743;8036;8041.171750393726;8071.354291656653;4229.154169166179;4388.868333949987;4412.6690498212765;4440.045390241114;4459;3499.5975462821593;3635.345637785171;3636.3636363636256;3641.7632853864416;3642.7996460194945;656.2027137028533;672.176583684982;678.1049015299757;683.9829004274933;693.8508220732567;621.2659534348205;658.7002913674266;679.9932000680029;693.9340762627543;722.8987941688168;9104.499252541042;9418.10527999839;9510.90489095114;9524.190443812215;9561.995685867216;568.7810193075613;652.3378770547897;669.4142625202948;673.7103045690302;716.0762616225012;4241.2365774160335;4361.08463139401;4423.955760442354;4437.733735975788;4482.618977386958;60651.69674151601;88846.55576722075;90759.54620226989;91620.08379916249;96463;74446.6277668619;76508;78403.21596784073;79878;80095.7985630216;74087;74727.62636186893;74950;75706;76024;9496.192823609794;9567.952160239432;9668.888077871135;9721.368111073041;9774.560144793359;37195.44206836846;47738.76130619325;48151;48299;48757;61843;66332;66865.97608287037;67083.66458167679;67913.32086679268;12656.936715316364;12725.809112863499;12934.09461337735;12990.545330913372;13235.338233088114;14362.784558231844;14382.856171438152;14470.421183152555;14524.49164279245;14728.852711472748;13167.144135631153;13314.667133321744;13466.79799802984;13816.861831381557;13825.51610693621;13359.732805344032;13364.198148111145;13399.732005360032;13679;13873.65315867071;12796.936015319863;12896.226226426445;13018.211928788536;13083.803742943674;13481.460741570227;10924.051264149748;11079.335239885633;11230.831537527107;11363.886361136283;11379.21743555972;10888.31199832917;11127.276727012719;11344;11390.943045284721;11433.885661143615 07:22:10 INFO - PROCESS | 1669 | __end_tp_report 07:22:10 INFO - PROCESS | 1669 | __start_cc_report 07:22:10 INFO - PROCESS | 1669 | _x_x_mozilla_cycle_collect,1494 07:22:10 INFO - PROCESS | 1669 | __end_cc_report 07:22:10 INFO - PROCESS | 1669 | __startTimestamp1464618130425__endTimestamp 07:22:10 INFO - PROCESS | 1669 | 07:22:10 INFO - PROCESS | 1669 | ------- Summary: start ------- 07:22:10 INFO - PROCESS | 1669 | Number of tests: 6 07:22:10 INFO - PROCESS | 1669 | 07:22:10 INFO - PROCESS | 1669 | [#0] dojo.html Cycles:165 Average:5615.95 Median:5811.77 stddev:646.78 (11.1%) stddev-sans-first:615.51 07:22:10 INFO - PROCESS | 1669 | Values: 3006.2 3398.9 3401.6 3405.6 3445.2 5469.5 5477.0 5585.7 5597.9 5599.1 4658.5 5545.2 5561.6 5660.1 5749.9 5016.9 5070.1 5124.1 5131.0 5262.3 5173.9 5215.4 5224.5 5225.2 5293.9 4186.5 5012.2 5068.5 5073.9 5193.5 5115.3 5135.0 5137.0 5142.7 5143.2 4986.8 5026.7 5091.0 5123.4 5133.6 5380.4 5399.9 5421.3 5482.3 5559.8 6066.6 6112.1 6124.2 6127.3 6136.2 6040.8 6098.1 6126.6 6167.4 6239.5 5763.2 5797.6 5802.3 5803.3 5805.2 6133.0 6314.3 6318.8 6319.7 6321.3 5857.4 5960.9 5964.4 5982.3 5990.1 6097.5 6106.2 6118.3 6120.5 6124.8 6114.2 6124.5 6139.4 6141.5 6142.7 6108.7 6146.4 6148.0 6152.0 6163.7 4756.1 4896.3 4902.4 4902.7 4935.4 4274.6 4389.6 4397.5 4411.8 4423.3 5508.2 5510.6 5520.9 5522.4 5529.3 4901.0 6038.1 6048.1 6055.1 6057.8 5503.1 5511.1 5512.3 5515.8 5520.6 4414.9 5218.1 5251.3 5279.4 5331.4 6260.7 6275.8 6276.1 6276.2 6286.9 6166.6 6173.9 6193.5 6196.7 6203.4 6146.2 6226.8 6262.1 6272.1 6289.7 6077.4 6091.1 6101.1 6103.5 6107.5 6142.5 6161.8 6174.8 6186.5 6190.5 5645.0 5684.1 5834.2 5926.7 5930.9 5847.0 5853.5 5885.9 5897.1 5930.7 5808.7 5811.8 5835.2 5862.6 5893.1 5968.3 5974.3 5980.3 5994.4 6032.3 5666.2 5683.9 5728.3 5818.6 5822.6 07:22:10 INFO - PROCESS | 1669 | 07:22:10 INFO - PROCESS | 1669 | [#1] ext.html Cycles:180 Average:69813.17 Median:22237.11 stddev:147246.77 (662.2%) stddev-sans-first:147567.86 07:22:10 INFO - PROCESS | 1669 | Values: 492.2 507.5 528.4 528.4 538.4 30646.2 31821.0 43401.9 44583.8 44715.6 41988.4 43753.8 46136.8 46578.1 47025.5 9543.2 9758.0 9767.8 9770.1 9772.7 8702.7 8934.5 8936.9 9053.3 9055.4 11681.9 11911.6 11995.8 12096.8 12097.2 11733.8 11814.3 11850.9 11854.0 11861.6 8641.0 10101.3 11734.9 11737.8 11838.3 42239.4 42731.6 42807.8 42825.0 42946.0 3498.3 3575.2 3708.9 3897.5 4036.2 28056.0 31871.8 32437.2 32519.2 33348.7 3331.6 3409.6 3437.9 3455.1 3485.8 517148.0 517262.0 517409.0 518062.4 518478.0 66865.0 66882.3 67547.0 67566.7 67591.0 57052.0 70105.6 70105.6 70214.6 70224.6 17201.7 17459.2 17489.0 17490.6 17552.0 11812.5 12412.4 13087.6 13160.9 13165.9 16670.9 17029.6 17525.5 18943.4 19326.6 5979.3 6983.3 7018.6 7105.5 7109.9 897.3 1043.6 1094.2 1120.2 1128.0 22749.0 27021.7 31518.1 31617.7 32076.7 1060.4 1086.5 1091.7 1107.7 1125.6 15214.9 16222.4 16396.5 16666.8 16698.8 668293.0 671942.0 672884.0 673824.0 673831.0 63590.0 63725.0 63861.0 63893.0 64184.4 1389.7 1433.6 1446.5 1453.1 1455.7 30229.0 31233.4 31234.5 31752.5 32089.7 56421.2 56680.1 57165.0 57286.0 57352.1 431130.0 440840.0 444046.0 444134.0 445321.0 33477.7 36833.4 44577.3 44959.6 45172.5 38803.2 39702.8 42277.8 51241.5 53742.7 41031.6 41851.7 42500.4 42603.8 42658.1 40636.4 40949.8 41687.8 41855.8 41910.0 39295.4 39804.0 40438.0 40610.8 40720.0 34007.7 35229.6 36466.6 36561.9 37423.4 26081.1 26285.6 26300.5 26460.5 27005.1 07:22:10 INFO - PROCESS | 1669 | 07:22:10 INFO - PROCESS | 1669 | [#2] jquery.html Cycles:180 Average:33361.56 Median:2520.86 stddev:98425.79 (3904.5%) stddev-sans-first:98670.60 07:22:10 INFO - PROCESS | 1669 | Values: 304.0 371.4 375.4 377.7 399.6 15393.8 15742.4 18025.5 18052.7 18476.3 19576.5 20400.4 21214.2 21224.9 21414.4 5869.3 6029.7 6074.6 6146.8 6155.3 5853.8 5974.3 5999.4 6016.3 6030.2 5893.4 5970.2 5980.5 5994.2 6003.7 5910.4 5949.7 5954.1 5966.0 5970.3 4967.9 5079.1 5254.8 5289.8 5303.6 12752.4 12858.5 12933.8 12982.7 13035.5 2109.5 2143.6 2144.1 2185.1 2856.6 9329.8 9633.5 9673.8 9827.6 9865.8 1596.6 1613.6 1741.5 1822.6 1878.0 54870.2 75771.0 76618.6 120311.0 120698.4 11456.6 11604.0 11605.9 11636.0 11815.2 14504.7 14621.9 14652.0 14656.3 14665.9 6906.3 8414.2 8560.2 8666.4 8687.0 8473.3 8496.4 8530.6 8650.0 8671.2 3777.9 4431.3 4531.5 4559.7 4583.2 1640.8 1879.2 1897.7 1921.4 1925.2 817.3 928.4 940.6 1013.3 1028.4 17954.3 20132.9 21079.9 21308.7 21762.1 959.5 968.6 1000.5 1006.9 1032.6 9106.9 9754.3 10618.1 11004.8 11435.7 579368.0 587152.0 597996.0 598230.0 600973.0 101492.7 105399.0 133631.0 133661.0 135626.0 964.2 996.2 1022.6 1024.9 1029.8 19487.5 19802.8 19809.5 19837.8 19842.6 41153.0 51903.5 51936.7 53415.5 53879.0 71893.0 75571.6 75837.9 76021.0 76625.6 8036.0 8215.2 8271.4 8286.5 8443.3 12938.8 13082.5 13105.6 13204.3 13313.9 7751.8 11137.2 11318.4 11322.5 11379.3 11139.6 11188.6 11233.4 11460.3 11498.7 10453.8 11406.2 11546.0 11647.5 11847.7 7656.9 8131.9 8213.8 8236.1 8299.3 11320.4 11459.9 11464.8 11470.5 11498.7 07:22:10 INFO - PROCESS | 1669 | 07:22:10 INFO - PROCESS | 1669 | [#3] mootools.html Cycles:180 Average:5645.02 Median:5023.54 stddev:3147.66 (62.7%) stddev-sans-first:3140.39 07:22:10 INFO - PROCESS | 1669 | Values: 1409.9 1543.8 1575.4 1809.4 1962.5 7694.2 8491.7 8670.3 8731.3 8789.1 8392.5 8641.6 8651.2 8651.6 8667.2 2324.7 2355.8 2359.3 2359.8 2362.3 2110.4 2130.1 2275.7 2301.6 2344.2 2260.3 2272.9 2286.4 2327.5 2332.9 1782.5 2103.8 2299.7 2311.8 2325.3 2309.8 2316.9 2319.5 2322.8 2323.5 7175.2 8409.4 8615.5 8621.0 8633.7 5123.6 5308.0 5314.6 5316.4 5320.3 3455.9 3502.4 3569.9 3617.0 3622.5 1062.9 1067.9 1070.9 1071.8 1075.1 11229.2 11469.4 11474.1 11475.7 11483.7 4611.7 4635.1 4755.8 4781.2 4787.8 9961.9 9966.7 9969.0 9971.3 9987.8 5319.3 5319.6 5322.5 5324.8 5325.3 4055.2 4362.0 4491.7 4501.8 4744.1 3399.5 3888.9 3985.9 4049.9 4077.1 1013.7 1095.7 1243.0 1287.1 1290.6 832.0 877.9 882.1 946.6 968.3 5689.9 5757.6 5976.2 6011.5 6021.6 968.1 977.6 984.4 988.0 1025.1 3934.8 3995.9 4043.9 4050.8 4058.9 10240.1 10422.4 10556.3 10562.6 10585.9 9891.4 10329.3 10331.5 10475.9 10479.9 7862.6 7908.1 8144.6 8173.1 8179.0 5978.1 5980.6 5996.8 6030.6 6064.8 9554.0 9766.2 9798.4 9899.8 9906.5 9558.6 9788.9 9841.5 10028.8 10039.4 7828.5 8164.6 8168.1 8175.7 8183.8 7914.3 7982.8 8121.4 8131.6 8133.0 7396.7 7403.3 7453.7 7587.1 7657.0 7260.3 7339.6 7357.5 7430.9 7494.2 6902.4 6990.5 7051.9 7054.2 7059.8 4794.9 4936.4 4996.1 5001.4 5021.9 4373.8 4505.0 4839.5 4886.8 5025.2 07:22:10 INFO - PROCESS | 1669 | 07:22:10 INFO - PROCESS | 1669 | [#4] prototype.html Cycles:180 Average:4318.85 Median:4688.84 stddev:1321.89 (28.2%) stddev-sans-first:1290.55 07:22:10 INFO - PROCESS | 1669 | Values: 290.5 293.7 310.2 311.7 312.5 4891.8 5154.2 5261.7 5363.7 5436.8 4914.8 4916.0 5118.5 5360.0 5388.4 3562.7 4124.3 4129.9 4144.6 4154.8 3944.5 4099.9 4142.3 4152.7 4165.0 3654.5 4005.9 4111.2 4125.0 4152.9 3764.7 4069.2 4073.3 4083.1 4109.4 3606.1 3608.3 3616.5 3623.8 3633.8 4341.5 4910.6 5156.5 5175.8 5238.7 4237.3 4284.3 4342.5 4669.6 4715.0 4690.0 4939.5 5253.4 5661.4 5694.4 3582.7 3875.6 3880.6 3880.9 3895.2 5682.4 5776.3 5901.5 5911.8 5979.9 2883.8 3412.4 3824.7 3936.9 3945.6 3471.6 3997.8 4012.6 4014.4 4015.8 4265.3 4463.8 4629.8 4652.4 4693.0 4155.7 5762.0 5768.6 5775.3 5782.8 1106.8 1346.7 1346.9 1365.5 1367.9 735.1 777.8 790.4 816.2 839.6 4062.9 4443.7 4448.7 4484.5 4528.3 4709.9 4834.5 4952.0 4960.8 4968.6 3959.2 4437.4 4459.5 4460.7 4479.7 2146.9 2212.2 2392.6 2425.8 2659.2 4471.1 4919.8 4930.0 4952.9 5118.6 4434.9 4687.7 4826.9 5051.1 5085.1 4654.3 4657.5 5146.4 5176.5 5219.6 4223.2 4535.3 4713.4 4726.8 4933.4 4053.9 4199.5 4559.7 4903.0 4943.0 4153.6 4763.2 4811.9 5490.8 5885.4 3163.8 3385.6 3762.0 3792.8 3928.9 4955.7 5842.2 5861.6 5923.1 5946.2 4918.2 5299.3 5644.2 5700.2 5749.4 5020.2 5653.3 5678.5 5688.5 5699.7 4706.0 5037.4 5155.7 5507.5 5570.9 5325.7 5607.6 5609.2 5667.8 5687.6 4787.8 4799.6 5211.7 5479.4 5553.6 07:22:10 INFO - PROCESS | 1669 | 07:22:10 INFO - PROCESS | 1669 | [#5] yui.html Cycles:180 Average:18857.93 Median:48225.00 stddev:25403.87 (52.7%) stddev-sans-first:25438.60 07:22:10 INFO - PROCESS | 1669 | Values: 712.6 827.9 851.4 853.6 860.1 13565.0 14555.4 14796.1 15514.8 15516.0 14165.9 14226.7 14241.6 14259.6 14262.8 706.8 734.5 871.0 902.6 903.9 895.8 899.4 900.5 902.3 903.6 947.3 949.9 955.5 955.9 956.1 927.8 946.7 946.7 947.2 947.8 557.2 685.4 707.7 708.9 709.5 14804.0 14849.8 14871.1 14879.9 14890.9 11992.8 12605.3 13395.9 13944.2 14059.2 10693.0 12456.8 12890.6 12965.7 13388.8 4574.9 4723.6 4815.7 4888.4 4888.4 82961.0 84203.0 85092.6 85093.7 85182.0 10926.0 11139.9 11675.0 11725.4 11759.4 21292.4 21398.5 21437.8 21444.6 21454.4 7832.5 8015.3 8036.0 8041.2 8071.4 4229.2 4388.9 4412.7 4440.0 4459.0 3499.6 3635.3 3636.4 3641.8 3642.8 656.2 672.2 678.1 684.0 693.9 621.3 658.7 680.0 693.9 722.9 9104.5 9418.1 9510.9 9524.2 9562.0 568.8 652.3 669.4 673.7 716.1 4241.2 4361.1 4424.0 4437.7 4482.6 60651.7 88846.6 90759.5 91620.1 96463.0 74446.6 76508.0 78403.2 79878.0 80095.8 74087.0 74727.6 74950.0 75706.0 76024.0 9496.2 9568.0 9668.9 9721.4 9774.6 37195.4 47738.8 48151.0 48299.0 48757.0 61843.0 66332.0 66866.0 67083.7 67913.3 12656.9 12725.8 12934.1 12990.5 13235.3 14362.8 14382.9 14470.4 14524.5 14728.9 13167.1 13314.7 13466.8 13816.9 13825.5 13359.7 13364.2 13399.7 13679.0 13873.7 12796.9 12896.2 13018.2 13083.8 13481.5 10924.1 11079.3 11230.8 11363.9 11379.2 10888.3 11127.3 11344.0 11390.9 11433.9 07:22:10 INFO - PROCESS | 1669 | -------- Summary: end -------- 07:22:10 INFO - PROCESS | 1669 | 07:22:10 INFO - TEST-INFO | 1669: exit 0 07:22:10 INFO - TEST-OK | dromaeo_css | took 1116356ms 07:22:10 INFO - TEST-START | kraken 07:22:10 INFO - operating with platform_type : mac_ 07:22:10 INFO - Initialising browser for kraken test... 07:22:11 INFO - TEST-INFO | started process 1675 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/tmp3Cc916/profile http://localhost:49275/getInfo.html) 07:22:13 INFO - PROCESS | 1675 | __metrics Screen width/height:1600/1200 07:22:13 INFO - PROCESS | 1675 | colorDepth:24 07:22:13 INFO - PROCESS | 1675 | Browser inner width/height: 1024/689 07:22:13 INFO - PROCESS | 1675 | __metrics 07:22:13 INFO - PROCESS | 1675 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:22:13 ERROR - PROCESS | 1675 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 07:22:14 INFO - TEST-INFO | 1675: exit 0 07:22:14 INFO - Browser initialized. 07:22:14 INFO - Running cycle 1/1 for kraken test... 07:22:14 INFO - TEST-INFO | started process 1676 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/tmp3Cc916/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:22:26 INFO - PROCESS | 1676 | RSS: Main: 241901568 07:22:26 INFO - PROCESS | 1676 | 07:22:28 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 07:22:29 INFO - PROCESS | 1676 | RSS: Main: 292356096 07:22:29 INFO - PROCESS | 1676 | 07:22:32 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 07:22:32 INFO - PROCESS | 1676 | RSS: Main: 301527040 07:22:32 INFO - PROCESS | 1676 | 07:22:36 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 07:22:37 INFO - PROCESS | 1676 | RSS: Main: 384835584 07:22:37 INFO - PROCESS | 1676 | 07:22:39 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 07:22:39 INFO - PROCESS | 1676 | RSS: Main: 287424512 07:22:39 INFO - PROCESS | 1676 | 07:22:41 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:22:42 INFO - PROCESS | 1676 | RSS: Main: 291999744 07:22:42 INFO - PROCESS | 1676 | 07:22:45 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 07:22:45 INFO - PROCESS | 1676 | RSS: Main: 328679424 07:22:45 INFO - PROCESS | 1676 | 07:22:48 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 07:22:49 INFO - PROCESS | 1676 | RSS: Main: 330608640 07:22:49 INFO - PROCESS | 1676 | 07:22:52 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 07:22:52 INFO - PROCESS | 1676 | RSS: Main: 330641408 07:22:52 INFO - PROCESS | 1676 | 07:22:54 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:22:54 INFO - PROCESS | 1676 | RSS: Main: 309886976 07:22:54 INFO - PROCESS | 1676 | 07:22:56 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:22:57 INFO - PROCESS | 1676 | RSS: Main: 316055552 07:22:57 INFO - PROCESS | 1676 | 07:22:59 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:23:00 INFO - PROCESS | 1676 | RSS: Main: 321187840 07:23:00 INFO - PROCESS | 1676 | 07:23:03 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:23:03 INFO - PROCESS | 1676 | RSS: Main: 323002368 07:23:03 INFO - PROCESS | 1676 | 07:23:06 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:23:07 INFO - PROCESS | 1676 | RSS: Main: 324583424 07:23:07 INFO - PROCESS | 1676 | 07:23:09 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 07:23:09 INFO - PROCESS | 1676 | RSS: Main: 368218112 07:23:09 INFO - PROCESS | 1676 | 07:23:09 INFO - PROCESS | 1676 | __start_tp_report 07:23:09 INFO - PROCESS | 1676 | _x_x_mozilla_page_load 07:23:09 INFO - PROCESS | 1676 | _x_x_mozilla_page_load_details 07:23:09 INFO - PROCESS | 1676 | |i|pagename|runs| 07:23:09 INFO - PROCESS | 1676 | |0;ai-astar;113;106;112;112;111;113;111;112;112;110 07:23:09 INFO - PROCESS | 1676 | |1;audio-beat-detection;151;117;114;169;117;120;132;115;149;118 07:23:09 INFO - PROCESS | 1676 | |2;audio-dft;181;185;184;184;186;181;179;182;258;224 07:23:09 INFO - PROCESS | 1676 | |3;audio-fft;75;76;57;107;56;56;59;73;84;57 07:23:09 INFO - PROCESS | 1676 | |4;audio-oscillator;84;95;78;73;101;99;128;101;68;103 07:23:09 INFO - PROCESS | 1676 | |5;imaging-gaussian-blur;89;87;89;88;89;86;91;87;90;87 07:23:09 INFO - PROCESS | 1676 | |6;imaging-darkroom;94;96;96;96;95;95;96;96;94;95 07:23:09 INFO - PROCESS | 1676 | |7;imaging-desaturate;92;130;90;91;88;86;88;90;91;86 07:23:09 INFO - PROCESS | 1676 | |8;json-parse-financial;57;56;56;55;56;56;55;54;54;56 07:23:09 INFO - PROCESS | 1676 | |9;json-stringify-tinderbox;64;77;69;69;74;71;70;70;69;68 07:23:09 INFO - PROCESS | 1676 | |10;stanford-crypto-aes;86;89;106;106;85;85;83;86;117;86 07:23:09 INFO - PROCESS | 1676 | |11;stanford-crypto-ccm;137;136;136;135;138;139;139;144;155;139 07:23:09 INFO - PROCESS | 1676 | |12;stanford-crypto-pbkdf2;165;164;168;217;165;162;163;164;159;157 07:23:09 INFO - PROCESS | 1676 | |13;stanford-crypto-sha256-iterative;67;66;66;65;66;66;64;65;66;65 07:23:09 INFO - PROCESS | 1676 | __end_tp_report 07:23:09 INFO - PROCESS | 1676 | __start_cc_report 07:23:09 INFO - PROCESS | 1676 | _x_x_mozilla_cycle_collect,2014 07:23:09 INFO - PROCESS | 1676 | __end_cc_report 07:23:09 INFO - PROCESS | 1676 | __startTimestamp1464618189362__endTimestamp 07:23:09 INFO - PROCESS | 1676 | 07:23:09 INFO - PROCESS | 1676 | ------- Summary: start ------- 07:23:09 INFO - PROCESS | 1676 | Number of tests: 14 07:23:09 INFO - PROCESS | 1676 | 07:23:09 INFO - PROCESS | 1676 | [#0] ai-astar Cycles:10 Average:111.20 Median:112.00 stddev:2.04 (1.8%) stddev-sans-first:2.06 07:23:09 INFO - PROCESS | 1676 | Values: 113.0 106.0 112.0 112.0 111.0 113.0 111.0 112.0 112.0 110.0 07:23:09 INFO - PROCESS | 1676 | 07:23:09 INFO - PROCESS | 1676 | [#1] audio-beat-detection Cycles:10 Average:130.20 Median:126.00 stddev:19.41 (15.4%) stddev-sans-first:19.07 07:23:09 INFO - PROCESS | 1676 | Values: 151.0 117.0 114.0 169.0 117.0 120.0 132.0 115.0 149.0 118.0 07:23:09 INFO - PROCESS | 1676 | 07:23:09 INFO - PROCESS | 1676 | [#2] audio-dft Cycles:10 Average:194.40 Median:184.50 stddev:25.92 (14.0%) stddev-sans-first:27.03 07:23:09 INFO - PROCESS | 1676 | Values: 181.0 185.0 184.0 184.0 186.0 181.0 179.0 182.0 258.0 224.0 07:23:09 INFO - PROCESS | 1676 | 07:23:09 INFO - PROCESS | 1676 | [#3] audio-fft Cycles:10 Average:70.00 Median:66.00 stddev:16.62 (25.2%) stddev-sans-first:17.53 07:23:09 INFO - PROCESS | 1676 | Values: 75.0 76.0 57.0 107.0 56.0 56.0 59.0 73.0 84.0 57.0 07:23:09 INFO - PROCESS | 1676 | 07:23:09 INFO - PROCESS | 1676 | [#4] audio-oscillator Cycles:10 Average:93.00 Median:75.50 stddev:17.71 (23.5%) stddev-sans-first:18.49 07:23:09 INFO - PROCESS | 1676 | Values: 84.0 95.0 78.0 73.0 101.0 99.0 128.0 101.0 68.0 103.0 07:23:09 INFO - PROCESS | 1676 | 07:23:09 INFO - PROCESS | 1676 | [#5] imaging-gaussian-blur Cycles:10 Average:88.30 Median:89.00 stddev:1.57 (1.8%) stddev-sans-first:1.64 07:23:09 INFO - PROCESS | 1676 | Values: 89.0 87.0 89.0 88.0 89.0 86.0 91.0 87.0 90.0 87.0 07:23:09 INFO - PROCESS | 1676 | 07:23:09 INFO - PROCESS | 1676 | [#6] imaging-darkroom Cycles:10 Average:95.30 Median:96.00 stddev:0.82 (0.9%) stddev-sans-first:0.73 07:23:09 INFO - PROCESS | 1676 | Values: 94.0 96.0 96.0 96.0 95.0 95.0 96.0 96.0 94.0 95.0 07:23:09 INFO - PROCESS | 1676 | 07:23:09 INFO - PROCESS | 1676 | [#7] imaging-desaturate Cycles:10 Average:93.20 Median:90.00 stddev:13.10 (14.6%) stddev-sans-first:13.88 07:23:09 INFO - PROCESS | 1676 | Values: 92.0 130.0 90.0 91.0 88.0 86.0 88.0 90.0 91.0 86.0 07:23:09 INFO - PROCESS | 1676 | 07:23:09 INFO - PROCESS | 1676 | [#8] json-parse-financial Cycles:10 Average:55.50 Median:56.00 stddev:0.97 (1.7%) stddev-sans-first:0.87 07:23:09 INFO - PROCESS | 1676 | Values: 57.0 56.0 56.0 55.0 56.0 56.0 55.0 54.0 54.0 56.0 07:23:09 INFO - PROCESS | 1676 | 07:23:09 INFO - PROCESS | 1676 | [#9] json-stringify-tinderbox Cycles:10 Average:70.10 Median:70.00 stddev:3.48 (5.0%) stddev-sans-first:2.91 07:23:09 INFO - PROCESS | 1676 | Values: 64.0 77.0 69.0 69.0 74.0 71.0 70.0 70.0 69.0 68.0 07:23:09 INFO - PROCESS | 1676 | 07:23:09 INFO - PROCESS | 1676 | [#10] stanford-crypto-aes Cycles:10 Average:92.90 Median:85.50 stddev:12.04 (14.1%) stddev-sans-first:12.51 07:23:09 INFO - PROCESS | 1676 | Values: 86.0 89.0 106.0 106.0 85.0 85.0 83.0 86.0 117.0 86.0 07:23:09 INFO - PROCESS | 1676 | 07:23:09 INFO - PROCESS | 1676 | [#11] stanford-crypto-ccm Cycles:10 Average:139.80 Median:139.00 stddev:5.90 (4.2%) stddev-sans-first:6.17 07:23:09 INFO - PROCESS | 1676 | Values: 137.0 136.0 136.0 135.0 138.0 139.0 139.0 144.0 155.0 139.0 07:23:09 INFO - PROCESS | 1676 | 07:23:09 INFO - PROCESS | 1676 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.40 Median:164.50 stddev:17.36 (10.6%) stddev-sans-first:18.37 07:23:09 INFO - PROCESS | 1676 | Values: 165.0 164.0 168.0 217.0 165.0 162.0 163.0 164.0 159.0 157.0 07:23:09 INFO - PROCESS | 1676 | 07:23:09 INFO - PROCESS | 1676 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.60 Median:66.00 stddev:0.84 (1.3%) stddev-sans-first:0.73 07:23:09 INFO - PROCESS | 1676 | Values: 67.0 66.0 66.0 65.0 66.0 66.0 64.0 65.0 66.0 65.0 07:23:09 INFO - PROCESS | 1676 | -------- Summary: end -------- 07:23:09 INFO - PROCESS | 1676 | 07:23:10 INFO - TEST-INFO | 1676: exit 0 07:23:10 INFO - TEST-OK | kraken | took 59168ms 07:23:10 INFO - SUITE-END | took 1175s 07:23:10 INFO - Completed test suite (00:19:35) 07:23:10 INFO - javascript benchmark 07:23:10 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3006.2484378905274, 3398.9490157647638, 3401.608814986276, 3405.625381208068, 3445.2248244145053, 5469.507744303012, 5476.972615136924, 5585.720713964306, 5597.944020559793, 5599.104143337062, 4658.510856360084, 5545.195946587743, 5561.58288128391, 5660.122680984454, 5749.856253593663, 5016.949830501703, 5070.112730272206, 5124.103281925648, 5130.9743451282875, 5262.2632831403635, 5173.922391164136, 5215.426303106655, 5224.45143259958, 5225.190095535198, 5293.947060529384, 4186.49762028557, 5012.1980483122525, 5068.543831055186, 5073.949260507385, 5193.480651934814, 5115.283860259567, 5134.974325128388, 5136.972605478919, 5142.691438513663, 5143.151380022292, 4986.775595098229, 5026.673266237683, 5091.032703786306, 5123.410807757118, 5133.589312854962, 5380.381256155553, 5399.919001214985, 5421.349438067457, 5482.2873026506695, 5559.833205003856, 6066.617731664256, 6112.050940411485, 6124.234470691164, 6127.325994140641, 6136.23297087864, 6040.818775436744, 6098.129681996337, 6126.609281623656, 6167.383261673842, 6239.469645080174, 5763.164341170549, 5797.594168408171, 5802.274715660543, 5803.332616749086, 5805.245318108618, 6132.95739724248, 6314.273858506239, 6318.810435686937, 6319.715612797435, 6321.304656487875, 5857.355690874, 5960.910586341209, 5964.373740757158, 5982.341942386334, 5990.101484777319, 6097.464185086671, 6106.206193194945, 6118.265808103056, 6120.538559592075, 6124.777645458507, 6114.205153330038, 6124.5100391968535, 6139.430809034379, 6141.455323060903, 6142.682951206388, 6108.680338522356, 6146.385361463817, 6147.969260153671, 6152.0, 6163.660998645028, 4756.072565849695, 4896.2655601660035, 4902.362692849978, 4902.656814023056, 4935.358403407534, 4274.5939135782055, 4389.604935555815, 4397.494288156839, 4411.757353345597, 4423.314386270134, 5508.228847961289, 5510.641808282448, 5520.889582208333, 5522.392536820947, 5529.30883639545, 4900.975495122573, 6038.094285857069, 6048.123022161762, 6055.122007308915, 6057.757689692363, 5503.147012213113, 5511.090670039399, 5512.3385193777, 5515.834524964257, 5520.641158324696, 4414.933775993362, 5218.139007063868, 5251.343582052244, 5279.44565820591, 5331.386890507563, 6260.718267677966, 6275.811725648238, 6276.058591211355, 6276.152719382916, 6286.937130628727, 6166.568340216142, 6173.876522469615, 6193.473554747897, 6196.690165491797, 6203.4416902479, 6146.200993870947, 6226.844328891632, 6262.09199666055, 6272.090546870587, 6289.7484100635465, 6077.392260774064, 6091.0558863376245, 6101.120797011208, 6103.481204097701, 6107.450329470369, 6142.49757021464, 6161.84595385119, 6174.845628859314, 6186.505079593711, 6190.473809726223, 5645.040343141593, 5684.090545512863, 5834.212381328466, 5926.733297001731, 5930.881382372415, 5847.035239185487, 5853.502452291603, 5885.941140588539, 5897.144913987534, 5930.673812940329, 5808.738606762621, 5811.767529298781, 5835.183074369506, 5862.589618726648, 5893.145493903446, 5968.31364393083, 5974.342822289458, 5980.282366116094, 5994.370591088047, 6032.306284777307, 5666.15007748841, 5683.857903552444, 5728.3412407572, 5818.592698511064, 5822.592418530662], "value": 5574.87239386864, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [492.2272032908334, 507.48490282363406, 528.4187393866745, 528.434575004745, 538.4211972129962, 30646.233844153878, 31821.0, 43401.91495212617, 44583.77708111463, 44715.552844471546, 41988.37017444741, 43753.7812310938, 46136.769316153455, 46578.068438631206, 47025.529744702544, 9543.188828949547, 9758.0, 9767.75580610487, 9770.120689137977, 9772.657956971507, 8702.695405660803, 8934.463932164059, 8936.910630893673, 9053.275737940981, 9055.411398259092, 11681.941590292037, 11911.642650720492, 11995.7600847983, 12096.758064838697, 12097.213681110743, 11733.823992640117, 11814.29114253139, 11850.881491185108, 11854.0, 11861.584844530485, 8641.006284277326, 10101.343412678152, 11734.94132529332, 11737.823932641017, 11838.348890811001, 42239.366409503884, 42731.57268427338, 42807.785961069996, 42825.0, 42946.0, 3498.2828520153307, 3575.249197668493, 3708.87018954338, 3897.5170640023684, 4036.2129384769983, 28056.018039368526, 31871.84064079688, 32437.189070273194, 32519.18702032468, 33348.66651333504, 3331.583552055993, 3409.5567576214935, 3437.862485500577, 3455.1016735648914, 3485.8431370588382, 517148.0, 517262.0, 517409.0, 518062.4096879529, 518478.0, 66865.0, 66882.33117668859, 67547.0, 67566.66216668885, 67590.986135208, 57052.0, 70105.64947175334, 70105.64947175334, 70214.64892675607, 70224.6488767553, 17201.7419738704, 17459.21433535494, 17488.950662960262, 17490.56273593145, 17552.03463809503, 11812.52749890012, 12412.379381030913, 13087.607371778862, 13160.934195329153, 13165.93417032928, 16670.916390434584, 17029.574260643334, 17525.484936518635, 18943.43169704911, 19326.613467730567, 5979.252593425822, 6983.266756990544, 7018.613976231254, 7105.538140020882, 7109.92890071103, 897.3359713811863, 1043.5512729526376, 1094.1848322999258, 1120.2158489057692, 1128.0298942909255, 22749.0, 27021.72978270192, 31518.054458366285, 31617.683823161933, 32076.679233207837, 1060.4273692206277, 1086.5327908999047, 1091.67795500326, 1107.6621630402803, 1125.6341688951125, 15214.934954553075, 16222.439296678676, 16396.508104756875, 16666.83333166677, 16698.83301166997, 668293.0, 671942.0, 672884.0, 673824.0, 673831.0, 63590.0, 63725.0, 63861.0, 63893.0, 64184.35815641784, 1389.7220555888862, 1433.5770947570493, 1446.4503488674236, 1453.055513915963, 1455.745244582194, 30229.0, 31233.375332492767, 31234.531482027334, 31752.460370374276, 32089.67910320867, 56421.153682695614, 56680.149797752245, 57165.0, 57286.0, 57352.1397179051, 431130.0, 440840.0, 444046.0, 444134.0, 445321.0, 33477.66522334745, 36833.447498288086, 44577.331340029275, 44959.55040449554, 45172.548274516834, 38803.223935521695, 39702.808915732574, 42277.78861105675, 51241.48758512367, 53742.73128634332, 41031.58968410278, 41851.67454570949, 42500.36249456199, 42603.7869810649, 42658.14683706371, 40636.390454142624, 40949.79525102475, 41687.791561042, 41855.7907210462, 41910.0, 39295.41056884206, 39803.99173027925, 40438.0, 40610.79694601508, 40720.0, 34007.659923400446, 35229.64770352366, 36466.63533364632, 36561.90314290576, 37423.43864841975, 26081.08716195, 26285.60571591466, 26300.473990520466, 26460.470790584466, 27005.054823081096], "value": 21057.40929004512, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [303.9635243770748, 371.40389674572316, 375.41810194198985, 377.7091639437635, 399.6183644619389, 15393.846061539381, 15742.370305187793, 18025.4592362229, 18052.729209061876, 18476.260949562, 19576.510587235327, 20400.387988360337, 21214.151433942625, 21224.938753062324, 21414.357569272906, 5869.295684517864, 6029.728662210211, 6074.5747797654185, 6146.815595532141, 6155.261368635747, 5853.824385268449, 5974.312954010279, 5999.370066143058, 6016.338202797689, 6030.246219222598, 5893.44012318829, 5970.1940238067955, 5980.491658209057, 5994.220751302347, 6003.6697981611, 5910.408959104098, 5949.732262048218, 5954.106883967418, 5966.045432730764, 5970.253718285215, 4967.875803104916, 5079.1365467870555, 5254.7635356409055, 5289.761960711778, 5303.575713942914, 12752.426140823685, 12858.485660573566, 12933.805992910115, 12982.675433114153, 13035.478580856754, 2109.546447513792, 2143.6462983607685, 2144.0994782191515, 2185.136870935987, 2856.5857950597256, 9329.766755831159, 9633.470159141245, 9673.758156046084, 9827.557759900821, 9865.802683946353, 1596.5609457399307, 1613.5804690780365, 1741.5297869575145, 1822.6081392500676, 1877.9906100469411, 54870.17694734582, 75771.0, 76618.61690691566, 120311.0, 120698.39650801777, 11456.599019034295, 11604.0, 11605.88394116065, 11636.0, 11815.172937894436, 14504.709905802036, 14621.853781462261, 14652.0, 14656.340464679115, 14665.926670366793, 6906.274841141708, 8414.242718155396, 8560.229579337889, 8666.350023748244, 8686.95656521726, 8473.279771219515, 8496.405251632326, 8530.616122274512, 8650.048494665582, 8671.21959023691, 3777.905552361158, 4431.313146462304, 4531.501534831166, 4559.658025648089, 4583.152116858392, 1640.8031036275484, 1879.2201236486978, 1897.6658709787039, 1921.4427815933502, 1925.2299080367684, 817.3175398542165, 928.3640706116332, 940.6237504998055, 1013.2704452793977, 1028.3803010258873, 17954.281828726966, 20132.89933550323, 21079.8946005269, 21308.680369794467, 21762.12951481923, 959.5442164971722, 968.5931908598405, 1000.4997501249376, 1006.9345492542961, 1032.5869652139054, 9106.86339704905, 9754.317197796228, 10618.097461715686, 11004.779904401867, 11435.656930292105, 579368.0, 587152.0, 597996.0, 598230.0, 600973.0, 101492.6990758072, 105399.0, 133631.0, 133661.0, 135626.0, 964.1852634523816, 996.1731762636994, 1022.6062965758236, 1024.893843152329, 1029.8146333659863, 19487.512812179808, 19802.801971980673, 19809.504762381057, 19837.801621983595, 19842.603147936672, 41152.97117571965, 51903.48096518986, 51936.74031629818, 53415.46584534105, 53879.0, 71893.0, 75571.62214189114, 75837.9184603956, 76021.0, 76625.61687191528, 8036.0, 8215.219554142346, 8271.421000529905, 8286.502809831429, 8443.282321002784, 12938.805917911428, 13082.476700932237, 13105.606831795061, 13204.339783011003, 13313.86686133165, 7751.844963100819, 11137.164712646421, 11318.434078296217, 11322.490487928228, 11379.260348077349, 11139.554417823196, 11188.552457901593, 11233.438328083399, 11460.312381257152, 11498.712532186762, 10453.790924181625, 11406.20156589064, 11546.0, 11647.534098636299, 11847.696753356951, 7656.885146722692, 8131.878021829559, 8213.83572328562, 8236.094029656853, 8299.29455996247, 11320.433978300887, 11459.942700286445, 11464.828027579426, 11470.483828227583, 11498.712532186428], "value": 9230.392895434163, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1409.8519655436173, 1543.76843473479, 1575.4328441760963, 1809.375765360951, 1962.5486138188226, 7694.23057694231, 8491.660333586657, 8670.26302764265, 8731.25784308333, 8789.077146899579, 8392.538410387426, 8641.611127499262, 8651.178138076888, 8651.567421628924, 8667.17661822126, 2324.721033475981, 2355.8350915435926, 2359.327591636384, 2359.7994170495526, 2362.2795047510504, 2110.419634600482, 2130.084063852543, 2275.6586512023246, 2301.585714571385, 2344.22640528626, 2260.2880092770806, 2272.8977196026135, 2286.3598192506042, 2327.511222643247, 2332.9066837326486, 1782.5454509100225, 2103.8001389867936, 2299.7125359330084, 2311.768823117683, 2325.2559181062065, 2309.8383113182085, 2316.9189078382174, 2319.501307218956, 2322.790948814598, 2323.453988312745, 7175.210726820045, 8409.411341206118, 8615.526146061964, 8621.0, 8633.697820576312, 5123.590112791005, 5308.0, 5314.60140489461, 5316.362036555638, 5320.281761962163, 3455.913602159941, 3502.355721430329, 3569.8572057117685, 3616.992569124135, 3622.456631505269, 1062.9362238265728, 1067.9359238445718, 1070.903618674323, 1071.785642871421, 1075.1237741240864, 11229.213955023151, 11469.369184694839, 11474.082073434189, 11475.713107172303, 11483.655490335219, 4611.700239484489, 4635.096156249567, 4755.762211889391, 4781.2111001685025, 4787.760611969387, 9961.900380996241, 9966.651167209258, 9969.003099690119, 9971.252156088321, 9987.750306242257, 5319.28189694394, 5319.5744340452675, 5322.494363035506, 5324.787008519694, 5325.254464374991, 4055.2444942142592, 4361.956380436219, 4491.73049617024, 4501.7974191161475, 4744.122337367598, 3399.453248771803, 3888.8833334999995, 3985.860494882665, 4049.8582549610624, 4077.1448990709155, 1013.6806905824642, 1095.70963694621, 1242.9993854946854, 1287.1118927939692, 1290.593463059152, 831.9708810191614, 877.903430622631, 882.1046637662778, 946.5787724462685, 968.3366893677853, 5689.943100569024, 5757.625754325955, 5976.163337132805, 6011.489023433057, 6021.57848950578, 968.0899954043177, 977.5600979559226, 984.3601658921615, 987.9922479071307, 1025.0620682075817, 3934.7639141651593, 3995.9200815983518, 4043.8786836394956, 4050.797460126982, 4058.8579399721457, 10240.078392944672, 10422.426766527913, 10556.26106172576, 10562.577496900038, 10585.894141058645, 9891.35706179096, 10329.276950613385, 10331.48342582868, 10475.895241047492, 10479.895201048044, 7862.646180921873, 7908.130105688369, 8144.633491492897, 8173.141820108803, 8178.959105204436, 5978.133170690138, 5980.581359304807, 5996.820095397145, 6030.577859549965, 6064.757409703562, 9554.044595540669, 9766.169875560658, 9798.363106398061, 9899.752506187115, 9906.504674766089, 9558.617655293989, 9788.85316720264, 9841.45871977048, 10028.84668263159, 10039.4478303694, 7828.45200835936, 8164.632591533514, 8168.060673022441, 8175.67297308101, 8183.83632327362, 7914.327282180849, 7982.840343192987, 8121.390895682729, 8131.634076466691, 8133.024037115584, 7396.741114060982, 7403.259674032553, 7453.664585093576, 7587.051618547682, 7657.042869641295, 7260.310270524292, 7339.6330183489545, 7357.5217610855125, 7430.92569074324, 7494.213107623839, 6902.413294869993, 6990.475714321548, 7051.929480705127, 7054.188768291507, 7059.752908648173, 4794.856154315376, 4936.358273424432, 4996.125678006261, 5001.399832020035, 5021.874453138701, 4373.759443230653, 4504.954950450454, 4839.491853355347, 4886.780094895667, 5025.195968644999], "value": 4539.152898178978, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [290.5220911600417, 293.6886899886121, 310.24145963120174, 311.67274361920005, 312.4719224510577, 4891.804327826887, 5154.226865970107, 5261.684298942067, 5363.678179309244, 5436.75534600943, 4914.778834952427, 4915.975420122894, 5118.51374119459, 5360.035193665139, 5388.353397592295, 3562.71498280137, 4124.257633625946, 4129.9174016519655, 4144.564820693814, 4154.8130334134885, 3944.526656801188, 4099.938500922489, 4142.2958097123565, 4152.650388623694, 4164.958350416502, 3654.506641603389, 4005.8798236052967, 4111.170529114545, 4125.0, 4152.937705934413, 3764.736468447196, 4069.1861627674584, 4073.30753771858, 4083.1425400665894, 4109.445224894631, 3606.1165014571334, 3608.3478643197227, 3616.4756110364115, 3623.7644553103964, 3633.800140992271, 4341.45731783527, 4910.558049775537, 5156.458571849977, 5175.7670904809, 5238.73806309683, 4237.300845360512, 4284.335927931175, 4342.478902531717, 4669.603083737887, 4714.996126646161, 4690.0, 4939.481354457766, 5253.4221235664045, 5661.433856614347, 5694.430556944314, 3582.659647333499, 3875.6047822783776, 3880.611938806125, 3880.941785873214, 3895.2014836958356, 5682.374938756692, 5776.335721392094, 5901.468867801918, 5911.822645320647, 5979.910301345483, 2883.8176347697345, 3412.436947903568, 3824.6557809796695, 3936.9212615747524, 3945.5659877413464, 3471.600765911902, 3997.7801220932547, 4012.6388625023887, 4014.41790940312, 4015.819288132041, 4265.253580623378, 4463.799129039201, 4629.814807407666, 4652.395188625457, 4692.976535117371, 4155.688323375759, 5762.0, 5768.567357448208, 5775.335836378787, 5782.797602083907, 1106.756513567014, 1346.696993176547, 1346.9057165998286, 1365.487942021742, 1367.9452821887212, 735.1398863329916, 777.8332501248128, 790.4229912164037, 816.2368185746346, 839.5592314035058, 4062.939055914164, 4443.667074785763, 4448.7108337957925, 4484.484284307281, 4528.298113792367, 4709.882252943636, 4834.4529750479505, 4951.950480495221, 4960.77676504551, 4968.627352948543, 3959.208158368338, 4437.356583295468, 4459.50267850005, 4460.687751857339, 4479.664025198123, 2146.905078409991, 2212.1970593965866, 2392.641103834404, 2425.8180636452335, 2659.2421159969317, 4471.105778844244, 4919.827806026772, 4929.950700493021, 4952.900941981212, 5118.59051275897, 4434.937753944431, 4687.671862969628, 4826.889815342491, 5051.055343692705, 5085.135526960352, 4654.277917170669, 4657.464391594942, 5146.408163061295, 5176.482351764868, 5219.564619729605, 4223.184030866757, 4535.31970204472, 4713.363695901009, 4726.834560790356, 4933.383327084114, 4053.858114965962, 4199.501296728442, 4559.742927250899, 4902.950970490392, 4942.950570494249, 4153.605407486284, 4763.21379482694, 4811.903761924812, 5490.780368785204, 5885.3820348862755, 3163.813569911283, 3385.610654774732, 3761.962380376161, 3792.829322680431, 3928.8821335359985, 4955.677880937872, 5842.2405087338375, 5861.6189947653265, 5923.052311630118, 5946.205283516741, 4918.188498897642, 5299.33758280215, 5644.209810626598, 5700.201971724045, 5749.367569567428, 5020.221865610836, 5653.265075540318, 5678.488935995781, 5688.516476099578, 5699.6865172414255, 4705.976470117627, 5037.420696619935, 5155.664881782672, 5507.504324610804, 5570.944290557042, 5325.653832500875, 5607.635503692247, 5609.242752228397, 5667.801626943037, 5687.630304030225, 4787.784549695341, 4799.640026997919, 5211.6710238890355, 5479.369872464558, 5553.555715542828], "value": 3898.233954892563, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [712.6472396163898, 827.9296259817916, 851.4295422067219, 853.5689476814208, 860.0797147052652, 13565.0, 14555.417783288669, 14796.112233265998, 15514.767278490832, 15516.0, 14165.92917035416, 14226.71546569068, 14241.643958901033, 14259.643508912282, 14262.786058209134, 706.8268274272798, 734.4675110544865, 870.9520976346298, 902.62992173209, 903.9367244292902, 895.8387490251752, 899.4468401932797, 900.522722956834, 902.264654306738, 903.6249956268174, 947.261136313673, 949.8812648418948, 955.4840386191448, 955.9187469065103, 956.0964888180672, 927.8469052606346, 946.6544711180438, 946.6781294359881, 947.1712251779693, 947.7914858731102, 557.2393682623231, 685.4345165238698, 707.723987644819, 708.9007538944552, 709.5458906299972, 14804.0, 14849.777253341208, 14871.107733535915, 14879.925600372037, 14890.925545372203, 11992.820107698391, 12605.30670813105, 13395.86604133956, 13944.233067181302, 14059.156450612996, 10693.0, 12456.75086498274, 12890.613281601472, 12965.675858103528, 13388.799168012487, 4574.908501829945, 4723.645726570487, 4815.686980346266, 4888.388951381077, 4888.388951381077, 82960.9999999994, 84203.0, 85092.57453712754, 85093.72359414551, 85182.0, 10926.0, 11139.944300278446, 11675.0, 11725.41372931367, 11759.412029398496, 21292.36122916315, 21398.465038374165, 21437.78562214358, 21444.571108578053, 21454.356369308945, 7832.530048197126, 8015.318697910743, 8036.0, 8041.171750393726, 8071.354291656653, 4229.154169166179, 4388.868333949987, 4412.6690498212765, 4440.045390241114, 4459.0, 3499.5975462821593, 3635.345637785171, 3636.3636363636256, 3641.7632853864416, 3642.7996460194945, 656.2027137028533, 672.176583684982, 678.1049015299757, 683.9829004274933, 693.8508220732567, 621.2659534348205, 658.7002913674266, 679.9932000680029, 693.9340762627543, 722.8987941688168, 9104.499252541042, 9418.10527999839, 9510.90489095114, 9524.190443812215, 9561.995685867216, 568.7810193075613, 652.3378770547897, 669.4142625202948, 673.7103045690302, 716.0762616225012, 4241.2365774160335, 4361.08463139401, 4423.955760442354, 4437.733735975788, 4482.618977386958, 60651.69674151601, 88846.55576722075, 90759.54620226989, 91620.08379916249, 96463.0, 74446.6277668619, 76508.0, 78403.21596784073, 79878.0, 80095.7985630216, 74087.0, 74727.62636186893, 74950.0, 75706.0, 76024.0, 9496.192823609794, 9567.952160239432, 9668.888077871135, 9721.368111073041, 9774.560144793359, 37195.44206836846, 47738.76130619325, 48151.0, 48299.0, 48757.0, 61843.0, 66332.0, 66865.97608287037, 67083.66458167679, 67913.32086679268, 12656.936715316364, 12725.809112863499, 12934.09461337735, 12990.545330913372, 13235.338233088114, 14362.784558231844, 14382.856171438152, 14470.421183152555, 14524.49164279245, 14728.852711472748, 13167.144135631153, 13314.667133321744, 13466.79799802984, 13816.861831381557, 13825.51610693621, 13359.732805344032, 13364.198148111145, 13399.732005360032, 13679.0, 13873.65315867071, 12796.936015319863, 12896.226226426445, 13018.211928788536, 13083.803742943674, 13481.460741570227, 10924.051264149748, 11079.335239885633, 11230.831537527107, 11363.886361136283, 11379.21743555972, 10888.31199832917, 11127.276727012719, 11344.0, 11390.943045284721, 11433.885661143615], "value": 7446.883332896786, "unit": "score"}], "name": "dromaeo_css", "value": 7229.717211461436, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 106.0, 112.0, 112.0, 111.0, 113.0, 111.0, 112.0, 112.0, 110.0], "value": 111.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 117.0, 114.0, 169.0, 117.0, 120.0, 132.0, 115.0, 149.0, 118.0], "value": 130.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 185.0, 184.0, 184.0, 186.0, 181.0, 179.0, 182.0, 258.0, 224.0], "value": 194.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 57.0, 107.0, 56.0, 56.0, 59.0, 73.0, 84.0, 57.0], "value": 70.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 95.0, 78.0, 73.0, 101.0, 99.0, 128.0, 101.0, 68.0, 103.0], "value": 93.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 89.0, 88.0, 89.0, 86.0, 91.0, 87.0, 90.0, 87.0], "value": 88.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 96.0, 96.0, 96.0, 95.0, 95.0, 96.0, 96.0, 94.0, 95.0], "value": 95.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 130.0, 90.0, 91.0, 88.0, 86.0, 88.0, 90.0, 91.0, 86.0], "value": 93.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 56.0, 56.0, 55.0, 56.0, 56.0, 55.0, 54.0, 54.0, 56.0], "value": 55.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 77.0, 69.0, 69.0, 74.0, 71.0, 70.0, 70.0, 69.0, 68.0], "value": 70.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 89.0, 106.0, 106.0, 85.0, 85.0, 83.0, 86.0, 117.0, 86.0], "value": 92.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 136.0, 136.0, 135.0, 138.0, 139.0, 139.0, 144.0, 155.0, 139.0], "value": 139.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 164.0, 168.0, 217.0, 165.0, 162.0, 163.0, 164.0, 159.0, 157.0], "value": 168.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 66.0, 65.0, 66.0, 66.0, 64.0, 65.0, 66.0, 65.0], "value": 65.6, "unit": "score"}], "name": "kraken", "value": 1467.8999999999999, "alertThreshold": 2.0}]} 07:23:10 INFO - Return code: 0 07:23:10 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 07:23:10 ERROR - # TBPL SUCCESS # 07:23:10 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:23:10 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:23:10 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:23:10 INFO - 2016-05-30 07:23:10.674 screenresolution[1680:9694] starting screenresolution argv=screenresolution get 07:23:10 INFO - 2016-05-30 07:23:10.677 screenresolution[1680:9694] Display 0: 1600x1200x32@60 07:23:10 INFO - 2016-05-30 07:23:10.683 screenresolution[1681:9697] starting screenresolution argv=screenresolution list 07:23:10 INFO - Available Modes on Display 0 07:23:10 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:23:10 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:23:10 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:23:10 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:23:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:23:10 INFO - Intel Iris: 07:23:10 INFO - Chipset Model: Intel Iris 07:23:10 INFO - Type: GPU 07:23:10 INFO - Bus: Built-In 07:23:10 INFO - VRAM (Dynamic, Max): 1536 MB 07:23:10 INFO - Vendor: Intel (0x8086) 07:23:10 INFO - Device ID: 0x0a2e 07:23:10 INFO - Revision ID: 0x0009 07:23:10 INFO - Displays: 07:23:10 INFO - Display: 07:23:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:23:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:23:10 INFO - Display Serial Number: 1600x1200 60 07:23:10 INFO - Main Display: Yes 07:23:10 INFO - Mirror: Off 07:23:10 INFO - Online: Yes 07:23:10 INFO - Rotation: Supported 07:23:10 INFO - Return code: 0 07:23:10 INFO - Running post-action listener: _resource_record_post_action 07:23:10 INFO - ##### 07:23:10 INFO - ##### Finished run-tests step (success) 07:23:10 INFO - ##### 07:23:10 INFO - Running post-run listener: _resource_record_post_run 07:23:10 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 30804480; Write bytes: 695663104; Read time: 303; Write time: 1989 07:23:10 INFO - TinderboxPrint: CPU usage
53.7% 07:23:10 INFO - TinderboxPrint: I/O read bytes / time
30,804,480 / 303 07:23:10 INFO - TinderboxPrint: I/O write bytes / time
695,663,104 / 1,989 07:23:10 INFO - TinderboxPrint: CPU idle
2,241.8 (46.3%) 07:23:10 INFO - TinderboxPrint: CPU system
963.5 (19.9%) 07:23:10 INFO - TinderboxPrint: CPU user
1,640.4 (33.9%) 07:23:10 INFO - TinderboxPrint: Swap in / out
426,655,744 / 0 07:23:10 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 395995648; Write bytes: 389318144; Read time: 29599; Write time: 729 07:23:10 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 29018624; Write bytes: 295089152; Read time: 284; Write time: 1222 07:23:11 INFO - Running post-run listener: _upload_blobber_files 07:23:11 INFO - Blob upload gear active. 07:23:11 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:23:11 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 07:23:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 07:23:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 07:23:11 INFO - (blobuploader) - INFO - Open directory for files ... 07:23:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:23:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:23:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:23:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:23:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:23:12 INFO - (blobuploader) - INFO - Done attempting. 07:23:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:23:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:23:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:23:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:23:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:23:14 INFO - (blobuploader) - INFO - Done attempting. 07:23:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:23:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:23:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:23:16 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:23:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:23:16 INFO - (blobuploader) - INFO - Done attempting. 07:23:16 INFO - (blobuploader) - INFO - Iteration through files over. 07:23:16 INFO - Return code: 0 07:23:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:23:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:23:16 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c03236881a17edefea3d317750a8cf6d54a6e643ad146bc1e2a51c0cc8f6f8b80180e4f720e4247ce6be253b6ff0933fdb658176728300480d3d195e5c7edda8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f450fd41a386f656a2fe77525ec5a7a6881e1cad5c89f46e25412c8d61019f536f71a4bb3383264f5d13ac31bd9e7073b48f1cc5f06ecabfbc41dc65779055f8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c23085c21847e530258cff55a17986cccecd17d67a2d05b72920d0c1db6599e3b7b7cf455dfd91c6eb392a20af9a3a7fd5f7e65f61bc2ad9cfc9b303f237a869"} 07:23:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:23:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:23:16 INFO - Contents: 07:23:16 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c03236881a17edefea3d317750a8cf6d54a6e643ad146bc1e2a51c0cc8f6f8b80180e4f720e4247ce6be253b6ff0933fdb658176728300480d3d195e5c7edda8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f450fd41a386f656a2fe77525ec5a7a6881e1cad5c89f46e25412c8d61019f536f71a4bb3383264f5d13ac31bd9e7073b48f1cc5f06ecabfbc41dc65779055f8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c23085c21847e530258cff55a17986cccecd17d67a2d05b72920d0c1db6599e3b7b7cf455dfd91c6eb392a20af9a3a7fd5f7e65f61bc2ad9cfc9b303f237a869"} 07:23:16 INFO - Running post-run listener: copy_logs_to_upload_dir 07:23:16 INFO - Copying logs to upload dir... 07:23:16 INFO - mkdir: /builds/slave/test/build/upload/logs 07:23:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.925311 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-05-30 07:23:16.518554) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-30 07:23:16.522442) ========= 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.62ipePxby1/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.VOR95Nd9N4/Listeners TMPDIR=/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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/c03236881a17edefea3d317750a8cf6d54a6e643ad146bc1e2a51c0cc8f6f8b80180e4f720e4247ce6be253b6ff0933fdb658176728300480d3d195e5c7edda8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f450fd41a386f656a2fe77525ec5a7a6881e1cad5c89f46e25412c8d61019f536f71a4bb3383264f5d13ac31bd9e7073b48f1cc5f06ecabfbc41dc65779055f8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c23085c21847e530258cff55a17986cccecd17d67a2d05b72920d0c1db6599e3b7b7cf455dfd91c6eb392a20af9a3a7fd5f7e65f61bc2ad9cfc9b303f237a869"} build_url:https://queue.taskcluster.net/v1/task/PrwcUSx8QcWaKGouc0DjRA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011091 build_url: 'https://queue.taskcluster.net/v1/task/PrwcUSx8QcWaKGouc0DjRA/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/c03236881a17edefea3d317750a8cf6d54a6e643ad146bc1e2a51c0cc8f6f8b80180e4f720e4247ce6be253b6ff0933fdb658176728300480d3d195e5c7edda8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f450fd41a386f656a2fe77525ec5a7a6881e1cad5c89f46e25412c8d61019f536f71a4bb3383264f5d13ac31bd9e7073b48f1cc5f06ecabfbc41dc65779055f8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c23085c21847e530258cff55a17986cccecd17d67a2d05b72920d0c1db6599e3b7b7cf455dfd91c6eb392a20af9a3a7fd5f7e65f61bc2ad9cfc9b303f237a869"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-30 07:23:16.554009) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 07:23:16.554382) ========= 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.62ipePxby1/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.VOR95Nd9N4/Listeners TMPDIR=/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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.004857 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 07:23:16.602843) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-30 07:23:16.603184) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-30 07:23:16.603557) ========= ========= Total master_lag: 0.16 =========