builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0194 starttime: 1464382151.43 results: success (0) buildid: 20160527122546 builduid: 3608fad19f1b46f0abad57506cd1118b revision: 6438b11898cf5775f446ac0ddcf13f9e8506b26a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-27 13:49:11.431395) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-27 13:49:11.431785) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-27 13:49:11.432065) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-27 13:49:11.478527) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 13:49:11.478883) ========= 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.OZ4ng8EeTb/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.bYrtufKXHr/Listeners TMPDIR=/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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-27 13:49:11-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 156M=0s 2016-05-27 13:49:12 (156 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.564595 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 13:49:12.066261) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 13:49:12.067022) ========= 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.OZ4ng8EeTb/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.bYrtufKXHr/Listeners TMPDIR=/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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.019362 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 13:49:12.120161) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-27 13:49:12.121013) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 6438b11898cf5775f446ac0ddcf13f9e8506b26a --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 6438b11898cf5775f446ac0ddcf13f9e8506b26a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.OZ4ng8EeTb/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.bYrtufKXHr/Listeners TMPDIR=/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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-27 13:49:12,188 truncating revision to first 12 chars 2016-05-27 13:49:12,188 Setting DEBUG logging. 2016-05-27 13:49:12,188 attempt 1/10 2016-05-27 13:49:12,188 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/6438b11898cf?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-27 13:49:13,328 unpacking tar archive at: fx-team-6438b11898cf/testing/mozharness/ program finished with exit code 0 elapsedTime=1.392798 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-27 13:49:13.538178) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-27 13:49:13.538876) ========= script_repo_revision: 6438b11898cf5775f446ac0ddcf13f9e8506b26a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-27 13:49:13.539884) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-27 13:49:13.540515) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-27 13:49:13.557928) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-05-27 13:49:13.558534) ========= /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.OZ4ng8EeTb/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.bYrtufKXHr/Listeners TMPDIR=/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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 13:49:13 INFO - MultiFileLogger online at 20160527 13:49:13 in /builds/slave/test 13:49:13 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 13:49:13 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:49:13 INFO - {'append_to_log': False, 13:49:13 INFO - 'base_work_dir': '/builds/slave/test', 13:49:13 INFO - 'blob_upload_branch': 'Fx-Team', 13:49:13 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:49:13 INFO - 'branch': 'Fx-Team', 13:49:13 INFO - 'buildbot_json_path': 'buildprops.json', 13:49:13 INFO - 'config_files': ('talos/mac_config.py',), 13:49:13 INFO - 'default_actions': ('clobber', 13:49:13 INFO - 'read-buildbot-config', 13:49:13 INFO - 'download-and-extract', 13:49:13 INFO - 'populate-webroot', 13:49:13 INFO - 'create-virtualenv', 13:49:13 INFO - 'install', 13:49:13 INFO - 'run-tests'), 13:49:13 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:49:13 INFO - 'download_minidump_stackwalk': True, 13:49:13 INFO - 'download_symbols': 'ondemand', 13:49:13 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:49:13 INFO - 'tooltool.py': '/tools/tooltool.py', 13:49:13 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:49:13 INFO - '/tools/misc-python/virtualenv.py')}, 13:49:13 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:49:13 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:49:13 INFO - 'installer_path': 'installer.exe', 13:49:13 INFO - 'log_level': 'info', 13:49:13 INFO - 'log_name': 'talos', 13:49:13 INFO - 'log_to_console': True, 13:49:13 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 13:49:13 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 13:49:13 INFO - 'opt_config_files': (), 13:49:13 INFO - 'pip_index': False, 13:49:13 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:49:13 INFO - 'cmd': ('bash', 13:49:13 INFO - '-c', 13:49:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:49:13 INFO - 'enabled': True, 13:49:13 INFO - 'halt_on_failure': False, 13:49:13 INFO - 'name': 'check_screen_resolution'},), 13:49:13 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:49:13 INFO - 'cmd': ('bash', 13:49:13 INFO - '-c', 13:49:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:49:13 INFO - 'enabled': True, 13:49:13 INFO - 'halt_on_failure': False, 13:49:13 INFO - 'name': 'check_screen_resolution'},), 13:49:13 INFO - 'run_cmd_checks_enabled': True, 13:49:13 INFO - 'sps_profile': False, 13:49:13 INFO - 'sps_profile_interval': 0, 13:49:13 INFO - 'suite': 'dromaeojs', 13:49:13 INFO - 'system_bits': '32', 13:49:13 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:49:13 INFO - 'title': 't-yosemite-r7-0194', 13:49:13 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:49:13 INFO - 'use_talos_json': True, 13:49:13 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:49:13 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:49:13 INFO - 'work_dir': 'build'} 13:49:13 INFO - ##### 13:49:13 INFO - ##### Running clobber step. 13:49:13 INFO - ##### 13:49:13 INFO - Running pre-action listener: _resource_record_pre_action 13:49:13 INFO - Running main action method: clobber 13:49:13 INFO - rmtree: /builds/slave/test/build 13:49:13 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:49:15 INFO - Running post-action listener: _resource_record_post_action 13:49:15 INFO - ##### 13:49:15 INFO - ##### Finished clobber step (success) 13:49:15 INFO - ##### 13:49:15 INFO - ##### 13:49:15 INFO - ##### Running read-buildbot-config step. 13:49:15 INFO - ##### 13:49:15 INFO - Running pre-action listener: _resource_record_pre_action 13:49:15 INFO - Running main action method: read_buildbot_config 13:49:15 INFO - Using buildbot properties: 13:49:15 INFO - { 13:49:15 INFO - "project": "", 13:49:15 INFO - "product": "firefox", 13:49:15 INFO - "script_repo_revision": "production", 13:49:15 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 13:49:15 INFO - "repository": "", 13:49:15 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 13:49:15 INFO - "buildid": "20160527122546", 13:49:15 INFO - "pgo_build": "False", 13:49:15 INFO - "basedir": "/builds/slave/test", 13:49:15 INFO - "buildnumber": 24, 13:49:15 INFO - "slavename": "t-yosemite-r7-0194", 13:49:15 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 13:49:15 INFO - "platform": "yosemite_r7", 13:49:15 INFO - "scheduler": "tests-fx-team-macosx64-talos", 13:49:15 INFO - "branch": "fx-team", 13:49:15 INFO - "repo_path": "integration/fx-team", 13:49:15 INFO - "revision": "6438b11898cf5775f446ac0ddcf13f9e8506b26a", 13:49:15 INFO - "stage_platform": "macosx64", 13:49:15 INFO - "builduid": "3608fad19f1b46f0abad57506cd1118b", 13:49:15 INFO - "slavebuilddir": "test" 13:49:15 INFO - } 13:49:15 INFO - Found installer url https://queue.taskcluster.net/v1/task/Ii50GD-nRfCaWbGHnihkwg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 13:49:15 INFO - Running post-action listener: _resource_record_post_action 13:49:15 INFO - ##### 13:49:15 INFO - ##### Finished read-buildbot-config step (success) 13:49:15 INFO - ##### 13:49:15 INFO - ##### 13:49:15 INFO - ##### Running download-and-extract step. 13:49:15 INFO - ##### 13:49:15 INFO - Running pre-action listener: _resource_record_pre_action 13:49:15 INFO - Running main action method: download_and_extract 13:49:15 INFO - mkdir: /builds/slave/test/build/tests 13:49:15 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')]} 13:49:15 INFO - https://queue.taskcluster.net/v1/task/Ii50GD-nRfCaWbGHnihkwg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 13:49:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ii50GD-nRfCaWbGHnihkwg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 13:49:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ii50GD-nRfCaWbGHnihkwg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 13:49:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ii50GD-nRfCaWbGHnihkwg/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 13:49:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ii50GD-nRfCaWbGHnihkwg/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 13:49:23 INFO - Downloaded 1199 bytes. 13:49:23 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 13:49:23 INFO - Using the following test package requirements: 13:49:23 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 13:49:23 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:49:23 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 13:49:23 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:49:23 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 13:49:23 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:49:23 INFO - u'jsshell-mac.zip'], 13:49:23 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:49:23 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 13:49:23 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 13:49:23 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:49:23 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 13:49:23 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:49:23 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 13:49:23 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:49:23 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 13:49:23 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:49:23 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 13:49:23 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 13:49:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:49:23 INFO - https://queue.taskcluster.net/v1/task/Ii50GD-nRfCaWbGHnihkwg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 13:49:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ii50GD-nRfCaWbGHnihkwg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 13:49:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ii50GD-nRfCaWbGHnihkwg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 13:49:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ii50GD-nRfCaWbGHnihkwg/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 13:49:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ii50GD-nRfCaWbGHnihkwg/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 13:49:25 INFO - Downloaded 18684736 bytes. 13:49:25 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'] 13:49:25 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 13:49:29 INFO - Return code: 0 13:49:29 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 13:49:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:49:29 INFO - https://queue.taskcluster.net/v1/task/Ii50GD-nRfCaWbGHnihkwg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 13:49:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ii50GD-nRfCaWbGHnihkwg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 13:49:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ii50GD-nRfCaWbGHnihkwg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 13:49:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ii50GD-nRfCaWbGHnihkwg/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 13:49:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ii50GD-nRfCaWbGHnihkwg/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 13:49:31 INFO - Downloaded 18684736 bytes. 13:49:31 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'] 13:49:31 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 13:49:34 INFO - Return code: 0 13:49:34 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')]} 13:49:34 INFO - https://queue.taskcluster.net/v1/task/Ii50GD-nRfCaWbGHnihkwg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 13:49:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ii50GD-nRfCaWbGHnihkwg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 13:49:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ii50GD-nRfCaWbGHnihkwg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 13:49:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ii50GD-nRfCaWbGHnihkwg/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 13:49:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ii50GD-nRfCaWbGHnihkwg/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 13:49:36 INFO - Downloaded 10834034 bytes. 13:49:36 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'] 13:49:36 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 13:49:36 INFO - Return code: 0 13:49:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:49:36 INFO - https://queue.taskcluster.net/v1/task/Ii50GD-nRfCaWbGHnihkwg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 13:49:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ii50GD-nRfCaWbGHnihkwg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 13:49:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ii50GD-nRfCaWbGHnihkwg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 13:49:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ii50GD-nRfCaWbGHnihkwg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 13:49:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ii50GD-nRfCaWbGHnihkwg/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 13:49:39 INFO - Downloaded 110442567 bytes. 13:49:39 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Ii50GD-nRfCaWbGHnihkwg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 13:49:39 INFO - mkdir: /builds/slave/test/properties 13:49:39 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:49:39 INFO - Writing to file /builds/slave/test/properties/build_url 13:49:39 INFO - Contents: 13:49:39 INFO - build_url:https://queue.taskcluster.net/v1/task/Ii50GD-nRfCaWbGHnihkwg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 13:49:40 INFO - Running post-action listener: _resource_record_post_action 13:49:40 INFO - Running post-action listener: set_extra_try_arguments 13:49:40 INFO - ##### 13:49:40 INFO - ##### Finished download-and-extract step (success) 13:49:40 INFO - ##### 13:49:40 INFO - ##### 13:49:40 INFO - ##### Running populate-webroot step. 13:49:40 INFO - ##### 13:49:40 INFO - Running pre-action listener: _resource_record_pre_action 13:49:40 INFO - Running main action method: populate_webroot 13:49:40 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:49:40 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:49:40 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:49:40 INFO - '--tppagecycles', 13:49:40 INFO - '7'], 13:49:40 INFO - 'tests': ['tsvgm']}}, 13:49:40 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:49:40 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:49:40 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:49:40 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:49:40 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:49:40 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:49:40 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:49:40 INFO - 'talos_options': ['--disable-e10s'], 13:49:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:49:40 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:49:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:49:40 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:49:40 INFO - 'talos_options': ['--disable-e10s'], 13:49:40 INFO - 'tests': ['damp', 'tps']}, 13:49:40 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:49:40 INFO - 'tests': ['damp', 'tps']}, 13:49:40 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:49:40 INFO - 'tests': ['dromaeo_dom']}, 13:49:40 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:49:40 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:49:40 INFO - 'tests': ['a11yr', 13:49:40 INFO - 'ts_paint', 13:49:40 INFO - 'tpaint', 13:49:40 INFO - 'sessionrestore', 13:49:40 INFO - 'sessionrestore_no_auto_restore', 13:49:40 INFO - 'tabpaint']}, 13:49:40 INFO - 'other-e10s': {'tests': ['a11yr', 13:49:40 INFO - 'ts_paint', 13:49:40 INFO - 'tpaint', 13:49:40 INFO - 'sessionrestore', 13:49:40 INFO - 'sessionrestore_no_auto_restore', 13:49:40 INFO - 'tabpaint']}, 13:49:40 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:49:40 INFO - 'ts_paint', 13:49:40 INFO - 'tpaint', 13:49:40 INFO - 'sessionrestore', 13:49:40 INFO - 'sessionrestore_no_auto_restore', 13:49:40 INFO - 'tabpaint']}, 13:49:40 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:49:40 INFO - 'ts_paint', 13:49:40 INFO - 'tpaint', 13:49:40 INFO - 'sessionrestore', 13:49:40 INFO - 'sessionrestore_no_auto_restore', 13:49:40 INFO - 'tabpaint']}, 13:49:40 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:49:40 INFO - 'tests': ['a11yr', 13:49:40 INFO - 'ts_paint', 13:49:40 INFO - 'tpaint', 13:49:40 INFO - 'sessionrestore', 13:49:40 INFO - 'sessionrestore_no_auto_restore', 13:49:40 INFO - 'tabpaint']}, 13:49:40 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:49:40 INFO - 'tests': ['a11yr', 13:49:40 INFO - 'ts_paint', 13:49:40 INFO - 'tpaint', 13:49:40 INFO - 'sessionrestore', 13:49:40 INFO - 'sessionrestore_no_auto_restore', 13:49:40 INFO - 'tabpaint']}, 13:49:40 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:49:40 INFO - 'tests': ['tsvgx', 13:49:40 INFO - 'tsvgr_opacity', 13:49:40 INFO - 'tart', 13:49:40 INFO - 'tscrollx', 13:49:40 INFO - 'cart']}, 13:49:40 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:49:40 INFO - 'tsvgr_opacity', 13:49:40 INFO - 'tart', 13:49:40 INFO - 'tscrollx', 13:49:40 INFO - 'cart']}, 13:49:40 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:49:40 INFO - 'talos_options': ['--disable-e10s'], 13:49:40 INFO - 'tests': ['tp5o']}, 13:49:40 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:49:40 INFO - 'tests': ['tp5o']}, 13:49:40 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:49:40 INFO - 'talos_options': ['--disable-e10s', 13:49:40 INFO - '--xperf_path', 13:49:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:49:40 INFO - 'tests': ['tp5n']}, 13:49:40 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:49:40 INFO - 'talos_options': ['--xperf_path', 13:49:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:49:40 INFO - 'tests': ['tp5n']}}, 13:49:40 INFO - 'talos.zip': {'path': '', 13:49:40 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:49:40 INFO - Running post-action listener: _resource_record_post_action 13:49:40 INFO - ##### 13:49:40 INFO - ##### Finished populate-webroot step (success) 13:49:40 INFO - ##### 13:49:40 INFO - ##### 13:49:40 INFO - ##### Running create-virtualenv step. 13:49:40 INFO - ##### 13:49:40 INFO - Running pre-action listener: _resource_record_pre_action 13:49:40 INFO - Running main action method: create_virtualenv 13:49:40 INFO - Creating virtualenv /builds/slave/test/build/venv 13:49:40 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 13:49:40 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:49:40 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:49:40 INFO - Using real prefix '/tools/python27' 13:49:40 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:49:41 INFO - Installing distribute.............................................................................................................................................................................................done. 13:49:44 INFO - Installing pip.................done. 13:49:44 INFO - Return code: 0 13:49:44 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:49: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')]} 13:49:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:49:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:49: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')]} 13:49:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:49:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:49:44 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 0x103036a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10303c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcbca49df20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102edad30>, '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 0x102cfedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102ed1b20>, '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.OZ4ng8EeTb/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.bYrtufKXHr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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 13:49:44 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 13:49:44 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 13:49:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OZ4ng8EeTb/Render', 13:49:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:49:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:49:44 INFO - 'HOME': '/Users/cltbld', 13:49:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:49:44 INFO - 'LOGNAME': 'cltbld', 13:49:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:49:44 INFO - 'MOZ_NO_REMOTE': '1', 13:49:44 INFO - 'NO_EM_RESTART': '1', 13:49:44 INFO - 'PAGER': '/bin/cat', 13:49:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:49:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:49:44 INFO - 'PWD': '/builds/slave/test', 13:49:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:49:44 INFO - 'SHELL': '/bin/bash', 13:49:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bYrtufKXHr/Listeners', 13:49:44 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 13:49:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:49:44 INFO - 'USER': 'cltbld', 13:49:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:49:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:49:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:49:44 INFO - 'XPC_FLAGS': '0x0', 13:49:44 INFO - 'XPC_SERVICE_NAME': '0', 13:49:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:49:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:49:44 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 13:49:44 INFO - Cleaning up... 13:49:44 INFO - Return code: 0 13:49:44 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 13:49: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')]} 13:49:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:49:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:49: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')]} 13:49:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:49:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:49:44 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 0x103036a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10303c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcbca49df20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102edad30>, '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 0x102cfedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102ed1b20>, '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.OZ4ng8EeTb/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.bYrtufKXHr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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 13:49:44 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 13:49:44 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 13:49:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OZ4ng8EeTb/Render', 13:49:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:49:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:49:44 INFO - 'HOME': '/Users/cltbld', 13:49:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:49:44 INFO - 'LOGNAME': 'cltbld', 13:49:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:49:44 INFO - 'MOZ_NO_REMOTE': '1', 13:49:44 INFO - 'NO_EM_RESTART': '1', 13:49:44 INFO - 'PAGER': '/bin/cat', 13:49:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:49:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:49:44 INFO - 'PWD': '/builds/slave/test', 13:49:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:49:44 INFO - 'SHELL': '/bin/bash', 13:49:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bYrtufKXHr/Listeners', 13:49:44 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 13:49:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:49:44 INFO - 'USER': 'cltbld', 13:49:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:49:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:49:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:49:44 INFO - 'XPC_FLAGS': '0x0', 13:49:44 INFO - 'XPC_SERVICE_NAME': '0', 13:49:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:49:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:49:45 INFO - Downloading/unpacking psutil>=3.1.1 13:49:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:49:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:49:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:49:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:49:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:49:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:49:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:49:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:49:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:49:48 INFO - Installing collected packages: psutil 13:49:48 INFO - Running setup.py install for psutil 13:49:48 INFO - building 'psutil._psutil_osx' extension 13:49:48 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 13:49:48 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 13:49:48 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 13:49:48 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 13:49:48 INFO - building 'psutil._psutil_posix' extension 13:49:48 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 13:49:49 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 13:49:49 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 13:49:49 INFO - ^ 13:49:49 INFO - 1 warning generated. 13:49:49 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 13:49:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:49:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:49:49 INFO - Successfully installed psutil 13:49:49 INFO - Cleaning up... 13:49:49 INFO - Return code: 0 13:49:49 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 13:49:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:49:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:49:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:49:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:49:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:49:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:49:49 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 0x103036a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10303c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcbca49df20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102edad30>, '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 0x102cfedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102ed1b20>, '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.OZ4ng8EeTb/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.bYrtufKXHr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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 13:49:49 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 13:49:49 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 13:49:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OZ4ng8EeTb/Render', 13:49:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:49:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:49:49 INFO - 'HOME': '/Users/cltbld', 13:49:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:49:49 INFO - 'LOGNAME': 'cltbld', 13:49:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:49:49 INFO - 'MOZ_NO_REMOTE': '1', 13:49:49 INFO - 'NO_EM_RESTART': '1', 13:49:49 INFO - 'PAGER': '/bin/cat', 13:49:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:49:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:49:49 INFO - 'PWD': '/builds/slave/test', 13:49:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:49:49 INFO - 'SHELL': '/bin/bash', 13:49:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bYrtufKXHr/Listeners', 13:49:49 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 13:49:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:49:49 INFO - 'USER': 'cltbld', 13:49:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:49:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:49:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:49:49 INFO - 'XPC_FLAGS': '0x0', 13:49:49 INFO - 'XPC_SERVICE_NAME': '0', 13:49:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:49:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:49:49 INFO - Downloading/unpacking mozsystemmonitor==0.3 13:49:49 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 13:49:49 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 13:49:49 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 13:49:49 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 13:49:49 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 13:49:49 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 13:49:51 INFO - Downloading mozsystemmonitor-0.3.tar.gz 13:49:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:49:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 13:49:52 INFO - Installing collected packages: mozsystemmonitor 13:49:52 INFO - Running setup.py install for mozsystemmonitor 13:49:52 INFO - Successfully installed mozsystemmonitor 13:49:52 INFO - Cleaning up... 13:49:52 INFO - Return code: 0 13:49:52 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:49:52 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')]} 13:49:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:49:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:49:52 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')]} 13:49:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:49:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:49:52 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 0x103036a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10303c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcbca49df20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102edad30>, '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 0x102cfedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102ed1b20>, '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.OZ4ng8EeTb/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.bYrtufKXHr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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 13:49:52 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 13:49:52 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 13:49:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OZ4ng8EeTb/Render', 13:49:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:49:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:49:52 INFO - 'HOME': '/Users/cltbld', 13:49:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:49:52 INFO - 'LOGNAME': 'cltbld', 13:49:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:49:52 INFO - 'MOZ_NO_REMOTE': '1', 13:49:52 INFO - 'NO_EM_RESTART': '1', 13:49:52 INFO - 'PAGER': '/bin/cat', 13:49:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:49:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:49:52 INFO - 'PWD': '/builds/slave/test', 13:49:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:49:52 INFO - 'SHELL': '/bin/bash', 13:49:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bYrtufKXHr/Listeners', 13:49:52 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 13:49:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:49:52 INFO - 'USER': 'cltbld', 13:49:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:49:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:49:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:49:52 INFO - 'XPC_FLAGS': '0x0', 13:49:52 INFO - 'XPC_SERVICE_NAME': '0', 13:49:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:49:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:49:52 INFO - Downloading/unpacking blobuploader==1.2.4 13:49:52 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 13:49:52 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 13:49:52 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 13:49:52 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 13:49:52 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 13:49:52 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 13:49:54 INFO - Downloading blobuploader-1.2.4.tar.gz 13:49:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:49:55 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:49:55 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 13:49:55 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 13:49:55 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 13:49:55 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 13:49:55 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 13:49:55 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 13:49:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:49:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:49:55 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 13:49:55 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 13:49:55 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 13:49:55 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 13:49:55 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 13:49:55 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 13:49:56 INFO - Downloading docopt-0.6.1.tar.gz 13:49:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:49:56 INFO - Installing collected packages: blobuploader, requests, docopt 13:49:56 INFO - Running setup.py install for blobuploader 13:49:56 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:49:56 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:49:56 INFO - Running setup.py install for requests 13:49:56 INFO - Running setup.py install for docopt 13:49:56 INFO - Successfully installed blobuploader requests docopt 13:49:56 INFO - Cleaning up... 13:49:57 INFO - Return code: 0 13:49:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:49: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')]} 13:49:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:49:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:49: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')]} 13:49:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:49:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:49:57 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 0x103036a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10303c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcbca49df20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102edad30>, '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 0x102cfedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102ed1b20>, '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.OZ4ng8EeTb/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.bYrtufKXHr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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 13:49:57 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 13:49:57 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 13:49:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OZ4ng8EeTb/Render', 13:49:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:49:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:49:57 INFO - 'HOME': '/Users/cltbld', 13:49:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:49:57 INFO - 'LOGNAME': 'cltbld', 13:49:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:49:57 INFO - 'MOZ_NO_REMOTE': '1', 13:49:57 INFO - 'NO_EM_RESTART': '1', 13:49:57 INFO - 'PAGER': '/bin/cat', 13:49:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:49:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:49:57 INFO - 'PWD': '/builds/slave/test', 13:49:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:49:57 INFO - 'SHELL': '/bin/bash', 13:49:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bYrtufKXHr/Listeners', 13:49:57 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 13:49:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:49:57 INFO - 'USER': 'cltbld', 13:49:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:49:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:49:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:49:57 INFO - 'XPC_FLAGS': '0x0', 13:49:57 INFO - 'XPC_SERVICE_NAME': '0', 13:49:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:49:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:49:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:49:57 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-qMdO35-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:49:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:49:57 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-IXVwsB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:49:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:49:57 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-ToyVE1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:49:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:49:57 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-1cml8Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:49:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:49:57 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-pdSdnP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:49:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:49:57 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-fTtyH0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:49:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:49:57 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-hSpQxj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:49:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:49:58 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-s4NbbC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:49:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:49:58 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-IGDyIf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:49:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:49:58 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-SSMdIc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:49:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:49:58 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-H1EXT4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:49:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:49:58 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-BRADSt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:49:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:49:58 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-cKWc2E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:49:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:49:58 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-qBnjz2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:49:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:49:58 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-w47PuS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:49:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:49:58 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-hqU6nU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:49:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:49:58 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-rFHCzP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:49:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:49:59 INFO - Running setup.py install for manifestparser 13:49:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:49:59 INFO - Running setup.py install for mozcrash 13:49:59 INFO - Running setup.py install for mozdebug 13:49:59 INFO - Running setup.py install for mozdevice 13:49:59 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:49:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:49:59 INFO - Running setup.py install for mozfile 13:49:59 INFO - Running setup.py install for mozhttpd 13:49:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:49:59 INFO - Running setup.py install for mozinfo 13:50:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:50:00 INFO - Running setup.py install for mozInstall 13:50:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:50:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:50:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:50:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:50:00 INFO - Running setup.py install for mozleak 13:50:00 INFO - Running setup.py install for mozlog 13:50:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:50:00 INFO - Running setup.py install for moznetwork 13:50:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:50:00 INFO - Running setup.py install for mozprocess 13:50:00 INFO - Running setup.py install for mozprofile 13:50:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:50:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:50:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:50:00 INFO - Running setup.py install for mozrunner 13:50:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:50:01 INFO - Running setup.py install for mozscreenshot 13:50:01 INFO - Running setup.py install for moztest 13:50:01 INFO - Running setup.py install for mozversion 13:50:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:50:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:50:01 INFO - Cleaning up... 13:50:01 INFO - Return code: 0 13:50:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:50: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')]} 13:50:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:50:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:50: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')]} 13:50:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:50:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:50:01 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 0x103036a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10303c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcbca49df20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102edad30>, '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 0x102cfedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102ed1b20>, '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.OZ4ng8EeTb/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.bYrtufKXHr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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 13:50:01 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 13:50:01 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 13:50:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OZ4ng8EeTb/Render', 13:50:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:50:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:50:01 INFO - 'HOME': '/Users/cltbld', 13:50:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:50:01 INFO - 'LOGNAME': 'cltbld', 13:50:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:50:01 INFO - 'MOZ_NO_REMOTE': '1', 13:50:01 INFO - 'NO_EM_RESTART': '1', 13:50:01 INFO - 'PAGER': '/bin/cat', 13:50:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:50:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:50:01 INFO - 'PWD': '/builds/slave/test', 13:50:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:50:01 INFO - 'SHELL': '/bin/bash', 13:50:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bYrtufKXHr/Listeners', 13:50:01 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 13:50:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:50:01 INFO - 'USER': 'cltbld', 13:50:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:50:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:50:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:50:01 INFO - 'XPC_FLAGS': '0x0', 13:50:01 INFO - 'XPC_SERVICE_NAME': '0', 13:50:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:50:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:50:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:50:01 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-UMzW4m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:50:01 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)) 13:50:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:50:01 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-ErO9KN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:50:02 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)) 13:50:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:50:02 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-GldDnM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:50:02 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)) 13:50:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:50:02 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-xL1Ufj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:50:02 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)) 13:50:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:50:02 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-8I3F8v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:50:02 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)) 13:50:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:50:02 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-JrZ8Ul-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:50:02 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)) 13:50:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:50:02 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-rDS0tG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:50:02 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)) 13:50:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:50:02 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-en1B7f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:50:02 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)) 13:50:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:50:02 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-Dvozll-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:50:02 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)) 13:50:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:50:02 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-bzkk5w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:50:02 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)) 13:50:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:50:02 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-xbBLEW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:50:03 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)) 13:50:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:50:03 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-ULNZfX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:50:03 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)) 13:50:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:50:03 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-bv2D7Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:50:03 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)) 13:50:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:50:03 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-oUYfsp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:50:03 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)) 13:50:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:50:03 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-1Vt4tL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:50:03 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)) 13:50:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:50:03 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-2dVvFz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:50:03 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)) 13:50:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:50:03 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-nbm2yw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:50:03 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)) 13:50:03 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)) 13:50:03 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)) 13:50:03 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)) 13:50:03 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)) 13:50:03 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)) 13:50:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:50:03 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 13:50:03 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 13:50:03 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 13:50:03 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 13:50:03 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 13:50:03 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 13:50:06 INFO - Downloading blessings-1.6.tar.gz 13:50:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:50:06 INFO - Installing collected packages: blessings 13:50:06 INFO - Running setup.py install for blessings 13:50:06 INFO - Successfully installed blessings 13:50:06 INFO - Cleaning up... 13:50:06 INFO - Return code: 0 13:50:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:50:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:50:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:50:06 INFO - Reading from file tmpfile_stdout 13:50:06 INFO - Current package versions: 13:50:06 INFO - blessings == 1.6 13:50:06 INFO - blobuploader == 1.2.4 13:50:06 INFO - docopt == 0.6.1 13:50:06 INFO - manifestparser == 1.1 13:50:06 INFO - mozInstall == 1.12 13:50:06 INFO - mozcrash == 0.17 13:50:06 INFO - mozdebug == 0.1 13:50:06 INFO - mozdevice == 0.48 13:50:06 INFO - mozfile == 1.2 13:50:06 INFO - mozhttpd == 0.7 13:50:06 INFO - mozinfo == 0.9 13:50:06 INFO - mozleak == 0.1 13:50:06 INFO - mozlog == 3.2 13:50:06 INFO - moznetwork == 0.27 13:50:06 INFO - mozprocess == 0.22 13:50:06 INFO - mozprofile == 0.28 13:50:06 INFO - mozrunner == 6.11 13:50:06 INFO - mozscreenshot == 0.1 13:50:06 INFO - mozsystemmonitor == 0.3 13:50:06 INFO - moztest == 0.7 13:50:06 INFO - mozversion == 1.4 13:50:06 INFO - psutil == 3.1.1 13:50:06 INFO - requests == 1.2.3 13:50:06 INFO - wsgiref == 0.1.2 13:50:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:50:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:50:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:50:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:50:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:50:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:50:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:50:06 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 0x103036a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10303c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcbca49df20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102edad30>, '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 0x102cfedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102ed1b20>, '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.OZ4ng8EeTb/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.bYrtufKXHr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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 13:50:06 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 13:50:06 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 13:50:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OZ4ng8EeTb/Render', 13:50:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:50:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:50:06 INFO - 'HOME': '/Users/cltbld', 13:50:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:50:06 INFO - 'LOGNAME': 'cltbld', 13:50:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:50:06 INFO - 'MOZ_NO_REMOTE': '1', 13:50:06 INFO - 'NO_EM_RESTART': '1', 13:50:06 INFO - 'PAGER': '/bin/cat', 13:50:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:50:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:50:06 INFO - 'PWD': '/builds/slave/test', 13:50:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:50:06 INFO - 'SHELL': '/bin/bash', 13:50:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bYrtufKXHr/Listeners', 13:50:06 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 13:50:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:50:06 INFO - 'USER': 'cltbld', 13:50:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:50:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:50:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:50:06 INFO - 'XPC_FLAGS': '0x0', 13:50:06 INFO - 'XPC_SERVICE_NAME': '0', 13:50:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:50:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:50:06 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)) 13:50:06 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)) 13:50:06 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)) 13:50:06 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)) 13:50:06 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)) 13:50:06 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)) 13:50:06 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)) 13:50:06 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)) 13:50:06 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)) 13:50:06 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)) 13:50:06 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)) 13:50:06 INFO - Cleaning up... 13:50:07 INFO - Return code: 0 13:50:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:50:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:50:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:50:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:50:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:50:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:50:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:50:07 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 0x103036a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10303c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcbca49df20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102edad30>, '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 0x102cfedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102ed1b20>, '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.OZ4ng8EeTb/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.bYrtufKXHr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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 13:50:07 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 13:50:07 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 13:50:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OZ4ng8EeTb/Render', 13:50:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:50:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:50:07 INFO - 'HOME': '/Users/cltbld', 13:50:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:50:07 INFO - 'LOGNAME': 'cltbld', 13:50:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:50:07 INFO - 'MOZ_NO_REMOTE': '1', 13:50:07 INFO - 'NO_EM_RESTART': '1', 13:50:07 INFO - 'PAGER': '/bin/cat', 13:50:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:50:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:50:07 INFO - 'PWD': '/builds/slave/test', 13:50:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:50:07 INFO - 'SHELL': '/bin/bash', 13:50:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bYrtufKXHr/Listeners', 13:50:07 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 13:50:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:50:07 INFO - 'USER': 'cltbld', 13:50:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:50:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:50:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:50:07 INFO - 'XPC_FLAGS': '0x0', 13:50:07 INFO - 'XPC_SERVICE_NAME': '0', 13:50:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:50:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:50:07 INFO - Downloading/unpacking jsonschema 13:50: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 13:50: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 13:50: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 13:50: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 13:50: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 13:50: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 13:50:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:50:09 INFO - Downloading/unpacking functools32 (from jsonschema) 13:50:09 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 13:50:09 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 13:50:09 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 13:50:09 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 13:50:09 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 13:50:09 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 13:50:10 INFO - Downloading functools32-3.2.3-2.tar.gz 13:50:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:50:10 INFO - warning: no files found matching '*.txt' 13:50:10 INFO - no previously-included directories found matching 'build' 13:50:10 INFO - no previously-included directories found matching 'dist' 13:50:10 INFO - no previously-included directories found matching '.git*' 13:50:10 INFO - Installing collected packages: jsonschema, functools32 13:50:10 INFO - Running setup.py install for functools32 13:50:10 INFO - warning: no files found matching '*.txt' 13:50:10 INFO - no previously-included directories found matching 'build' 13:50:10 INFO - no previously-included directories found matching 'dist' 13:50:10 INFO - no previously-included directories found matching '.git*' 13:50:10 INFO - Successfully installed jsonschema functools32 13:50:10 INFO - Cleaning up... 13:50:10 INFO - Return code: 0 13:50:10 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 13:50:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:50:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:50:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:50:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:50:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:50:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:50:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103036a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10303c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcbca49df20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102edad30>, '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 0x102cfedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102ed1b20>, '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.OZ4ng8EeTb/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.bYrtufKXHr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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 13:50:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 13:50:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 13:50:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OZ4ng8EeTb/Render', 13:50:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:50:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:50:10 INFO - 'HOME': '/Users/cltbld', 13:50:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:50:10 INFO - 'LOGNAME': 'cltbld', 13:50:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:50:10 INFO - 'MOZ_NO_REMOTE': '1', 13:50:10 INFO - 'NO_EM_RESTART': '1', 13:50:10 INFO - 'PAGER': '/bin/cat', 13:50:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:50:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:50:10 INFO - 'PWD': '/builds/slave/test', 13:50:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:50:10 INFO - 'SHELL': '/bin/bash', 13:50:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bYrtufKXHr/Listeners', 13:50:10 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 13:50:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:50:10 INFO - 'USER': 'cltbld', 13:50:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:50:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:50:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:50:10 INFO - 'XPC_FLAGS': '0x0', 13:50:10 INFO - 'XPC_SERVICE_NAME': '0', 13:50:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:50:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:50:10 INFO - Downloading/unpacking flake8 13:50:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:50:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:50:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:50:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:50:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:50:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:50:13 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 13:50:13 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 13:50:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:50:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:50:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:50:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:50:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:50:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:50:13 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 13:50:13 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 13:50:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:50:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:50:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:50:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:50:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:50:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:50:13 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 13:50:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:50:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:50:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:50:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:50:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:50:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:50:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 13:50:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 13:50:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 13:50:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 13:50:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 13:50:14 INFO - no previously-included directories found matching 'docs/_build' 13:50:14 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 13:50:14 INFO - Running setup.py install for pep8 13:50:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 13:50:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 13:50:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 13:50:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 13:50:14 INFO - no previously-included directories found matching 'docs/_build' 13:50:14 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 13:50:14 INFO - Successfully installed flake8 mccabe pyflakes pep8 13:50:14 INFO - Cleaning up... 13:50:14 INFO - Return code: 0 13:50:14 INFO - Running post-action listener: _resource_record_post_action 13:50:14 INFO - Running post-action listener: _start_resource_monitoring 13:50:14 INFO - Starting resource monitoring. 13:50:14 INFO - ##### 13:50:14 INFO - ##### Finished create-virtualenv step (success) 13:50:14 INFO - ##### 13:50:14 INFO - ##### 13:50:14 INFO - ##### Running install step. 13:50:14 INFO - ##### 13:50:14 INFO - Running pre-action listener: _resource_record_pre_action 13:50:14 INFO - Running main action method: install 13:50:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:50:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:50:14 INFO - Reading from file tmpfile_stdout 13:50:14 INFO - Detecting whether we're running mozinstall >=1.0... 13:50:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:50:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:50:15 INFO - Reading from file tmpfile_stdout 13:50:15 INFO - Output received: 13:50:15 INFO - Usage: mozinstall [options] installer 13:50:15 INFO - Options: 13:50:15 INFO - -h, --help show this help message and exit 13:50:15 INFO - -d DEST, --destination=DEST 13:50:15 INFO - Directory to install application into. [default: 13:50:15 INFO - "/builds/slave/test"] 13:50:15 INFO - --app=APP Application being installed. [default: firefox] 13:50:15 INFO - mkdir: /builds/slave/test/build/application 13:50:15 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'] 13:50:15 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 13:50:49 INFO - Reading from file tmpfile_stdout 13:50:49 INFO - Output received: 13:50:49 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 13:50:49 INFO - Running post-action listener: _resource_record_post_action 13:50:49 INFO - ##### 13:50:49 INFO - ##### Finished install step (success) 13:50:49 INFO - ##### 13:50:49 INFO - ##### 13:50:49 INFO - ##### Running run-tests step. 13:50:49 INFO - ##### 13:50:49 INFO - Running pre-action listener: _resource_record_pre_action 13:50:49 INFO - Running main action method: run_tests 13:50:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:50:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:50:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:50:49 INFO - 2016-05-27 13:50:49.058 screenresolution[1921:19488] starting screenresolution argv=screenresolution get 13:50:49 INFO - 2016-05-27 13:50:49.061 screenresolution[1921:19488] Display 0: 1600x1200x32@60 13:50:49 INFO - 2016-05-27 13:50:49.066 screenresolution[1922:19490] starting screenresolution argv=screenresolution list 13:50:49 INFO - Available Modes on Display 0 13:50:49 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:50:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:50:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:50:49 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:50:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:50:49 INFO - Intel Iris: 13:50:49 INFO - Chipset Model: Intel Iris 13:50:49 INFO - Type: GPU 13:50:49 INFO - Bus: Built-In 13:50:49 INFO - VRAM (Dynamic, Max): 1536 MB 13:50:49 INFO - Vendor: Intel (0x8086) 13:50:49 INFO - Device ID: 0x0a2e 13:50:49 INFO - Revision ID: 0x0009 13:50:49 INFO - Displays: 13:50:49 INFO - Display: 13:50:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:50:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:50:49 INFO - Display Serial Number: 1600x1200 60 13:50:49 INFO - Main Display: Yes 13:50:49 INFO - Mirror: Off 13:50:49 INFO - Online: Yes 13:50:49 INFO - Rotation: Supported 13:50:49 INFO - Return code: 0 13:50:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:50:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:50:49 INFO - Python 2.7.3 13:50:49 INFO - Return code: 0 13:50:49 INFO - grabbing minidump binary from tooltool 13:50:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:50:49 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 0x102edad30>, '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 0x102cfedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102ed1b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:50:49 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 13:50:49 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 13:50:49 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 13:50:49 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:50:49 INFO - Return code: 0 13:50:49 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 13:50:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:50:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:50:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 13:50:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:50:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 13:50:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 13:50:50 INFO - Return code: 0 13:50:50 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/Ii50GD-nRfCaWbGHnihkwg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0194', '--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 13:50:50 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/Ii50GD-nRfCaWbGHnihkwg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0194 --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 13:50:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OZ4ng8EeTb/Render', 13:50:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:50:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:50:50 INFO - 'HOME': '/Users/cltbld', 13:50:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:50:50 INFO - 'LOGNAME': 'cltbld', 13:50:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:50:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 13:50:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:50:50 INFO - 'MOZ_NO_REMOTE': '1', 13:50:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:50:50 INFO - 'NO_EM_RESTART': '1', 13:50:50 INFO - 'PAGER': '/bin/cat', 13:50:50 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:50:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:50:50 INFO - 'PWD': '/builds/slave/test', 13:50:50 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:50:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:50:50 INFO - 'SHELL': '/bin/bash', 13:50:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bYrtufKXHr/Listeners', 13:50:50 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 13:50:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:50:50 INFO - 'USER': 'cltbld', 13:50:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:50:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:50:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:50:50 INFO - 'XPC_FLAGS': '0x0', 13:50:50 INFO - 'XPC_SERVICE_NAME': '0', 13:50:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:50:50 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/Ii50GD-nRfCaWbGHnihkwg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0194', '--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 13:50:50 INFO - mozversion application_buildid: 20160527122546 13:50:50 INFO - mozversion application_changeset: 6438b11898cf5775f446ac0ddcf13f9e8506b26a 13:50:50 INFO - mozversion application_display_name: Nightly 13:50:50 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:50:50 INFO - mozversion application_name: Firefox 13:50:50 INFO - mozversion application_remotingname: firefox 13:50:50 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 13:50:50 INFO - mozversion application_vendor: Mozilla 13:50:50 INFO - mozversion application_version: 49.0a1 13:50:50 INFO - mozversion platform_buildid: 20160527122546 13:50:50 INFO - mozversion platform_changeset: 6438b11898cf5775f446ac0ddcf13f9e8506b26a 13:50:50 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 13:50:50 INFO - mozversion platform_version: 49.0a1 13:50:50 INFO - using testdate: 1464382250 13:50:50 INFO - actual date: 1464382250 13:50:50 INFO - starting webserver on 'localhost:55817' 13:50:50 INFO - SUITE-START | Running 2 tests 13:50:50 INFO - TEST-START | dromaeo_css 13:50:50 INFO - operating with platform_type : mac_ 13:50:50 INFO - Initialising browser for dromaeo_css test... 13:50:50 INFO - TEST-INFO | started process 1940 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/tmpLxUdsM/profile http://localhost:55817/getInfo.html) 13:50:52 INFO - PROCESS | 1940 | __metrics Screen width/height:1600/1200 13:50:52 INFO - PROCESS | 1940 | colorDepth:24 13:50:52 INFO - PROCESS | 1940 | Browser inner width/height: 1024/689 13:50:52 INFO - PROCESS | 1940 | __metrics 13:50:52 INFO - PROCESS | 1940 | 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" 13:50:52 ERROR - PROCESS | 1940 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 13:50:53 INFO - TEST-INFO | 1940: exit 0 13:50:53 INFO - Browser initialized. 13:50:53 INFO - Running cycle 1/1 for dromaeo_css test... 13:50:53 INFO - TEST-INFO | started process 1942 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/tmpLxUdsM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:51:05 INFO - PROCESS | 1942 | RSS: Main: 241631232 13:51:05 INFO - PROCESS | 1942 | 13:51:57 INFO - PROCESS | 1942 | 1464382317050 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] 13:54:07 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:55817/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55817/tests/dromaeo/cssquery-ext.html) 13:54:08 INFO - PROCESS | 1942 | RSS: Main: 350687232 13:54:08 INFO - PROCESS | 1942 | 13:57:11 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:55817/tests/dromaeo/cssquery-ext.html (next: http://localhost:55817/tests/dromaeo/cssquery-jquery.html) 13:57:12 INFO - PROCESS | 1942 | RSS: Main: 373989376 13:57:12 INFO - PROCESS | 1942 | 14:00:14 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:55817/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55817/tests/dromaeo/cssquery-mootools.html) 14:00:14 INFO - PROCESS | 1942 | RSS: Main: 371757056 14:00:14 INFO - PROCESS | 1942 | 14:03:17 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:55817/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55817/tests/dromaeo/cssquery-prototype.html) 14:03:17 INFO - PROCESS | 1942 | RSS: Main: 372281344 14:03:17 INFO - PROCESS | 1942 | 14:06:21 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:55817/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55817/tests/dromaeo/cssquery-yui.html) 14:06:21 INFO - PROCESS | 1942 | RSS: Main: 333819904 14:06:21 INFO - PROCESS | 1942 | 14:09:24 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:55817/tests/dromaeo/cssquery-yui.html (next: http://localhost:55817/tests/dromaeo/cssquery-dojo.html) 14:09:25 INFO - PROCESS | 1942 | RSS: Main: 384225280 14:09:25 INFO - PROCESS | 1942 | 14:09:25 INFO - PROCESS | 1942 | __start_tp_report 14:09:25 INFO - PROCESS | 1942 | _x_x_mozilla_page_load 14:09:25 INFO - PROCESS | 1942 | _x_x_mozilla_page_load_details 14:09:25 INFO - PROCESS | 1942 | |i|pagename|runs| 14:09:25 INFO - PROCESS | 1942 | |0;dojo.html;3400.9489857652134;3429.0055883793684;3444.810535420552;3451.8446669899854;3503.87736429225;5530.861728456791;5595.944040559593;5609.382967873531;5640.435956404358;5687.17535957286;5601.663900165982;5645.745941432645;5720.1991721159075;5756.913646295309;5759.884802303952;5087.262346959688;5102.107131252016;5160.664556803796;5166.147585648364;5262.4211336753115;5243.580513558906;5274.077036518613;5285.709285989269;5298.0993231150605;5320.9467905321035;5112.718800465973;5138.97430512845;5143.408508021588;5187.922181167285;5208.557272631793;5076.64463487556;5077.898442031139;5078.009788091324;5126.871828204325;5204.375474942994;4316.115196384765;5101.4133374662015;5104.081265372231;5115.948840511584;5169.888473978112;5465.508104270595;5522.475364840334;5541.445855414467;5597.552195824365;5597.580181486404;6006.699665016776;6032.004719221323;6064.909026364564;6071.666058366788;6081.457322408717;6155.907661385038;6174.753009879644;6177.289611694668;6188.226471691039;6189.473894718954;5615.410381909841;5698.116791897262;5706.885862282731;5745.626534275259;5761.971190144064;6207.751689932443;6239.157713708682;6287.748490060438;6296.307406185316;6332.145160403379;6091.634501929899;6112.938870611326;6113.32753397126;6119.418655227746;6133.93949991259;6101.206843110367;6193.6903154842075;6248.4064013918605;6265.310815810257;6271.05934109878;6022.789202377896;6106.542009349317;6120.203342909657;6130.122981477994;6177.96911015442;5249.186376111708;6137.294211165773;6218.253809542884;6241.875162496724;6246.344133865879;4826.348442960226;4930.063287975273;4946.109700253989;4947.233178857283;4971.40343158816;4395.208862404797;4411.779411029435;4417.646588272931;4466.64266858657;4502.977485112553;5688;5702.914456283159;5707.828765136969;5708.9723849706625;5737.8565535861935;6075.5747097703625;6153.40011596988;6272.996320588684;6276.246850377984;6278.497720182374;5582.441755824385;5663.178839068312;5670.886582268332;5721.485066344049;5733.598648094677;5373.919391209135;5377.4084850666395;5396.352437707422;5402.37872644643;5406.810761623403;6211;6297.307296197506;6310.936890631218;6329.113924050636;6349.365063493706;5213.817516386908;6173.6913154343;6216.378362163748;6249.093881387264;6307.526935479766;6267.896913776589;6304.369563043659;6308.747650093945;6312.747490100344;6323.525735569759;5951.880962380642;5960.642361458326;6145.385461453998;6148.877022459616;6169.413905678954;6110.6195130195665;6126.731429653803;6139.584134579286;6189.597676151036;6272.466840318624;6027.78902738402;6048.60684055535;6063.909041364472;6064.97979335473;6095.359987201457;5114.667546609458;5800.6809625471005;5982.192404025401;6025.78909738157;6036.63780173191;5799.536037116936;5823.242978412949;5857.560682948711;5873.177755114373;5911.438413350725;5026.271190677258;6004.87990240184;6062.939370606237;6068.6965651716355;6071.210742603434;5770.8845823082465;5770.942290577041;5836.970815145897;5853.09277062056;5901.468867801918 14:09:25 INFO - PROCESS | 1942 | |1;ext.html;488.5114885114885;503.31549093233195;517.4928570001399;526.5550609734772;539.1319974840508;30097.097087087393;30754.692453075462;41402.378964315525;41766.791166044204;41929;41244.58755412445;44063.898402539955;44077.898052548706;44175;44179.11641767163;9636.759081022978;9869;9870.90129098707;9872.90127098727;9874.851877221847;9643.80712385752;9791.412515249072;9795.314327997043;9804;9850.70447886561;9757.853632195523;9804.558794854249;9814.362066465657;9814.46020468874;9825.557849896735;8336;9742.805143897118;9793.167580755644;9803.362781419257;9812.70561883141;9443.952780236124;9676.516174191262;9684.51577421126;9688.515574221332;9733.951330243304;40105.99735006619;40343;40493.59506404957;40540.59459405427;40662.983425414604;3454.5336379588684;3477.982610086933;3518.542589463379;3997.6237900548576;4412.933805992912;30779.538306925413;31446.842765786252;31503.36993260122;32312.19219519507;32436.18909527257;3260.788048776822;3278.229616040229;3279.3933122372437;3307.5534802801794;3350.9329813403597;500931.4953425246;510378;510546.4472677613;512034;512386;31289.061328159736;57341.71329143411;58423.70788146032;58463.70768146132;58530;35592.28815423677;35822.8208858954;35840.28319433597;35866;35936.10159745975;14662.486812961495;14842.628934276729;14843.554693359216;14854.034487758261;14854.405823767145;12319.384030798603;12417.31704756247;12419.130660853654;12419.93790031044;12467.93766031164;7815.609219539;7833.764987050397;7857.842843143219;7889.447738658238;7906.05127384706;3280.3111346617;3298.7855789373616;3299.389612921569;3309.7352211822995;3335.182880194368;953.4565297780338;1035.7203555040096;1053.1206442620344;1097.2429023973432;1178.8938995490446;23130.884345578503;27085.864570677022;30012.54981175284;30414.69585304163;31219.751209951803;1092.5738961804902;1118.1613789657756;1126.983095253572;1161.4831400027076;1172.1091970102614;14060.226687532284;14862.70274594502;15599.986000909905;15761.842381576267;16514.6697066058;654760;662250;669100;672722;679445;58840.411595883495;59288.70355648194;59291;59336;59511.40488595145;1318.5319211679907;1438.2521089033644;1462.9195394253418;1476.4463326252655;1483.5475180070182;30850;31178.844105780234;31210;31740.523892142097;32046.519302210017;49675.75162124166;49960.75019625024;50545.494545054076;50609.746951265006;50651.493485064675;403202;404361;404868;406553;407130;23748.881255593613;26116.477670446864;26582.734172658027;26615.06847260414;26629.600555992063;48522.08867467963;48562.27156592583;48977.5102248973;49447.75276123596;49555;38813;38927.80536097301;39481.80259098686;40429.79785101055;40430.19139617132;38298.425523616614;38975.80512097421;39087.550554158246;39907;39944.40083398693;38559.42160867645;38742.031449213995;39467.815965521084;39597.2080558393;41525.961850953965;31375.84312078425;32274.838625806722;34108.65891341154;34239.65760342365;34384.65615343815;23296.883515582314;24654.383640409134;24819;25048.872800723646;25537.74462255428 14:09:25 INFO - PROCESS | 1942 | |2;jquery.html;357.33178955353475;364.2060308527409;386.48597365503883;389.4508742672831;408.779259200032;19230.615387692236;20118.893460859646;21715.457113572167;21759.8032108234;21771.23800666977;12082.15424920256;12403.62789116328;12417.56538521152;12502.998560690874;16115;5402.621816472849;5565.220869078333;5568.86077848053;5603.103503439451;5613.298337707786;3924.9803750981205;4188.057687020427;5875.324337701155;6191.297393216856;6194.566380353377;6189.566730328879;6206.379362063803;6212.9068063979075;6213.285472170713;6650.201975762915;3884.6503814656817;6559.27847936729;6750.088738020402;6789.3889549940495;6798.52410331277;3999.900002499932;4012.29784787661;4043.514778226602;5584.944150558483;5949.732262048218;13324;13750.931245343809;13837.446502139903;13873.236971966538;13889.58331250054;2166.631672615659;2200.3179014505395;2275.3174047785596;2334.4397344637337;2706.201670507205;9955.153811925995;10122.29143959923;10360.533775980024;10384.896151038469;10431.791364172674;1825.8813177143443;1847.9353222637144;1868.271374164077;1902.115516284934;1963.5974625201943;115683;120396.39801800935;121287.36137915788;121741.39129304385;123482;11531.942340288246;11604.129690273263;11614.593489227836;11729.706757331134;11790.938815506646;13400.731985360433;13963.441462341596;14855.851441485447;14972.1016738996;14973;8553.272971797467;8738.30093592511;8751.824963500821;8849.433664237427;8858.468491890508;7279.745208917662;8534.658613655509;8567.74296771098;8649.178328058824;8658.523781192094;3864.478295430137;4581.221192397301;4582.106489234568;4650.627949764011;4680.3447517347595;1750.88619239749;1754.6864592254112;1828.725691146312;1915.396650055228;1950.151684017457;890.8930928288518;891.1355984694835;937.9859302110474;956.4261443134064;995.0149352141473;18475.722864157047;20257.898710506353;21159.25942592002;21395.358139255848;21418.912559996483;928.2063835420753;931.3387494878576;952.4237836109193;969.7042402067437;989.3569180033036;9545.52272386378;10936.398498082523;11325.830112548318;11340.886591134147;11374.14693897961;568864;575747;575776;604440;607257;118961.07791915468;122901;138543;143270.28364858316;147984;921.5622579274791;980.9901900981041;981.3130808434124;1013.285633628305;1029.2897900448665;16177.433789817771;17847.821521785136;18667.906660467153;19085.809141908958;19103;36126;47744.7612761934;56113;56281;56424;80072;81656.59171704104;82676;84360.31279374256;84592;7636.465447418627;7984.600769961595;8051.83896322082;8083.6766529338165;8097.8380432392205;8849.469031858109;11304.604338848101;12693.682657933625;12699.238045717286;12733.363331833556;5584.609077364708;7437.739679111205;11110.722231944266;11124.110071194124;11245.325280483197;10493.895061049292;11086.445677716243;11176.16178786578;11252.381119038531;11379.374134422354;11508.769824603294;11542.307461552333;11746.412679366169;11755.941220293844;11817.940910295394;8107.756767296991;8211.58942052907;8293.33653307722;8329.292010179202;8521.147885211429;11171.608993685182;11264.94367528157;11363.943180284046;11380.260283081574;11395.487203075716 14:09:25 INFO - PROCESS | 1942 | |3;mootools.html;1407.6762344660733;1537.6309685675435;1580.8498192671696;1790.2749386498465;1944.2806161720166;7885.80285492863;8722.956385218073;8936.240419564328;8940.105989401056;9061.048589898064;8616.526091064989;8847.469151850893;8849.601767920443;8850;8853.424527405714;2103.4320733401973;2315.988420057897;2357.092519380038;2390.5457962986975;2395.772401621843;2393.1265088242753;2425.19968410425;2426.369144022558;2429.465517586128;2430.4531480416854;2327.767223277676;2361.3624321433203;2363.9054437822465;2365.6569797379457;2390.557746816836;2086.1446806809286;2148.903299351533;2231.509068005036;2307.5038866643754;2347.812175025994;2349.177787774265;2373.7982271506944;2376.1327115602853;2377.5244951009868;2378.191414918919;8746.56267186638;8781.341399395007;8783.64865405383;8788.604512796875;8804.47173169612;5206.817761378372;5347.919781203285;5352;5353.437889021636;5361.678299302015;3179.4753865612147;3492.3189977954307;3647.2705458908326;3651.6713495785243;3651.908702282438;1045.4354648489807;1083.5828206140613;1085.6960051185602;1090.1496832470687;1102.5755084292525;11577.073834093337;11608.24546404481;11618.302901825917;11626.941865290704;11666.59166929162;4673.686525170063;4704.153252414599;4848;4848.806047758121;4851.587615052689;9933.403995760278;9939.50302484873;9940.950295248478;9944.602215911282;9951.203903687687;5273.736313184402;5347;5369.543588794996;5372.1941708744;5380.3274590676165;4398.8020539075815;4552.271636538138;4782.354382158364;4923.704577725276;4926.630502712311;3933.5476420212044;3971.8212680428874;4042.110735638155;4073.89815254621;4087.4073259377224;1094.3816743539874;1278.974420511603;1293.9676508087373;1303.5111833062601;1308.4177540994167;824.2458150792078;916.2532535983153;959.9568019439142;979.2508730820928;979.5445118020154;5244.868878278074;5737.3975732548315;5809.970950145223;6050.818475445743;6235.96882015587;925.7593025813337;972.3825370889537;974.91713204377;978.7406337320616;1002.2583288366702;3948.8617898373996;4092.935836682484;4105.609967053125;4110.547839737626;4168.9994401344065;10528.684139475828;10619.09737672291;10952.616658416917;10963.287386319864;10979.231453798158;10847.945760271306;10848.457577121113;10850;10853.403062831461;10858.34849909008;7069.469789765685;7851.568163750934;8274.379421543408;8364.289035432057;8369.623366948503;5827.679477628772;5945.64326140433;5964.8807023860145;6017.157597936205;6029.517638588814;9929.453880036668;9949.104580587782;10083.344582602107;10089;10118.848217276895;8839.204471597586;9976.20190384782;10204.489775511343;10284.023017813295;10417.270791044857;6621.569597976309;6821.556598821259;6995.4609985803045;7113.928860711534;7282.67227974753;8507.40448168647;8636.654533818577;8715.389922705603;8715.651373944971;8722.563871806258;7564.546127232384;7735;7803.882941755992;7863.488873223222;7893.684252630061;7468.850622987618;7555.584442855476;7591.316781489692;7725.768226953201;7760.417968652261;7174.856502870018;7232.4575656827;7252.782416527301;7257.129144502483;7275.781726548212;4962.181240095489;5068.467810879952;5099.439061703284;5155.123628983007;5164.1995490699;5137.33214682089;5193.350831146106;5232.895342093213;5244.632875698817;5304.336957880265 14:09:25 INFO - PROCESS | 1942 | |4;prototype.html;297.724604740615;302.38012075245746;312.3175860744273;315.04070106525654;316.6058257469451;4790.6407019473545;4808.591269742072;5010.674306170105;5027.396712394513;5070.518300761431;4539.954600453994;4559.677693468899;4936.506349365061;4958.702477851331;5224.242484839706;3810.485584446106;3985.32982901456;4000.3199456092534;4002.019505221225;4013.0368711509172;4011.6790656747535;4013.9799301003454;4030.899227519321;4035.1122752994293;4041.454403655513;3663.3148751574176;3693.8153092345274;3955.7428767130186;3965.2267807777494;4037.9798101009455;3175.4601717708047;3867.9556519739513;3916.3929590913453;3978.781167035812;3985.282649123142;2878.784091193169;2931.721486458783;3422.5892892852767;3431.5367425397503;3464.4630082337276;4542.387452454364;4972.458537853241;5061.493850614946;5074.619403544749;5098.515641014134;3423.8288085595623;4540.909181816378;4543.136804007261;4558.703684260546;4590.242609969351;4617.21507343749;4799.912038263332;5495;5500.50495455407;5528.6406383585345;3786.280606684721;3797.259534390795;3799.5060642116623;3805.7335986480957;3816.713746469026;5293.920591191135;5381.865453363658;5637.238972738669;5724.4561766632105;5794.420557944172;3155.3846999834814;3644.2893635740775;3646.58064322601;3810.5046343975105;3834;3842.654161125512;3868.787216703052;3892.163184915257;3895.2209558088493;3896.7077469189926;4261.97869010653;4436.844710435119;4515.277555591089;4556.726596404226;4564.6348292136545;4567.954320456819;5148.124818780816;5152.536271735562;5210.557102646242;5514.393416724157;1303.7848754955326;1312.9212247265195;1325.681836359286;1328.8338957630297;1330.7158591958805;737.6201256352982;781.1992707474884;795.7612716185237;813.9267465928095;816.6488409983646;3719.330520506335;4120.7733574653685;4233.724807887539;4263.339182426729;4321.503027151855;4222.218889505451;4339.717918335361;4682.157211701926;4807.053143476897;4822.853830045666;3741.644543768338;4146.91706165875;4154.729942553724;4220.704550681484;4326.697131200785;2361.6811730416175;2464.1745015420033;2975.8363290018824;2990.7607391408637;3010.1872494426384;5239.083160446907;5691.820443396489;5868.823935281863;5949.876314934608;5980.906682661121;5156.690598564098;5357.732113394315;5442.4148529448985;5913.586048976616;5934.525237980951;5702.97346477638;5896.317175684967;5908.202392676933;5941.851453713605;5959.837831622878;4869.6591238612955;5653.830385088289;5687.289088863892;5727.31272247333;5727.599068065362;4403.493598236244;5421.783128674809;5570.359408667893;5593.972030139823;5671.801486947937;5295.152775555893;5328.547073498796;5885.352611212677;5923.452915246486;5967.522598192221;3359.126627076929;3630.5280313560124;3906.570277269555;3917.6865850732443;3934.468846705658;4842.903141937212;5652.689102099424;5669.461401166883;5678.3186017678145;5684.744186511534;4878.341423907869;5319.973400133129;5499.615026948075;5500.944990550043;5545.306836645419;4814.446338670958;5471.534919531884;5480.80817171413;5495.038368285614;5498.505134537751;4772.570468657837;5372.018529716045;5378.2739330189925;5378.381486128988;5389.568834493152;4658.86023419298;5160.251763494347;5481.917771233355;5501.834944951658;5519.862003449785;4725.8582242532775;4781.282807578891;5053.166227572409;5343.435151127558;5385.953337731637 14:09:25 INFO - PROCESS | 1942 | |5;yui.html;709.8367375503634;722.9421646268299;794.3843521271012;798.5009369144285;821.6302663801288;14482.493112741056;15217.163056031914;15229.01011434256;15253.008554443953;15285.388584456623;13824.516141935035;13844.030917835755;13871.861281387182;13888.722225555508;13900;719.7552832037106;740.9184989651134;743.3495691270139;744.5941961630897;889.3863234368298;729.6242435145904;866.6836604639325;902.5036230073466;902.5532361481044;908.3686837647838;939.7509659940088;953.5851904421564;956;957.3872721458272;958.511159308754;950.2113246005798;951.5575257505259;951.6574033347989;952.3095755577186;952.471378384996;551.9144532597413;573.4237091722833;573.4294377094817;573.4781348972457;687.5427840486071;14349.497767578085;14647.780283295653;14729.484468043567;14750.188739619316;14751.631209219748;13425.798613020812;13722.313884305844;13731.45074197031;13847.099938504065;14033.508827190999;11010.559577616887;11884.821727674092;12279.386030698519;12283.57007504733;12355.32045737484;4526.841560545398;4547.567981041796;4553.203189441834;4726.6927649703;4760.1907675694865;86108.56945715295;86717.56641216816;87092;87854.12145878587;88863.55568222118;10873.293235939638;10933.18001149917;11064;11065.612703555336;11460;17827.554311142325;21410.78589214119;21414.143434262765;21426.250081247083;21447.571048578942;7914.841703166019;7928.6828526859435;7998.520088794575;8024.679012839538;8248.505089694638;4399.890002749893;4464.15181115587;4472.865814025585;4489.438820147481;4642.141203877293;3356.590232102523;3399.473081672345;3453.430184019659;3460.8788692395647;3461.6192218855904;660.6267458885715;664.441868830185;668.5275506859097;668.812732434919;669.3239827773983;539.7139516056496;603.9883195647274;615.6767696959132;661.7783042680755;668.2782594797704;8823.470591764386;8827.704368528186;8851.955740221258;8906.332025098143;9262.67580634688;585.9003969325225;632.6615260835431;634.3974465254548;645.6707079389545;664.2925284571916;4234.703570750018;4237.957620423818;4300.182965236595;4307.892302692458;4316.201502722007;72856.63571682214;95082.04917950732;97048;97530;98020;74664;76614;77687.61156194183;77969;78438;72071;72576;73538;73947.52104957978;74851;9172.816543669223;9391.20174785151;9501.667441639509;9524.333296669167;9595.760105997128;44345;45466.31800523061;46308.7684561575;46340.30489542727;46467.76766116148;62553;63080.68459657672;63264.102076937765;63273.683631581545;65982.67008664926;12341.321227332583;12486.937565312115;12615.810762838748;12663.430145643653;12881.420336085086;13902.582922512342;14111.858881411465;14194.077384969944;14245.430182792572;14493.130412174882;11707.010750115589;12085.456154472893;12133.09001824849;12261.276658987605;12276.263424194576;13036.674083147618;13166;13282.40229189708;13334.933205343743;13847;12912.548060818202;12987.675308117372;13028.934855325662;13336.599902002956;13466.93266533661;10686.465676715978;10716.249862509549;10943.945280273547;10971.177161712743;11030.724231894266;10781.784364312827;10907.945460272647;10971;11024.724381890517;11038.50326735315 14:09:25 INFO - PROCESS | 1942 | __end_tp_report 14:09:25 INFO - PROCESS | 1942 | __start_cc_report 14:09:25 INFO - PROCESS | 1942 | _x_x_mozilla_cycle_collect,1120 14:09:25 INFO - PROCESS | 1942 | __end_cc_report 14:09:25 INFO - PROCESS | 1942 | __startTimestamp1464383365007__endTimestamp 14:09:25 INFO - PROCESS | 1942 | 14:09:25 INFO - PROCESS | 1942 | ------- Summary: start ------- 14:09:25 INFO - PROCESS | 1942 | Number of tests: 6 14:09:25 INFO - PROCESS | 1942 | 14:09:25 INFO - PROCESS | 1942 | [#0] dojo.html Cycles:165 Average:5674.13 Median:5800.68 stddev:625.96 (10.8%) stddev-sans-first:601.94 14:09:25 INFO - PROCESS | 1942 | Values: 3400.9 3429.0 3444.8 3451.8 3503.9 5530.9 5595.9 5609.4 5640.4 5687.2 5601.7 5645.7 5720.2 5756.9 5759.9 5087.3 5102.1 5160.7 5166.1 5262.4 5243.6 5274.1 5285.7 5298.1 5320.9 5112.7 5139.0 5143.4 5187.9 5208.6 5076.6 5077.9 5078.0 5126.9 5204.4 4316.1 5101.4 5104.1 5115.9 5169.9 5465.5 5522.5 5541.4 5597.6 5597.6 6006.7 6032.0 6064.9 6071.7 6081.5 6155.9 6174.8 6177.3 6188.2 6189.5 5615.4 5698.1 5706.9 5745.6 5762.0 6207.8 6239.2 6287.7 6296.3 6332.1 6091.6 6112.9 6113.3 6119.4 6133.9 6101.2 6193.7 6248.4 6265.3 6271.1 6022.8 6106.5 6120.2 6130.1 6178.0 5249.2 6137.3 6218.3 6241.9 6246.3 4826.3 4930.1 4946.1 4947.2 4971.4 4395.2 4411.8 4417.6 4466.6 4503.0 5688.0 5702.9 5707.8 5709.0 5737.9 6075.6 6153.4 6273.0 6276.2 6278.5 5582.4 5663.2 5670.9 5721.5 5733.6 5373.9 5377.4 5396.4 5402.4 5406.8 6211.0 6297.3 6310.9 6329.1 6349.4 5213.8 6173.7 6216.4 6249.1 6307.5 6267.9 6304.4 6308.7 6312.7 6323.5 5951.9 5960.6 6145.4 6148.9 6169.4 6110.6 6126.7 6139.6 6189.6 6272.5 6027.8 6048.6 6063.9 6065.0 6095.4 5114.7 5800.7 5982.2 6025.8 6036.6 5799.5 5823.2 5857.6 5873.2 5911.4 5026.3 6004.9 6062.9 6068.7 6071.2 5770.9 5770.9 5837.0 5853.1 5901.5 14:09:25 INFO - PROCESS | 1942 | 14:09:25 INFO - PROCESS | 1942 | [#1] ext.html Cycles:180 Average:65494.03 Median:39925.70 stddev:144122.99 (361.0%) stddev-sans-first:144444.65 14:09:25 INFO - PROCESS | 1942 | Values: 488.5 503.3 517.5 526.6 539.1 30097.1 30754.7 41402.4 41766.8 41929.0 41244.6 44063.9 44077.9 44175.0 44179.1 9636.8 9869.0 9870.9 9872.9 9874.9 9643.8 9791.4 9795.3 9804.0 9850.7 9757.9 9804.6 9814.4 9814.5 9825.6 8336.0 9742.8 9793.2 9803.4 9812.7 9444.0 9676.5 9684.5 9688.5 9734.0 40106.0 40343.0 40493.6 40540.6 40663.0 3454.5 3478.0 3518.5 3997.6 4412.9 30779.5 31446.8 31503.4 32312.2 32436.2 3260.8 3278.2 3279.4 3307.6 3350.9 500931.5 510378.0 510546.4 512034.0 512386.0 31289.1 57341.7 58423.7 58463.7 58530.0 35592.3 35822.8 35840.3 35866.0 35936.1 14662.5 14842.6 14843.6 14854.0 14854.4 12319.4 12417.3 12419.1 12419.9 12467.9 7815.6 7833.8 7857.8 7889.4 7906.1 3280.3 3298.8 3299.4 3309.7 3335.2 953.5 1035.7 1053.1 1097.2 1178.9 23130.9 27085.9 30012.5 30414.7 31219.8 1092.6 1118.2 1127.0 1161.5 1172.1 14060.2 14862.7 15600.0 15761.8 16514.7 654760.0 662250.0 669100.0 672722.0 679445.0 58840.4 59288.7 59291.0 59336.0 59511.4 1318.5 1438.3 1462.9 1476.4 1483.5 30850.0 31178.8 31210.0 31740.5 32046.5 49675.8 49960.8 50545.5 50609.7 50651.5 403202.0 404361.0 404868.0 406553.0 407130.0 23748.9 26116.5 26582.7 26615.1 26629.6 48522.1 48562.3 48977.5 49447.8 49555.0 38813.0 38927.8 39481.8 40429.8 40430.2 38298.4 38975.8 39087.6 39907.0 39944.4 38559.4 38742.0 39467.8 39597.2 41526.0 31375.8 32274.8 34108.7 34239.7 34384.7 23296.9 24654.4 24819.0 25048.9 25537.7 14:09:25 INFO - PROCESS | 1942 | 14:09:25 INFO - PROCESS | 1942 | [#2] jquery.html Cycles:180 Average:34398.60 Median:2520.32 stddev:98441.10 (3905.9%) stddev-sans-first:98684.07 14:09:25 INFO - PROCESS | 1942 | Values: 357.3 364.2 386.5 389.5 408.8 19230.6 20118.9 21715.5 21759.8 21771.2 12082.2 12403.6 12417.6 12503.0 16115.0 5402.6 5565.2 5568.9 5603.1 5613.3 3925.0 4188.1 5875.3 6191.3 6194.6 6189.6 6206.4 6212.9 6213.3 6650.2 3884.7 6559.3 6750.1 6789.4 6798.5 3999.9 4012.3 4043.5 5584.9 5949.7 13324.0 13750.9 13837.4 13873.2 13889.6 2166.6 2200.3 2275.3 2334.4 2706.2 9955.2 10122.3 10360.5 10384.9 10431.8 1825.9 1847.9 1868.3 1902.1 1963.6 115683.0 120396.4 121287.4 121741.4 123482.0 11531.9 11604.1 11614.6 11729.7 11790.9 13400.7 13963.4 14855.9 14972.1 14973.0 8553.3 8738.3 8751.8 8849.4 8858.5 7279.7 8534.7 8567.7 8649.2 8658.5 3864.5 4581.2 4582.1 4650.6 4680.3 1750.9 1754.7 1828.7 1915.4 1950.2 890.9 891.1 938.0 956.4 995.0 18475.7 20257.9 21159.3 21395.4 21418.9 928.2 931.3 952.4 969.7 989.4 9545.5 10936.4 11325.8 11340.9 11374.1 568864.0 575747.0 575776.0 604440.0 607257.0 118961.1 122901.0 138543.0 143270.3 147984.0 921.6 981.0 981.3 1013.3 1029.3 16177.4 17847.8 18667.9 19085.8 19103.0 36126.0 47744.8 56113.0 56281.0 56424.0 80072.0 81656.6 82676.0 84360.3 84592.0 7636.5 7984.6 8051.8 8083.7 8097.8 8849.5 11304.6 12693.7 12699.2 12733.4 5584.6 7437.7 11110.7 11124.1 11245.3 10493.9 11086.4 11176.2 11252.4 11379.4 11508.8 11542.3 11746.4 11755.9 11817.9 8107.8 8211.6 8293.3 8329.3 8521.1 11171.6 11264.9 11363.9 11380.3 11395.5 14:09:25 INFO - PROCESS | 1942 | 14:09:25 INFO - PROCESS | 1942 | [#3] mootools.html Cycles:180 Average:5755.87 Median:5146.23 stddev:3213.37 (62.4%) stddev-sans-first:3205.77 14:09:25 INFO - PROCESS | 1942 | Values: 1407.7 1537.6 1580.8 1790.3 1944.3 7885.8 8723.0 8936.2 8940.1 9061.0 8616.5 8847.5 8849.6 8850.0 8853.4 2103.4 2316.0 2357.1 2390.5 2395.8 2393.1 2425.2 2426.4 2429.5 2430.5 2327.8 2361.4 2363.9 2365.7 2390.6 2086.1 2148.9 2231.5 2307.5 2347.8 2349.2 2373.8 2376.1 2377.5 2378.2 8746.6 8781.3 8783.6 8788.6 8804.5 5206.8 5347.9 5352.0 5353.4 5361.7 3179.5 3492.3 3647.3 3651.7 3651.9 1045.4 1083.6 1085.7 1090.1 1102.6 11577.1 11608.2 11618.3 11626.9 11666.6 4673.7 4704.2 4848.0 4848.8 4851.6 9933.4 9939.5 9941.0 9944.6 9951.2 5273.7 5347.0 5369.5 5372.2 5380.3 4398.8 4552.3 4782.4 4923.7 4926.6 3933.5 3971.8 4042.1 4073.9 4087.4 1094.4 1279.0 1294.0 1303.5 1308.4 824.2 916.3 960.0 979.3 979.5 5244.9 5737.4 5810.0 6050.8 6236.0 925.8 972.4 974.9 978.7 1002.3 3948.9 4092.9 4105.6 4110.5 4169.0 10528.7 10619.1 10952.6 10963.3 10979.2 10847.9 10848.5 10850.0 10853.4 10858.3 7069.5 7851.6 8274.4 8364.3 8369.6 5827.7 5945.6 5964.9 6017.2 6029.5 9929.5 9949.1 10083.3 10089.0 10118.8 8839.2 9976.2 10204.5 10284.0 10417.3 6621.6 6821.6 6995.5 7113.9 7282.7 8507.4 8636.7 8715.4 8715.7 8722.6 7564.5 7735.0 7803.9 7863.5 7893.7 7468.9 7555.6 7591.3 7725.8 7760.4 7174.9 7232.5 7252.8 7257.1 7275.8 4962.2 5068.5 5099.4 5155.1 5164.2 5137.3 5193.4 5232.9 5244.6 5304.3 14:09:25 INFO - PROCESS | 1942 | 14:09:25 INFO - PROCESS | 1942 | [#4] prototype.html Cycles:180 Average:4356.74 Median:4749.21 stddev:1355.27 (28.5%) stddev-sans-first:1324.38 14:09:25 INFO - PROCESS | 1942 | Values: 297.7 302.4 312.3 315.0 316.6 4790.6 4808.6 5010.7 5027.4 5070.5 4540.0 4559.7 4936.5 4958.7 5224.2 3810.5 3985.3 4000.3 4002.0 4013.0 4011.7 4014.0 4030.9 4035.1 4041.5 3663.3 3693.8 3955.7 3965.2 4038.0 3175.5 3868.0 3916.4 3978.8 3985.3 2878.8 2931.7 3422.6 3431.5 3464.5 4542.4 4972.5 5061.5 5074.6 5098.5 3423.8 4540.9 4543.1 4558.7 4590.2 4617.2 4799.9 5495.0 5500.5 5528.6 3786.3 3797.3 3799.5 3805.7 3816.7 5293.9 5381.9 5637.2 5724.5 5794.4 3155.4 3644.3 3646.6 3810.5 3834.0 3842.7 3868.8 3892.2 3895.2 3896.7 4262.0 4436.8 4515.3 4556.7 4564.6 4568.0 5148.1 5152.5 5210.6 5514.4 1303.8 1312.9 1325.7 1328.8 1330.7 737.6 781.2 795.8 813.9 816.6 3719.3 4120.8 4233.7 4263.3 4321.5 4222.2 4339.7 4682.2 4807.1 4822.9 3741.6 4146.9 4154.7 4220.7 4326.7 2361.7 2464.2 2975.8 2990.8 3010.2 5239.1 5691.8 5868.8 5949.9 5980.9 5156.7 5357.7 5442.4 5913.6 5934.5 5703.0 5896.3 5908.2 5941.9 5959.8 4869.7 5653.8 5687.3 5727.3 5727.6 4403.5 5421.8 5570.4 5594.0 5671.8 5295.2 5328.5 5885.4 5923.5 5967.5 3359.1 3630.5 3906.6 3917.7 3934.5 4842.9 5652.7 5669.5 5678.3 5684.7 4878.3 5320.0 5499.6 5500.9 5545.3 4814.4 5471.5 5480.8 5495.0 5498.5 4772.6 5372.0 5378.3 5378.4 5389.6 4658.9 5160.3 5481.9 5501.8 5519.9 4725.9 4781.3 5053.2 5343.4 5386.0 14:09:25 INFO - PROCESS | 1942 | 14:09:25 INFO - PROCESS | 1942 | [#5] yui.html Cycles:180 Average:18823.06 Median:25491.22 stddev:25812.53 (101.3%) stddev-sans-first:25849.11 14:09:25 INFO - PROCESS | 1942 | Values: 709.8 722.9 794.4 798.5 821.6 14482.5 15217.2 15229.0 15253.0 15285.4 13824.5 13844.0 13871.9 13888.7 13900.0 719.8 740.9 743.3 744.6 889.4 729.6 866.7 902.5 902.6 908.4 939.8 953.6 956.0 957.4 958.5 950.2 951.6 951.7 952.3 952.5 551.9 573.4 573.4 573.5 687.5 14349.5 14647.8 14729.5 14750.2 14751.6 13425.8 13722.3 13731.5 13847.1 14033.5 11010.6 11884.8 12279.4 12283.6 12355.3 4526.8 4547.6 4553.2 4726.7 4760.2 86108.6 86717.6 87092.0 87854.1 88863.6 10873.3 10933.2 11064.0 11065.6 11460.0 17827.6 21410.8 21414.1 21426.3 21447.6 7914.8 7928.7 7998.5 8024.7 8248.5 4399.9 4464.2 4472.9 4489.4 4642.1 3356.6 3399.5 3453.4 3460.9 3461.6 660.6 664.4 668.5 668.8 669.3 539.7 604.0 615.7 661.8 668.3 8823.5 8827.7 8852.0 8906.3 9262.7 585.9 632.7 634.4 645.7 664.3 4234.7 4238.0 4300.2 4307.9 4316.2 72856.6 95082.0 97048.0 97530.0 98020.0 74664.0 76614.0 77687.6 77969.0 78438.0 72071.0 72576.0 73538.0 73947.5 74851.0 9172.8 9391.2 9501.7 9524.3 9595.8 44345.0 45466.3 46308.8 46340.3 46467.8 62553.0 63080.7 63264.1 63273.7 65982.7 12341.3 12486.9 12615.8 12663.4 12881.4 13902.6 14111.9 14194.1 14245.4 14493.1 11707.0 12085.5 12133.1 12261.3 12276.3 13036.7 13166.0 13282.4 13334.9 13847.0 12912.5 12987.7 13028.9 13336.6 13466.9 10686.5 10716.2 10943.9 10971.2 11030.7 10781.8 10907.9 10971.0 11024.7 11038.5 14:09:25 INFO - PROCESS | 1942 | -------- Summary: end -------- 14:09:25 INFO - PROCESS | 1942 | 14:09:25 INFO - TEST-INFO | 1942: exit 0 14:09:25 INFO - TEST-OK | dromaeo_css | took 1114859ms 14:09:25 INFO - TEST-START | kraken 14:09:25 INFO - operating with platform_type : mac_ 14:09:25 INFO - Initialising browser for kraken test... 14:09:25 INFO - TEST-INFO | started process 1946 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/tmp_tITV9/profile http://localhost:55817/getInfo.html) 14:09:27 INFO - PROCESS | 1946 | __metrics Screen width/height:1600/1200 14:09:27 INFO - PROCESS | 1946 | colorDepth:24 14:09:27 INFO - PROCESS | 1946 | Browser inner width/height: 1024/689 14:09:27 INFO - PROCESS | 1946 | __metrics 14:09:27 INFO - PROCESS | 1946 | 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" 14:09:27 ERROR - PROCESS | 1946 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 14:09:28 INFO - TEST-INFO | 1946: exit 0 14:09:28 INFO - Browser initialized. 14:09:28 INFO - Running cycle 1/1 for kraken test... 14:09:28 INFO - TEST-INFO | started process 1947 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/tmp_tITV9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:09:40 INFO - PROCESS | 1947 | RSS: Main: 241590272 14:09:40 INFO - PROCESS | 1947 | 14:09:43 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:55817/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55817/tests/kraken/driver.html?testName=audio-beat-detection) 14:09:43 INFO - PROCESS | 1947 | RSS: Main: 291553280 14:09:43 INFO - PROCESS | 1947 | 14:09:46 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:55817/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55817/tests/kraken/driver.html?testName=audio-dft) 14:09:47 INFO - PROCESS | 1947 | RSS: Main: 300867584 14:09:47 INFO - PROCESS | 1947 | 14:09:50 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:55817/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55817/tests/kraken/driver.html?testName=audio-fft) 14:09:51 INFO - PROCESS | 1947 | RSS: Main: 386985984 14:09:51 INFO - PROCESS | 1947 | 14:09:53 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:55817/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55817/tests/kraken/driver.html?testName=audio-oscillator) 14:09:53 INFO - PROCESS | 1947 | RSS: Main: 288722944 14:09:53 INFO - PROCESS | 1947 | 14:09:55 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:55817/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55817/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:09:56 INFO - PROCESS | 1947 | RSS: Main: 293826560 14:09:56 INFO - PROCESS | 1947 | 14:09:59 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:55817/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55817/tests/kraken/driver.html?testName=imaging-darkroom) 14:10:00 INFO - PROCESS | 1947 | RSS: Main: 330027008 14:10:00 INFO - PROCESS | 1947 | 14:10:02 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:55817/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55817/tests/kraken/driver.html?testName=imaging-desaturate) 14:10:03 INFO - PROCESS | 1947 | RSS: Main: 329928704 14:10:03 INFO - PROCESS | 1947 | 14:10:06 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:55817/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55817/tests/kraken/driver.html?testName=json-parse-financial) 14:10:06 INFO - PROCESS | 1947 | RSS: Main: 330575872 14:10:06 INFO - PROCESS | 1947 | 14:10:08 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:55817/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55817/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:10:09 INFO - PROCESS | 1947 | RSS: Main: 309202944 14:10:09 INFO - PROCESS | 1947 | 14:10:10 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:55817/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55817/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:10:11 INFO - PROCESS | 1947 | RSS: Main: 316276736 14:10:11 INFO - PROCESS | 1947 | 14:10:13 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:55817/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55817/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:10:14 INFO - PROCESS | 1947 | RSS: Main: 321208320 14:10:14 INFO - PROCESS | 1947 | 14:10:17 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:55817/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55817/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:10:17 INFO - PROCESS | 1947 | RSS: Main: 322265088 14:10:17 INFO - PROCESS | 1947 | 14:10:20 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:55817/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55817/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:10:21 INFO - PROCESS | 1947 | RSS: Main: 322904064 14:10:21 INFO - PROCESS | 1947 | 14:10:22 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:55817/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55817/tests/kraken/driver.html?testName=ai-astar) 14:10:23 INFO - PROCESS | 1947 | RSS: Main: 366366720 14:10:23 INFO - PROCESS | 1947 | 14:10:23 INFO - PROCESS | 1947 | __start_tp_report 14:10:23 INFO - PROCESS | 1947 | _x_x_mozilla_page_load 14:10:23 INFO - PROCESS | 1947 | _x_x_mozilla_page_load_details 14:10:23 INFO - PROCESS | 1947 | |i|pagename|runs| 14:10:23 INFO - PROCESS | 1947 | |0;ai-astar;113;108;112;111;112;112;112;113;112;112 14:10:23 INFO - PROCESS | 1947 | |1;audio-beat-detection;156;125;121;168;118;120;151;111;172;117 14:10:23 INFO - PROCESS | 1947 | |2;audio-dft;183;185;184;182;183;181;183;181;182;185 14:10:23 INFO - PROCESS | 1947 | |3;audio-fft;82;58;59;84;57;57;65;64;82;55 14:10:23 INFO - PROCESS | 1947 | |4;audio-oscillator;86;68;102;70;72;69;120;100;98;101 14:10:23 INFO - PROCESS | 1947 | |5;imaging-gaussian-blur;90;130;87;85;87;84;87;85;87;84 14:10:23 INFO - PROCESS | 1947 | |6;imaging-darkroom;93;94;95;93;94;94;94;94;94;93 14:10:23 INFO - PROCESS | 1947 | |7;imaging-desaturate;86;87;87;86;85;83;86;84;86;86 14:10:23 INFO - PROCESS | 1947 | |8;json-parse-financial;57;56;52;52;56;53;53;55;54;53 14:10:23 INFO - PROCESS | 1947 | |9;json-stringify-tinderbox;66;66;74;75;75;73;70;70;72;72 14:10:23 INFO - PROCESS | 1947 | |10;stanford-crypto-aes;84;83;86;82;85;85;86;87;119;87 14:10:23 INFO - PROCESS | 1947 | |11;stanford-crypto-ccm;141;135;139;141;139;137;134;137;152;139 14:10:23 INFO - PROCESS | 1947 | |12;stanford-crypto-pbkdf2;165;164;165;170;164;165;164;167;162;166 14:10:23 INFO - PROCESS | 1947 | |13;stanford-crypto-sha256-iterative;65;67;66;66;68;65;67;66;67;78 14:10:23 INFO - PROCESS | 1947 | __end_tp_report 14:10:23 INFO - PROCESS | 1947 | __start_cc_report 14:10:23 INFO - PROCESS | 1947 | _x_x_mozilla_cycle_collect,1907 14:10:23 INFO - PROCESS | 1947 | __end_cc_report 14:10:23 INFO - PROCESS | 1947 | __startTimestamp1464383423209__endTimestamp 14:10:23 INFO - PROCESS | 1947 | 14:10:23 INFO - PROCESS | 1947 | ------- Summary: start ------- 14:10:23 INFO - PROCESS | 1947 | Number of tests: 14 14:10:23 INFO - PROCESS | 1947 | 14:10:23 INFO - PROCESS | 1947 | [#0] ai-astar Cycles:10 Average:111.70 Median:112.00 stddev:1.42 (1.3%) stddev-sans-first:1.42 14:10:23 INFO - PROCESS | 1947 | Values: 113.0 108.0 112.0 111.0 112.0 112.0 112.0 113.0 112.0 112.0 14:10:23 INFO - PROCESS | 1947 | 14:10:23 INFO - PROCESS | 1947 | [#1] audio-beat-detection Cycles:10 Average:135.90 Median:138.00 stddev:23.23 (16.8%) stddev-sans-first:23.47 14:10:23 INFO - PROCESS | 1947 | Values: 156.0 125.0 121.0 168.0 118.0 120.0 151.0 111.0 172.0 117.0 14:10:23 INFO - PROCESS | 1947 | 14:10:23 INFO - PROCESS | 1947 | [#2] audio-dft Cycles:10 Average:182.90 Median:183.00 stddev:1.45 (0.8%) stddev-sans-first:1.54 14:10:23 INFO - PROCESS | 1947 | Values: 183.0 185.0 184.0 182.0 183.0 181.0 183.0 181.0 182.0 185.0 14:10:23 INFO - PROCESS | 1947 | 14:10:23 INFO - PROCESS | 1947 | [#3] audio-fft Cycles:10 Average:66.30 Median:64.50 stddev:11.72 (18.2%) stddev-sans-first:10.97 14:10:23 INFO - PROCESS | 1947 | Values: 82.0 58.0 59.0 84.0 57.0 57.0 65.0 64.0 82.0 55.0 14:10:23 INFO - PROCESS | 1947 | 14:10:23 INFO - PROCESS | 1947 | [#4] audio-oscillator Cycles:10 Average:88.60 Median:69.50 stddev:18.18 (26.2%) stddev-sans-first:19.26 14:10:23 INFO - PROCESS | 1947 | Values: 86.0 68.0 102.0 70.0 72.0 69.0 120.0 100.0 98.0 101.0 14:10:23 INFO - PROCESS | 1947 | 14:10:23 INFO - PROCESS | 1947 | [#5] imaging-gaussian-blur Cycles:10 Average:90.60 Median:87.00 stddev:13.96 (16.0%) stddev-sans-first:14.81 14:10:23 INFO - PROCESS | 1947 | Values: 90.0 130.0 87.0 85.0 87.0 84.0 87.0 85.0 87.0 84.0 14:10:23 INFO - PROCESS | 1947 | 14:10:23 INFO - PROCESS | 1947 | [#6] imaging-darkroom Cycles:10 Average:93.80 Median:94.00 stddev:0.63 (0.7%) stddev-sans-first:0.60 14:10:23 INFO - PROCESS | 1947 | Values: 93.0 94.0 95.0 93.0 94.0 94.0 94.0 94.0 94.0 93.0 14:10:23 INFO - PROCESS | 1947 | 14:10:23 INFO - PROCESS | 1947 | [#7] imaging-desaturate Cycles:10 Average:85.60 Median:86.00 stddev:1.26 (1.5%) stddev-sans-first:1.33 14:10:23 INFO - PROCESS | 1947 | Values: 86.0 87.0 87.0 86.0 85.0 83.0 86.0 84.0 86.0 86.0 14:10:23 INFO - PROCESS | 1947 | 14:10:23 INFO - PROCESS | 1947 | [#8] json-parse-financial Cycles:10 Average:54.10 Median:54.50 stddev:1.79 (3.3%) stddev-sans-first:1.56 14:10:23 INFO - PROCESS | 1947 | Values: 57.0 56.0 52.0 52.0 56.0 53.0 53.0 55.0 54.0 53.0 14:10:23 INFO - PROCESS | 1947 | 14:10:23 INFO - PROCESS | 1947 | [#9] json-stringify-tinderbox Cycles:10 Average:71.30 Median:72.50 stddev:3.30 (4.6%) stddev-sans-first:2.89 14:10:23 INFO - PROCESS | 1947 | Values: 66.0 66.0 74.0 75.0 75.0 73.0 70.0 70.0 72.0 72.0 14:10:23 INFO - PROCESS | 1947 | 14:10:23 INFO - PROCESS | 1947 | [#10] stanford-crypto-aes Cycles:10 Average:88.40 Median:85.50 stddev:10.88 (12.7%) stddev-sans-first:11.42 14:10:23 INFO - PROCESS | 1947 | Values: 84.0 83.0 86.0 82.0 85.0 85.0 86.0 87.0 119.0 87.0 14:10:23 INFO - PROCESS | 1947 | 14:10:23 INFO - PROCESS | 1947 | [#11] stanford-crypto-ccm Cycles:10 Average:139.40 Median:139.00 stddev:4.99 (3.6%) stddev-sans-first:5.26 14:10:23 INFO - PROCESS | 1947 | Values: 141.0 135.0 139.0 141.0 139.0 137.0 134.0 137.0 152.0 139.0 14:10:23 INFO - PROCESS | 1947 | 14:10:23 INFO - PROCESS | 1947 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.20 Median:165.00 stddev:2.15 (1.3%) stddev-sans-first:2.28 14:10:23 INFO - PROCESS | 1947 | Values: 165.0 164.0 165.0 170.0 164.0 165.0 164.0 167.0 162.0 166.0 14:10:23 INFO - PROCESS | 1947 | 14:10:23 INFO - PROCESS | 1947 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.50 Median:67.00 stddev:3.81 (5.7%) stddev-sans-first:3.93 14:10:23 INFO - PROCESS | 1947 | Values: 65.0 67.0 66.0 66.0 68.0 65.0 67.0 66.0 67.0 78.0 14:10:23 INFO - PROCESS | 1947 | -------- Summary: end -------- 14:10:23 INFO - PROCESS | 1947 | 14:10:24 INFO - TEST-INFO | 1947: exit 0 14:10:24 INFO - TEST-OK | kraken | took 58469ms 14:10:24 INFO - SUITE-END | took 1173s 14:10:24 INFO - Completed test suite (00:19:33) 14:10:24 INFO - javascript benchmark 14:10:24 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3400.9489857652134, 3429.0055883793684, 3444.810535420552, 3451.8446669899854, 3503.87736429225, 5530.861728456791, 5595.944040559593, 5609.382967873531, 5640.435956404358, 5687.17535957286, 5601.663900165982, 5645.745941432645, 5720.1991721159075, 5756.913646295309, 5759.884802303952, 5087.262346959688, 5102.107131252016, 5160.664556803796, 5166.147585648364, 5262.4211336753115, 5243.580513558906, 5274.077036518613, 5285.709285989269, 5298.0993231150605, 5320.9467905321035, 5112.718800465973, 5138.97430512845, 5143.408508021588, 5187.922181167285, 5208.557272631793, 5076.64463487556, 5077.898442031139, 5078.009788091324, 5126.871828204325, 5204.375474942994, 4316.115196384765, 5101.4133374662015, 5104.081265372231, 5115.948840511584, 5169.888473978112, 5465.508104270595, 5522.475364840334, 5541.445855414467, 5597.552195824365, 5597.580181486404, 6006.699665016776, 6032.004719221323, 6064.909026364564, 6071.666058366788, 6081.457322408717, 6155.907661385038, 6174.753009879644, 6177.289611694668, 6188.226471691039, 6189.473894718954, 5615.410381909841, 5698.116791897262, 5706.885862282731, 5745.626534275259, 5761.971190144064, 6207.751689932443, 6239.157713708682, 6287.748490060438, 6296.307406185316, 6332.145160403379, 6091.634501929899, 6112.938870611326, 6113.32753397126, 6119.418655227746, 6133.93949991259, 6101.206843110367, 6193.6903154842075, 6248.4064013918605, 6265.310815810257, 6271.05934109878, 6022.789202377896, 6106.542009349317, 6120.203342909657, 6130.122981477994, 6177.96911015442, 5249.186376111708, 6137.294211165773, 6218.253809542884, 6241.875162496724, 6246.344133865879, 4826.348442960226, 4930.063287975273, 4946.109700253989, 4947.233178857283, 4971.40343158816, 4395.208862404797, 4411.779411029435, 4417.646588272931, 4466.64266858657, 4502.977485112553, 5688.0, 5702.914456283159, 5707.828765136969, 5708.9723849706625, 5737.8565535861935, 6075.5747097703625, 6153.40011596988, 6272.996320588684, 6276.246850377984, 6278.497720182374, 5582.441755824385, 5663.178839068312, 5670.886582268332, 5721.485066344049, 5733.598648094677, 5373.919391209135, 5377.4084850666395, 5396.352437707422, 5402.37872644643, 5406.810761623403, 6211.0, 6297.307296197506, 6310.936890631218, 6329.113924050636, 6349.365063493706, 5213.817516386908, 6173.6913154343, 6216.378362163748, 6249.093881387264, 6307.526935479766, 6267.896913776589, 6304.369563043659, 6308.747650093945, 6312.747490100344, 6323.525735569759, 5951.880962380642, 5960.642361458326, 6145.385461453998, 6148.877022459616, 6169.413905678954, 6110.6195130195665, 6126.731429653803, 6139.584134579286, 6189.597676151036, 6272.466840318624, 6027.78902738402, 6048.60684055535, 6063.909041364472, 6064.97979335473, 6095.359987201457, 5114.667546609458, 5800.6809625471005, 5982.192404025401, 6025.78909738157, 6036.63780173191, 5799.536037116936, 5823.242978412949, 5857.560682948711, 5873.177755114373, 5911.438413350725, 5026.271190677258, 6004.87990240184, 6062.939370606237, 6068.6965651716355, 6071.210742603434, 5770.8845823082465, 5770.942290577041, 5836.970815145897, 5853.09277062056, 5901.468867801918], "value": 5636.400378867884, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [488.5114885114885, 503.31549093233195, 517.4928570001399, 526.5550609734772, 539.1319974840508, 30097.097087087393, 30754.692453075462, 41402.378964315525, 41766.791166044204, 41929.0, 41244.58755412445, 44063.898402539955, 44077.898052548706, 44175.0, 44179.11641767163, 9636.759081022978, 9869.0, 9870.90129098707, 9872.90127098727, 9874.851877221847, 9643.80712385752, 9791.412515249072, 9795.314327997043, 9804.0, 9850.70447886561, 9757.853632195523, 9804.558794854249, 9814.362066465657, 9814.46020468874, 9825.557849896735, 8336.0, 9742.805143897118, 9793.167580755644, 9803.362781419257, 9812.70561883141, 9443.952780236124, 9676.516174191262, 9684.51577421126, 9688.515574221332, 9733.951330243304, 40105.99735006619, 40343.0, 40493.59506404957, 40540.59459405427, 40662.983425414604, 3454.5336379588684, 3477.982610086933, 3518.542589463379, 3997.6237900548576, 4412.933805992912, 30779.538306925413, 31446.842765786252, 31503.36993260122, 32312.19219519507, 32436.18909527257, 3260.788048776822, 3278.229616040229, 3279.3933122372437, 3307.5534802801794, 3350.9329813403597, 500931.4953425246, 510378.0, 510546.4472677613, 512034.0, 512386.0, 31289.061328159736, 57341.71329143411, 58423.70788146032, 58463.70768146132, 58530.0, 35592.28815423677, 35822.8208858954, 35840.28319433597, 35866.0, 35936.10159745975, 14662.486812961495, 14842.628934276729, 14843.554693359216, 14854.034487758261, 14854.405823767145, 12319.384030798603, 12417.31704756247, 12419.130660853654, 12419.93790031044, 12467.93766031164, 7815.609219539, 7833.764987050397, 7857.842843143219, 7889.447738658238, 7906.05127384706, 3280.3111346617, 3298.7855789373616, 3299.389612921569, 3309.7352211822995, 3335.182880194368, 953.4565297780338, 1035.7203555040096, 1053.1206442620344, 1097.2429023973432, 1178.8938995490446, 23130.884345578503, 27085.864570677022, 30012.54981175284, 30414.69585304163, 31219.751209951803, 1092.5738961804902, 1118.1613789657756, 1126.983095253572, 1161.4831400027076, 1172.1091970102614, 14060.226687532284, 14862.70274594502, 15599.986000909905, 15761.842381576267, 16514.6697066058, 654760.0, 662250.0, 669100.0, 672722.0, 679445.0, 58840.411595883495, 59288.70355648194, 59291.0, 59336.0, 59511.40488595145, 1318.5319211679907, 1438.2521089033644, 1462.9195394253418, 1476.4463326252655, 1483.5475180070182, 30850.0, 31178.844105780234, 31210.0, 31740.523892142097, 32046.519302210017, 49675.75162124166, 49960.75019625024, 50545.494545054076, 50609.746951265006, 50651.493485064675, 403202.0, 404361.0, 404868.0, 406553.0, 407130.0, 23748.881255593613, 26116.477670446864, 26582.734172658027, 26615.06847260414, 26629.600555992063, 48522.08867467963, 48562.27156592583, 48977.5102248973, 49447.75276123596, 49555.0, 38813.0, 38927.80536097301, 39481.80259098686, 40429.79785101055, 40430.19139617132, 38298.425523616614, 38975.80512097421, 39087.550554158246, 39907.0, 39944.40083398693, 38559.42160867645, 38742.031449213995, 39467.815965521084, 39597.2080558393, 41525.961850953965, 31375.84312078425, 32274.838625806722, 34108.65891341154, 34239.65760342365, 34384.65615343815, 23296.883515582314, 24654.383640409134, 24819.0, 25048.872800723646, 25537.74462255428], "value": 18731.918889907873, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [357.33178955353475, 364.2060308527409, 386.48597365503883, 389.4508742672831, 408.779259200032, 19230.615387692236, 20118.893460859646, 21715.457113572167, 21759.8032108234, 21771.23800666977, 12082.15424920256, 12403.62789116328, 12417.56538521152, 12502.998560690874, 16115.0, 5402.621816472849, 5565.220869078333, 5568.86077848053, 5603.103503439451, 5613.298337707786, 3924.9803750981205, 4188.057687020427, 5875.324337701155, 6191.297393216856, 6194.566380353377, 6189.566730328879, 6206.379362063803, 6212.9068063979075, 6213.285472170713, 6650.201975762915, 3884.6503814656817, 6559.27847936729, 6750.088738020402, 6789.3889549940495, 6798.52410331277, 3999.900002499932, 4012.29784787661, 4043.514778226602, 5584.944150558483, 5949.732262048218, 13324.0, 13750.931245343809, 13837.446502139903, 13873.236971966538, 13889.58331250054, 2166.631672615659, 2200.3179014505395, 2275.3174047785596, 2334.4397344637337, 2706.201670507205, 9955.153811925995, 10122.29143959923, 10360.533775980024, 10384.896151038469, 10431.791364172674, 1825.8813177143443, 1847.9353222637144, 1868.271374164077, 1902.115516284934, 1963.5974625201943, 115683.0, 120396.39801800935, 121287.36137915788, 121741.39129304385, 123482.0, 11531.942340288246, 11604.129690273263, 11614.593489227836, 11729.706757331134, 11790.938815506646, 13400.731985360433, 13963.441462341596, 14855.851441485447, 14972.1016738996, 14973.0, 8553.272971797467, 8738.30093592511, 8751.824963500821, 8849.433664237427, 8858.468491890508, 7279.745208917662, 8534.658613655509, 8567.74296771098, 8649.178328058824, 8658.523781192094, 3864.478295430137, 4581.221192397301, 4582.106489234568, 4650.627949764011, 4680.3447517347595, 1750.88619239749, 1754.6864592254112, 1828.725691146312, 1915.396650055228, 1950.151684017457, 890.8930928288518, 891.1355984694835, 937.9859302110474, 956.4261443134064, 995.0149352141473, 18475.722864157047, 20257.898710506353, 21159.25942592002, 21395.358139255848, 21418.912559996483, 928.2063835420753, 931.3387494878576, 952.4237836109193, 969.7042402067437, 989.3569180033036, 9545.52272386378, 10936.398498082523, 11325.830112548318, 11340.886591134147, 11374.14693897961, 568864.0, 575747.0, 575776.0, 604440.0, 607257.0, 118961.07791915468, 122901.0, 138543.0, 143270.28364858316, 147984.0, 921.5622579274791, 980.9901900981041, 981.3130808434124, 1013.285633628305, 1029.2897900448665, 16177.433789817771, 17847.821521785136, 18667.906660467153, 19085.809141908958, 19103.0, 36126.0, 47744.7612761934, 56113.0, 56281.0, 56424.0, 80072.0, 81656.59171704104, 82676.0, 84360.31279374256, 84592.0, 7636.465447418627, 7984.600769961595, 8051.83896322082, 8083.6766529338165, 8097.8380432392205, 8849.469031858109, 11304.604338848101, 12693.682657933625, 12699.238045717286, 12733.363331833556, 5584.609077364708, 7437.739679111205, 11110.722231944266, 11124.110071194124, 11245.325280483197, 10493.895061049292, 11086.445677716243, 11176.16178786578, 11252.381119038531, 11379.374134422354, 11508.769824603294, 11542.307461552333, 11746.412679366169, 11755.941220293844, 11817.940910295394, 8107.756767296991, 8211.58942052907, 8293.33653307722, 8329.292010179202, 8521.147885211429, 11171.608993685182, 11264.94367528157, 11363.943180284046, 11380.260283081574, 11395.487203075716], "value": 9213.193721725447, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1407.6762344660733, 1537.6309685675435, 1580.8498192671696, 1790.2749386498465, 1944.2806161720166, 7885.80285492863, 8722.956385218073, 8936.240419564328, 8940.105989401056, 9061.048589898064, 8616.526091064989, 8847.469151850893, 8849.601767920443, 8850.0, 8853.424527405714, 2103.4320733401973, 2315.988420057897, 2357.092519380038, 2390.5457962986975, 2395.772401621843, 2393.1265088242753, 2425.19968410425, 2426.369144022558, 2429.465517586128, 2430.4531480416854, 2327.767223277676, 2361.3624321433203, 2363.9054437822465, 2365.6569797379457, 2390.557746816836, 2086.1446806809286, 2148.903299351533, 2231.509068005036, 2307.5038866643754, 2347.812175025994, 2349.177787774265, 2373.7982271506944, 2376.1327115602853, 2377.5244951009868, 2378.191414918919, 8746.56267186638, 8781.341399395007, 8783.64865405383, 8788.604512796875, 8804.47173169612, 5206.817761378372, 5347.919781203285, 5352.0, 5353.437889021636, 5361.678299302015, 3179.4753865612147, 3492.3189977954307, 3647.2705458908326, 3651.6713495785243, 3651.908702282438, 1045.4354648489807, 1083.5828206140613, 1085.6960051185602, 1090.1496832470687, 1102.5755084292525, 11577.073834093337, 11608.24546404481, 11618.302901825917, 11626.941865290704, 11666.59166929162, 4673.686525170063, 4704.153252414599, 4848.0, 4848.806047758121, 4851.587615052689, 9933.403995760278, 9939.50302484873, 9940.950295248478, 9944.602215911282, 9951.203903687687, 5273.736313184402, 5347.0, 5369.543588794996, 5372.1941708744, 5380.3274590676165, 4398.8020539075815, 4552.271636538138, 4782.354382158364, 4923.704577725276, 4926.630502712311, 3933.5476420212044, 3971.8212680428874, 4042.110735638155, 4073.89815254621, 4087.4073259377224, 1094.3816743539874, 1278.974420511603, 1293.9676508087373, 1303.5111833062601, 1308.4177540994167, 824.2458150792078, 916.2532535983153, 959.9568019439142, 979.2508730820928, 979.5445118020154, 5244.868878278074, 5737.3975732548315, 5809.970950145223, 6050.818475445743, 6235.96882015587, 925.7593025813337, 972.3825370889537, 974.91713204377, 978.7406337320616, 1002.2583288366702, 3948.8617898373996, 4092.935836682484, 4105.609967053125, 4110.547839737626, 4168.9994401344065, 10528.684139475828, 10619.09737672291, 10952.616658416917, 10963.287386319864, 10979.231453798158, 10847.945760271306, 10848.457577121113, 10850.0, 10853.403062831461, 10858.34849909008, 7069.469789765685, 7851.568163750934, 8274.379421543408, 8364.289035432057, 8369.623366948503, 5827.679477628772, 5945.64326140433, 5964.8807023860145, 6017.157597936205, 6029.517638588814, 9929.453880036668, 9949.104580587782, 10083.344582602107, 10089.0, 10118.848217276895, 8839.204471597586, 9976.20190384782, 10204.489775511343, 10284.023017813295, 10417.270791044857, 6621.569597976309, 6821.556598821259, 6995.4609985803045, 7113.928860711534, 7282.67227974753, 8507.40448168647, 8636.654533818577, 8715.389922705603, 8715.651373944971, 8722.563871806258, 7564.546127232384, 7735.0, 7803.882941755992, 7863.488873223222, 7893.684252630061, 7468.850622987618, 7555.584442855476, 7591.316781489692, 7725.768226953201, 7760.417968652261, 7174.856502870018, 7232.4575656827, 7252.782416527301, 7257.129144502483, 7275.781726548212, 4962.181240095489, 5068.467810879952, 5099.439061703284, 5155.123628983007, 5164.1995490699, 5137.33214682089, 5193.350831146106, 5232.895342093213, 5244.632875698817, 5304.336957880265], "value": 4630.130231288462, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [297.724604740615, 302.38012075245746, 312.3175860744273, 315.04070106525654, 316.6058257469451, 4790.6407019473545, 4808.591269742072, 5010.674306170105, 5027.396712394513, 5070.518300761431, 4539.954600453994, 4559.677693468899, 4936.506349365061, 4958.702477851331, 5224.242484839706, 3810.485584446106, 3985.32982901456, 4000.3199456092534, 4002.019505221225, 4013.0368711509172, 4011.6790656747535, 4013.9799301003454, 4030.899227519321, 4035.1122752994293, 4041.454403655513, 3663.3148751574176, 3693.8153092345274, 3955.7428767130186, 3965.2267807777494, 4037.9798101009455, 3175.4601717708047, 3867.9556519739513, 3916.3929590913453, 3978.781167035812, 3985.282649123142, 2878.784091193169, 2931.721486458783, 3422.5892892852767, 3431.5367425397503, 3464.4630082337276, 4542.387452454364, 4972.458537853241, 5061.493850614946, 5074.619403544749, 5098.515641014134, 3423.8288085595623, 4540.909181816378, 4543.136804007261, 4558.703684260546, 4590.242609969351, 4617.21507343749, 4799.912038263332, 5495.0, 5500.50495455407, 5528.6406383585345, 3786.280606684721, 3797.259534390795, 3799.5060642116623, 3805.7335986480957, 3816.713746469026, 5293.920591191135, 5381.865453363658, 5637.238972738669, 5724.4561766632105, 5794.420557944172, 3155.3846999834814, 3644.2893635740775, 3646.58064322601, 3810.5046343975105, 3834.0, 3842.654161125512, 3868.787216703052, 3892.163184915257, 3895.2209558088493, 3896.7077469189926, 4261.97869010653, 4436.844710435119, 4515.277555591089, 4556.726596404226, 4564.6348292136545, 4567.954320456819, 5148.124818780816, 5152.536271735562, 5210.557102646242, 5514.393416724157, 1303.7848754955326, 1312.9212247265195, 1325.681836359286, 1328.8338957630297, 1330.7158591958805, 737.6201256352982, 781.1992707474884, 795.7612716185237, 813.9267465928095, 816.6488409983646, 3719.330520506335, 4120.7733574653685, 4233.724807887539, 4263.339182426729, 4321.503027151855, 4222.218889505451, 4339.717918335361, 4682.157211701926, 4807.053143476897, 4822.853830045666, 3741.644543768338, 4146.91706165875, 4154.729942553724, 4220.704550681484, 4326.697131200785, 2361.6811730416175, 2464.1745015420033, 2975.8363290018824, 2990.7607391408637, 3010.1872494426384, 5239.083160446907, 5691.820443396489, 5868.823935281863, 5949.876314934608, 5980.906682661121, 5156.690598564098, 5357.732113394315, 5442.4148529448985, 5913.586048976616, 5934.525237980951, 5702.97346477638, 5896.317175684967, 5908.202392676933, 5941.851453713605, 5959.837831622878, 4869.6591238612955, 5653.830385088289, 5687.289088863892, 5727.31272247333, 5727.599068065362, 4403.493598236244, 5421.783128674809, 5570.359408667893, 5593.972030139823, 5671.801486947937, 5295.152775555893, 5328.547073498796, 5885.352611212677, 5923.452915246486, 5967.522598192221, 3359.126627076929, 3630.5280313560124, 3906.570277269555, 3917.6865850732443, 3934.468846705658, 4842.903141937212, 5652.689102099424, 5669.461401166883, 5678.3186017678145, 5684.744186511534, 4878.341423907869, 5319.973400133129, 5499.615026948075, 5500.944990550043, 5545.306836645419, 4814.446338670958, 5471.534919531884, 5480.80817171413, 5495.038368285614, 5498.505134537751, 4772.570468657837, 5372.018529716045, 5378.2739330189925, 5378.381486128988, 5389.568834493152, 4658.86023419298, 5160.251763494347, 5481.917771233355, 5501.834944951658, 5519.862003449785, 4725.8582242532775, 4781.282807578891, 5053.166227572409, 5343.435151127558, 5385.953337731637], "value": 3928.1762792164873, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [709.8367375503634, 722.9421646268299, 794.3843521271012, 798.5009369144285, 821.6302663801288, 14482.493112741056, 15217.163056031914, 15229.01011434256, 15253.008554443953, 15285.388584456623, 13824.516141935035, 13844.030917835755, 13871.861281387182, 13888.722225555508, 13900.0, 719.7552832037106, 740.9184989651134, 743.3495691270139, 744.5941961630897, 889.3863234368298, 729.6242435145904, 866.6836604639325, 902.5036230073466, 902.5532361481044, 908.3686837647838, 939.7509659940088, 953.5851904421564, 956.0, 957.3872721458272, 958.511159308754, 950.2113246005798, 951.5575257505259, 951.6574033347989, 952.3095755577186, 952.471378384996, 551.9144532597413, 573.4237091722833, 573.4294377094817, 573.4781348972457, 687.5427840486071, 14349.497767578085, 14647.780283295653, 14729.484468043567, 14750.188739619316, 14751.631209219748, 13425.798613020812, 13722.313884305844, 13731.45074197031, 13847.099938504065, 14033.508827190999, 11010.559577616887, 11884.821727674092, 12279.386030698519, 12283.57007504733, 12355.32045737484, 4526.841560545398, 4547.567981041796, 4553.203189441834, 4726.6927649703, 4760.1907675694865, 86108.56945715295, 86717.56641216816, 87092.0, 87854.12145878587, 88863.55568222118, 10873.293235939638, 10933.18001149917, 11064.0, 11065.612703555336, 11460.0, 17827.554311142325, 21410.78589214119, 21414.143434262765, 21426.250081247083, 21447.571048578942, 7914.841703166019, 7928.6828526859435, 7998.520088794575, 8024.679012839538, 8248.505089694638, 4399.890002749893, 4464.15181115587, 4472.865814025585, 4489.438820147481, 4642.141203877293, 3356.590232102523, 3399.473081672345, 3453.430184019659, 3460.8788692395647, 3461.6192218855904, 660.6267458885715, 664.441868830185, 668.5275506859097, 668.812732434919, 669.3239827773983, 539.7139516056496, 603.9883195647274, 615.6767696959132, 661.7783042680755, 668.2782594797704, 8823.470591764386, 8827.704368528186, 8851.955740221258, 8906.332025098143, 9262.67580634688, 585.9003969325225, 632.6615260835431, 634.3974465254548, 645.6707079389545, 664.2925284571916, 4234.703570750018, 4237.957620423818, 4300.182965236595, 4307.892302692458, 4316.201502722007, 72856.63571682214, 95082.04917950732, 97048.0, 97530.0, 98020.0, 74664.0, 76614.0, 77687.61156194183, 77969.0, 78438.0, 72071.0, 72576.0, 73538.0, 73947.52104957978, 74851.0, 9172.816543669223, 9391.20174785151, 9501.667441639509, 9524.333296669167, 9595.760105997128, 44345.0, 45466.31800523061, 46308.7684561575, 46340.30489542727, 46467.76766116148, 62553.0, 63080.68459657672, 63264.102076937765, 63273.683631581545, 65982.67008664926, 12341.321227332583, 12486.937565312115, 12615.810762838748, 12663.430145643653, 12881.420336085086, 13902.582922512342, 14111.858881411465, 14194.077384969944, 14245.430182792572, 14493.130412174882, 11707.010750115589, 12085.456154472893, 12133.09001824849, 12261.276658987605, 12276.263424194576, 13036.674083147618, 13166.0, 13282.40229189708, 13334.933205343743, 13847.0, 12912.548060818202, 12987.675308117372, 13028.934855325662, 13336.599902002956, 13466.93266533661, 10686.465676715978, 10716.249862509549, 10943.945280273547, 10971.177161712743, 11030.724231894266, 10781.784364312827, 10907.945460272647, 10971.0, 11024.724381890517, 11038.50326735315], "value": 7267.67742364032, "unit": "score"}], "name": "dromaeo_css", "value": 7104.548298510982, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 108.0, 112.0, 111.0, 112.0, 112.0, 112.0, 113.0, 112.0, 112.0], "value": 111.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 125.0, 121.0, 168.0, 118.0, 120.0, 151.0, 111.0, 172.0, 117.0], "value": 135.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 185.0, 184.0, 182.0, 183.0, 181.0, 183.0, 181.0, 182.0, 185.0], "value": 182.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 58.0, 59.0, 84.0, 57.0, 57.0, 65.0, 64.0, 82.0, 55.0], "value": 66.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 68.0, 102.0, 70.0, 72.0, 69.0, 120.0, 100.0, 98.0, 101.0], "value": 88.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 130.0, 87.0, 85.0, 87.0, 84.0, 87.0, 85.0, 87.0, 84.0], "value": 90.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 95.0, 93.0, 94.0, 94.0, 94.0, 94.0, 94.0, 93.0], "value": 93.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 87.0, 87.0, 86.0, 85.0, 83.0, 86.0, 84.0, 86.0, 86.0], "value": 85.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 56.0, 52.0, 52.0, 56.0, 53.0, 53.0, 55.0, 54.0, 53.0], "value": 54.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 74.0, 75.0, 75.0, 73.0, 70.0, 70.0, 72.0, 72.0], "value": 71.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 86.0, 82.0, 85.0, 85.0, 86.0, 87.0, 119.0, 87.0], "value": 88.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 135.0, 139.0, 141.0, 139.0, 137.0, 134.0, 137.0, 152.0, 139.0], "value": 139.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 164.0, 165.0, 170.0, 164.0, 165.0, 164.0, 167.0, 162.0, 166.0], "value": 165.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 67.0, 66.0, 66.0, 68.0, 65.0, 67.0, 66.0, 67.0, 78.0], "value": 67.5, "unit": "score"}], "name": "kraken", "value": 1441.3000000000002, "alertThreshold": 2.0}]} 14:10:24 INFO - Return code: 0 14:10:24 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 14:10:24 ERROR - # TBPL SUCCESS # 14:10:24 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:10:24 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:10:24 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:10:24 INFO - 2016-05-27 14:10:24.474 screenresolution[1951:21468] starting screenresolution argv=screenresolution get 14:10:24 INFO - 2016-05-27 14:10:24.477 screenresolution[1951:21468] Display 0: 1600x1200x32@60 14:10:24 INFO - 2016-05-27 14:10:24.483 screenresolution[1952:21472] starting screenresolution argv=screenresolution list 14:10:24 INFO - Available Modes on Display 0 14:10:24 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:10:24 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:10:24 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:10:24 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:10:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:10:24 INFO - Intel Iris: 14:10:24 INFO - Chipset Model: Intel Iris 14:10:24 INFO - Type: GPU 14:10:24 INFO - Bus: Built-In 14:10:24 INFO - VRAM (Dynamic, Max): 1536 MB 14:10:24 INFO - Vendor: Intel (0x8086) 14:10:24 INFO - Device ID: 0x0a2e 14:10:24 INFO - Revision ID: 0x0009 14:10:24 INFO - Displays: 14:10:24 INFO - Display: 14:10:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:10:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:10:24 INFO - Display Serial Number: 1600x1200 60 14:10:24 INFO - Main Display: Yes 14:10:24 INFO - Mirror: Off 14:10:24 INFO - Online: Yes 14:10:24 INFO - Rotation: Supported 14:10:24 INFO - Return code: 0 14:10:24 INFO - Running post-action listener: _resource_record_post_action 14:10:24 INFO - ##### 14:10:24 INFO - ##### Finished run-tests step (success) 14:10:24 INFO - ##### 14:10:24 INFO - Running post-run listener: _resource_record_post_run 14:10:24 INFO - Total resource usage - Wall time: 1209s; CPU: 54.0%; Read bytes: 323584; Write bytes: 697346048; Read time: 3; Write time: 1889 14:10:24 INFO - TinderboxPrint: CPU usage
53.8% 14:10:24 INFO - TinderboxPrint: I/O read bytes / time
323,584 / 3 14:10:24 INFO - TinderboxPrint: I/O write bytes / time
697,346,048 / 1,889 14:10:24 INFO - TinderboxPrint: CPU idle
2,235.5 (46.2%) 14:10:24 INFO - TinderboxPrint: CPU system
964.1 (19.9%) 14:10:24 INFO - TinderboxPrint: CPU user
1,637.9 (33.9%) 14:10:24 INFO - TinderboxPrint: Swap in / out
397,860,864 / 0 14:10:24 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 393665024; Write bytes: 388015616; Read time: 29410; Write time: 771 14:10:24 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 323584; Write bytes: 295993856; Read time: 3; Write time: 1083 14:10:25 INFO - Running post-run listener: _upload_blobber_files 14:10:25 INFO - Blob upload gear active. 14:10:25 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:10:25 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 14:10:25 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'] 14:10:25 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 14:10:25 INFO - (blobuploader) - INFO - Open directory for files ... 14:10:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:10:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:10:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:10:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:10:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:10:26 INFO - (blobuploader) - INFO - Done attempting. 14:10:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:10:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:10:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:10:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:10:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:10:27 INFO - (blobuploader) - INFO - Done attempting. 14:10:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:10:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:10:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:10:31 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:10:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:10:31 INFO - (blobuploader) - INFO - Done attempting. 14:10:31 INFO - (blobuploader) - INFO - Iteration through files over. 14:10:31 INFO - Return code: 0 14:10:31 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:10:31 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:10:31 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7947862a6a5aab166f4cd37a64a4df0a4a627aeb35a210126543d68c8d8b8b22f97fcf2e76fb509610de69332ddbfdce6716a513531cd83be83c3a557f497bd7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a03b3af9a3284421f023ffb7781303384c90ed2ee832ee2bfbfd589bf7031086a7bcdedc37bb5ab3db606d41d7e10558c8b6004e859ef92ff764ce482c707b08", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bebdb6ef00901263ca8e9d1eb21feb1baaaee3d814915b2ae90f1630336963b59e51865889f28631ac80669cd6f2cd32afa712105fdc9d24dbf2aea786966095"} 14:10:31 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:10:31 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:10:31 INFO - Contents: 14:10:31 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7947862a6a5aab166f4cd37a64a4df0a4a627aeb35a210126543d68c8d8b8b22f97fcf2e76fb509610de69332ddbfdce6716a513531cd83be83c3a557f497bd7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a03b3af9a3284421f023ffb7781303384c90ed2ee832ee2bfbfd589bf7031086a7bcdedc37bb5ab3db606d41d7e10558c8b6004e859ef92ff764ce482c707b08", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bebdb6ef00901263ca8e9d1eb21feb1baaaee3d814915b2ae90f1630336963b59e51865889f28631ac80669cd6f2cd32afa712105fdc9d24dbf2aea786966095"} 14:10:31 INFO - Running post-run listener: copy_logs_to_upload_dir 14:10:31 INFO - Copying logs to upload dir... 14:10:31 INFO - mkdir: /builds/slave/test/build/upload/logs 14:10:31 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1277.519955 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-05-27 14:10:31.116320) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-27 14:10:31.120509) ========= 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.OZ4ng8EeTb/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.bYrtufKXHr/Listeners TMPDIR=/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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/7947862a6a5aab166f4cd37a64a4df0a4a627aeb35a210126543d68c8d8b8b22f97fcf2e76fb509610de69332ddbfdce6716a513531cd83be83c3a557f497bd7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a03b3af9a3284421f023ffb7781303384c90ed2ee832ee2bfbfd589bf7031086a7bcdedc37bb5ab3db606d41d7e10558c8b6004e859ef92ff764ce482c707b08", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bebdb6ef00901263ca8e9d1eb21feb1baaaee3d814915b2ae90f1630336963b59e51865889f28631ac80669cd6f2cd32afa712105fdc9d24dbf2aea786966095"} build_url:https://queue.taskcluster.net/v1/task/Ii50GD-nRfCaWbGHnihkwg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011192 build_url: 'https://queue.taskcluster.net/v1/task/Ii50GD-nRfCaWbGHnihkwg/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/7947862a6a5aab166f4cd37a64a4df0a4a627aeb35a210126543d68c8d8b8b22f97fcf2e76fb509610de69332ddbfdce6716a513531cd83be83c3a557f497bd7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a03b3af9a3284421f023ffb7781303384c90ed2ee832ee2bfbfd589bf7031086a7bcdedc37bb5ab3db606d41d7e10558c8b6004e859ef92ff764ce482c707b08", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bebdb6ef00901263ca8e9d1eb21feb1baaaee3d814915b2ae90f1630336963b59e51865889f28631ac80669cd6f2cd32afa712105fdc9d24dbf2aea786966095"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-27 14:10:31.151365) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 14:10:31.151694) ========= 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.OZ4ng8EeTb/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.bYrtufKXHr/Listeners TMPDIR=/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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.005316 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 14:10:31.200851) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-27 14:10:31.201183) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-27 14:10:31.201538) ========= ========= Total master_lag: 0.18 =========