builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0318 starttime: 1464089173.59 results: success (0) buildid: 20160524022810 builduid: 82f405c25a6d4a959409e65fc5664b13 revision: e8f4f77bd99eee8e95262779c90235ce5aaaafe4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-24 04:26:13.592234) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-24 04:26:13.596174) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-24 04:26:13.596469) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-24 04:26:13.624785) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 04:26:13.625395) ========= 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.9vPONKBnjO/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.FT5pYy7yy8/Listeners TMPDIR=/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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-24 04:26:13-- 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% 19.5M=0.001s 2016-05-24 04:26:14 (19.5 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.490431 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 04:26:14.161062) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 04:26:14.161479) ========= 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.9vPONKBnjO/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.FT5pYy7yy8/Listeners TMPDIR=/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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.025493 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 04:26:14.217282) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-24 04:26:14.220693) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev e8f4f77bd99eee8e95262779c90235ce5aaaafe4 --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 e8f4f77bd99eee8e95262779c90235ce5aaaafe4 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.9vPONKBnjO/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.FT5pYy7yy8/Listeners TMPDIR=/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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-24 04:26:14,300 truncating revision to first 12 chars 2016-05-24 04:26:14,300 Setting DEBUG logging. 2016-05-24 04:26:14,300 attempt 1/10 2016-05-24 04:26:14,300 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/e8f4f77bd99e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-24 04:26:15,406 unpacking tar archive at: fx-team-e8f4f77bd99e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.363681 ========= master_lag: 1.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-24 04:26:16.621678) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-24 04:26:16.621968) ========= script_repo_revision: e8f4f77bd99eee8e95262779c90235ce5aaaafe4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-24 04:26:16.622363) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-24 04:26:16.622817) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-24 04:26:16.652144) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 29 secs) (at 2016-05-24 04:26:16.652425) ========= /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.9vPONKBnjO/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.FT5pYy7yy8/Listeners TMPDIR=/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 04:26:16 INFO - MultiFileLogger online at 20160524 04:26:16 in /builds/slave/test 04:26:16 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 04:26:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:26:16 INFO - {'append_to_log': False, 04:26:16 INFO - 'base_work_dir': '/builds/slave/test', 04:26:16 INFO - 'blob_upload_branch': 'Fx-Team', 04:26:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:26:16 INFO - 'branch': 'Fx-Team', 04:26:16 INFO - 'buildbot_json_path': 'buildprops.json', 04:26:16 INFO - 'config_files': ('talos/mac_config.py',), 04:26:16 INFO - 'default_actions': ('clobber', 04:26:16 INFO - 'read-buildbot-config', 04:26:16 INFO - 'download-and-extract', 04:26:16 INFO - 'populate-webroot', 04:26:16 INFO - 'create-virtualenv', 04:26:16 INFO - 'install', 04:26:16 INFO - 'run-tests'), 04:26:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:26:16 INFO - 'download_minidump_stackwalk': True, 04:26:16 INFO - 'download_symbols': 'ondemand', 04:26:16 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:26:16 INFO - 'tooltool.py': '/tools/tooltool.py', 04:26:16 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:26:16 INFO - '/tools/misc-python/virtualenv.py')}, 04:26:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:26:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:26:16 INFO - 'installer_path': 'installer.exe', 04:26:16 INFO - 'log_level': 'info', 04:26:16 INFO - 'log_name': 'talos', 04:26:16 INFO - 'log_to_console': True, 04:26:16 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:26:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:26:16 INFO - 'opt_config_files': (), 04:26:16 INFO - 'pip_index': False, 04:26:16 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:26:16 INFO - 'cmd': ('bash', 04:26:16 INFO - '-c', 04:26:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:26:16 INFO - 'enabled': True, 04:26:16 INFO - 'halt_on_failure': False, 04:26:16 INFO - 'name': 'check_screen_resolution'},), 04:26:16 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:26:16 INFO - 'cmd': ('bash', 04:26:16 INFO - '-c', 04:26:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:26:16 INFO - 'enabled': True, 04:26:16 INFO - 'halt_on_failure': False, 04:26:16 INFO - 'name': 'check_screen_resolution'},), 04:26:16 INFO - 'run_cmd_checks_enabled': True, 04:26:16 INFO - 'sps_profile': False, 04:26:16 INFO - 'sps_profile_interval': 0, 04:26:16 INFO - 'suite': 'dromaeojs', 04:26:16 INFO - 'system_bits': '32', 04:26:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:26:16 INFO - 'title': 't-yosemite-r7-0318', 04:26:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:26:16 INFO - 'use_talos_json': True, 04:26:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:26:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:26:16 INFO - 'work_dir': 'build'} 04:26:16 INFO - ##### 04:26:16 INFO - ##### Running clobber step. 04:26:16 INFO - ##### 04:26:16 INFO - Running pre-action listener: _resource_record_pre_action 04:26:16 INFO - Running main action method: clobber 04:26:16 INFO - rmtree: /builds/slave/test/build 04:26:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:26:18 INFO - Running post-action listener: _resource_record_post_action 04:26:18 INFO - ##### 04:26:18 INFO - ##### Finished clobber step (success) 04:26:18 INFO - ##### 04:26:18 INFO - ##### 04:26:18 INFO - ##### Running read-buildbot-config step. 04:26:18 INFO - ##### 04:26:18 INFO - Running pre-action listener: _resource_record_pre_action 04:26:18 INFO - Running main action method: read_buildbot_config 04:26:18 INFO - Using buildbot properties: 04:26:18 INFO - { 04:26:18 INFO - "project": "", 04:26:18 INFO - "product": "firefox", 04:26:18 INFO - "script_repo_revision": "production", 04:26:18 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 04:26:18 INFO - "repository": "", 04:26:18 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 04:26:18 INFO - "buildid": "20160524022810", 04:26:18 INFO - "pgo_build": "False", 04:26:18 INFO - "basedir": "/builds/slave/test", 04:26:18 INFO - "buildnumber": 24, 04:26:18 INFO - "slavename": "t-yosemite-r7-0318", 04:26:18 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 04:26:18 INFO - "platform": "yosemite_r7", 04:26:18 INFO - "scheduler": "tests-fx-team-macosx64-talos", 04:26:18 INFO - "branch": "fx-team", 04:26:18 INFO - "repo_path": "integration/fx-team", 04:26:18 INFO - "revision": "e8f4f77bd99eee8e95262779c90235ce5aaaafe4", 04:26:18 INFO - "stage_platform": "macosx64", 04:26:18 INFO - "builduid": "82f405c25a6d4a959409e65fc5664b13", 04:26:18 INFO - "slavebuilddir": "test" 04:26:18 INFO - } 04:26:18 INFO - Found installer url https://queue.taskcluster.net/v1/task/MLNIfzbgTwigewqAHuWtBg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 04:26:18 INFO - Running post-action listener: _resource_record_post_action 04:26:18 INFO - ##### 04:26:18 INFO - ##### Finished read-buildbot-config step (success) 04:26:18 INFO - ##### 04:26:18 INFO - ##### 04:26:18 INFO - ##### Running download-and-extract step. 04:26:18 INFO - ##### 04:26:18 INFO - Running pre-action listener: _resource_record_pre_action 04:26:18 INFO - Running main action method: download_and_extract 04:26:18 INFO - mkdir: /builds/slave/test/build/tests 04:26:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:26:18 INFO - https://queue.taskcluster.net/v1/task/MLNIfzbgTwigewqAHuWtBg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 04:26:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MLNIfzbgTwigewqAHuWtBg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 04:26:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MLNIfzbgTwigewqAHuWtBg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 04:26:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MLNIfzbgTwigewqAHuWtBg/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 04:26:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MLNIfzbgTwigewqAHuWtBg/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 04:26:26 INFO - Downloaded 1199 bytes. 04:26:26 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 04:26:26 INFO - Using the following test package requirements: 04:26:26 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 04:26:26 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:26:26 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 04:26:26 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:26:26 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 04:26:26 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:26:26 INFO - u'jsshell-mac.zip'], 04:26:26 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:26:26 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 04:26:26 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 04:26:26 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:26:26 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 04:26:26 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:26:26 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 04:26:26 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:26:26 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 04:26:26 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:26:26 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 04:26:26 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:26:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:26:26 INFO - https://queue.taskcluster.net/v1/task/MLNIfzbgTwigewqAHuWtBg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:26:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MLNIfzbgTwigewqAHuWtBg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:26:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MLNIfzbgTwigewqAHuWtBg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:26:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MLNIfzbgTwigewqAHuWtBg/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 04:26:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MLNIfzbgTwigewqAHuWtBg/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 04:26:35 INFO - Downloaded 18630548 bytes. 04:26:35 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'] 04:26:35 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 04:26:38 INFO - Return code: 0 04:26:38 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 04:26:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:26:38 INFO - https://queue.taskcluster.net/v1/task/MLNIfzbgTwigewqAHuWtBg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:26:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MLNIfzbgTwigewqAHuWtBg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:26:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MLNIfzbgTwigewqAHuWtBg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:26:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MLNIfzbgTwigewqAHuWtBg/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 04:26:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MLNIfzbgTwigewqAHuWtBg/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 04:26:41 INFO - Downloaded 18630548 bytes. 04:26:41 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'] 04:26:41 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 04:26:44 INFO - Return code: 0 04:26:44 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')]} 04:26:44 INFO - https://queue.taskcluster.net/v1/task/MLNIfzbgTwigewqAHuWtBg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 04:26:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MLNIfzbgTwigewqAHuWtBg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 04:26:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MLNIfzbgTwigewqAHuWtBg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 04:26:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MLNIfzbgTwigewqAHuWtBg/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 04:26:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MLNIfzbgTwigewqAHuWtBg/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 04:26:45 INFO - Downloaded 10840359 bytes. 04:26:45 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'] 04:26:45 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 04:26:46 INFO - Return code: 0 04:26:46 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')]} 04:26:46 INFO - https://queue.taskcluster.net/v1/task/MLNIfzbgTwigewqAHuWtBg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 04:26:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MLNIfzbgTwigewqAHuWtBg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 04:26:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MLNIfzbgTwigewqAHuWtBg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 04:26:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MLNIfzbgTwigewqAHuWtBg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 04:26:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MLNIfzbgTwigewqAHuWtBg/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 04:26:51 INFO - Downloaded 110171715 bytes. 04:26:51 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/MLNIfzbgTwigewqAHuWtBg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 04:26:51 INFO - mkdir: /builds/slave/test/properties 04:26:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:26:51 INFO - Writing to file /builds/slave/test/properties/build_url 04:26:51 INFO - Contents: 04:26:51 INFO - build_url:https://queue.taskcluster.net/v1/task/MLNIfzbgTwigewqAHuWtBg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 04:26:53 INFO - Running post-action listener: _resource_record_post_action 04:26:53 INFO - Running post-action listener: set_extra_try_arguments 04:26:53 INFO - ##### 04:26:53 INFO - ##### Finished download-and-extract step (success) 04:26:53 INFO - ##### 04:26:53 INFO - ##### 04:26:53 INFO - ##### Running populate-webroot step. 04:26:53 INFO - ##### 04:26:53 INFO - Running pre-action listener: _resource_record_pre_action 04:26:53 INFO - Running main action method: populate_webroot 04:26:53 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:26:53 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:26:53 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:26:53 INFO - '--tppagecycles', 04:26:53 INFO - '7'], 04:26:53 INFO - 'tests': ['tsvgm']}}, 04:26:53 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:26:53 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:26:53 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:26:53 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:26:53 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:26:53 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:26:53 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:26:53 INFO - 'talos_options': ['--disable-e10s'], 04:26:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:26:53 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:26:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:26:53 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:26:53 INFO - 'talos_options': ['--disable-e10s'], 04:26:53 INFO - 'tests': ['damp', 'tps']}, 04:26:53 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:26:53 INFO - 'tests': ['damp', 'tps']}, 04:26:53 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:26:53 INFO - 'tests': ['dromaeo_dom']}, 04:26:53 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:26:53 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:26:53 INFO - 'tests': ['a11yr', 04:26:53 INFO - 'ts_paint', 04:26:53 INFO - 'tpaint', 04:26:53 INFO - 'sessionrestore', 04:26:53 INFO - 'sessionrestore_no_auto_restore', 04:26:53 INFO - 'tabpaint']}, 04:26:53 INFO - 'other-e10s': {'tests': ['a11yr', 04:26:53 INFO - 'ts_paint', 04:26:53 INFO - 'tpaint', 04:26:53 INFO - 'sessionrestore', 04:26:53 INFO - 'sessionrestore_no_auto_restore', 04:26:53 INFO - 'tabpaint']}, 04:26:53 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:26:53 INFO - 'ts_paint', 04:26:53 INFO - 'tpaint', 04:26:53 INFO - 'sessionrestore', 04:26:53 INFO - 'sessionrestore_no_auto_restore', 04:26:53 INFO - 'tabpaint']}, 04:26:53 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:26:53 INFO - 'ts_paint', 04:26:53 INFO - 'tpaint', 04:26:53 INFO - 'sessionrestore', 04:26:53 INFO - 'sessionrestore_no_auto_restore', 04:26:53 INFO - 'tabpaint']}, 04:26:53 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:26:53 INFO - 'tests': ['a11yr', 04:26:53 INFO - 'ts_paint', 04:26:53 INFO - 'tpaint', 04:26:53 INFO - 'sessionrestore', 04:26:53 INFO - 'sessionrestore_no_auto_restore', 04:26:53 INFO - 'tabpaint']}, 04:26:53 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:26:53 INFO - 'tests': ['a11yr', 04:26:53 INFO - 'ts_paint', 04:26:53 INFO - 'tpaint', 04:26:53 INFO - 'sessionrestore', 04:26:53 INFO - 'sessionrestore_no_auto_restore', 04:26:53 INFO - 'tabpaint']}, 04:26:53 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:26:53 INFO - 'tests': ['tsvgx', 04:26:53 INFO - 'tsvgr_opacity', 04:26:53 INFO - 'tart', 04:26:53 INFO - 'tscrollx', 04:26:53 INFO - 'cart']}, 04:26:53 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:26:53 INFO - 'tsvgr_opacity', 04:26:53 INFO - 'tart', 04:26:53 INFO - 'tscrollx', 04:26:53 INFO - 'cart']}, 04:26:53 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:26:53 INFO - 'talos_options': ['--disable-e10s'], 04:26:53 INFO - 'tests': ['tp5o']}, 04:26:53 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:26:53 INFO - 'tests': ['tp5o']}, 04:26:53 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:26:53 INFO - 'talos_options': ['--disable-e10s', 04:26:53 INFO - '--xperf_path', 04:26:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:26:53 INFO - 'tests': ['tp5n']}, 04:26:53 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:26:53 INFO - 'talos_options': ['--xperf_path', 04:26:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:26:53 INFO - 'tests': ['tp5n']}}, 04:26:53 INFO - 'talos.zip': {'path': '', 04:26:53 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:26:53 INFO - Running post-action listener: _resource_record_post_action 04:26:53 INFO - ##### 04:26:53 INFO - ##### Finished populate-webroot step (success) 04:26:53 INFO - ##### 04:26:53 INFO - ##### 04:26:53 INFO - ##### Running create-virtualenv step. 04:26:53 INFO - ##### 04:26:53 INFO - Running pre-action listener: _resource_record_pre_action 04:26:53 INFO - Running main action method: create_virtualenv 04:26:53 INFO - Creating virtualenv /builds/slave/test/build/venv 04:26:53 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 04:26:53 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:26:53 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:26:53 INFO - Using real prefix '/tools/python27' 04:26:53 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:26:53 INFO - Installing distribute.............................................................................................................................................................................................done. 04:26:56 INFO - Installing pip.................done. 04:26:56 INFO - Return code: 0 04:26:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:26:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:26:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:26:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:26:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:26:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:26:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:26:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103aeca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103af2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2fbd88040>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103990d30>, '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 0x1037b4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103987b20>, '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.9vPONKBnjO/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.FT5pYy7yy8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 04:26:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:26:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:26:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9vPONKBnjO/Render', 04:26:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:26:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:26:56 INFO - 'HOME': '/Users/cltbld', 04:26:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:26:56 INFO - 'LOGNAME': 'cltbld', 04:26:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:26:56 INFO - 'MOZ_NO_REMOTE': '1', 04:26:56 INFO - 'NO_EM_RESTART': '1', 04:26:56 INFO - 'PAGER': '/bin/cat', 04:26:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:26:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:26:56 INFO - 'PWD': '/builds/slave/test', 04:26:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:26:56 INFO - 'SHELL': '/bin/bash', 04:26:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FT5pYy7yy8/Listeners', 04:26:56 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 04:26:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:26:56 INFO - 'USER': 'cltbld', 04:26:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:26:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:26:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:26:56 INFO - 'XPC_FLAGS': '0x0', 04:26:56 INFO - 'XPC_SERVICE_NAME': '0', 04:26:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:26:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:26:57 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 04:26:57 INFO - Cleaning up... 04:26:57 INFO - Return code: 0 04:26:57 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:26:57 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')]} 04:26:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:26:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:26:57 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')]} 04:26:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:26:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:26:57 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 0x103aeca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103af2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2fbd88040>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103990d30>, '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 0x1037b4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103987b20>, '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.9vPONKBnjO/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.FT5pYy7yy8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 04:26:57 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 04:26:57 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 04:26:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9vPONKBnjO/Render', 04:26:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:26:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:26:57 INFO - 'HOME': '/Users/cltbld', 04:26:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:26:57 INFO - 'LOGNAME': 'cltbld', 04:26:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:26:57 INFO - 'MOZ_NO_REMOTE': '1', 04:26:57 INFO - 'NO_EM_RESTART': '1', 04:26:57 INFO - 'PAGER': '/bin/cat', 04:26:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:26:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:26:57 INFO - 'PWD': '/builds/slave/test', 04:26:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:26:57 INFO - 'SHELL': '/bin/bash', 04:26:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FT5pYy7yy8/Listeners', 04:26:57 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 04:26:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:26:57 INFO - 'USER': 'cltbld', 04:26:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:26:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:26:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:26:57 INFO - 'XPC_FLAGS': '0x0', 04:26:57 INFO - 'XPC_SERVICE_NAME': '0', 04:26:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:26:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:26:57 INFO - Downloading/unpacking psutil>=3.1.1 04:26: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 04:26: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 04:26: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 04:26: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 04:26: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 04:26: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 04:26:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:27:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:27:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:27:00 INFO - Installing collected packages: psutil 04:27:00 INFO - Running setup.py install for psutil 04:27:00 INFO - building 'psutil._psutil_osx' extension 04:27:00 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 04:27:00 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 04:27:00 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 04:27:00 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 04:27:00 INFO - building 'psutil._psutil_posix' extension 04:27:00 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 04:27:00 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:27:00 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:27:00 INFO - ^ 04:27:00 INFO - 1 warning generated. 04:27:00 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 04:27:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:27:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:27:01 INFO - Successfully installed psutil 04:27:01 INFO - Cleaning up... 04:27:01 INFO - Return code: 0 04:27:01 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:27:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:27:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:27:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:27:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:27:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:27:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:27:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103aeca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103af2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2fbd88040>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103990d30>, '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 0x1037b4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103987b20>, '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.9vPONKBnjO/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.FT5pYy7yy8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 04:27:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:27:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 04:27:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9vPONKBnjO/Render', 04:27:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:27:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:27:01 INFO - 'HOME': '/Users/cltbld', 04:27:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:27:01 INFO - 'LOGNAME': 'cltbld', 04:27:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:27:01 INFO - 'MOZ_NO_REMOTE': '1', 04:27:01 INFO - 'NO_EM_RESTART': '1', 04:27:01 INFO - 'PAGER': '/bin/cat', 04:27:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:27:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:27:01 INFO - 'PWD': '/builds/slave/test', 04:27:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:27:01 INFO - 'SHELL': '/bin/bash', 04:27:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FT5pYy7yy8/Listeners', 04:27:01 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 04:27:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:27:01 INFO - 'USER': 'cltbld', 04:27:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:27:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:27:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:27:01 INFO - 'XPC_FLAGS': '0x0', 04:27:01 INFO - 'XPC_SERVICE_NAME': '0', 04:27:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:27:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:27:01 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:27:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:27:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:27:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:27:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:27:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:27:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:27:03 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:27:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:27:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:27:04 INFO - Installing collected packages: mozsystemmonitor 04:27:04 INFO - Running setup.py install for mozsystemmonitor 04:27:04 INFO - Successfully installed mozsystemmonitor 04:27:04 INFO - Cleaning up... 04:27:04 INFO - Return code: 0 04:27:04 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:27:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:27:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:27:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:27:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:27:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:27:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:27:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103aeca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103af2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2fbd88040>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103990d30>, '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 0x1037b4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103987b20>, '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.9vPONKBnjO/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.FT5pYy7yy8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 04:27:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:27:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:27:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9vPONKBnjO/Render', 04:27:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:27:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:27:04 INFO - 'HOME': '/Users/cltbld', 04:27:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:27:04 INFO - 'LOGNAME': 'cltbld', 04:27:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:27:04 INFO - 'MOZ_NO_REMOTE': '1', 04:27:04 INFO - 'NO_EM_RESTART': '1', 04:27:04 INFO - 'PAGER': '/bin/cat', 04:27:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:27:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:27:04 INFO - 'PWD': '/builds/slave/test', 04:27:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:27:04 INFO - 'SHELL': '/bin/bash', 04:27:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FT5pYy7yy8/Listeners', 04:27:04 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 04:27:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:27:04 INFO - 'USER': 'cltbld', 04:27:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:27:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:27:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:27:04 INFO - 'XPC_FLAGS': '0x0', 04:27:04 INFO - 'XPC_SERVICE_NAME': '0', 04:27:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:27:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:27:04 INFO - Downloading/unpacking blobuploader==1.2.4 04:27:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:27:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:27:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:27:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:27:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:27:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:27:06 INFO - Downloading blobuploader-1.2.4.tar.gz 04:27:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:27:06 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:27:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:27:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:27:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:27:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:27:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:27:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:27:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:27:07 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:27:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:27:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:27:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:27:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:27:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:27:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:27:08 INFO - Downloading docopt-0.6.1.tar.gz 04:27:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:27:08 INFO - Installing collected packages: blobuploader, requests, docopt 04:27:08 INFO - Running setup.py install for blobuploader 04:27:08 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:27:08 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:27:08 INFO - Running setup.py install for requests 04:27:08 INFO - Running setup.py install for docopt 04:27:08 INFO - Successfully installed blobuploader requests docopt 04:27:08 INFO - Cleaning up... 04:27:09 INFO - Return code: 0 04:27:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:27:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:27:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:27:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:27:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:27:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:27:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:27:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103aeca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103af2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2fbd88040>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103990d30>, '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 0x1037b4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103987b20>, '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.9vPONKBnjO/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.FT5pYy7yy8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 04:27:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:27:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:27:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9vPONKBnjO/Render', 04:27:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:27:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:27:09 INFO - 'HOME': '/Users/cltbld', 04:27:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:27:09 INFO - 'LOGNAME': 'cltbld', 04:27:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:27:09 INFO - 'MOZ_NO_REMOTE': '1', 04:27:09 INFO - 'NO_EM_RESTART': '1', 04:27:09 INFO - 'PAGER': '/bin/cat', 04:27:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:27:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:27:09 INFO - 'PWD': '/builds/slave/test', 04:27:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:27:09 INFO - 'SHELL': '/bin/bash', 04:27:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FT5pYy7yy8/Listeners', 04:27:09 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 04:27:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:27:09 INFO - 'USER': 'cltbld', 04:27:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:27:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:27:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:27:09 INFO - 'XPC_FLAGS': '0x0', 04:27:09 INFO - 'XPC_SERVICE_NAME': '0', 04:27:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:27:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:27:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:27:09 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-Bmz9rZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:27:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:27:09 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-lsYPcs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:27:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:27:09 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-JTYe1S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:27:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:27:09 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-PDsTGC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:27:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:27:09 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-RSLFyZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:27:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:27:09 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-StXRFy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:27:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:27:09 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-GzQJov-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:27:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:27:10 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-04qAal-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:27:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:27:10 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-MPCB7G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:27:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:27:10 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-_8BFdH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:27:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:27:10 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-PupO53-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:27:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:27:10 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-Uyoe6t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:27:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:27:10 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-w0MicE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:27:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:27:10 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-5f9hZc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:27:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:27:10 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-Zvu0Tw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:27:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:27:10 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-mhNvpP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:27:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:27:10 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-bTfVWd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:27:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:27:11 INFO - Running setup.py install for manifestparser 04:27:11 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:27:11 INFO - Running setup.py install for mozcrash 04:27:11 INFO - Running setup.py install for mozdebug 04:27:11 INFO - Running setup.py install for mozdevice 04:27:11 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:27:11 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:27:11 INFO - Running setup.py install for mozfile 04:27:11 INFO - Running setup.py install for mozhttpd 04:27:11 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:27:11 INFO - Running setup.py install for mozinfo 04:27:12 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:27:12 INFO - Running setup.py install for mozInstall 04:27:12 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:27:12 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:27:12 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:27:12 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:27:12 INFO - Running setup.py install for mozleak 04:27:12 INFO - Running setup.py install for mozlog 04:27:12 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:27:12 INFO - Running setup.py install for moznetwork 04:27:12 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:27:12 INFO - Running setup.py install for mozprocess 04:27:12 INFO - Running setup.py install for mozprofile 04:27:12 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:27:12 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:27:12 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:27:12 INFO - Running setup.py install for mozrunner 04:27:13 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:27:13 INFO - Running setup.py install for mozscreenshot 04:27:13 INFO - Running setup.py install for moztest 04:27:13 INFO - Running setup.py install for mozversion 04:27:13 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:27:13 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:27:13 INFO - Cleaning up... 04:27:13 INFO - Return code: 0 04:27:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:27:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:27:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:27:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:27:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:27:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:27:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:27:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103aeca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103af2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2fbd88040>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103990d30>, '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 0x1037b4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103987b20>, '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.9vPONKBnjO/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.FT5pYy7yy8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 04:27:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:27:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:27:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9vPONKBnjO/Render', 04:27:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:27:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:27:13 INFO - 'HOME': '/Users/cltbld', 04:27:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:27:13 INFO - 'LOGNAME': 'cltbld', 04:27:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:27:13 INFO - 'MOZ_NO_REMOTE': '1', 04:27:13 INFO - 'NO_EM_RESTART': '1', 04:27:13 INFO - 'PAGER': '/bin/cat', 04:27:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:27:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:27:13 INFO - 'PWD': '/builds/slave/test', 04:27:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:27:13 INFO - 'SHELL': '/bin/bash', 04:27:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FT5pYy7yy8/Listeners', 04:27:13 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 04:27:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:27:13 INFO - 'USER': 'cltbld', 04:27:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:27:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:27:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:27:13 INFO - 'XPC_FLAGS': '0x0', 04:27:13 INFO - 'XPC_SERVICE_NAME': '0', 04:27:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:27:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:27:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:27:13 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-ZjzIwr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:27:13 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)) 04:27:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:27:13 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-PTTEd6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:27:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:27:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:27:14 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-0MiGZa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:27:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:27:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:27:14 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-aNNefk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:27:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:27:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:27:14 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-W5hnhA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:27:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 04:27:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:27:14 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-vWa33v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:27:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 04:27:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:27:14 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-5O4JI7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:27:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 04:27:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:27:14 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-w0XfG7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:27:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 04:27:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:27:14 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-Kf273y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:27:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 04:27:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:27:14 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-6zpwfF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:27:14 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)) 04:27:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:27:14 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-POBr2Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:27:15 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 04:27:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:27:15 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-sQ0F4Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:27:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 04:27:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:27:15 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-8GONYq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:27:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 04:27:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:27:15 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-pPHBpa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:27:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 04:27:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:27:15 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-Y8oFYc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:27:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 04:27:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:27:15 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-5rRKVA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:27:15 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 04:27:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:27:15 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-XetIUW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:27:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 04:27:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:27:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:27:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:27:15 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:27:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:27:15 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:27:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:27:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:27:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:27:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:27:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:27:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:27:19 INFO - Downloading blessings-1.6.tar.gz 04:27:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:27:19 INFO - Installing collected packages: blessings 04:27:19 INFO - Running setup.py install for blessings 04:27:19 INFO - Successfully installed blessings 04:27:19 INFO - Cleaning up... 04:27:19 INFO - Return code: 0 04:27:19 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:27:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:27:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:27:19 INFO - Reading from file tmpfile_stdout 04:27:19 INFO - Current package versions: 04:27:19 INFO - blessings == 1.6 04:27:19 INFO - blobuploader == 1.2.4 04:27:19 INFO - docopt == 0.6.1 04:27:19 INFO - manifestparser == 1.1 04:27:19 INFO - mozInstall == 1.12 04:27:19 INFO - mozcrash == 0.17 04:27:19 INFO - mozdebug == 0.1 04:27:19 INFO - mozdevice == 0.48 04:27:19 INFO - mozfile == 1.2 04:27:19 INFO - mozhttpd == 0.7 04:27:19 INFO - mozinfo == 0.9 04:27:19 INFO - mozleak == 0.1 04:27:19 INFO - mozlog == 3.2 04:27:19 INFO - moznetwork == 0.27 04:27:19 INFO - mozprocess == 0.22 04:27:19 INFO - mozprofile == 0.28 04:27:19 INFO - mozrunner == 6.11 04:27:19 INFO - mozscreenshot == 0.1 04:27:19 INFO - mozsystemmonitor == 0.3 04:27:19 INFO - moztest == 0.7 04:27:19 INFO - mozversion == 1.4 04:27:19 INFO - psutil == 3.1.1 04:27:19 INFO - requests == 1.2.3 04:27:19 INFO - wsgiref == 0.1.2 04:27:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:27: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')]} 04:27:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:27:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:27: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')]} 04:27:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:27:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:27:20 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 0x103aeca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103af2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2fbd88040>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103990d30>, '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 0x1037b4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103987b20>, '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.9vPONKBnjO/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.FT5pYy7yy8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 04:27:20 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 04:27:20 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 04:27:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9vPONKBnjO/Render', 04:27:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:27:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:27:20 INFO - 'HOME': '/Users/cltbld', 04:27:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:27:20 INFO - 'LOGNAME': 'cltbld', 04:27:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:27:20 INFO - 'MOZ_NO_REMOTE': '1', 04:27:20 INFO - 'NO_EM_RESTART': '1', 04:27:20 INFO - 'PAGER': '/bin/cat', 04:27:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:27:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:27:20 INFO - 'PWD': '/builds/slave/test', 04:27:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:27:20 INFO - 'SHELL': '/bin/bash', 04:27:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FT5pYy7yy8/Listeners', 04:27:20 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 04:27:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:27:20 INFO - 'USER': 'cltbld', 04:27:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:27:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:27:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:27:20 INFO - 'XPC_FLAGS': '0x0', 04:27:20 INFO - 'XPC_SERVICE_NAME': '0', 04:27:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:27:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:27:20 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)) 04:27:20 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)) 04:27:20 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)) 04:27:20 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)) 04:27:20 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)) 04:27:20 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)) 04:27:20 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)) 04:27:20 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)) 04:27:20 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)) 04:27:20 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)) 04:27:20 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)) 04:27:20 INFO - Cleaning up... 04:27:20 INFO - Return code: 0 04:27:20 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:27: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')]} 04:27:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:27:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:27: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')]} 04:27:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:27:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:27:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103aeca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103af2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2fbd88040>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103990d30>, '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 0x1037b4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103987b20>, '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.9vPONKBnjO/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.FT5pYy7yy8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 04:27:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:27:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:27:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9vPONKBnjO/Render', 04:27:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:27:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:27:20 INFO - 'HOME': '/Users/cltbld', 04:27:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:27:20 INFO - 'LOGNAME': 'cltbld', 04:27:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:27:20 INFO - 'MOZ_NO_REMOTE': '1', 04:27:20 INFO - 'NO_EM_RESTART': '1', 04:27:20 INFO - 'PAGER': '/bin/cat', 04:27:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:27:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:27:20 INFO - 'PWD': '/builds/slave/test', 04:27:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:27:20 INFO - 'SHELL': '/bin/bash', 04:27:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FT5pYy7yy8/Listeners', 04:27:20 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 04:27:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:27:20 INFO - 'USER': 'cltbld', 04:27:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:27:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:27:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:27:20 INFO - 'XPC_FLAGS': '0x0', 04:27:20 INFO - 'XPC_SERVICE_NAME': '0', 04:27:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:27:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:27:20 INFO - Downloading/unpacking jsonschema 04:27:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:27:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:27:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:27:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:27:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:27:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:27:23 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:27:23 INFO - Downloading/unpacking functools32 (from jsonschema) 04:27:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:27:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:27:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:27:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:27:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:27:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:27:23 INFO - Downloading functools32-3.2.3-2.tar.gz 04:27:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:27:23 INFO - warning: no files found matching '*.txt' 04:27:23 INFO - no previously-included directories found matching 'build' 04:27:23 INFO - no previously-included directories found matching 'dist' 04:27:23 INFO - no previously-included directories found matching '.git*' 04:27:23 INFO - Installing collected packages: jsonschema, functools32 04:27:24 INFO - Running setup.py install for functools32 04:27:24 INFO - warning: no files found matching '*.txt' 04:27:24 INFO - no previously-included directories found matching 'build' 04:27:24 INFO - no previously-included directories found matching 'dist' 04:27:24 INFO - no previously-included directories found matching '.git*' 04:27:24 INFO - Successfully installed jsonschema functools32 04:27:24 INFO - Cleaning up... 04:27:24 INFO - Return code: 0 04:27:24 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 04:27:24 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')]} 04:27:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:27:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:27:24 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')]} 04:27:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:27:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:27:24 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 0x103aeca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103af2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2fbd88040>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103990d30>, '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 0x1037b4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103987b20>, '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.9vPONKBnjO/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.FT5pYy7yy8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 04:27:24 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 04:27:24 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 04:27:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9vPONKBnjO/Render', 04:27:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:27:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:27:24 INFO - 'HOME': '/Users/cltbld', 04:27:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:27:24 INFO - 'LOGNAME': 'cltbld', 04:27:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:27:24 INFO - 'MOZ_NO_REMOTE': '1', 04:27:24 INFO - 'NO_EM_RESTART': '1', 04:27:24 INFO - 'PAGER': '/bin/cat', 04:27:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:27:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:27:24 INFO - 'PWD': '/builds/slave/test', 04:27:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:27:24 INFO - 'SHELL': '/bin/bash', 04:27:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FT5pYy7yy8/Listeners', 04:27:24 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 04:27:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:27:24 INFO - 'USER': 'cltbld', 04:27:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:27:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:27:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:27:24 INFO - 'XPC_FLAGS': '0x0', 04:27:24 INFO - 'XPC_SERVICE_NAME': '0', 04:27:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:27:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:27:24 INFO - Downloading/unpacking flake8 04:27:24 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 04:27:24 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 04:27:24 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 04:27:24 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 04:27:24 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 04:27:24 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 04:27:26 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 04:27:26 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 04:27:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:27:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:27:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:27:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:27:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:27:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:27:27 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 04:27:27 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 04:27:27 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 04:27:27 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 04:27:27 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 04:27:27 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 04:27:27 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 04:27:27 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 04:27:27 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 04:27:27 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 04:27:27 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 04:27:27 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 04:27:27 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 04:27:27 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 04:27:27 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 04:27:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 04:27:28 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:27:28 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:27:28 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:27:28 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:27:28 INFO - no previously-included directories found matching 'docs/_build' 04:27:28 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 04:27:28 INFO - Running setup.py install for pep8 04:27:28 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:27:28 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:27:28 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:27:28 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:27:28 INFO - no previously-included directories found matching 'docs/_build' 04:27:28 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 04:27:28 INFO - Successfully installed flake8 mccabe pyflakes pep8 04:27:28 INFO - Cleaning up... 04:27:28 INFO - Return code: 0 04:27:28 INFO - Running post-action listener: _resource_record_post_action 04:27:28 INFO - Running post-action listener: _start_resource_monitoring 04:27:28 INFO - Starting resource monitoring. 04:27:28 INFO - ##### 04:27:28 INFO - ##### Finished create-virtualenv step (success) 04:27:28 INFO - ##### 04:27:28 INFO - ##### 04:27:28 INFO - ##### Running install step. 04:27:28 INFO - ##### 04:27:28 INFO - Running pre-action listener: _resource_record_pre_action 04:27:28 INFO - Running main action method: install 04:27:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:27:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:27:28 INFO - Reading from file tmpfile_stdout 04:27:28 INFO - Detecting whether we're running mozinstall >=1.0... 04:27:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:27:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:27:28 INFO - Reading from file tmpfile_stdout 04:27:28 INFO - Output received: 04:27:28 INFO - Usage: mozinstall [options] installer 04:27:28 INFO - Options: 04:27:28 INFO - -h, --help show this help message and exit 04:27:28 INFO - -d DEST, --destination=DEST 04:27:28 INFO - Directory to install application into. [default: 04:27:28 INFO - "/builds/slave/test"] 04:27:28 INFO - --app=APP Application being installed. [default: firefox] 04:27:28 INFO - mkdir: /builds/slave/test/build/application 04:27:28 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'] 04:27:28 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 04:28:02 INFO - Reading from file tmpfile_stdout 04:28:02 INFO - Output received: 04:28:02 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 04:28:02 INFO - Running post-action listener: _resource_record_post_action 04:28:02 INFO - ##### 04:28:02 INFO - ##### Finished install step (success) 04:28:02 INFO - ##### 04:28:02 INFO - ##### 04:28:02 INFO - ##### Running run-tests step. 04:28:02 INFO - ##### 04:28:02 INFO - Running pre-action listener: _resource_record_pre_action 04:28:02 INFO - Running main action method: run_tests 04:28:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:28:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:28:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:28:02 INFO - 2016-05-24 04:28:02.620 screenresolution[1642:7869] starting screenresolution argv=screenresolution get 04:28:02 INFO - 2016-05-24 04:28:02.622 screenresolution[1642:7869] Display 0: 1600x1200x32@60 04:28:02 INFO - 2016-05-24 04:28:02.628 screenresolution[1643:7871] starting screenresolution argv=screenresolution list 04:28:02 INFO - Available Modes on Display 0 04:28:02 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:28:02 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:28:02 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:28:02 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:28:02 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:28:02 INFO - Intel Iris: 04:28:02 INFO - Chipset Model: Intel Iris 04:28:02 INFO - Type: GPU 04:28:02 INFO - Bus: Built-In 04:28:02 INFO - VRAM (Dynamic, Max): 1536 MB 04:28:02 INFO - Vendor: Intel (0x8086) 04:28:02 INFO - Device ID: 0x0a2e 04:28:02 INFO - Revision ID: 0x0009 04:28:02 INFO - Displays: 04:28:02 INFO - Display: 04:28:02 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:28:02 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:28:02 INFO - Display Serial Number: 1600x1200 60 04:28:02 INFO - Main Display: Yes 04:28:02 INFO - Mirror: Off 04:28:02 INFO - Online: Yes 04:28:02 INFO - Rotation: Supported 04:28:02 INFO - Return code: 0 04:28:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:28:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:28:02 INFO - Python 2.7.3 04:28:02 INFO - Return code: 0 04:28:02 INFO - grabbing minidump binary from tooltool 04:28: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')]} 04:28:02 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 0x103990d30>, '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 0x1037b4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103987b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:28:02 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 04:28:02 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 04:28:02 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 04:28:02 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:28:02 INFO - Return code: 0 04:28:02 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:28:02 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:28:02 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:28:02 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:28:02 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:28:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 04:28:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 04:28:04 INFO - Return code: 0 04:28:04 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/MLNIfzbgTwigewqAHuWtBg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0318', '--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 04:28:04 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/MLNIfzbgTwigewqAHuWtBg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0318 --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 04:28:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9vPONKBnjO/Render', 04:28:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:28:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:28:04 INFO - 'HOME': '/Users/cltbld', 04:28:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:28:04 INFO - 'LOGNAME': 'cltbld', 04:28:04 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:28:04 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:28:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:28:04 INFO - 'MOZ_NO_REMOTE': '1', 04:28:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:28:04 INFO - 'NO_EM_RESTART': '1', 04:28:04 INFO - 'PAGER': '/bin/cat', 04:28:04 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:28:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:28:04 INFO - 'PWD': '/builds/slave/test', 04:28:04 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:28:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:28:04 INFO - 'SHELL': '/bin/bash', 04:28:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FT5pYy7yy8/Listeners', 04:28:04 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 04:28:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:28:04 INFO - 'USER': 'cltbld', 04:28:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:28:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:28:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:28:04 INFO - 'XPC_FLAGS': '0x0', 04:28:04 INFO - 'XPC_SERVICE_NAME': '0', 04:28:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:28:04 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/MLNIfzbgTwigewqAHuWtBg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0318', '--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 04:28:04 INFO - mozversion application_buildid: 20160524022810 04:28:04 INFO - mozversion application_changeset: e8f4f77bd99eee8e95262779c90235ce5aaaafe4 04:28:04 INFO - mozversion application_display_name: Nightly 04:28:04 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:28:04 INFO - mozversion application_name: Firefox 04:28:04 INFO - mozversion application_remotingname: firefox 04:28:04 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 04:28:04 INFO - mozversion application_vendor: Mozilla 04:28:04 INFO - mozversion application_version: 49.0a1 04:28:04 INFO - mozversion platform_buildid: 20160524022810 04:28:04 INFO - mozversion platform_changeset: e8f4f77bd99eee8e95262779c90235ce5aaaafe4 04:28:04 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 04:28:04 INFO - mozversion platform_version: 49.0a1 04:28:04 INFO - using testdate: 1464089284 04:28:04 INFO - actual date: 1464089284 04:28:04 INFO - starting webserver on 'localhost:49276' 04:28:04 INFO - SUITE-START | Running 2 tests 04:28:04 INFO - TEST-START | dromaeo_css 04:28:04 INFO - operating with platform_type : mac_ 04:28:04 INFO - Initialising browser for dromaeo_css test... 04:28:04 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/tmpjFaOi_/profile http://localhost:49276/getInfo.html) 04:28:06 INFO - PROCESS | 1661 | __metrics Screen width/height:1600/1200 04:28:06 INFO - PROCESS | 1661 | colorDepth:24 04:28:06 INFO - PROCESS | 1661 | Browser inner width/height: 1024/689 04:28:06 INFO - PROCESS | 1661 | __metrics 04:28:06 INFO - PROCESS | 1661 | 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" 04:28:06 ERROR - PROCESS | 1661 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 04:28:07 INFO - TEST-INFO | 1661: exit 0 04:28:07 INFO - Browser initialized. 04:28:07 INFO - Running cycle 1/1 for dromaeo_css test... 04:28:07 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/tmpjFaOi_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:28:19 INFO - PROCESS | 1664 | RSS: Main: 238854144 04:28:19 INFO - PROCESS | 1664 | 04:29:10 INFO - PROCESS | 1664 | 1464089350203 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] 04:31:21 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49276/tests/dromaeo/cssquery-ext.html) 04:31:22 INFO - PROCESS | 1664 | RSS: Main: 340971520 04:31:22 INFO - PROCESS | 1664 | 04:34:25 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-ext.html (next: http://localhost:49276/tests/dromaeo/cssquery-jquery.html) 04:34:26 INFO - PROCESS | 1664 | RSS: Main: 373272576 04:34:26 INFO - PROCESS | 1664 | 04:37:28 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49276/tests/dromaeo/cssquery-mootools.html) 04:37:28 INFO - PROCESS | 1664 | RSS: Main: 370606080 04:37:28 INFO - PROCESS | 1664 | 04:40:31 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49276/tests/dromaeo/cssquery-prototype.html) 04:40:31 INFO - PROCESS | 1664 | RSS: Main: 368914432 04:40:31 INFO - PROCESS | 1664 | 04:43:34 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49276/tests/dromaeo/cssquery-yui.html) 04:43:35 INFO - PROCESS | 1664 | RSS: Main: 339587072 04:43:35 INFO - PROCESS | 1664 | 04:46:38 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-yui.html (next: http://localhost:49276/tests/dromaeo/cssquery-dojo.html) 04:46:38 INFO - PROCESS | 1664 | RSS: Main: 377028608 04:46:38 INFO - PROCESS | 1664 | 04:46:38 INFO - PROCESS | 1664 | __start_tp_report 04:46:38 INFO - PROCESS | 1664 | _x_x_mozilla_page_load 04:46:38 INFO - PROCESS | 1664 | _x_x_mozilla_page_load_details 04:46:38 INFO - PROCESS | 1664 | |i|pagename|runs| 04:46:38 INFO - PROCESS | 1664 | |0;dojo.html;3314.6685331466856;3467.5665541807275;3482.651734826516;3503.1242189452646;3538.41616133338;5553.833384998451;5575.553955683546;5655.745491452884;5699.857503562413;5705.343885453169;5101.158308879039;5561.998840208761;5669.262995810549;5670.149477578365;5761.711914404284;5119.206522988943;5150.2532132840715;5210.322658054448;5224.843254702346;5306.363236411636;5306.681599104066;5317.627766056358;5338.519533242027;5351.518363347298;5395.514403703666;5153.871153221162;5205.843824685227;5228.8692782680355;5230;5273;5238.5809135269465;5257.027449921777;5268.130758424855;5271.789128434858;5281.947180528184;5131.384233891919;5181.5854731621375;5195.792168313263;5217.791288348461;5227.79088836446;4627.676062675614;5528.474794894479;5587.022271102582;5663.716814159276;5669.546436285087;6161.7227224775;6260.624362538262;6262.1546091278005;6267.905981410283;6277.8744425111245;4616.792244349012;6103.974138059492;6156.353582873823;6225;6256.092866534373;5875.764969401262;5881.411858814127;5922.733476993502;5936.821895343147;5942.5840191186635;6188.412100850412;6312.400321969406;6317.368263173737;6330.177076979963;6334.651594162273;6116.816495505053;6122.886104953835;6139.4781443577795;6177.382261773787;6184.134221209034;6227.719752611143;6237.625742455467;6239.251289845157;6245.18812554365;6255.874882502416;5987.101934709741;6071.726772295257;6200.658963757037;6238.220222472191;6243.687815609202;6114.602550834182;6163.414475624809;6165.537584681167;6194.5973511721595;6221.751129954841;4913.263010548375;4913.606911447076;4979.377577802775;4984.700917944935;4999.775010124553;4389.780510974439;4452.243118669834;4467.374567560544;4476.015276639134;4500.504944456107;4856.95143048572;5672.376038635747;5698.287714035746;5768.971155144198;5802.24570805801;6273.828435266102;6306.085617585516;6330.4619107375465;6339.841503962346;6349.587276827084;5603.215549823028;5640.661560306395;5679.971600141973;5732.684702341411;5779.884402312015;5431.321084864392;5435.0488664483555;5445.537129343971;5458.6724796512335;5507.008738427042;6321.5258855586935;6370.076338930921;6376.458001069868;6388.7125079371535;6400.103985442135;5205;6136.978641397139;6206.627602343874;6340.334264902303;6361.109444677656;6354.968225158845;6369.681515924278;6377.457916077;6381.457576106083;6390.360963903759;6178.814635560941;6208.037754148114;6237.688115594293;6249.875002500015;6258.561900667091;5223.973880130575;6118.847028824315;6145.047517634774;6165;6223.751049957951;6042.2749270086115;6055.515558755201;6113.141862489121;6116.761878222105;6140.504248697302;5281.260623512634;6101.023836186189;6114;6128.233970753656;6139.815805525842;5754.5684073695475;5792.768289268382;5903.0555111182;5946.851328716817;5983.491403230774;6137.667686279186;6145.170401995673;6148.692565371803;6153.630782152906;6171.352008039271;5960.821175364746;5995.16067750524;6018.157457955977;6024.427679370453;6027.939720602738 04:46:38 INFO - PROCESS | 1664 | |1;ext.html;501.17056271870047;512.4670342843442;513.1994089220816;527.8944211157769;555.9805406810763;30386.696133038662;44911.32633010503;46358;46462.76768616156;46617.76691116548;36502;45306;45325.32012019822;45908.311375329395;45942;8475.660973561082;9491.335606507548;9626.470544120106;9681.080297371773;9704.078112579293;8570.100139485357;11322.547098116065;11574.53701851925;11582.826257606142;11853.70365740859;11595.768084638303;11607.013403860681;11674.474648640831;11752.529898804038;11824.290542567473;8173.877391839127;9886;10480.790384192349;11494.36780977046;11604.361760103191;11656.708582285426;11747.7063073423;11770.117241206945;11780;11781.293122412682;41337.58662413368;41666;41863.37204941928;41885.371719424234;41917.371239431435;3081.1834863761123;3137.7436562141775;3170.9048728538182;3195.568598239231;3295.736341092707;29251.56122658162;29771;30575.388492230253;30682.53976190359;31409.685903140904;2531.1141100614823;2638.6833579970335;2651.5890037044287;2653.40298432853;2707.5667893137;526665;528089.9999999962;528756;529589;532915.3354233243;34210;65833.67083164553;67231.66384168048;67334.65330693456;67565.66217168883;35302.47046294308;36359.636403636156;36419.63580364215;36460;36474.635253647124;14614.415423382945;14615.342309596093;14843.332050057774;15063.246837658293;15129.47046853355;11435.656930292105;11633;12549.372531373394;12746.68133296675;12764.29796361209;7722.6910923563555;7892.960535197287;7905.644246008944;7947.443678942419;7984.800379990546;3196.0411876436974;3236.7896086754286;3242.610886693612;3242.72436842871;3256.6743325667244;924.426855349674;989.737719504332;1004.372267332917;1075.3494136047734;1094.4199574225672;27426;29603.519824008712;29752.702472975427;30038.249043773645;30177;1053.9407895065563;1113;1137.141458199071;1142.6457798082622;1169.6666450061862;15350.616234594225;15367.308471118826;15496.767548486781;15616.92191539035;15746.527604171893;626404;628980;630348;635084;640229;62893.37106628967;62994.68502657549;63176.36823631705;63185.68407158027;63386.68306658437;1298.8051792231051;1410.1045835894076;1434.7776094705337;1454.1493226462637;1467.5964109869872;27321.726782732712;30380.544291836082;30381;31003.534946975364;31190.688093118777;51575;58638;60417;60572.091418627875;64661.67669161624;585069;586530;594628;594893;601138.9943050431;34847.825760871034;40768.14622976363;40805.79597101996;43843.56156438395;44370.77814610906;47339.76330118327;48223.27665085097;48306.758466207444;48483.03033939231;48652.75673621609;35011.64988350084;35870.69551653714;36484.08789780277;38183.236335273694;39051;31663.366732664756;32437;38266.851994440214;38923.41614875723;39050.804745976086;37699.43450848294;37731.67939122227;38225.426618600184;38542.80728596339;38766;33164.1708957278;33174.502382464765;33696.49455258124;33977.49033764446;34025.48961765526;24706.50586988214;24745.752542474343;24771.018318534112;25202.621960670236;25443.87278063598 04:46:38 INFO - PROCESS | 1664 | |2;jquery.html;381.1139101588806;383.3387406723402;383.382753766436;392.6662337013537;400.0438950907332;19193.232270709173;20278.087486063127;20981.790182098175;21122.471938201554;21123.57752844944;11940.223885447462;11954.402279886015;12357.691057723561;12387.69030774231;12403.945664618519;5275.472452754722;5393.1370980643105;5410.891782164374;5443.618946673734;5478.287822583058;3825.1393436476847;4098;5859.531237501011;6188.226471691039;6200.906986395207;5631;6163.506919446456;6185.536084793636;6197.566170368076;6199.349068347826;3830.272248272819;6394.13679153313;6572.769953051668;6774.424173945221;6799.320067993186;5657.632253903483;5998.610090344114;6000.879982400371;6005.369436209223;6012.669303188323;13251.668708282274;13556.525521606794;13566.050376473651;13577.321133943362;13637.590872273848;2196.509901236775;2203.3830527452337;2305.054927479742;2384.475415408607;2464.952395232019;9907.950460247652;10227.488625568692;14283.428662853474;14958.252087395587;14986.501349864926;1723.5863392785766;1773.3970450047045;1783;1813.4106415415042;1833.8899666020081;114832;117873;118728;118789;120729.39635301767;9526.476043817518;11593.478293476814;11674.474648640831;11677.708057298636;11859.46632401544;14827.703445931022;14882.702345953021;14954.775678364833;15007.099574025593;15009.624759380884;8448.408611397143;8488.575571221538;8565.400421970528;8601.268892144239;8665.653373864974;7082.504224704324;8398.328133749286;8536.487810731376;8601.182887625666;8649.783755406164;3733.439984002421;4568;4571.062932098912;4581.221192397301;4628.352030715702;1745.2146534059723;1802.8587630277211;1817.681905666503;1912.8852268863914;1986.056623104043;873.9737807865774;915.697819719491;945.177695405002;974.5858010345574;1005.1857995024054;14609.488667896572;20177.596448070955;20346.796532034787;20580;20632;917.7246825952187;931.6692574136084;981.4798156976814;982.6511588386163;1024.472396715692;10963.068139208097;11006.339619622848;11037.613683521038;11049.889501105046;11134;581678;588204;599172;600570;613575;128621;129110;129299;132570;133725;997.6857289953641;1001.9649312274036;1013.5996281468779;1026.5688410867454;1029.7322696099068;18134.72797908006;18424.447266582025;18524.1664125112;18721.344752933583;18770.906145469184;50192.498075020245;50795.492045079074;52522.474775253286;52771.20843187279;53183.93632127413;68287.3171268281;75101.49797004138;75482.8677569848;75894;76054.61972690102;7867.134615192439;7917.2874441300555;7994.800129996796;8245.95877020611;8270.172982701682;8859.734207973772;11270.605528806453;11364.829527557258;12250.693732656755;12580.811287830507;10622.20333474977;10655.840162397415;10824;10886.074683651661;10933.617323393642;9666.854997174907;10031.598736050768;10093.091621753783;10581.576736930436;10901.509432075418;10704.090152336827;10916.563337466412;10939.83590246163;10986.066184374418;10999.395033273247;7978.720744773905;7982.241687039722;8207.917920820715;8230.42387032926;8235.547044912588;9875.308728388944;10470.528826202686;10843.349399036084;11006.174536909604;11068.889311106786 04:46:38 INFO - PROCESS | 1664 | |3;mootools.html;1410.0482174532187;1583.2400447785067;1595.7446808510638;1842.4933143385567;1979.0995097230752;7698.384129269659;8463.746087617374;8706.347023973196;8731.039585645589;8823.691170809023;8412.36907231957;8675.69635062773;8676.696315628938;8684.261837743783;8698.304135669146;2115.132795553823;2305.8616483011074;2378.90484380625;2384.332386931662;2407.7712617301327;2136.839737019722;2407.1935901473025;2422.188566830114;2432.1609044879488;2443.2792326263793;2361.9409514762096;2372.2764556810166;2398.436367453647;2400.3519049856527;2404.1224952892157;2092.1108528875243;2299.494111295512;2343.3321503371544;2363.255574494046;2373.4066483379156;2388.259639511757;2400.303911865557;2402.1352313167245;2406.759324067579;2409;8601.655933762642;8663.176998185163;8685.956570217171;8691.304695624334;8702.129787021311;5117.309163262949;5157.871053223662;5310.36275646921;5323.520883120537;5335.7865685372535;3387.5596172497662;3461.303961059088;3538.0800991742076;3540.7521473496868;3549.148204430918;1008.092716555099;1015.57345914716;1030.922680798943;1042.2339580408393;1042.4214560918688;11577.53689852405;11593.13051521131;11594.652160435286;11607.709807254801;11625.767484650345;4397.912041759211;4407.603315701538;4709.788059537261;4799.880002999883;4810.182269014206;9797.706068817946;9814.852777208347;9935.503224838729;9952.651657191964;9979.451130187765;5254.369475662945;5342.1185504392115;5342.759575819097;5347.572194224453;5348.411674715778;4381.978090109529;4538;4747.881302967454;4896.608271338285;4900.877478063006;3880.5537363203593;3944.526656801145;3984.7609143450904;4012.4583181270737;4017.2568074905653;1260.4832018872219;1264.5574049082747;1267.702090008847;1276.1194775604804;1286.6976260578756;843.7512481527374;861.6165806216173;890.8040231149137;912.6668765900531;916.9083091690777;5620.381758006616;5722.284714410699;5888.705564721747;5901.675407852524;5911.438413350725;947.4410098042188;948.3645957208687;958.5111593087576;980.843065109579;993.5777294649745;3846.9615303847163;3942.645161935382;3990.541087774943;4005.559388467266;4013.337799263147;10168.4915754212;10610.09814165789;10733.677989660322;10775.030247277782;10828.187885908588;9148.954255228682;10100.191984641211;10591.470426478645;10607.469626518643;10720.142388608892;7890.289873911375;8136.389770767216;8254.257116859391;8271.1728827118;8282.917170828214;5815.389384114607;5911.852203694942;5936.703164841827;6036.577439579363;6043.425874541911;9660.033996600283;9942.204623629948;10118.696439106838;10139.7465063374;10191.643292484727;10048.648297309559;10053.145482634058;10186.796264074825;10264.230182736175;10265.2301077418;6893.138357705287;7109.360157585842;7303.890441643486;7473.103227612722;7566.31903128721;8150.592470376576;8245.299149572353;8271.917280827356;8281.17188281167;8346.958265208636;7231.457640677076;7335.449841262033;7363.190049094703;7486.288802563748;7486.513376630502;7219.60292183935;7243.311885370881;7262.164851042197;7271.927280727336;7319.890201646873;6821.215560210441;6851.862962740817;6891.586504809728;6896.896546551706;6911.447084233148;4771.308160316804;4783.832565860178;4845.9757701211265;4919.852404427873;5043.546080852594;4830.975845120752;4892.535209155125;4923.876903077452;4949.604031677385;5128.282040514406 04:46:38 INFO - PROCESS | 1664 | |4;prototype.html;305.7997011957168;311.37569173806514;314.52192667145937;319.4569232305082;322.4180354460198;4520.502744698085;4891.8259617691765;5447.237386765856;5455.890882182354;5468.031508656885;4774.9945023090295;4984.7756850941705;5381.327334083239;5452.863678408042;5453.509184173424;3235.8867439639616;3518.102883764649;3859.903502412434;3941.507311586052;4042.333015052513;3577.8032208228537;3584.494512304825;3741.420079887622;3966.5438474575503;4129.483814523184;3986.448672849379;4052.29084910141;4064.227796718614;4077.5718549552316;4086.5504794472586;3783.9621603783885;3825.5218097737784;3830.425436184567;4109.753414795107;4136.5449800522065;3555;3564.4475106358555;3567.250877315767;3568.5368998710665;3569.9306984082664;5176.88696930158;5182.714950677711;5209.557187639055;5221.791128354861;5225.738713064369;4298.591633794785;4685.367475390813;4693.436787585511;4694.929576056362;4695.1783437898575;5057.443681195065;5318.787248510055;5602.271704678406;5623.49388555028;5659.236003139565;3837.961620383788;3840.711946603988;3847.4998250227572;3855.2289542091694;3880.9029774255587;4629.675922685414;5643.858903527444;5951.018082016462;5956.761729530814;5964.582479226456;3347.983260083684;3861.0694652673665;3874.9150237933422;3893.2674959643255;3914.941275880864;3633.4368172933237;3865.555461121951;3944.36890097583;3956.3867600521967;4004.0390306326854;4088.448059511928;4608.686524340614;4650.6046986005895;4660.254359302496;4676.321933319718;4967.875803104951;5223.5559977402345;5308.505002648749;5417.485338892799;5676.971615141898;1122.7586068995206;1208.9032877369789;1225.5894275417656;1350.9054366194468;1358.3344161360862;787.4055088408329;810.528890064531;822.2280984478458;826.0871736730946;838.6016642094957;4024.3762216856435;4870.981440311757;4987.551120399109;5308.946910530923;5324.3078399808555;5842.611285454426;5848.093545500455;5853.853653658693;5861.501772349313;5873.941260587425;5077.644564880423;5267.710275934784;5307.575393968474;5325.547328477045;5335.706536140549;2574.472233192191;3027.818330900153;3039.328369396823;3059;3061.9081427557207;5143.974280128576;5898.726206955095;6038.849028774228;6062.333143354227;6105.297890742532;5178.695480511212;5339;5627.043402621564;6039.698015099228;6060.787782475295;4905.975470122627;5461.5357694596405;5684.589246452096;6033.577649564576;6105.755769769159;4644.838790302424;4885.975570122127;5827.271591051119;5832.929310095025;5849.590528663123;5133.640645154804;5202.0116177926075;5362.302900622894;5519.779208831602;5796.855078623068;5164.741762911764;5600.663960162403;6067.908981365194;6075.1191077294425;6165.260168779775;3282.3435312937986;3687.0413692439624;3772.3775577029483;3822.5412950446125;3822.6490708214806;4958.603311735124;5792.304923409218;5804.839032193662;5829.650220986754;5838.94161058384;5366.543843773166;5377.596680248918;5525.640833345819;5655.236543066633;5698.1452782083015;5038.924416133687;5290.259363689163;5638.464345887134;5647.915281270866;5692.51613612831;4503.347014682919;4703.647226457961;5397.487238712316;5564.888702225851;5566.471185237396;4749.812546863284;5046.646734728533;5130.666506677054;5324.760385782572;5674.602777805514;4733.644976626698;4847.006363695505;5546.334439867242;5569.749361278671;5588.441155884379 04:46:38 INFO - PROCESS | 1664 | |5;yui.html;648.8183308673571;680.6596701649175;713.9464540159489;736.8784150615148;747.3199388556415;14802.925985370071;15544.222788860568;15544.84455155448;15570.53288401347;15570.844291557096;14258.928705356484;15123.621909452242;15781.84218157818;15789.210539473037;16194.271257793398;711.9430445564342;730.9232530584266;870.6691457246237;909.4225167018924;919.5034681272105;894.1818236313765;894.5884892949218;906.8231694819513;908.9227415669676;911.6535716427749;940.3229674634252;953.9713808585753;957.9952100239524;960.3085778239656;962.5139304651176;737.9298966598192;920.1994264989435;950.3870003847479;951.0869565217399;956.6125719083782;701.9438444924393;702.5960072958019;703.8873780195145;704.6089420371692;706.4913262451028;15178;15255.160966146857;15260.465883694018;15267;15267.160306183156;11937;11976.760464790656;12072.637820865388;12083.154179207459;12270.877291227152;9659.951700241454;9858.359206651545;9862.358946668515;10339.534720937501;10799.892001079967;3380.2056516718553;3447.379471695094;3466.3933811583124;3503.737219708532;3537.3809583322814;83110.58444707737;84353.15646843515;84418;85524.71712924312;85968.57015714944;9201.861972070425;9225.215856652125;9288.48913309761;9362.016988216139;9406.717798466058;20116.89941550312;20936.37190884276;20989.475263118544;21019.894900525705;21027.579448410946;7347.5224110432655;7520.323170914644;7577.280158384945;7666.348360389318;7915.960420197862;4032.7580345178794;4099.815508302134;4108.650764685035;4193.22425351311;4207.579242075768;3367.1413789484013;3378.9831050844587;3451.7583769135917;3453.9654603454146;3458.170039190576;520.1988937036996;539.4632340820908;624.8125562331282;630.9526785491106;642.8135840606266;512.2776884592706;512.595049910574;516.7183884782802;587.2248631805975;601.2034054877305;6771.796846094625;8054.798630034179;8178.141295164041;8276.08963014068;8432.620532076071;572.9942700573024;574.5633318677835;597.3220394851787;601.1583782704249;602.2892986276236;3831.7126215533945;3873.825677844504;3874.7287689862;3926.155876486569;4063.837446502166;82239;84263;85344.57327713321;85681.57159214164;89683.55158224168;59660.210193694256;78537.60731196307;78641.21358786453;81511.5924420374;83703;68217.65891170512;69193.65403172951;69419;71980.64009679918;73928;6570.704318305592;8185;8229.95885020571;8330.750077497685;8490.405671602928;44382.77808610936;44783.55216447924;44839.879003025184;45360.09279814451;45906.311405329616;62259.6887015562;62267.6886615564;62937.05594416179;63020;63385.732285354956;11214.607488737855;11365.670037642429;11463.484143213409;11685.415729213675;11990.700232494257;11615.825762613398;11851.288922664668;11884.286942783461;11905.583304584645;12447.626571202878;11071.778564428505;11465.541378344773;11485.196036277715;11939.343336116597;11960.162788604714;11146;11374.601888933848;11415;11502.079833613123;12417.19288246261;11175.776484470427;11180.720481988015;11197.160212984221;11620.547431571054;12307.876921230672;9378.5779639915;9455.669051583161;9473.384230025027;9743.415395076318;9773.804523909625;9354.391964522285;9568.61725530971;9576.856347154659;9628.374155679858;9812.215022798016 04:46:38 INFO - PROCESS | 1664 | __end_tp_report 04:46:38 INFO - PROCESS | 1664 | __start_cc_report 04:46:38 INFO - PROCESS | 1664 | _x_x_mozilla_cycle_collect,1155 04:46:38 INFO - PROCESS | 1664 | __end_cc_report 04:46:38 INFO - PROCESS | 1664 | __startTimestamp1464090398722__endTimestamp 04:46:38 INFO - PROCESS | 1664 | 04:46:38 INFO - PROCESS | 1664 | ------- Summary: start ------- 04:46:38 INFO - PROCESS | 1664 | Number of tests: 6 04:46:38 INFO - PROCESS | 1664 | 04:46:38 INFO - PROCESS | 1664 | [#0] dojo.html Cycles:165 Average:5722.28 Median:5946.85 stddev:642.13 (10.8%) stddev-sans-first:615.70 04:46:38 INFO - PROCESS | 1664 | Values: 3314.7 3467.6 3482.7 3503.1 3538.4 5553.8 5575.6 5655.7 5699.9 5705.3 5101.2 5562.0 5669.3 5670.1 5761.7 5119.2 5150.3 5210.3 5224.8 5306.4 5306.7 5317.6 5338.5 5351.5 5395.5 5153.9 5205.8 5228.9 5230.0 5273.0 5238.6 5257.0 5268.1 5271.8 5281.9 5131.4 5181.6 5195.8 5217.8 5227.8 4627.7 5528.5 5587.0 5663.7 5669.5 6161.7 6260.6 6262.2 6267.9 6277.9 4616.8 6104.0 6156.4 6225.0 6256.1 5875.8 5881.4 5922.7 5936.8 5942.6 6188.4 6312.4 6317.4 6330.2 6334.7 6116.8 6122.9 6139.5 6177.4 6184.1 6227.7 6237.6 6239.3 6245.2 6255.9 5987.1 6071.7 6200.7 6238.2 6243.7 6114.6 6163.4 6165.5 6194.6 6221.8 4913.3 4913.6 4979.4 4984.7 4999.8 4389.8 4452.2 4467.4 4476.0 4500.5 4857.0 5672.4 5698.3 5769.0 5802.2 6273.8 6306.1 6330.5 6339.8 6349.6 5603.2 5640.7 5680.0 5732.7 5779.9 5431.3 5435.0 5445.5 5458.7 5507.0 6321.5 6370.1 6376.5 6388.7 6400.1 5205.0 6137.0 6206.6 6340.3 6361.1 6355.0 6369.7 6377.5 6381.5 6390.4 6178.8 6208.0 6237.7 6249.9 6258.6 5224.0 6118.8 6145.0 6165.0 6223.8 6042.3 6055.5 6113.1 6116.8 6140.5 5281.3 6101.0 6114.0 6128.2 6139.8 5754.6 5792.8 5903.1 5946.9 5983.5 6137.7 6145.2 6148.7 6153.6 6171.4 5960.8 5995.2 6018.2 6024.4 6027.9 04:46:38 INFO - PROCESS | 1664 | 04:46:38 INFO - PROCESS | 1664 | [#1] ext.html Cycles:180 Average:71325.45 Median:35157.06 stddev:156761.16 (445.9%) stddev-sans-first:157110.73 04:46:38 INFO - PROCESS | 1664 | Values: 501.2 512.5 513.2 527.9 556.0 30386.7 44911.3 46358.0 46462.8 46617.8 36502.0 45306.0 45325.3 45908.3 45942.0 8475.7 9491.3 9626.5 9681.1 9704.1 8570.1 11322.5 11574.5 11582.8 11853.7 11595.8 11607.0 11674.5 11752.5 11824.3 8173.9 9886.0 10480.8 11494.4 11604.4 11656.7 11747.7 11770.1 11780.0 11781.3 41337.6 41666.0 41863.4 41885.4 41917.4 3081.2 3137.7 3170.9 3195.6 3295.7 29251.6 29771.0 30575.4 30682.5 31409.7 2531.1 2638.7 2651.6 2653.4 2707.6 526665.0 528090.0 528756.0 529589.0 532915.3 34210.0 65833.7 67231.7 67334.7 67565.7 35302.5 36359.6 36419.6 36460.0 36474.6 14614.4 14615.3 14843.3 15063.2 15129.5 11435.7 11633.0 12549.4 12746.7 12764.3 7722.7 7893.0 7905.6 7947.4 7984.8 3196.0 3236.8 3242.6 3242.7 3256.7 924.4 989.7 1004.4 1075.3 1094.4 27426.0 29603.5 29752.7 30038.2 30177.0 1053.9 1113.0 1137.1 1142.6 1169.7 15350.6 15367.3 15496.8 15616.9 15746.5 626404.0 628980.0 630348.0 635084.0 640229.0 62893.4 62994.7 63176.4 63185.7 63386.7 1298.8 1410.1 1434.8 1454.1 1467.6 27321.7 30380.5 30381.0 31003.5 31190.7 51575.0 58638.0 60417.0 60572.1 64661.7 585069.0 586530.0 594628.0 594893.0 601139.0 34847.8 40768.1 40805.8 43843.6 44370.8 47339.8 48223.3 48306.8 48483.0 48652.8 35011.6 35870.7 36484.1 38183.2 39051.0 31663.4 32437.0 38266.9 38923.4 39050.8 37699.4 37731.7 38225.4 38542.8 38766.0 33164.2 33174.5 33696.5 33977.5 34025.5 24706.5 24745.8 24771.0 25202.6 25443.9 04:46:38 INFO - PROCESS | 1664 | 04:46:38 INFO - PROCESS | 1664 | [#2] jquery.html Cycles:180 Average:34304.84 Median:11660.04 stddev:99743.20 (855.4%) stddev-sans-first:99990.48 04:46:38 INFO - PROCESS | 1664 | Values: 381.1 383.3 383.4 392.7 400.0 19193.2 20278.1 20981.8 21122.5 21123.6 11940.2 11954.4 12357.7 12387.7 12403.9 5275.5 5393.1 5410.9 5443.6 5478.3 3825.1 4098.0 5859.5 6188.2 6200.9 5631.0 6163.5 6185.5 6197.6 6199.3 3830.3 6394.1 6572.8 6774.4 6799.3 5657.6 5998.6 6000.9 6005.4 6012.7 13251.7 13556.5 13566.1 13577.3 13637.6 2196.5 2203.4 2305.1 2384.5 2465.0 9908.0 10227.5 14283.4 14958.3 14986.5 1723.6 1773.4 1783.0 1813.4 1833.9 114832.0 117873.0 118728.0 118789.0 120729.4 9526.5 11593.5 11674.5 11677.7 11859.5 14827.7 14882.7 14954.8 15007.1 15009.6 8448.4 8488.6 8565.4 8601.3 8665.7 7082.5 8398.3 8536.5 8601.2 8649.8 3733.4 4568.0 4571.1 4581.2 4628.4 1745.2 1802.9 1817.7 1912.9 1986.1 874.0 915.7 945.2 974.6 1005.2 14609.5 20177.6 20346.8 20580.0 20632.0 917.7 931.7 981.5 982.7 1024.5 10963.1 11006.3 11037.6 11049.9 11134.0 581678.0 588204.0 599172.0 600570.0 613575.0 128621.0 129110.0 129299.0 132570.0 133725.0 997.7 1002.0 1013.6 1026.6 1029.7 18134.7 18424.4 18524.2 18721.3 18770.9 50192.5 50795.5 52522.5 52771.2 53183.9 68287.3 75101.5 75482.9 75894.0 76054.6 7867.1 7917.3 7994.8 8246.0 8270.2 8859.7 11270.6 11364.8 12250.7 12580.8 10622.2 10655.8 10824.0 10886.1 10933.6 9666.9 10031.6 10093.1 10581.6 10901.5 10704.1 10916.6 10939.8 10986.1 10999.4 7978.7 7982.2 8207.9 8230.4 8235.5 9875.3 10470.5 10843.3 11006.2 11068.9 04:46:38 INFO - PROCESS | 1664 | 04:46:38 INFO - PROCESS | 1664 | [#3] mootools.html Cycles:180 Average:5667.17 Median:4898.74 stddev:3159.67 (64.5%) stddev-sans-first:3152.34 04:46:38 INFO - PROCESS | 1664 | Values: 1410.0 1583.2 1595.7 1842.5 1979.1 7698.4 8463.7 8706.3 8731.0 8823.7 8412.4 8675.7 8676.7 8684.3 8698.3 2115.1 2305.9 2378.9 2384.3 2407.8 2136.8 2407.2 2422.2 2432.2 2443.3 2361.9 2372.3 2398.4 2400.4 2404.1 2092.1 2299.5 2343.3 2363.3 2373.4 2388.3 2400.3 2402.1 2406.8 2409.0 8601.7 8663.2 8686.0 8691.3 8702.1 5117.3 5157.9 5310.4 5323.5 5335.8 3387.6 3461.3 3538.1 3540.8 3549.1 1008.1 1015.6 1030.9 1042.2 1042.4 11577.5 11593.1 11594.7 11607.7 11625.8 4397.9 4407.6 4709.8 4799.9 4810.2 9797.7 9814.9 9935.5 9952.7 9979.5 5254.4 5342.1 5342.8 5347.6 5348.4 4382.0 4538.0 4747.9 4896.6 4900.9 3880.6 3944.5 3984.8 4012.5 4017.3 1260.5 1264.6 1267.7 1276.1 1286.7 843.8 861.6 890.8 912.7 916.9 5620.4 5722.3 5888.7 5901.7 5911.4 947.4 948.4 958.5 980.8 993.6 3847.0 3942.6 3990.5 4005.6 4013.3 10168.5 10610.1 10733.7 10775.0 10828.2 9149.0 10100.2 10591.5 10607.5 10720.1 7890.3 8136.4 8254.3 8271.2 8282.9 5815.4 5911.9 5936.7 6036.6 6043.4 9660.0 9942.2 10118.7 10139.7 10191.6 10048.6 10053.1 10186.8 10264.2 10265.2 6893.1 7109.4 7303.9 7473.1 7566.3 8150.6 8245.3 8271.9 8281.2 8347.0 7231.5 7335.4 7363.2 7486.3 7486.5 7219.6 7243.3 7262.2 7271.9 7319.9 6821.2 6851.9 6891.6 6896.9 6911.4 4771.3 4783.8 4846.0 4919.9 5043.5 4831.0 4892.5 4923.9 4949.6 5128.3 04:46:38 INFO - PROCESS | 1664 | 04:46:38 INFO - PROCESS | 1664 | [#4] prototype.html Cycles:180 Average:4520.21 Median:5067.54 stddev:1405.51 (27.7%) stddev-sans-first:1373.39 04:46:38 INFO - PROCESS | 1664 | Values: 305.8 311.4 314.5 319.5 322.4 4520.5 4891.8 5447.2 5455.9 5468.0 4775.0 4984.8 5381.3 5452.9 5453.5 3235.9 3518.1 3859.9 3941.5 4042.3 3577.8 3584.5 3741.4 3966.5 4129.5 3986.4 4052.3 4064.2 4077.6 4086.6 3784.0 3825.5 3830.4 4109.8 4136.5 3555.0 3564.4 3567.3 3568.5 3569.9 5176.9 5182.7 5209.6 5221.8 5225.7 4298.6 4685.4 4693.4 4694.9 4695.2 5057.4 5318.8 5602.3 5623.5 5659.2 3838.0 3840.7 3847.5 3855.2 3880.9 4629.7 5643.9 5951.0 5956.8 5964.6 3348.0 3861.1 3874.9 3893.3 3914.9 3633.4 3865.6 3944.4 3956.4 4004.0 4088.4 4608.7 4650.6 4660.3 4676.3 4967.9 5223.6 5308.5 5417.5 5677.0 1122.8 1208.9 1225.6 1350.9 1358.3 787.4 810.5 822.2 826.1 838.6 4024.4 4871.0 4987.6 5308.9 5324.3 5842.6 5848.1 5853.9 5861.5 5873.9 5077.6 5267.7 5307.6 5325.5 5335.7 2574.5 3027.8 3039.3 3059.0 3061.9 5144.0 5898.7 6038.8 6062.3 6105.3 5178.7 5339.0 5627.0 6039.7 6060.8 4906.0 5461.5 5684.6 6033.6 6105.8 4644.8 4886.0 5827.3 5832.9 5849.6 5133.6 5202.0 5362.3 5519.8 5796.9 5164.7 5600.7 6067.9 6075.1 6165.3 3282.3 3687.0 3772.4 3822.5 3822.6 4958.6 5792.3 5804.8 5829.7 5838.9 5366.5 5377.6 5525.6 5655.2 5698.1 5038.9 5290.3 5638.5 5647.9 5692.5 4503.3 4703.6 5397.5 5564.9 5566.5 4749.8 5046.6 5130.7 5324.8 5674.6 4733.6 4847.0 5546.3 5569.7 5588.4 04:46:38 INFO - PROCESS | 1664 | 04:46:38 INFO - PROCESS | 1664 | [#5] yui.html Cycles:180 Average:17936.97 Median:62263.69 stddev:24981.90 (40.1%) stddev-sans-first:25018.25 04:46:38 INFO - PROCESS | 1664 | Values: 648.8 680.7 713.9 736.9 747.3 14802.9 15544.2 15544.8 15570.5 15570.8 14258.9 15123.6 15781.8 15789.2 16194.3 711.9 730.9 870.7 909.4 919.5 894.2 894.6 906.8 908.9 911.7 940.3 954.0 958.0 960.3 962.5 737.9 920.2 950.4 951.1 956.6 701.9 702.6 703.9 704.6 706.5 15178.0 15255.2 15260.5 15267.0 15267.2 11937.0 11976.8 12072.6 12083.2 12270.9 9660.0 9858.4 9862.4 10339.5 10799.9 3380.2 3447.4 3466.4 3503.7 3537.4 83110.6 84353.2 84418.0 85524.7 85968.6 9201.9 9225.2 9288.5 9362.0 9406.7 20116.9 20936.4 20989.5 21019.9 21027.6 7347.5 7520.3 7577.3 7666.3 7916.0 4032.8 4099.8 4108.7 4193.2 4207.6 3367.1 3379.0 3451.8 3454.0 3458.2 520.2 539.5 624.8 631.0 642.8 512.3 512.6 516.7 587.2 601.2 6771.8 8054.8 8178.1 8276.1 8432.6 573.0 574.6 597.3 601.2 602.3 3831.7 3873.8 3874.7 3926.2 4063.8 82239.0 84263.0 85344.6 85681.6 89683.6 59660.2 78537.6 78641.2 81511.6 83703.0 68217.7 69193.7 69419.0 71980.6 73928.0 6570.7 8185.0 8230.0 8330.8 8490.4 44382.8 44783.6 44839.9 45360.1 45906.3 62259.7 62267.7 62937.1 63020.0 63385.7 11214.6 11365.7 11463.5 11685.4 11990.7 11615.8 11851.3 11884.3 11905.6 12447.6 11071.8 11465.5 11485.2 11939.3 11960.2 11146.0 11374.6 11415.0 11502.1 12417.2 11175.8 11180.7 11197.2 11620.5 12307.9 9378.6 9455.7 9473.4 9743.4 9773.8 9354.4 9568.6 9576.9 9628.4 9812.2 04:46:38 INFO - PROCESS | 1664 | -------- Summary: end -------- 04:46:38 INFO - PROCESS | 1664 | 04:46:39 INFO - TEST-INFO | 1664: exit 0 04:46:39 INFO - TEST-OK | dromaeo_css | took 1115011ms 04:46:39 INFO - TEST-START | kraken 04:46:39 INFO - operating with platform_type : mac_ 04:46:39 INFO - Initialising browser for kraken test... 04:46:39 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/tmpYwjvGY/profile http://localhost:49276/getInfo.html) 04:46:41 INFO - PROCESS | 1670 | __metrics Screen width/height:1600/1200 04:46:41 INFO - PROCESS | 1670 | colorDepth:24 04:46:41 INFO - PROCESS | 1670 | Browser inner width/height: 1024/689 04:46:41 INFO - PROCESS | 1670 | __metrics 04:46:41 INFO - PROCESS | 1670 | 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" 04:46:41 ERROR - PROCESS | 1670 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 04:46:42 INFO - TEST-INFO | 1670: exit 0 04:46:42 INFO - Browser initialized. 04:46:42 INFO - Running cycle 1/1 for kraken test... 04:46:42 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/tmpYwjvGY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:46:54 INFO - PROCESS | 1672 | RSS: Main: 238714880 04:46:54 INFO - PROCESS | 1672 | 04:46:57 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-beat-detection) 04:46:58 INFO - PROCESS | 1672 | RSS: Main: 287375360 04:46:58 INFO - PROCESS | 1672 | 04:47:00 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-dft) 04:47:01 INFO - PROCESS | 1672 | RSS: Main: 297390080 04:47:01 INFO - PROCESS | 1672 | 04:47:05 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-fft) 04:47:05 INFO - PROCESS | 1672 | RSS: Main: 389025792 04:47:05 INFO - PROCESS | 1672 | 04:47:07 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-oscillator) 04:47:08 INFO - PROCESS | 1672 | RSS: Main: 287506432 04:47:08 INFO - PROCESS | 1672 | 04:47:10 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:47:11 INFO - PROCESS | 1672 | RSS: Main: 291627008 04:47:11 INFO - PROCESS | 1672 | 04:47:14 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-darkroom) 04:47:14 INFO - PROCESS | 1672 | RSS: Main: 328785920 04:47:14 INFO - PROCESS | 1672 | 04:47:18 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-desaturate) 04:47:18 INFO - PROCESS | 1672 | RSS: Main: 332210176 04:47:18 INFO - PROCESS | 1672 | 04:47:21 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49276/tests/kraken/driver.html?testName=json-parse-financial) 04:47:22 INFO - PROCESS | 1672 | RSS: Main: 330633216 04:47:22 INFO - PROCESS | 1672 | 04:47:24 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49276/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:47:24 INFO - PROCESS | 1672 | RSS: Main: 316452864 04:47:24 INFO - PROCESS | 1672 | 04:47:26 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:47:27 INFO - PROCESS | 1672 | RSS: Main: 323686400 04:47:27 INFO - PROCESS | 1672 | 04:47:29 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:47:30 INFO - PROCESS | 1672 | RSS: Main: 326504448 04:47:30 INFO - PROCESS | 1672 | 04:47:33 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:47:34 INFO - PROCESS | 1672 | RSS: Main: 330821632 04:47:34 INFO - PROCESS | 1672 | 04:47:36 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:47:37 INFO - PROCESS | 1672 | RSS: Main: 328433664 04:47:37 INFO - PROCESS | 1672 | 04:47:39 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49276/tests/kraken/driver.html?testName=ai-astar) 04:47:39 INFO - PROCESS | 1672 | RSS: Main: 375287808 04:47:39 INFO - PROCESS | 1672 | 04:47:39 INFO - PROCESS | 1672 | __start_tp_report 04:47:39 INFO - PROCESS | 1672 | _x_x_mozilla_page_load 04:47:39 INFO - PROCESS | 1672 | _x_x_mozilla_page_load_details 04:47:39 INFO - PROCESS | 1672 | |i|pagename|runs| 04:47:39 INFO - PROCESS | 1672 | |0;ai-astar;112;103;113;126;166;90;166;167;166;113 04:47:39 INFO - PROCESS | 1672 | |1;audio-beat-detection;151;118;117;200;115;114;142;136;176;105 04:47:39 INFO - PROCESS | 1672 | |2;audio-dft;185;184;186;181;182;182;181;178;184;182 04:47:39 INFO - PROCESS | 1672 | |3;audio-fft;79;57;60;118;58;56;67;64;81;58 04:47:39 INFO - PROCESS | 1672 | |4;audio-oscillator;92;89;196;109;87;76;102;82;77;109 04:47:39 INFO - PROCESS | 1672 | |5;imaging-gaussian-blur;87;84;87;86;88;70;117;129;90;85 04:47:39 INFO - PROCESS | 1672 | |6;imaging-darkroom;93;94;94;95;94;93;95;95;93;94 04:47:39 INFO - PROCESS | 1672 | |7;imaging-desaturate;87;92;85;83;83;101;134;140;85;102 04:47:39 INFO - PROCESS | 1672 | |8;json-parse-financial;56;57;59;58;54;54;56;164;58;56 04:47:39 INFO - PROCESS | 1672 | |9;json-stringify-tinderbox;68;65;79;77;73;69;70;70;70;71 04:47:39 INFO - PROCESS | 1672 | |10;stanford-crypto-aes;85;83;86;83;83;93;104;87;85;87 04:47:39 INFO - PROCESS | 1672 | |11;stanford-crypto-ccm;145;126;152;139;139;138;136;131;216;138 04:47:39 INFO - PROCESS | 1672 | |12;stanford-crypto-pbkdf2;186;163;159;185;166;163;213;220;163;162 04:47:39 INFO - PROCESS | 1672 | |13;stanford-crypto-sha256-iterative;65;65;65;65;66;65;65;65;66;63 04:47:39 INFO - PROCESS | 1672 | __end_tp_report 04:47:39 INFO - PROCESS | 1672 | __start_cc_report 04:47:39 INFO - PROCESS | 1672 | _x_x_mozilla_cycle_collect,2259 04:47:39 INFO - PROCESS | 1672 | __end_cc_report 04:47:39 INFO - PROCESS | 1672 | __startTimestamp1464090459631__endTimestamp 04:47:39 INFO - PROCESS | 1672 | 04:47:39 INFO - PROCESS | 1672 | ------- Summary: start ------- 04:47:39 INFO - PROCESS | 1672 | Number of tests: 14 04:47:39 INFO - PROCESS | 1672 | 04:47:39 INFO - PROCESS | 1672 | [#0] ai-astar Cycles:10 Average:132.20 Median:166.00 stddev:30.65 (18.5%) stddev-sans-first:31.63 04:47:39 INFO - PROCESS | 1672 | Values: 112.0 103.0 113.0 126.0 166.0 90.0 166.0 167.0 166.0 113.0 04:47:39 INFO - PROCESS | 1672 | 04:47:39 INFO - PROCESS | 1672 | [#1] audio-beat-detection Cycles:10 Average:137.40 Median:139.00 stddev:30.75 (22.1%) stddev-sans-first:32.22 04:47:39 INFO - PROCESS | 1672 | Values: 151.0 118.0 117.0 200.0 115.0 114.0 142.0 136.0 176.0 105.0 04:47:39 INFO - PROCESS | 1672 | 04:47:39 INFO - PROCESS | 1672 | [#2] audio-dft Cycles:10 Average:182.50 Median:183.00 stddev:2.32 (1.3%) stddev-sans-first:2.28 04:47:39 INFO - PROCESS | 1672 | Values: 185.0 184.0 186.0 181.0 182.0 182.0 181.0 178.0 184.0 182.0 04:47:39 INFO - PROCESS | 1672 | 04:47:39 INFO - PROCESS | 1672 | [#3] audio-fft Cycles:10 Average:69.80 Median:62.00 stddev:19.16 (30.9%) stddev-sans-first:20.03 04:47:39 INFO - PROCESS | 1672 | Values: 79.0 57.0 60.0 118.0 58.0 56.0 67.0 64.0 81.0 58.0 04:47:39 INFO - PROCESS | 1672 | 04:47:39 INFO - PROCESS | 1672 | [#4] audio-oscillator Cycles:10 Average:101.90 Median:79.50 stddev:35.16 (44.2%) stddev-sans-first:37.11 04:47:39 INFO - PROCESS | 1672 | Values: 92.0 89.0 196.0 109.0 87.0 76.0 102.0 82.0 77.0 109.0 04:47:39 INFO - PROCESS | 1672 | 04:47:39 INFO - PROCESS | 1672 | [#5] imaging-gaussian-blur Cycles:10 Average:92.30 Median:86.50 stddev:17.31 (20.0%) stddev-sans-first:18.25 04:47:39 INFO - PROCESS | 1672 | Values: 87.0 84.0 87.0 86.0 88.0 70.0 117.0 129.0 90.0 85.0 04:47:39 INFO - PROCESS | 1672 | 04:47:39 INFO - PROCESS | 1672 | [#6] imaging-darkroom Cycles:10 Average:94.00 Median:94.00 stddev:0.82 (0.9%) stddev-sans-first:0.78 04:47:39 INFO - PROCESS | 1672 | Values: 93.0 94.0 94.0 95.0 94.0 93.0 95.0 95.0 93.0 94.0 04:47:39 INFO - PROCESS | 1672 | 04:47:39 INFO - PROCESS | 1672 | [#7] imaging-desaturate Cycles:10 Average:99.20 Median:84.00 stddev:21.12 (25.1%) stddev-sans-first:21.94 04:47:39 INFO - PROCESS | 1672 | Values: 87.0 92.0 85.0 83.0 83.0 101.0 134.0 140.0 85.0 102.0 04:47:39 INFO - PROCESS | 1672 | 04:47:39 INFO - PROCESS | 1672 | [#8] json-parse-financial Cycles:10 Average:67.20 Median:56.50 stddev:34.05 (60.3%) stddev-sans-first:35.88 04:47:39 INFO - PROCESS | 1672 | Values: 56.0 57.0 59.0 58.0 54.0 54.0 56.0 164.0 58.0 56.0 04:47:39 INFO - PROCESS | 1672 | 04:47:39 INFO - PROCESS | 1672 | [#9] json-stringify-tinderbox Cycles:10 Average:71.20 Median:70.50 stddev:4.16 (5.9%) stddev-sans-first:4.25 04:47:39 INFO - PROCESS | 1672 | Values: 68.0 65.0 79.0 77.0 73.0 69.0 70.0 70.0 70.0 71.0 04:47:39 INFO - PROCESS | 1672 | 04:47:39 INFO - PROCESS | 1672 | [#10] stanford-crypto-aes Cycles:10 Average:87.60 Median:85.50 stddev:6.48 (7.6%) stddev-sans-first:6.81 04:47:39 INFO - PROCESS | 1672 | Values: 85.0 83.0 86.0 83.0 83.0 93.0 104.0 87.0 85.0 87.0 04:47:39 INFO - PROCESS | 1672 | 04:47:39 INFO - PROCESS | 1672 | [#11] stanford-crypto-ccm Cycles:10 Average:146.00 Median:139.00 stddev:25.58 (18.4%) stddev-sans-first:27.13 04:47:39 INFO - PROCESS | 1672 | Values: 145.0 126.0 152.0 139.0 139.0 138.0 136.0 131.0 216.0 138.0 04:47:39 INFO - PROCESS | 1672 | 04:47:39 INFO - PROCESS | 1672 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.00 Median:175.50 stddev:22.45 (12.8%) stddev-sans-first:23.63 04:47:39 INFO - PROCESS | 1672 | Values: 186.0 163.0 159.0 185.0 166.0 163.0 213.0 220.0 163.0 162.0 04:47:39 INFO - PROCESS | 1672 | 04:47:39 INFO - PROCESS | 1672 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.00 Median:65.00 stddev:0.82 (1.3%) stddev-sans-first:0.87 04:47:39 INFO - PROCESS | 1672 | Values: 65.0 65.0 65.0 65.0 66.0 65.0 65.0 65.0 66.0 63.0 04:47:39 INFO - PROCESS | 1672 | -------- Summary: end -------- 04:47:39 INFO - PROCESS | 1672 | 04:47:40 INFO - TEST-INFO | 1672: exit 0 04:47:40 INFO - TEST-OK | kraken | took 61160ms 04:47:40 INFO - SUITE-END | took 1176s 04:47:40 INFO - Completed test suite (00:19:36) 04:47:40 INFO - javascript benchmark 04:47:40 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3314.6685331466856, 3467.5665541807275, 3482.651734826516, 3503.1242189452646, 3538.41616133338, 5553.833384998451, 5575.553955683546, 5655.745491452884, 5699.857503562413, 5705.343885453169, 5101.158308879039, 5561.998840208761, 5669.262995810549, 5670.149477578365, 5761.711914404284, 5119.206522988943, 5150.2532132840715, 5210.322658054448, 5224.843254702346, 5306.363236411636, 5306.681599104066, 5317.627766056358, 5338.519533242027, 5351.518363347298, 5395.514403703666, 5153.871153221162, 5205.843824685227, 5228.8692782680355, 5230.0, 5273.0, 5238.5809135269465, 5257.027449921777, 5268.130758424855, 5271.789128434858, 5281.947180528184, 5131.384233891919, 5181.5854731621375, 5195.792168313263, 5217.791288348461, 5227.79088836446, 4627.676062675614, 5528.474794894479, 5587.022271102582, 5663.716814159276, 5669.546436285087, 6161.7227224775, 6260.624362538262, 6262.1546091278005, 6267.905981410283, 6277.8744425111245, 4616.792244349012, 6103.974138059492, 6156.353582873823, 6225.0, 6256.092866534373, 5875.764969401262, 5881.411858814127, 5922.733476993502, 5936.821895343147, 5942.5840191186635, 6188.412100850412, 6312.400321969406, 6317.368263173737, 6330.177076979963, 6334.651594162273, 6116.816495505053, 6122.886104953835, 6139.4781443577795, 6177.382261773787, 6184.134221209034, 6227.719752611143, 6237.625742455467, 6239.251289845157, 6245.18812554365, 6255.874882502416, 5987.101934709741, 6071.726772295257, 6200.658963757037, 6238.220222472191, 6243.687815609202, 6114.602550834182, 6163.414475624809, 6165.537584681167, 6194.5973511721595, 6221.751129954841, 4913.263010548375, 4913.606911447076, 4979.377577802775, 4984.700917944935, 4999.775010124553, 4389.780510974439, 4452.243118669834, 4467.374567560544, 4476.015276639134, 4500.504944456107, 4856.95143048572, 5672.376038635747, 5698.287714035746, 5768.971155144198, 5802.24570805801, 6273.828435266102, 6306.085617585516, 6330.4619107375465, 6339.841503962346, 6349.587276827084, 5603.215549823028, 5640.661560306395, 5679.971600141973, 5732.684702341411, 5779.884402312015, 5431.321084864392, 5435.0488664483555, 5445.537129343971, 5458.6724796512335, 5507.008738427042, 6321.5258855586935, 6370.076338930921, 6376.458001069868, 6388.7125079371535, 6400.103985442135, 5205.0, 6136.978641397139, 6206.627602343874, 6340.334264902303, 6361.109444677656, 6354.968225158845, 6369.681515924278, 6377.457916077, 6381.457576106083, 6390.360963903759, 6178.814635560941, 6208.037754148114, 6237.688115594293, 6249.875002500015, 6258.561900667091, 5223.973880130575, 6118.847028824315, 6145.047517634774, 6165.0, 6223.751049957951, 6042.2749270086115, 6055.515558755201, 6113.141862489121, 6116.761878222105, 6140.504248697302, 5281.260623512634, 6101.023836186189, 6114.0, 6128.233970753656, 6139.815805525842, 5754.5684073695475, 5792.768289268382, 5903.0555111182, 5946.851328716817, 5983.491403230774, 6137.667686279186, 6145.170401995673, 6148.692565371803, 6153.630782152906, 6171.352008039271, 5960.821175364746, 5995.16067750524, 6018.157457955977, 6024.427679370453, 6027.939720602738], "value": 5684.163431730234, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [501.17056271870047, 512.4670342843442, 513.1994089220816, 527.8944211157769, 555.9805406810763, 30386.696133038662, 44911.32633010503, 46358.0, 46462.76768616156, 46617.76691116548, 36502.0, 45306.0, 45325.32012019822, 45908.311375329395, 45942.0, 8475.660973561082, 9491.335606507548, 9626.470544120106, 9681.080297371773, 9704.078112579293, 8570.100139485357, 11322.547098116065, 11574.53701851925, 11582.826257606142, 11853.70365740859, 11595.768084638303, 11607.013403860681, 11674.474648640831, 11752.529898804038, 11824.290542567473, 8173.877391839127, 9886.0, 10480.790384192349, 11494.36780977046, 11604.361760103191, 11656.708582285426, 11747.7063073423, 11770.117241206945, 11780.0, 11781.293122412682, 41337.58662413368, 41666.0, 41863.37204941928, 41885.371719424234, 41917.371239431435, 3081.1834863761123, 3137.7436562141775, 3170.9048728538182, 3195.568598239231, 3295.736341092707, 29251.56122658162, 29771.0, 30575.388492230253, 30682.53976190359, 31409.685903140904, 2531.1141100614823, 2638.6833579970335, 2651.5890037044287, 2653.40298432853, 2707.5667893137, 526665.0, 528089.9999999962, 528756.0, 529589.0, 532915.3354233243, 34210.0, 65833.67083164553, 67231.66384168048, 67334.65330693456, 67565.66217168883, 35302.47046294308, 36359.636403636156, 36419.63580364215, 36460.0, 36474.635253647124, 14614.415423382945, 14615.342309596093, 14843.332050057774, 15063.246837658293, 15129.47046853355, 11435.656930292105, 11633.0, 12549.372531373394, 12746.68133296675, 12764.29796361209, 7722.6910923563555, 7892.960535197287, 7905.644246008944, 7947.443678942419, 7984.800379990546, 3196.0411876436974, 3236.7896086754286, 3242.610886693612, 3242.72436842871, 3256.6743325667244, 924.426855349674, 989.737719504332, 1004.372267332917, 1075.3494136047734, 1094.4199574225672, 27426.0, 29603.519824008712, 29752.702472975427, 30038.249043773645, 30177.0, 1053.9407895065563, 1113.0, 1137.141458199071, 1142.6457798082622, 1169.6666450061862, 15350.616234594225, 15367.308471118826, 15496.767548486781, 15616.92191539035, 15746.527604171893, 626404.0, 628980.0, 630348.0, 635084.0, 640229.0, 62893.37106628967, 62994.68502657549, 63176.36823631705, 63185.68407158027, 63386.68306658437, 1298.8051792231051, 1410.1045835894076, 1434.7776094705337, 1454.1493226462637, 1467.5964109869872, 27321.726782732712, 30380.544291836082, 30381.0, 31003.534946975364, 31190.688093118777, 51575.0, 58638.0, 60417.0, 60572.091418627875, 64661.67669161624, 585069.0, 586530.0, 594628.0, 594893.0, 601138.9943050431, 34847.825760871034, 40768.14622976363, 40805.79597101996, 43843.56156438395, 44370.77814610906, 47339.76330118327, 48223.27665085097, 48306.758466207444, 48483.03033939231, 48652.75673621609, 35011.64988350084, 35870.69551653714, 36484.08789780277, 38183.236335273694, 39051.0, 31663.366732664756, 32437.0, 38266.851994440214, 38923.41614875723, 39050.804745976086, 37699.43450848294, 37731.67939122227, 38225.426618600184, 38542.80728596339, 38766.0, 33164.1708957278, 33174.502382464765, 33696.49455258124, 33977.49033764446, 34025.48961765526, 24706.50586988214, 24745.752542474343, 24771.018318534112, 25202.621960670236, 25443.87278063598], "value": 19363.3634206802, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [381.1139101588806, 383.3387406723402, 383.382753766436, 392.6662337013537, 400.0438950907332, 19193.232270709173, 20278.087486063127, 20981.790182098175, 21122.471938201554, 21123.57752844944, 11940.223885447462, 11954.402279886015, 12357.691057723561, 12387.69030774231, 12403.945664618519, 5275.472452754722, 5393.1370980643105, 5410.891782164374, 5443.618946673734, 5478.287822583058, 3825.1393436476847, 4098.0, 5859.531237501011, 6188.226471691039, 6200.906986395207, 5631.0, 6163.506919446456, 6185.536084793636, 6197.566170368076, 6199.349068347826, 3830.272248272819, 6394.13679153313, 6572.769953051668, 6774.424173945221, 6799.320067993186, 5657.632253903483, 5998.610090344114, 6000.879982400371, 6005.369436209223, 6012.669303188323, 13251.668708282274, 13556.525521606794, 13566.050376473651, 13577.321133943362, 13637.590872273848, 2196.509901236775, 2203.3830527452337, 2305.054927479742, 2384.475415408607, 2464.952395232019, 9907.950460247652, 10227.488625568692, 14283.428662853474, 14958.252087395587, 14986.501349864926, 1723.5863392785766, 1773.3970450047045, 1783.0, 1813.4106415415042, 1833.8899666020081, 114832.0, 117873.0, 118728.0, 118789.0, 120729.39635301767, 9526.476043817518, 11593.478293476814, 11674.474648640831, 11677.708057298636, 11859.46632401544, 14827.703445931022, 14882.702345953021, 14954.775678364833, 15007.099574025593, 15009.624759380884, 8448.408611397143, 8488.575571221538, 8565.400421970528, 8601.268892144239, 8665.653373864974, 7082.504224704324, 8398.328133749286, 8536.487810731376, 8601.182887625666, 8649.783755406164, 3733.439984002421, 4568.0, 4571.062932098912, 4581.221192397301, 4628.352030715702, 1745.2146534059723, 1802.8587630277211, 1817.681905666503, 1912.8852268863914, 1986.056623104043, 873.9737807865774, 915.697819719491, 945.177695405002, 974.5858010345574, 1005.1857995024054, 14609.488667896572, 20177.596448070955, 20346.796532034787, 20580.0, 20632.0, 917.7246825952187, 931.6692574136084, 981.4798156976814, 982.6511588386163, 1024.472396715692, 10963.068139208097, 11006.339619622848, 11037.613683521038, 11049.889501105046, 11134.0, 581678.0, 588204.0, 599172.0, 600570.0, 613575.0, 128621.0, 129110.0, 129299.0, 132570.0, 133725.0, 997.6857289953641, 1001.9649312274036, 1013.5996281468779, 1026.5688410867454, 1029.7322696099068, 18134.72797908006, 18424.447266582025, 18524.1664125112, 18721.344752933583, 18770.906145469184, 50192.498075020245, 50795.492045079074, 52522.474775253286, 52771.20843187279, 53183.93632127413, 68287.3171268281, 75101.49797004138, 75482.8677569848, 75894.0, 76054.61972690102, 7867.134615192439, 7917.2874441300555, 7994.800129996796, 8245.95877020611, 8270.172982701682, 8859.734207973772, 11270.605528806453, 11364.829527557258, 12250.693732656755, 12580.811287830507, 10622.20333474977, 10655.840162397415, 10824.0, 10886.074683651661, 10933.617323393642, 9666.854997174907, 10031.598736050768, 10093.091621753783, 10581.576736930436, 10901.509432075418, 10704.090152336827, 10916.563337466412, 10939.83590246163, 10986.066184374418, 10999.395033273247, 7978.720744773905, 7982.241687039722, 8207.917920820715, 8230.42387032926, 8235.547044912588, 9875.308728388944, 10470.528826202686, 10843.349399036084, 11006.174536909604, 11068.889311106786], "value": 9210.754797667125, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1410.0482174532187, 1583.2400447785067, 1595.7446808510638, 1842.4933143385567, 1979.0995097230752, 7698.384129269659, 8463.746087617374, 8706.347023973196, 8731.039585645589, 8823.691170809023, 8412.36907231957, 8675.69635062773, 8676.696315628938, 8684.261837743783, 8698.304135669146, 2115.132795553823, 2305.8616483011074, 2378.90484380625, 2384.332386931662, 2407.7712617301327, 2136.839737019722, 2407.1935901473025, 2422.188566830114, 2432.1609044879488, 2443.2792326263793, 2361.9409514762096, 2372.2764556810166, 2398.436367453647, 2400.3519049856527, 2404.1224952892157, 2092.1108528875243, 2299.494111295512, 2343.3321503371544, 2363.255574494046, 2373.4066483379156, 2388.259639511757, 2400.303911865557, 2402.1352313167245, 2406.759324067579, 2409.0, 8601.655933762642, 8663.176998185163, 8685.956570217171, 8691.304695624334, 8702.129787021311, 5117.309163262949, 5157.871053223662, 5310.36275646921, 5323.520883120537, 5335.7865685372535, 3387.5596172497662, 3461.303961059088, 3538.0800991742076, 3540.7521473496868, 3549.148204430918, 1008.092716555099, 1015.57345914716, 1030.922680798943, 1042.2339580408393, 1042.4214560918688, 11577.53689852405, 11593.13051521131, 11594.652160435286, 11607.709807254801, 11625.767484650345, 4397.912041759211, 4407.603315701538, 4709.788059537261, 4799.880002999883, 4810.182269014206, 9797.706068817946, 9814.852777208347, 9935.503224838729, 9952.651657191964, 9979.451130187765, 5254.369475662945, 5342.1185504392115, 5342.759575819097, 5347.572194224453, 5348.411674715778, 4381.978090109529, 4538.0, 4747.881302967454, 4896.608271338285, 4900.877478063006, 3880.5537363203593, 3944.526656801145, 3984.7609143450904, 4012.4583181270737, 4017.2568074905653, 1260.4832018872219, 1264.5574049082747, 1267.702090008847, 1276.1194775604804, 1286.6976260578756, 843.7512481527374, 861.6165806216173, 890.8040231149137, 912.6668765900531, 916.9083091690777, 5620.381758006616, 5722.284714410699, 5888.705564721747, 5901.675407852524, 5911.438413350725, 947.4410098042188, 948.3645957208687, 958.5111593087576, 980.843065109579, 993.5777294649745, 3846.9615303847163, 3942.645161935382, 3990.541087774943, 4005.559388467266, 4013.337799263147, 10168.4915754212, 10610.09814165789, 10733.677989660322, 10775.030247277782, 10828.187885908588, 9148.954255228682, 10100.191984641211, 10591.470426478645, 10607.469626518643, 10720.142388608892, 7890.289873911375, 8136.389770767216, 8254.257116859391, 8271.1728827118, 8282.917170828214, 5815.389384114607, 5911.852203694942, 5936.703164841827, 6036.577439579363, 6043.425874541911, 9660.033996600283, 9942.204623629948, 10118.696439106838, 10139.7465063374, 10191.643292484727, 10048.648297309559, 10053.145482634058, 10186.796264074825, 10264.230182736175, 10265.2301077418, 6893.138357705287, 7109.360157585842, 7303.890441643486, 7473.103227612722, 7566.31903128721, 8150.592470376576, 8245.299149572353, 8271.917280827356, 8281.17188281167, 8346.958265208636, 7231.457640677076, 7335.449841262033, 7363.190049094703, 7486.288802563748, 7486.513376630502, 7219.60292183935, 7243.311885370881, 7262.164851042197, 7271.927280727336, 7319.890201646873, 6821.215560210441, 6851.862962740817, 6891.586504809728, 6896.896546551706, 6911.447084233148, 4771.308160316804, 4783.832565860178, 4845.9757701211265, 4919.852404427873, 5043.546080852594, 4830.975845120752, 4892.535209155125, 4923.876903077452, 4949.604031677385, 5128.282040514406], "value": 4565.131932232035, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [305.7997011957168, 311.37569173806514, 314.52192667145937, 319.4569232305082, 322.4180354460198, 4520.502744698085, 4891.8259617691765, 5447.237386765856, 5455.890882182354, 5468.031508656885, 4774.9945023090295, 4984.7756850941705, 5381.327334083239, 5452.863678408042, 5453.509184173424, 3235.8867439639616, 3518.102883764649, 3859.903502412434, 3941.507311586052, 4042.333015052513, 3577.8032208228537, 3584.494512304825, 3741.420079887622, 3966.5438474575503, 4129.483814523184, 3986.448672849379, 4052.29084910141, 4064.227796718614, 4077.5718549552316, 4086.5504794472586, 3783.9621603783885, 3825.5218097737784, 3830.425436184567, 4109.753414795107, 4136.5449800522065, 3555.0, 3564.4475106358555, 3567.250877315767, 3568.5368998710665, 3569.9306984082664, 5176.88696930158, 5182.714950677711, 5209.557187639055, 5221.791128354861, 5225.738713064369, 4298.591633794785, 4685.367475390813, 4693.436787585511, 4694.929576056362, 4695.1783437898575, 5057.443681195065, 5318.787248510055, 5602.271704678406, 5623.49388555028, 5659.236003139565, 3837.961620383788, 3840.711946603988, 3847.4998250227572, 3855.2289542091694, 3880.9029774255587, 4629.675922685414, 5643.858903527444, 5951.018082016462, 5956.761729530814, 5964.582479226456, 3347.983260083684, 3861.0694652673665, 3874.9150237933422, 3893.2674959643255, 3914.941275880864, 3633.4368172933237, 3865.555461121951, 3944.36890097583, 3956.3867600521967, 4004.0390306326854, 4088.448059511928, 4608.686524340614, 4650.6046986005895, 4660.254359302496, 4676.321933319718, 4967.875803104951, 5223.5559977402345, 5308.505002648749, 5417.485338892799, 5676.971615141898, 1122.7586068995206, 1208.9032877369789, 1225.5894275417656, 1350.9054366194468, 1358.3344161360862, 787.4055088408329, 810.528890064531, 822.2280984478458, 826.0871736730946, 838.6016642094957, 4024.3762216856435, 4870.981440311757, 4987.551120399109, 5308.946910530923, 5324.3078399808555, 5842.611285454426, 5848.093545500455, 5853.853653658693, 5861.501772349313, 5873.941260587425, 5077.644564880423, 5267.710275934784, 5307.575393968474, 5325.547328477045, 5335.706536140549, 2574.472233192191, 3027.818330900153, 3039.328369396823, 3059.0, 3061.9081427557207, 5143.974280128576, 5898.726206955095, 6038.849028774228, 6062.333143354227, 6105.297890742532, 5178.695480511212, 5339.0, 5627.043402621564, 6039.698015099228, 6060.787782475295, 4905.975470122627, 5461.5357694596405, 5684.589246452096, 6033.577649564576, 6105.755769769159, 4644.838790302424, 4885.975570122127, 5827.271591051119, 5832.929310095025, 5849.590528663123, 5133.640645154804, 5202.0116177926075, 5362.302900622894, 5519.779208831602, 5796.855078623068, 5164.741762911764, 5600.663960162403, 6067.908981365194, 6075.1191077294425, 6165.260168779775, 3282.3435312937986, 3687.0413692439624, 3772.3775577029483, 3822.5412950446125, 3822.6490708214806, 4958.603311735124, 5792.304923409218, 5804.839032193662, 5829.650220986754, 5838.94161058384, 5366.543843773166, 5377.596680248918, 5525.640833345819, 5655.236543066633, 5698.1452782083015, 5038.924416133687, 5290.259363689163, 5638.464345887134, 5647.915281270866, 5692.51613612831, 4503.347014682919, 4703.647226457961, 5397.487238712316, 5564.888702225851, 5566.471185237396, 4749.812546863284, 5046.646734728533, 5130.666506677054, 5324.760385782572, 5674.602777805514, 4733.644976626698, 4847.006363695505, 5546.334439867242, 5569.749361278671, 5588.441155884379], "value": 4068.5670180372144, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [648.8183308673571, 680.6596701649175, 713.9464540159489, 736.8784150615148, 747.3199388556415, 14802.925985370071, 15544.222788860568, 15544.84455155448, 15570.53288401347, 15570.844291557096, 14258.928705356484, 15123.621909452242, 15781.84218157818, 15789.210539473037, 16194.271257793398, 711.9430445564342, 730.9232530584266, 870.6691457246237, 909.4225167018924, 919.5034681272105, 894.1818236313765, 894.5884892949218, 906.8231694819513, 908.9227415669676, 911.6535716427749, 940.3229674634252, 953.9713808585753, 957.9952100239524, 960.3085778239656, 962.5139304651176, 737.9298966598192, 920.1994264989435, 950.3870003847479, 951.0869565217399, 956.6125719083782, 701.9438444924393, 702.5960072958019, 703.8873780195145, 704.6089420371692, 706.4913262451028, 15178.0, 15255.160966146857, 15260.465883694018, 15267.0, 15267.160306183156, 11937.0, 11976.760464790656, 12072.637820865388, 12083.154179207459, 12270.877291227152, 9659.951700241454, 9858.359206651545, 9862.358946668515, 10339.534720937501, 10799.892001079967, 3380.2056516718553, 3447.379471695094, 3466.3933811583124, 3503.737219708532, 3537.3809583322814, 83110.58444707737, 84353.15646843515, 84418.0, 85524.71712924312, 85968.57015714944, 9201.861972070425, 9225.215856652125, 9288.48913309761, 9362.016988216139, 9406.717798466058, 20116.89941550312, 20936.37190884276, 20989.475263118544, 21019.894900525705, 21027.579448410946, 7347.5224110432655, 7520.323170914644, 7577.280158384945, 7666.348360389318, 7915.960420197862, 4032.7580345178794, 4099.815508302134, 4108.650764685035, 4193.22425351311, 4207.579242075768, 3367.1413789484013, 3378.9831050844587, 3451.7583769135917, 3453.9654603454146, 3458.170039190576, 520.1988937036996, 539.4632340820908, 624.8125562331282, 630.9526785491106, 642.8135840606266, 512.2776884592706, 512.595049910574, 516.7183884782802, 587.2248631805975, 601.2034054877305, 6771.796846094625, 8054.798630034179, 8178.141295164041, 8276.08963014068, 8432.620532076071, 572.9942700573024, 574.5633318677835, 597.3220394851787, 601.1583782704249, 602.2892986276236, 3831.7126215533945, 3873.825677844504, 3874.7287689862, 3926.155876486569, 4063.837446502166, 82239.0, 84263.0, 85344.57327713321, 85681.57159214164, 89683.55158224168, 59660.210193694256, 78537.60731196307, 78641.21358786453, 81511.5924420374, 83703.0, 68217.65891170512, 69193.65403172951, 69419.0, 71980.64009679918, 73928.0, 6570.704318305592, 8185.0, 8229.95885020571, 8330.750077497685, 8490.405671602928, 44382.77808610936, 44783.55216447924, 44839.879003025184, 45360.09279814451, 45906.311405329616, 62259.6887015562, 62267.6886615564, 62937.05594416179, 63020.0, 63385.732285354956, 11214.607488737855, 11365.670037642429, 11463.484143213409, 11685.415729213675, 11990.700232494257, 11615.825762613398, 11851.288922664668, 11884.286942783461, 11905.583304584645, 12447.626571202878, 11071.778564428505, 11465.541378344773, 11485.196036277715, 11939.343336116597, 11960.162788604714, 11146.0, 11374.601888933848, 11415.0, 11502.079833613123, 12417.19288246261, 11175.776484470427, 11180.720481988015, 11197.160212984221, 11620.547431571054, 12307.876921230672, 9378.5779639915, 9455.669051583161, 9473.384230025027, 9743.415395076318, 9773.804523909625, 9354.391964522285, 9568.61725530971, 9576.856347154659, 9628.374155679858, 9812.215022798016], "value": 6821.277651228351, "unit": "score"}], "name": "dromaeo_css", "value": 7103.2641303749, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 103.0, 113.0, 126.0, 166.0, 90.0, 166.0, 167.0, 166.0, 113.0], "value": 132.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 118.0, 117.0, 200.0, 115.0, 114.0, 142.0, 136.0, 176.0, 105.0], "value": 137.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 184.0, 186.0, 181.0, 182.0, 182.0, 181.0, 178.0, 184.0, 182.0], "value": 182.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 57.0, 60.0, 118.0, 58.0, 56.0, 67.0, 64.0, 81.0, 58.0], "value": 69.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 89.0, 196.0, 109.0, 87.0, 76.0, 102.0, 82.0, 77.0, 109.0], "value": 101.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 87.0, 86.0, 88.0, 70.0, 117.0, 129.0, 90.0, 85.0], "value": 92.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 94.0, 95.0, 94.0, 93.0, 95.0, 95.0, 93.0, 94.0], "value": 94.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 92.0, 85.0, 83.0, 83.0, 101.0, 134.0, 140.0, 85.0, 102.0], "value": 99.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 57.0, 59.0, 58.0, 54.0, 54.0, 56.0, 164.0, 58.0, 56.0], "value": 67.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 65.0, 79.0, 77.0, 73.0, 69.0, 70.0, 70.0, 70.0, 71.0], "value": 71.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 86.0, 83.0, 83.0, 93.0, 104.0, 87.0, 85.0, 87.0], "value": 87.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 126.0, 152.0, 139.0, 139.0, 138.0, 136.0, 131.0, 216.0, 138.0], "value": 146.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 163.0, 159.0, 185.0, 166.0, 163.0, 213.0, 220.0, 163.0, 162.0], "value": 178.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 65.0, 65.0, 66.0, 65.0, 65.0, 65.0, 66.0, 63.0], "value": 65.0, "unit": "score"}], "name": "kraken", "value": 1524.3, "alertThreshold": 2.0}]} 04:47:40 INFO - Return code: 0 04:47:40 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 04:47:40 ERROR - # TBPL SUCCESS # 04:47:40 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:47:40 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:47:40 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:47:40 INFO - 2016-05-24 04:47:40.949 screenresolution[1676:10100] starting screenresolution argv=screenresolution get 04:47:40 INFO - 2016-05-24 04:47:40.951 screenresolution[1676:10100] Display 0: 1600x1200x32@60 04:47:40 INFO - 2016-05-24 04:47:40.958 screenresolution[1677:10103] starting screenresolution argv=screenresolution list 04:47:40 INFO - Available Modes on Display 0 04:47:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:47:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:47:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:47:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:47:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:47:41 INFO - Intel Iris: 04:47:41 INFO - Chipset Model: Intel Iris 04:47:41 INFO - Type: GPU 04:47:41 INFO - Bus: Built-In 04:47:41 INFO - VRAM (Dynamic, Max): 1536 MB 04:47:41 INFO - Vendor: Intel (0x8086) 04:47:41 INFO - Device ID: 0x0a2e 04:47:41 INFO - Revision ID: 0x0009 04:47:41 INFO - Displays: 04:47:41 INFO - Display: 04:47:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:47:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:47:41 INFO - Display Serial Number: 1600x1200 60 04:47:41 INFO - Main Display: Yes 04:47:41 INFO - Mirror: Off 04:47:41 INFO - Online: Yes 04:47:41 INFO - Rotation: Supported 04:47:41 INFO - Return code: 0 04:47:41 INFO - Running post-action listener: _resource_record_post_action 04:47:41 INFO - ##### 04:47:41 INFO - ##### Finished run-tests step (success) 04:47:41 INFO - ##### 04:47:41 INFO - Running post-run listener: _resource_record_post_run 04:47:41 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 31153664; Write bytes: 695192064; Read time: 193; Write time: 1885 04:47:41 INFO - TinderboxPrint: CPU usage
53.7% 04:47:41 INFO - TinderboxPrint: I/O read bytes / time
31,153,664 / 193 04:47:41 INFO - TinderboxPrint: I/O write bytes / time
695,192,064 / 1,885 04:47:41 INFO - TinderboxPrint: CPU idle
2,247.6 (46.3%) 04:47:41 INFO - TinderboxPrint: CPU system
967.0 (19.9%) 04:47:41 INFO - TinderboxPrint: CPU user
1,635.2 (33.7%) 04:47:41 INFO - TinderboxPrint: Swap in / out
423,571,456 / 0 04:47:41 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 245760; Write bytes: 398398976; Read time: 4; Write time: 823 04:47:41 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 29363712; Write bytes: 295945216; Read time: 178; Write time: 1060 04:47:41 INFO - Running post-run listener: _upload_blobber_files 04:47:41 INFO - Blob upload gear active. 04:47:41 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:47:41 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 04:47:41 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'] 04:47:41 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 04:47:42 INFO - (blobuploader) - INFO - Open directory for files ... 04:47:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:47:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:47:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:47:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:47:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:47:42 INFO - (blobuploader) - INFO - Done attempting. 04:47:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:47:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:47:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:47:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:47:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:47:43 INFO - (blobuploader) - INFO - Done attempting. 04:47:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:47:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:47:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:47:46 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:47:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:47:46 INFO - (blobuploader) - INFO - Done attempting. 04:47:46 INFO - (blobuploader) - INFO - Iteration through files over. 04:47:46 INFO - Return code: 0 04:47:46 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:47:46 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:47:46 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/46cff713502b8a3974530c2be1b111b59b2af3efd4e78206dd9eac29edc6b5df6a26937e675c00533aa6e8234c36137c463a940b9dbe027435e34ac2fcb2dff9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0190379bae6c7b0d75b5e234488fdc342c53e590bc9f3209db6565950fa9c3d1ea03f603ff4e9074eeff48ce75af3359c7bc3da36ed146583090d461d4474b9c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7d05ef2985cdb73579a7bee1eab294eae60ddecc092e5df7a4cfa1ce39a90d3e07402f9f60ff7924738d2d555d4cc3239ed9f97534923fa5f127bf0232e66458"} 04:47:46 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:47:46 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:47:46 INFO - Contents: 04:47:46 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/46cff713502b8a3974530c2be1b111b59b2af3efd4e78206dd9eac29edc6b5df6a26937e675c00533aa6e8234c36137c463a940b9dbe027435e34ac2fcb2dff9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0190379bae6c7b0d75b5e234488fdc342c53e590bc9f3209db6565950fa9c3d1ea03f603ff4e9074eeff48ce75af3359c7bc3da36ed146583090d461d4474b9c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7d05ef2985cdb73579a7bee1eab294eae60ddecc092e5df7a4cfa1ce39a90d3e07402f9f60ff7924738d2d555d4cc3239ed9f97534923fa5f127bf0232e66458"} 04:47:46 INFO - Running post-run listener: copy_logs_to_upload_dir 04:47:46 INFO - Copying logs to upload dir... 04:47:46 INFO - mkdir: /builds/slave/test/build/upload/logs 04:47:46 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1289.515314 ========= master_lag: 0.11 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 29 secs) (at 2016-05-24 04:47:46.274357) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-24 04:47:46.277882) ========= 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.9vPONKBnjO/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.FT5pYy7yy8/Listeners TMPDIR=/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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/46cff713502b8a3974530c2be1b111b59b2af3efd4e78206dd9eac29edc6b5df6a26937e675c00533aa6e8234c36137c463a940b9dbe027435e34ac2fcb2dff9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0190379bae6c7b0d75b5e234488fdc342c53e590bc9f3209db6565950fa9c3d1ea03f603ff4e9074eeff48ce75af3359c7bc3da36ed146583090d461d4474b9c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7d05ef2985cdb73579a7bee1eab294eae60ddecc092e5df7a4cfa1ce39a90d3e07402f9f60ff7924738d2d555d4cc3239ed9f97534923fa5f127bf0232e66458"} build_url:https://queue.taskcluster.net/v1/task/MLNIfzbgTwigewqAHuWtBg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011268 build_url: 'https://queue.taskcluster.net/v1/task/MLNIfzbgTwigewqAHuWtBg/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/46cff713502b8a3974530c2be1b111b59b2af3efd4e78206dd9eac29edc6b5df6a26937e675c00533aa6e8234c36137c463a940b9dbe027435e34ac2fcb2dff9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0190379bae6c7b0d75b5e234488fdc342c53e590bc9f3209db6565950fa9c3d1ea03f603ff4e9074eeff48ce75af3359c7bc3da36ed146583090d461d4474b9c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7d05ef2985cdb73579a7bee1eab294eae60ddecc092e5df7a4cfa1ce39a90d3e07402f9f60ff7924738d2d555d4cc3239ed9f97534923fa5f127bf0232e66458"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-24 04:47:46.306655) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 04:47:46.306942) ========= 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.9vPONKBnjO/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.FT5pYy7yy8/Listeners TMPDIR=/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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.005114 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 04:47:46.355047) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-24 04:47:46.355327) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-24 04:47:46.355640) ========= ========= Total master_lag: 1.28 =========