builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0153 starttime: 1463165994.54 results: success (0) buildid: 20160513093143 builduid: ee418221229d4620aee41e5a0ed3e968 revision: c4a209a8e2a8483f71182ecfa6044b830fd85362 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-13 11:59:54.542917) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-13 11:59:54.543267) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-13 11:59:54.543526) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-13 11:59:54.629049) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 11:59:54.629497) ========= 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.h6ALDLbO6z/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.ukaEkXkg49/Listeners TMPDIR=/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/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-13 11:59:54-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 16.9M=0.001s 2016-05-13 11:59:55 (16.9 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.622594 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 11:59:55.288413) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 11:59:55.288698) ========= 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.h6ALDLbO6z/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.ukaEkXkg49/Listeners TMPDIR=/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/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.027420 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 11:59:55.336903) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-13 11:59:55.337197) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev c4a209a8e2a8483f71182ecfa6044b830fd85362 --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 c4a209a8e2a8483f71182ecfa6044b830fd85362 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.h6ALDLbO6z/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.ukaEkXkg49/Listeners TMPDIR=/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/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-13 11:59:55,415 truncating revision to first 12 chars 2016-05-13 11:59:55,416 Setting DEBUG logging. 2016-05-13 11:59:55,416 attempt 1/10 2016-05-13 11:59:55,416 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/c4a209a8e2a8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-13 11:59:56,584 unpacking tar archive at: fx-team-c4a209a8e2a8/testing/mozharness/ program finished with exit code 0 elapsedTime=1.409783 ========= master_lag: 0.11 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-13 11:59:56.860765) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-13 11:59:56.861023) ========= script_repo_revision: c4a209a8e2a8483f71182ecfa6044b830fd85362 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-13 11:59:56.861359) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-13 11:59:56.861630) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-13 11:59:56.875749) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 40 secs) (at 2016-05-13 11:59:56.876025) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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.h6ALDLbO6z/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.ukaEkXkg49/Listeners TMPDIR=/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 11:59:57 INFO - MultiFileLogger online at 20160513 11:59:57 in /builds/slave/test 11:59:57 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 11:59:57 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:59:57 INFO - {'append_to_log': False, 11:59:57 INFO - 'base_work_dir': '/builds/slave/test', 11:59:57 INFO - 'blob_upload_branch': 'Fx-Team', 11:59:57 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:59:57 INFO - 'branch': 'Fx-Team', 11:59:57 INFO - 'buildbot_json_path': 'buildprops.json', 11:59:57 INFO - 'config_files': ('talos/mac_config.py',), 11:59:57 INFO - 'default_actions': ('clobber', 11:59:57 INFO - 'read-buildbot-config', 11:59:57 INFO - 'download-and-extract', 11:59:57 INFO - 'populate-webroot', 11:59:57 INFO - 'create-virtualenv', 11:59:57 INFO - 'install', 11:59:57 INFO - 'run-tests'), 11:59:57 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:59:57 INFO - 'download_minidump_stackwalk': True, 11:59:57 INFO - 'download_symbols': 'ondemand', 11:59:57 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:59:57 INFO - 'tooltool.py': '/tools/tooltool.py', 11:59:57 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:59:57 INFO - '/tools/misc-python/virtualenv.py')}, 11:59:57 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:59:57 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:59:57 INFO - 'installer_path': 'installer.exe', 11:59:57 INFO - 'log_level': 'info', 11:59:57 INFO - 'log_name': 'talos', 11:59:57 INFO - 'log_to_console': True, 11:59:57 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:59:57 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:59:57 INFO - 'opt_config_files': (), 11:59:57 INFO - 'pip_index': False, 11:59:57 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:59:57 INFO - 'cmd': ('bash', 11:59:57 INFO - '-c', 11:59:57 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:59:57 INFO - 'enabled': True, 11:59:57 INFO - 'halt_on_failure': False, 11:59:57 INFO - 'name': 'check_screen_resolution'},), 11:59:57 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:59:57 INFO - 'cmd': ('bash', 11:59:57 INFO - '-c', 11:59:57 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:59:57 INFO - 'enabled': True, 11:59:57 INFO - 'halt_on_failure': False, 11:59:57 INFO - 'name': 'check_screen_resolution'},), 11:59:57 INFO - 'run_cmd_checks_enabled': True, 11:59:57 INFO - 'sps_profile': False, 11:59:57 INFO - 'sps_profile_interval': 0, 11:59:57 INFO - 'suite': 'dromaeojs-e10s', 11:59:57 INFO - 'system_bits': '32', 11:59:57 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:59:57 INFO - 'title': 't-yosemite-r7-0153', 11:59:57 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:59:57 INFO - 'use_talos_json': True, 11:59:57 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:59:57 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:59:57 INFO - 'work_dir': 'build'} 11:59:57 INFO - ##### 11:59:57 INFO - ##### Running clobber step. 11:59:57 INFO - ##### 11:59:57 INFO - Running pre-action listener: _resource_record_pre_action 11:59:57 INFO - Running main action method: clobber 11:59:57 INFO - rmtree: /builds/slave/test/build 11:59:57 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:59:59 INFO - Running post-action listener: _resource_record_post_action 11:59:59 INFO - ##### 11:59:59 INFO - ##### Finished clobber step (success) 11:59:59 INFO - ##### 11:59:59 INFO - ##### 11:59:59 INFO - ##### Running read-buildbot-config step. 11:59:59 INFO - ##### 11:59:59 INFO - Running pre-action listener: _resource_record_pre_action 11:59:59 INFO - Running main action method: read_buildbot_config 11:59:59 INFO - Using buildbot properties: 11:59:59 INFO - { 11:59:59 INFO - "project": "", 11:59:59 INFO - "product": "firefox", 11:59:59 INFO - "script_repo_revision": "production", 11:59:59 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 11:59:59 INFO - "repository": "", 11:59:59 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 11:59:59 INFO - "buildid": "20160513093143", 11:59:59 INFO - "pgo_build": "False", 11:59:59 INFO - "basedir": "/builds/slave/test", 11:59:59 INFO - "buildnumber": 5, 11:59:59 INFO - "slavename": "t-yosemite-r7-0153", 11:59:59 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 11:59:59 INFO - "platform": "yosemite_r7", 11:59:59 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 11:59:59 INFO - "branch": "fx-team", 11:59:59 INFO - "repo_path": "integration/fx-team", 11:59:59 INFO - "revision": "c4a209a8e2a8483f71182ecfa6044b830fd85362", 11:59:59 INFO - "stage_platform": "macosx64", 11:59:59 INFO - "builduid": "ee418221229d4620aee41e5a0ed3e968", 11:59:59 INFO - "slavebuilddir": "test" 11:59:59 INFO - } 11:59:59 INFO - Found installer url https://queue.taskcluster.net/v1/task/a-NOy8zESG2EKgTNwCXYbg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 11:59:59 INFO - Running post-action listener: _resource_record_post_action 11:59:59 INFO - ##### 11:59:59 INFO - ##### Finished read-buildbot-config step (success) 11:59:59 INFO - ##### 11:59:59 INFO - ##### 11:59:59 INFO - ##### Running download-and-extract step. 11:59:59 INFO - ##### 11:59:59 INFO - Running pre-action listener: _resource_record_pre_action 11:59:59 INFO - Running main action method: download_and_extract 11:59:59 INFO - mkdir: /builds/slave/test/build/tests 11:59:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:59:59 INFO - https://queue.taskcluster.net/v1/task/a-NOy8zESG2EKgTNwCXYbg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 11:59:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a-NOy8zESG2EKgTNwCXYbg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 11:59:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a-NOy8zESG2EKgTNwCXYbg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 11:59:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a-NOy8zESG2EKgTNwCXYbg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 11:59:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a-NOy8zESG2EKgTNwCXYbg/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 12:00:19 INFO - Downloaded 1199 bytes. 12:00:19 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 12:00:19 INFO - Using the following test package requirements: 12:00:19 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 12:00:19 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 12:00:19 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 12:00:19 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 12:00:19 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 12:00:19 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 12:00:19 INFO - u'jsshell-mac.zip'], 12:00:19 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 12:00:19 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 12:00:19 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 12:00:19 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 12:00:19 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 12:00:19 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 12:00:19 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 12:00:19 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 12:00:19 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 12:00:19 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 12:00:19 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 12:00:19 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 12:00:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:00:19 INFO - https://queue.taskcluster.net/v1/task/a-NOy8zESG2EKgTNwCXYbg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 12:00:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a-NOy8zESG2EKgTNwCXYbg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 12:00:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a-NOy8zESG2EKgTNwCXYbg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 12:00:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a-NOy8zESG2EKgTNwCXYbg/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 12:00:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a-NOy8zESG2EKgTNwCXYbg/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 12:00:24 INFO - Downloaded 18153905 bytes. 12:00:24 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'] 12:00:24 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 12:00:27 INFO - Return code: 0 12:00:27 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 12:00:27 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')]} 12:00:27 INFO - https://queue.taskcluster.net/v1/task/a-NOy8zESG2EKgTNwCXYbg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 12:00:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a-NOy8zESG2EKgTNwCXYbg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 12:00:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a-NOy8zESG2EKgTNwCXYbg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 12:00:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a-NOy8zESG2EKgTNwCXYbg/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 12:00:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a-NOy8zESG2EKgTNwCXYbg/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 12:00:34 INFO - Downloaded 18153905 bytes. 12:00:34 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'] 12:00:34 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 12:00:38 INFO - Return code: 0 12:00:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:00:38 INFO - https://queue.taskcluster.net/v1/task/a-NOy8zESG2EKgTNwCXYbg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 12:00:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a-NOy8zESG2EKgTNwCXYbg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 12:00:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a-NOy8zESG2EKgTNwCXYbg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 12:00:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a-NOy8zESG2EKgTNwCXYbg/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 12:00:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a-NOy8zESG2EKgTNwCXYbg/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 12:00:39 INFO - Downloaded 10840239 bytes. 12:00:39 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'] 12:00:39 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 12:00:39 INFO - Return code: 0 12:00:39 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')]} 12:00:39 INFO - https://queue.taskcluster.net/v1/task/a-NOy8zESG2EKgTNwCXYbg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 12:00:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a-NOy8zESG2EKgTNwCXYbg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 12:00:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a-NOy8zESG2EKgTNwCXYbg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 12:00:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a-NOy8zESG2EKgTNwCXYbg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 12:00:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a-NOy8zESG2EKgTNwCXYbg/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 12:00:46 INFO - Downloaded 110071634 bytes. 12:00:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/a-NOy8zESG2EKgTNwCXYbg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 12:00:46 INFO - mkdir: /builds/slave/test/properties 12:00:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:00:46 INFO - Writing to file /builds/slave/test/properties/build_url 12:00:46 INFO - Contents: 12:00:46 INFO - build_url:https://queue.taskcluster.net/v1/task/a-NOy8zESG2EKgTNwCXYbg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 12:00:47 INFO - Running post-action listener: _resource_record_post_action 12:00:47 INFO - Running post-action listener: set_extra_try_arguments 12:00:47 INFO - ##### 12:00:47 INFO - ##### Finished download-and-extract step (success) 12:00:47 INFO - ##### 12:00:47 INFO - ##### 12:00:47 INFO - ##### Running populate-webroot step. 12:00:47 INFO - ##### 12:00:47 INFO - Running pre-action listener: _resource_record_pre_action 12:00:47 INFO - Running main action method: populate_webroot 12:00:47 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:00:47 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:00:47 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:00:47 INFO - '--tppagecycles', 12:00:47 INFO - '7'], 12:00:47 INFO - 'tests': ['tsvgm']}}, 12:00:47 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:00:47 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:00:47 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:00:47 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:00:47 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:00:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:00:47 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:00:47 INFO - 'talos_options': ['--disable-e10s'], 12:00:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:00:47 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:00:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:00:47 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:00:47 INFO - 'talos_options': ['--disable-e10s'], 12:00:47 INFO - 'tests': ['damp', 'tps']}, 12:00:47 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:00:47 INFO - 'tests': ['damp', 'tps']}, 12:00:47 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:00:47 INFO - 'tests': ['dromaeo_dom']}, 12:00:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:00:47 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:00:47 INFO - 'tests': ['a11yr', 12:00:47 INFO - 'ts_paint', 12:00:47 INFO - 'tpaint', 12:00:47 INFO - 'sessionrestore', 12:00:47 INFO - 'sessionrestore_no_auto_restore', 12:00:47 INFO - 'tabpaint']}, 12:00:47 INFO - 'other-e10s': {'tests': ['a11yr', 12:00:47 INFO - 'ts_paint', 12:00:47 INFO - 'tpaint', 12:00:47 INFO - 'sessionrestore', 12:00:47 INFO - 'sessionrestore_no_auto_restore', 12:00:47 INFO - 'tabpaint']}, 12:00:47 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:00:47 INFO - 'ts_paint', 12:00:47 INFO - 'tpaint', 12:00:47 INFO - 'sessionrestore', 12:00:47 INFO - 'sessionrestore_no_auto_restore', 12:00:47 INFO - 'tabpaint']}, 12:00:47 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:00:47 INFO - 'ts_paint', 12:00:47 INFO - 'tpaint', 12:00:47 INFO - 'sessionrestore', 12:00:47 INFO - 'sessionrestore_no_auto_restore', 12:00:47 INFO - 'tabpaint']}, 12:00:47 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:00:47 INFO - 'tests': ['a11yr', 12:00:47 INFO - 'ts_paint', 12:00:47 INFO - 'tpaint', 12:00:47 INFO - 'sessionrestore', 12:00:47 INFO - 'sessionrestore_no_auto_restore', 12:00:47 INFO - 'tabpaint']}, 12:00:47 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:00:47 INFO - 'tests': ['a11yr', 12:00:47 INFO - 'ts_paint', 12:00:47 INFO - 'tpaint', 12:00:47 INFO - 'sessionrestore', 12:00:47 INFO - 'sessionrestore_no_auto_restore', 12:00:47 INFO - 'tabpaint']}, 12:00:47 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:00:47 INFO - 'tests': ['tsvgx', 12:00:47 INFO - 'tsvgr_opacity', 12:00:47 INFO - 'tart', 12:00:47 INFO - 'tscrollx', 12:00:47 INFO - 'cart']}, 12:00:47 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:00:47 INFO - 'tsvgr_opacity', 12:00:47 INFO - 'tart', 12:00:47 INFO - 'tscrollx', 12:00:47 INFO - 'cart']}, 12:00:47 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:00:47 INFO - 'talos_options': ['--disable-e10s'], 12:00:47 INFO - 'tests': ['tp5o']}, 12:00:47 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:00:47 INFO - 'tests': ['tp5o']}, 12:00:47 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:00:47 INFO - 'talos_options': ['--disable-e10s', 12:00:47 INFO - '--xperf_path', 12:00:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:00:47 INFO - 'tests': ['tp5n']}, 12:00:47 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:00:47 INFO - 'talos_options': ['--xperf_path', 12:00:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:00:47 INFO - 'tests': ['tp5n']}}, 12:00:47 INFO - 'talos.zip': {'path': '', 12:00:47 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:00:47 INFO - Running post-action listener: _resource_record_post_action 12:00:47 INFO - ##### 12:00:47 INFO - ##### Finished populate-webroot step (success) 12:00:47 INFO - ##### 12:00:47 INFO - ##### 12:00:47 INFO - ##### Running create-virtualenv step. 12:00:47 INFO - ##### 12:00:47 INFO - Running pre-action listener: _resource_record_pre_action 12:00:47 INFO - Running main action method: create_virtualenv 12:00:47 INFO - Creating virtualenv /builds/slave/test/build/venv 12:00:47 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 12:00:47 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:00:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:00:47 INFO - Using real prefix '/tools/python27' 12:00:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:00:48 INFO - Installing distribute.............................................................................................................................................................................................done. 12:00:51 INFO - Installing pip.................done. 12:00:51 INFO - Return code: 0 12:00:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:00:51 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')]} 12:00:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:00:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:00:51 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')]} 12:00:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:00:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:00:51 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 0x10e956a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e95c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b10c83970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e7fad30>, '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 0x10e61edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e7f1b20>, '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.h6ALDLbO6z/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.ukaEkXkg49/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/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 12:00:51 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 12:00:51 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 12:00:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.h6ALDLbO6z/Render', 12:00:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:00:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:00:51 INFO - 'HOME': '/Users/cltbld', 12:00:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:00:51 INFO - 'LOGNAME': 'cltbld', 12:00:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:00:51 INFO - 'MOZ_NO_REMOTE': '1', 12:00:51 INFO - 'NO_EM_RESTART': '1', 12:00:51 INFO - 'PAGER': '/bin/cat', 12:00:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:00:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:00:51 INFO - 'PWD': '/builds/slave/test', 12:00:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:00:51 INFO - 'SHELL': '/bin/bash', 12:00:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ukaEkXkg49/Listeners', 12:00:51 INFO - 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/', 12:00:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:00:51 INFO - 'USER': 'cltbld', 12:00:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:00:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:00:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:00:51 INFO - 'XPC_FLAGS': '0x0', 12:00:51 INFO - 'XPC_SERVICE_NAME': '0', 12:00:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:00:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:00:51 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 12:00:51 INFO - Cleaning up... 12:00:51 INFO - Return code: 0 12:00:51 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:00:51 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')]} 12:00:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:00:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:00:51 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')]} 12:00:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:00:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:00:51 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 0x10e956a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e95c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b10c83970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e7fad30>, '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 0x10e61edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e7f1b20>, '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.h6ALDLbO6z/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.ukaEkXkg49/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/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 12:00:51 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 12:00:51 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 12:00:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.h6ALDLbO6z/Render', 12:00:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:00:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:00:51 INFO - 'HOME': '/Users/cltbld', 12:00:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:00:51 INFO - 'LOGNAME': 'cltbld', 12:00:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:00:51 INFO - 'MOZ_NO_REMOTE': '1', 12:00:51 INFO - 'NO_EM_RESTART': '1', 12:00:51 INFO - 'PAGER': '/bin/cat', 12:00:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:00:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:00:51 INFO - 'PWD': '/builds/slave/test', 12:00:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:00:51 INFO - 'SHELL': '/bin/bash', 12:00:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ukaEkXkg49/Listeners', 12:00:51 INFO - 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/', 12:00:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:00:51 INFO - 'USER': 'cltbld', 12:00:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:00:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:00:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:00:51 INFO - 'XPC_FLAGS': '0x0', 12:00:51 INFO - 'XPC_SERVICE_NAME': '0', 12:00:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:00:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:00:51 INFO - Downloading/unpacking psutil>=3.1.1 12:00:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:00:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:00:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:00:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:00:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:00:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:00:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:00:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:00:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:00:55 INFO - Installing collected packages: psutil 12:00:55 INFO - Running setup.py install for psutil 12:00:55 INFO - building 'psutil._psutil_osx' extension 12:00:55 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 12:00:55 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 12:00:55 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 12:00:56 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 12:00:56 INFO - building 'psutil._psutil_posix' extension 12:00:56 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 12:00:56 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:00:56 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:00:56 INFO - ^ 12:00:56 INFO - 1 warning generated. 12:00:56 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 12:00:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:00:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:00:56 INFO - Successfully installed psutil 12:00:56 INFO - Cleaning up... 12:00:56 INFO - Return code: 0 12:00:56 INFO - Installing mozsystemmonitor==0.1 into virtualenv /builds/slave/test/build/venv 12:00:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:00:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:00:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:00:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:00:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:00:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:00:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e956a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e95c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b10c83970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e7fad30>, '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 0x10e61edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e7f1b20>, '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.h6ALDLbO6z/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.ukaEkXkg49/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/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 12:00:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.1'] in /builds/slave/test/build 12:00:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.1 12:00:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.h6ALDLbO6z/Render', 12:00:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:00:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:00:56 INFO - 'HOME': '/Users/cltbld', 12:00:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:00:56 INFO - 'LOGNAME': 'cltbld', 12:00:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:00:56 INFO - 'MOZ_NO_REMOTE': '1', 12:00:56 INFO - 'NO_EM_RESTART': '1', 12:00:56 INFO - 'PAGER': '/bin/cat', 12:00:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:00:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:00:56 INFO - 'PWD': '/builds/slave/test', 12:00:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:00:56 INFO - 'SHELL': '/bin/bash', 12:00:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ukaEkXkg49/Listeners', 12:00:56 INFO - 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/', 12:00:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:00:56 INFO - 'USER': 'cltbld', 12:00:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:00:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:00:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:00:56 INFO - 'XPC_FLAGS': '0x0', 12:00:56 INFO - 'XPC_SERVICE_NAME': '0', 12:00:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:00:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:00:56 INFO - Downloading/unpacking mozsystemmonitor==0.1 12:00:56 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 12:00:56 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 12:00:56 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 12:00:56 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 12:00:56 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 12:00:56 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 12:00:59 INFO - Downloading mozsystemmonitor-0.1.tar.gz 12:00:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:00:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.1) 12:00:59 INFO - Installing collected packages: mozsystemmonitor 12:00:59 INFO - Running setup.py install for mozsystemmonitor 12:00:59 INFO - Successfully installed mozsystemmonitor 12:00:59 INFO - Cleaning up... 12:00:59 INFO - Return code: 0 12:00:59 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:00:59 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')]} 12:00:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:00:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:00:59 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')]} 12:00:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:00:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:00:59 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 0x10e956a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e95c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b10c83970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e7fad30>, '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 0x10e61edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e7f1b20>, '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.h6ALDLbO6z/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.ukaEkXkg49/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/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 12:00:59 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 12:00:59 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 12:00:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.h6ALDLbO6z/Render', 12:00:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:00:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:00:59 INFO - 'HOME': '/Users/cltbld', 12:00:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:00:59 INFO - 'LOGNAME': 'cltbld', 12:00:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:00:59 INFO - 'MOZ_NO_REMOTE': '1', 12:00:59 INFO - 'NO_EM_RESTART': '1', 12:00:59 INFO - 'PAGER': '/bin/cat', 12:00:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:00:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:00:59 INFO - 'PWD': '/builds/slave/test', 12:00:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:00:59 INFO - 'SHELL': '/bin/bash', 12:00:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ukaEkXkg49/Listeners', 12:00:59 INFO - 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/', 12:00:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:00:59 INFO - 'USER': 'cltbld', 12:00:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:00:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:00:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:00:59 INFO - 'XPC_FLAGS': '0x0', 12:00:59 INFO - 'XPC_SERVICE_NAME': '0', 12:00:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:00:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:00:59 INFO - Downloading/unpacking blobuploader==1.2.4 12:00:59 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 12:00:59 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 12:00:59 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 12:00:59 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 12:00:59 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 12:00:59 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 12:01:02 INFO - Downloading blobuploader-1.2.4.tar.gz 12:01:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:01:02 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:01:02 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 12:01:02 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 12:01:02 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 12:01:02 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 12:01:02 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 12:01:02 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 12:01:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:01:03 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:01: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 12:01: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 12:01: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 12:01: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 12:01: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 12:01: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 12:01:03 INFO - Downloading docopt-0.6.1.tar.gz 12:01:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:01:03 INFO - Installing collected packages: blobuploader, requests, docopt 12:01:03 INFO - Running setup.py install for blobuploader 12:01:04 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:01:04 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:01:04 INFO - Running setup.py install for requests 12:01:04 INFO - Running setup.py install for docopt 12:01:04 INFO - Successfully installed blobuploader requests docopt 12:01:04 INFO - Cleaning up... 12:01:04 INFO - Return code: 0 12:01:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:01:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:01:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:01:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:01:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:01:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:01:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:01:04 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 0x10e956a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e95c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b10c83970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e7fad30>, '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 0x10e61edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e7f1b20>, '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.h6ALDLbO6z/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.ukaEkXkg49/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/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 12:01:04 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 12:01:04 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 12:01:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.h6ALDLbO6z/Render', 12:01:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:01:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:01:04 INFO - 'HOME': '/Users/cltbld', 12:01:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:01:04 INFO - 'LOGNAME': 'cltbld', 12:01:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:01:04 INFO - 'MOZ_NO_REMOTE': '1', 12:01:04 INFO - 'NO_EM_RESTART': '1', 12:01:04 INFO - 'PAGER': '/bin/cat', 12:01:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:01:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:01:04 INFO - 'PWD': '/builds/slave/test', 12:01:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:01:04 INFO - 'SHELL': '/bin/bash', 12:01:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ukaEkXkg49/Listeners', 12:01:04 INFO - 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/', 12:01:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:01:04 INFO - 'USER': 'cltbld', 12:01:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:01:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:01:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:01:04 INFO - 'XPC_FLAGS': '0x0', 12:01:04 INFO - 'XPC_SERVICE_NAME': '0', 12:01:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:01:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:01:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:01:05 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-d5JG1f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:01:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:01:05 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-MAlufe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:01:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:01:05 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-JVyLMR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:01:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:01:05 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-scD_oV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:01:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:01:05 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-qLMbMt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:01:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:01:05 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-am5NmO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:01:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:01:05 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-Ad1A2E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:01:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:01:05 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-mswzLR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:01:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:01:05 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-i6Xs6i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:01:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:01:06 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-dQfM00-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:01:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:01:06 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-NXuAtk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:01:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:01:06 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-k49CiQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:01:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:01:06 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-uWhyZj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:01:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:01:06 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-Xcs4k7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:01:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:01:06 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-EpPriT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:01:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:01:06 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-Ea00fk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:01:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:01:06 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-fj03Vy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:01:06 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:01:06 INFO - Running setup.py install for manifestparser 12:01:07 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:01:07 INFO - Running setup.py install for mozcrash 12:01:07 INFO - Running setup.py install for mozdebug 12:01:07 INFO - Running setup.py install for mozdevice 12:01:07 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:01:07 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:01:07 INFO - Running setup.py install for mozfile 12:01:07 INFO - Running setup.py install for mozhttpd 12:01:07 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:01:07 INFO - Running setup.py install for mozinfo 12:01:07 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:01:07 INFO - Running setup.py install for mozInstall 12:01:08 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:01:08 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:01:08 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:01:08 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:01:08 INFO - Running setup.py install for mozleak 12:01:08 INFO - Running setup.py install for mozlog 12:01:08 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:01:08 INFO - Running setup.py install for moznetwork 12:01:08 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:01:08 INFO - Running setup.py install for mozprocess 12:01:08 INFO - Running setup.py install for mozprofile 12:01:08 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:01:08 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:01:08 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:01:08 INFO - Running setup.py install for mozrunner 12:01:09 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:01:09 INFO - Running setup.py install for mozscreenshot 12:01:09 INFO - Running setup.py install for moztest 12:01:09 INFO - Running setup.py install for mozversion 12:01:09 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:01:09 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:01:09 INFO - Cleaning up... 12:01:09 INFO - Return code: 0 12:01:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:01:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:01:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:01:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:01:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:01:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:01:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:01:09 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 0x10e956a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e95c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b10c83970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e7fad30>, '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 0x10e61edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e7f1b20>, '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.h6ALDLbO6z/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.ukaEkXkg49/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/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 12:01:09 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 12:01:09 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 12:01:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.h6ALDLbO6z/Render', 12:01:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:01:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:01:09 INFO - 'HOME': '/Users/cltbld', 12:01:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:01:09 INFO - 'LOGNAME': 'cltbld', 12:01:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:01:09 INFO - 'MOZ_NO_REMOTE': '1', 12:01:09 INFO - 'NO_EM_RESTART': '1', 12:01:09 INFO - 'PAGER': '/bin/cat', 12:01:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:01:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:01:09 INFO - 'PWD': '/builds/slave/test', 12:01:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:01:09 INFO - 'SHELL': '/bin/bash', 12:01:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ukaEkXkg49/Listeners', 12:01:09 INFO - 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/', 12:01:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:01:09 INFO - 'USER': 'cltbld', 12:01:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:01:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:01:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:01:09 INFO - 'XPC_FLAGS': '0x0', 12:01:09 INFO - 'XPC_SERVICE_NAME': '0', 12:01:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:01:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:01:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:01:09 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-juFuX8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:01:09 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)) 12:01:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:01:09 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-KTvO6i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:01:10 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)) 12:01:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:01:10 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-6OkRkz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:01:10 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)) 12:01:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:01:10 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-V1n3BW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:01:10 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)) 12:01:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:01:10 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-j_asCF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:01:10 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)) 12:01:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:01:10 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-wFrpU3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:01:10 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)) 12:01:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:01:10 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-y9a1xT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:01:10 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)) 12:01:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:01:10 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-v0VmGO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:01:10 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)) 12:01:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:01:10 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-_Uola3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:01:10 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)) 12:01:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:01:10 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-hdzTNG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:01:10 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)) 12:01:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:01:10 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-AjY6gM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:01:11 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)) 12:01:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:01:11 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-RYdAUu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:01:11 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)) 12:01:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:01:11 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-OvRGQq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:01:11 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)) 12:01:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:01:11 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-VuUxIj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:01:11 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)) 12:01:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:01:11 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-xpj1CF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:01:11 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)) 12:01:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:01:11 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-86iqEg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:01:11 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)) 12:01:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:01:11 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-2ny_vP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:01:11 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)) 12:01:11 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)) 12:01:11 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)) 12:01:11 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)) 12:01:11 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)) 12:01:11 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)) 12:01:11 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:01:11 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 12:01:11 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 12:01:11 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 12:01:11 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 12:01:11 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 12:01:11 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 12:01:14 INFO - Downloading blessings-1.6.tar.gz 12:01:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:01:14 INFO - Installing collected packages: blessings 12:01:14 INFO - Running setup.py install for blessings 12:01:14 INFO - Successfully installed blessings 12:01:14 INFO - Cleaning up... 12:01:14 INFO - Return code: 0 12:01:14 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:01:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:01:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:01:14 INFO - Reading from file tmpfile_stdout 12:01:14 INFO - Current package versions: 12:01:14 INFO - blessings == 1.6 12:01:14 INFO - blobuploader == 1.2.4 12:01:14 INFO - docopt == 0.6.1 12:01:14 INFO - manifestparser == 1.1 12:01:14 INFO - mozInstall == 1.12 12:01:14 INFO - mozcrash == 0.17 12:01:14 INFO - mozdebug == 0.1 12:01:14 INFO - mozdevice == 0.48 12:01:14 INFO - mozfile == 1.2 12:01:14 INFO - mozhttpd == 0.7 12:01:14 INFO - mozinfo == 0.9 12:01:14 INFO - mozleak == 0.1 12:01:14 INFO - mozlog == 3.2 12:01:14 INFO - moznetwork == 0.27 12:01:14 INFO - mozprocess == 0.22 12:01:14 INFO - mozprofile == 0.28 12:01:14 INFO - mozrunner == 6.11 12:01:14 INFO - mozscreenshot == 0.1 12:01:14 INFO - mozsystemmonitor == 0.1 12:01:14 INFO - moztest == 0.7 12:01:14 INFO - mozversion == 1.4 12:01:14 INFO - psutil == 3.1.1 12:01:14 INFO - requests == 1.2.3 12:01:14 INFO - wsgiref == 0.1.2 12:01:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:01:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:01:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:01:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:01:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:01:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:01:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:01:14 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 0x10e956a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e95c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b10c83970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e7fad30>, '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 0x10e61edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e7f1b20>, '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.h6ALDLbO6z/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.ukaEkXkg49/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/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 12:01:14 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 12:01:14 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 12:01:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.h6ALDLbO6z/Render', 12:01:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:01:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:01:14 INFO - 'HOME': '/Users/cltbld', 12:01:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:01:14 INFO - 'LOGNAME': 'cltbld', 12:01:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:01:14 INFO - 'MOZ_NO_REMOTE': '1', 12:01:14 INFO - 'NO_EM_RESTART': '1', 12:01:14 INFO - 'PAGER': '/bin/cat', 12:01:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:01:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:01:14 INFO - 'PWD': '/builds/slave/test', 12:01:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:01:14 INFO - 'SHELL': '/bin/bash', 12:01:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ukaEkXkg49/Listeners', 12:01:14 INFO - 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/', 12:01:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:01:14 INFO - 'USER': 'cltbld', 12:01:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:01:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:01:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:01:14 INFO - 'XPC_FLAGS': '0x0', 12:01:14 INFO - 'XPC_SERVICE_NAME': '0', 12:01:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:01:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:01:14 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)) 12:01:14 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)) 12:01:14 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)) 12:01:14 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)) 12:01:14 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)) 12:01:14 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)) 12:01:14 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)) 12:01:14 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)) 12:01:14 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)) 12:01:14 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)) 12:01:14 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)) 12:01:14 INFO - Cleaning up... 12:01:14 INFO - Return code: 0 12:01:14 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:01:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:01:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:01:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:01:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:01:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:01:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:01:14 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 0x10e956a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e95c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b10c83970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e7fad30>, '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 0x10e61edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e7f1b20>, '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.h6ALDLbO6z/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.ukaEkXkg49/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/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 12:01:14 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 12:01:14 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 12:01:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.h6ALDLbO6z/Render', 12:01:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:01:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:01:14 INFO - 'HOME': '/Users/cltbld', 12:01:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:01:14 INFO - 'LOGNAME': 'cltbld', 12:01:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:01:14 INFO - 'MOZ_NO_REMOTE': '1', 12:01:14 INFO - 'NO_EM_RESTART': '1', 12:01:14 INFO - 'PAGER': '/bin/cat', 12:01:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:01:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:01:14 INFO - 'PWD': '/builds/slave/test', 12:01:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:01:14 INFO - 'SHELL': '/bin/bash', 12:01:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ukaEkXkg49/Listeners', 12:01:14 INFO - 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/', 12:01:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:01:14 INFO - 'USER': 'cltbld', 12:01:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:01:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:01:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:01:14 INFO - 'XPC_FLAGS': '0x0', 12:01:14 INFO - 'XPC_SERVICE_NAME': '0', 12:01:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:01:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:01:15 INFO - Downloading/unpacking jsonschema 12:01:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:01:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:01:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:01:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:01:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:01:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:01:17 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:01:17 INFO - Downloading/unpacking functools32 (from jsonschema) 12:01:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:01:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:01:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:01:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:01:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:01:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:01:17 INFO - Downloading functools32-3.2.3-2.tar.gz 12:01:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:01:18 INFO - warning: no files found matching '*.txt' 12:01:18 INFO - no previously-included directories found matching 'build' 12:01:18 INFO - no previously-included directories found matching 'dist' 12:01:18 INFO - no previously-included directories found matching '.git*' 12:01:18 INFO - Installing collected packages: jsonschema, functools32 12:01:18 INFO - Running setup.py install for functools32 12:01:18 INFO - warning: no files found matching '*.txt' 12:01:18 INFO - no previously-included directories found matching 'build' 12:01:18 INFO - no previously-included directories found matching 'dist' 12:01:18 INFO - no previously-included directories found matching '.git*' 12:01:18 INFO - Successfully installed jsonschema functools32 12:01:18 INFO - Cleaning up... 12:01:18 INFO - Return code: 0 12:01:18 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 12:01:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:01:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:01:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:01:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:01:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:01:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:01:18 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 0x10e956a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e95c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b10c83970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e7fad30>, '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 0x10e61edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e7f1b20>, '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.h6ALDLbO6z/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.ukaEkXkg49/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/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 12:01:18 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 12:01:18 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 12:01:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.h6ALDLbO6z/Render', 12:01:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:01:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:01:18 INFO - 'HOME': '/Users/cltbld', 12:01:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:01:18 INFO - 'LOGNAME': 'cltbld', 12:01:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:01:18 INFO - 'MOZ_NO_REMOTE': '1', 12:01:18 INFO - 'NO_EM_RESTART': '1', 12:01:18 INFO - 'PAGER': '/bin/cat', 12:01:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:01:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:01:18 INFO - 'PWD': '/builds/slave/test', 12:01:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:01:18 INFO - 'SHELL': '/bin/bash', 12:01:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ukaEkXkg49/Listeners', 12:01:18 INFO - 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/', 12:01:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:01:18 INFO - 'USER': 'cltbld', 12:01:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:01:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:01:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:01:18 INFO - 'XPC_FLAGS': '0x0', 12:01:18 INFO - 'XPC_SERVICE_NAME': '0', 12:01:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:01:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:01:18 INFO - Downloading/unpacking flake8 12:01:18 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 12:01:18 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 12:01:18 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 12:01:18 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 12:01:18 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 12:01:18 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 12:01:21 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 12:01:21 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 12:01:21 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 12:01:21 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 12:01:21 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 12:01:21 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 12:01:21 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 12:01:21 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 12:01:21 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 12:01:21 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 12:01:21 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 12:01:21 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 12:01:21 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 12:01:21 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 12:01:21 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 12:01:21 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 12:01:21 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 12:01:21 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 12:01:21 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 12:01:21 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 12:01:21 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 12:01:21 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 12:01:21 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 12:01:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 12:01:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 12:01:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 12:01:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 12:01:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 12:01:22 INFO - no previously-included directories found matching 'docs/_build' 12:01:22 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 12:01:22 INFO - Running setup.py install for pep8 12:01:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 12:01:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 12:01:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 12:01:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 12:01:22 INFO - no previously-included directories found matching 'docs/_build' 12:01:22 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 12:01:22 INFO - Successfully installed flake8 mccabe pyflakes pep8 12:01:22 INFO - Cleaning up... 12:01:22 INFO - Return code: 0 12:01:22 INFO - Running post-action listener: _resource_record_post_action 12:01:22 INFO - Running post-action listener: _start_resource_monitoring 12:01:22 INFO - Starting resource monitoring. 12:01:22 INFO - ##### 12:01:22 INFO - ##### Finished create-virtualenv step (success) 12:01:22 INFO - ##### 12:01:22 INFO - ##### 12:01:22 INFO - ##### Running install step. 12:01:22 INFO - ##### 12:01:22 INFO - Running pre-action listener: _resource_record_pre_action 12:01:22 INFO - Running main action method: install 12:01:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:01:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:01:22 INFO - Reading from file tmpfile_stdout 12:01:22 INFO - Detecting whether we're running mozinstall >=1.0... 12:01:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:01:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:01:23 INFO - Reading from file tmpfile_stdout 12:01:23 INFO - Output received: 12:01:23 INFO - Usage: mozinstall [options] installer 12:01:23 INFO - Options: 12:01:23 INFO - -h, --help show this help message and exit 12:01:23 INFO - -d DEST, --destination=DEST 12:01:23 INFO - Directory to install application into. [default: 12:01:23 INFO - "/builds/slave/test"] 12:01:23 INFO - --app=APP Application being installed. [default: firefox] 12:01:23 INFO - mkdir: /builds/slave/test/build/application 12:01:23 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'] 12:01:23 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 12:01:56 INFO - Reading from file tmpfile_stdout 12:01:56 INFO - Output received: 12:01:56 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 12:01:56 INFO - Running post-action listener: _resource_record_post_action 12:01:56 INFO - ##### 12:01:56 INFO - ##### Finished install step (success) 12:01:56 INFO - ##### 12:01:56 INFO - ##### 12:01:56 INFO - ##### Running run-tests step. 12:01:56 INFO - ##### 12:01:56 INFO - Running pre-action listener: _resource_record_pre_action 12:01:56 INFO - Running main action method: run_tests 12:01:56 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:01:56 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:01:56 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:01:56 INFO - 2016-05-13 12:01:56.106 screenresolution[1645:8193] starting screenresolution argv=screenresolution get 12:01:56 INFO - 2016-05-13 12:01:56.109 screenresolution[1645:8193] Display 0: 1600x1200x32@60 12:01:56 INFO - 2016-05-13 12:01:56.115 screenresolution[1646:8195] starting screenresolution argv=screenresolution list 12:01:56 INFO - Available Modes on Display 0 12:01:56 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:01:56 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:01:56 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:01:56 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:01:56 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:01:56 INFO - Intel Iris: 12:01:56 INFO - Chipset Model: Intel Iris 12:01:56 INFO - Type: GPU 12:01:56 INFO - Bus: Built-In 12:01:56 INFO - VRAM (Dynamic, Max): 1536 MB 12:01:56 INFO - Vendor: Intel (0x8086) 12:01:56 INFO - Device ID: 0x0a2e 12:01:56 INFO - Revision ID: 0x0009 12:01:56 INFO - Displays: 12:01:56 INFO - Display: 12:01:56 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:01:56 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:01:56 INFO - Display Serial Number: 1600x1200 60 12:01:56 INFO - Main Display: Yes 12:01:56 INFO - Mirror: Off 12:01:56 INFO - Online: Yes 12:01:56 INFO - Rotation: Supported 12:01:56 INFO - Return code: 0 12:01:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:01:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:01:56 INFO - Python 2.7.3 12:01:56 INFO - Return code: 0 12:01:56 INFO - grabbing minidump binary from tooltool 12:01:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:01:56 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 0x10e7fad30>, '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 0x10e61edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e7f1b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:01:56 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 12:01:56 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 12:01:56 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 12:01:56 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:01:56 INFO - Return code: 0 12:01:56 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:01:56 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:01:56 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:01:56 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:01:56 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:01:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 12:01:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 12:01:57 INFO - Return code: 0 12:01:57 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-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/a-NOy8zESG2EKgTNwCXYbg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0153', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 12:01:57 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-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/a-NOy8zESG2EKgTNwCXYbg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0153 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 12:01:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.h6ALDLbO6z/Render', 12:01:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:01:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:01:57 INFO - 'HOME': '/Users/cltbld', 12:01:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:01:57 INFO - 'LOGNAME': 'cltbld', 12:01:57 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:01:57 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:01:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:01:57 INFO - 'MOZ_NO_REMOTE': '1', 12:01:57 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:01:57 INFO - 'NO_EM_RESTART': '1', 12:01:57 INFO - 'PAGER': '/bin/cat', 12:01:57 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:01:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:01:57 INFO - 'PWD': '/builds/slave/test', 12:01:57 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:01:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:01:57 INFO - 'SHELL': '/bin/bash', 12:01:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ukaEkXkg49/Listeners', 12:01:57 INFO - 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/', 12:01:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:01:57 INFO - 'USER': 'cltbld', 12:01:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:01:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:01:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:01:57 INFO - 'XPC_FLAGS': '0x0', 12:01:57 INFO - 'XPC_SERVICE_NAME': '0', 12:01:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:01:57 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/a-NOy8zESG2EKgTNwCXYbg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0153', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 12:01:57 INFO - mozversion application_buildid: 20160513093143 12:01:57 INFO - mozversion application_changeset: c4a209a8e2a8483f71182ecfa6044b830fd85362 12:01:57 INFO - mozversion application_display_name: Nightly 12:01:57 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:01:57 INFO - mozversion application_name: Firefox 12:01:57 INFO - mozversion application_remotingname: firefox 12:01:57 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 12:01:57 INFO - mozversion application_vendor: Mozilla 12:01:57 INFO - mozversion application_version: 49.0a1 12:01:57 INFO - mozversion platform_buildid: 20160513093143 12:01:57 INFO - mozversion platform_changeset: c4a209a8e2a8483f71182ecfa6044b830fd85362 12:01:57 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 12:01:57 INFO - mozversion platform_version: 49.0a1 12:01:57 INFO - using testdate: 1463166117 12:01:57 INFO - actual date: 1463166117 12:01:57 INFO - starting webserver on 'localhost:49275' 12:01:57 INFO - SUITE-START | Running 2 tests 12:01:57 INFO - TEST-START | dromaeo_css 12:01:57 INFO - operating with platform_type : mac_ 12:01:57 INFO - Initialising browser for dromaeo_css test... 12:01:57 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/tmpkRlLx7/profile http://localhost:49275/getInfo.html) 12:02:00 INFO - PROCESS | 1664 | __metrics Screen width/height:1600/1200 12:02:00 INFO - PROCESS | 1664 | colorDepth:24 12:02:00 INFO - PROCESS | 1664 | Browser inner width/height: 1024/689 12:02:00 INFO - PROCESS | 1664 | __metrics 12:02:00 ERROR - PROCESS | 1664 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 12:02:01 INFO - TEST-INFO | 1664: exit 0 12:02:01 INFO - Browser initialized. 12:02:01 INFO - Running cycle 1/1 for dromaeo_css test... 12:02:01 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/tmpkRlLx7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:02:13 INFO - PROCESS | 1668 | RSS: Main: 249536512 12:02:13 INFO - PROCESS | 1668 | 12:03:03 INFO - PROCESS | 1668 | 1463166183181 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] 12:05:13 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 12:05:14 INFO - PROCESS | 1668 | RSS: Main: 261513216 12:05:14 INFO - PROCESS | 1668 | 12:08:17 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 12:08:17 INFO - PROCESS | 1668 | RSS: Main: 273068032 12:08:17 INFO - PROCESS | 1668 | 12:11:19 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 12:11:20 INFO - PROCESS | 1668 | RSS: Main: 274944000 12:11:20 INFO - PROCESS | 1668 | 12:14:21 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 12:14:22 INFO - PROCESS | 1668 | RSS: Main: 276729856 12:14:22 INFO - PROCESS | 1668 | 12:17:25 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 12:17:25 INFO - PROCESS | 1668 | RSS: Main: 279621632 12:17:25 INFO - PROCESS | 1668 | 12:20:28 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 12:20:28 INFO - PROCESS | 1668 | RSS: Main: 280399872 12:20:28 INFO - PROCESS | 1668 | 12:20:28 INFO - PROCESS | 1668 | __start_tp_report 12:20:28 INFO - PROCESS | 1668 | _x_x_mozilla_page_load 12:20:28 INFO - PROCESS | 1668 | _x_x_mozilla_page_load_details 12:20:28 INFO - PROCESS | 1668 | |i|pagename|runs| 12:20:28 INFO - PROCESS | 1668 | |0;dojo.html;2898.362360280739;3236.1909522619344;3287.9671203287967;3465.7747246428994;3479.391106556352;5620;5627.127795191742;5708.9999999999945;5711.200431939532;5720.313562372521;5713.914291285623;5819.738111784969;5836.766529338822;5848.6490810551395;5932.584719069667;4059.350503919381;4788.521147885201;4795.568398844103;5346.304980352568;5363.422921847001;399.47483673901877;4425.645948324126;5204.245384419257;5246.632735708502;5295.761690723926;5252.842414727565;5259.710715910624;5264.078786212417;5290.761915713783;5306.071437498423;5271;5305.920411193835;5316.38861530925;5332.066888294533;5380.24676545284;4413.977930110361;4804.351412559329;5111.952049829776;5166.560842328407;5215.895682086375;5526.50261476465;5617.185508101303;5630.465105814941;5654.830355089354;5706.343770466408;6176.320604733476;6253.96873015632;6282.403171698681;6287.371262873676;6301.464375528086;5303.973480132613;6216.720247588869;6250.15622890913;6298.905516417258;6325;5901.616394934315;5949.226600541945;5960.493358064569;5969.074793406985;5981.730822113015;6212.409821066992;6274.905876411857;6355.618662880242;6367.0449432584555;6367.713452894631;6049.848753781191;6049.969750151309;6092.268927728701;6093.238345206849;6190.3190649028575;6238.937610623836;6252.781152659635;6266.968665156645;6270.529710271748;6279.591826531261;6203.4416902479;6238.65687387189;6251.593646412968;6270.843228919313;6281.59169653971;6246.593971391845;6249.281332646713;6256.061590761331;6270.059491076284;6276.592021518678;4975.129352363394;5004.724740139327;5010.1733214020005;5013.674111182761;5025.045241404122;4401.691881568324;4470.150671372428;4488.528704486047;4499.505054444008;4533.637309015271;5742.167385729046;5868.970655146697;5924.763009479659;6025.397460253939;6026.156338112668;6292.244930608264;6292.46514046302;6295.150154729145;6307.905381419282;6313.527067568169;4703.3885594872445;5716.456936591017;5878.382769809194;5960.552958528041;6011.008183649737;5464.207689884945;5476.808311709071;5489.917651235235;5496.587755918322;5501.477359650827;6426.907425737636;6456;6489.059086432535;6489.643069631215;6493.740250390026;6183.227096612924;6184.165137706352;6230.875382492415;6261.499080073492;6287.654179020198;6386.744530218739;6443;6451.741930322923;6457.354264573505;6574.408303252731;6138.232720909887;6248.4064013918605;6259.812205633838;6261.7182226799;6287.245530536365;5805.82582522525;5892.762519870704;6195.101710252078;6280.466160376236;6290.2451705793765;6061.8153520761625;6180.536459765445;6229.345918678474;6294;6302.905456418066;6124.510039196943;6147.647517546133;6281.874362512633;6287.088372185916;6302.1492098566105;5915.526757859275;6034.9094763577705;6096.512279017757;6098.573099883143;6103.145559621746;6233.127362169391;6235.625862448268;6251.718672659832;6268.561200715906;6293.087502312231;5924.555658325729;5959.255093113361;5985.970070149621;6113.083037544404;6147.415995480422 12:20:28 INFO - PROCESS | 1668 | |1;ext.html;504.6417043898832;538.9919151212732;547.9041167795634;551.4733429574757;560.8261438953928;41178;43468.6959391218;43510.78244608777;43515.78242108785;43571.346429803576;44413.55586444135;44670.883227919236;45151;45164;45168;9632.711018669452;9762.658306959258;9813;9823.90176098237;9848.458334791583;9654.517274136266;9691.612335506607;9761.219102471785;9765.12113909748;9796.069373409515;9755.95122024389;9785.902140978606;9792.020797920222;9800.607975681;9808.21534277256;9734.977827328135;9788.804223915553;9792.363496372713;9803;9807.901920980841;9607.4715890626;9682.9515852421;9695.806083878282;9711.465869377182;9716;41108.38337424941;41364.37953430701;41398;41430.792846035576;41449.378259326135;2820.393615372684;3097.6871395445646;3114.8131112133347;3212.7429805615493;3400.2009527760956;21111.15555377783;21148.933404656393;26610.6008409874;27828.30429239265;27901.302467438272;2624.8818803153904;2704.5267078261422;2735.179446166142;2750.931226719328;2762.1575419497044;469176;469953;473004.63497682294;474024.6298768484;475177;29493;35261.294774104375;35495.29009419797;35581.64418355783;35586.28827423437;37365.06587335284;38154.23691526154;38279;38495.230095397936;38673.80663096667;16786.412475563542;17121;17285.395011174547;17320.65358692819;17336.826631733773;13116.540921067717;13138.802917956238;13169.683031696753;13247.867521324664;13262.469501219843;20320.69518957217;20437.182512699324;20550.58898822015;20586.58826823485;20679;8467.32261419085;8503.447275927045;8554.486730796172;8563.743087707378;8622.698205562776;943.6364453364822;979.1628157925014;1010.161565900301;1022.4683164754287;1094.7902567662618;30692.772289108187;30880;30955.380892382025;31075.223119421742;31175.376492470477;1060.7560261139981;1073.909981368911;1104.4698544698429;1133.2010932292715;1158.8319693644373;15745.44890928812;15893.364265429485;15925.442609508611;16193.838061619468;16261.18694065292;740776;742211;747288;748055;751082;49873.750631246614;62259.6887015562;62716;63073;63126.36873631205;1411.5906387147613;1462.6855226126434;1470.5955862137785;1476.852314768536;1484.3320505772447;29097.854510728157;29942.85028574843;30080.097597072123;30472.84763576168;30644.693553064182;55147.44852551423;55448;55479;55602.165967511326;55635.72182139063;596059;623528;635721.8566886001;648557;653019;27309.453810923274;27414.725852741216;27761.722382775915;27804.72195278021;27809.16572502828;47428.814279641905;47551;48981.51018489769;49109.75445122751;49238.76903077308;39108.80445597754;39482.40776388299;39558;39945.801625951266;40424.59575404326;38277.425838613;38452;38936.41595376128;39190.412143817295;39888.80055599703;38634.227315454096;38961.805190973864;39280.2143957125;39379.42482300676;39788.6073987409;32033.839830800698;33621.3275734479;33855.661443386234;34303.82848085744;34320;22484.32547023592;23233.07482184691;23969.880150599136;25719.485610288062;25936.351591210372 12:20:28 INFO - PROCESS | 1668 | |2;jquery.html;341.330991257136;389.89862635714707;392.38003953753065;405.93910913362976;412.13246116923875;17703.911480442614;19839.40481785545;20726.963651817423;21756.12975480981;21770.673439898375;20514.589708205865;20644.793552064475;20938.16247350108;21247.787522124774;21592.920353982317;5603.131514615241;5878.970605146979;6017.488513476338;6027.336992930774;6081.78713745019;5859.355470898197;5995.250593675791;5996.520278377741;6074.848128796772;6115.48018418435;5927.762889484416;5970.194023806774;6026.5178785697035;6043.244594425697;6055.878882422349;5233.450487698794;5918.585699001072;6005.909911351333;6019.367966363535;6020.127081573148;5052;5134.358205224347;5213;5235.58115350771;5265;12848.743025139538;13524;13526.323683815868;13756.105853119516;25386.492270154493;2030.1067530286628;2330.7669233076726;2776.403073339242;2784.2343355577377;2793.7904657150593;9917.75205619858;10087.798244035152;10091;11891.702707432298;15655.843441565667;1699.9065051422167;1786.767720196379;1812.465322729798;1816.6548355812354;1834.110456428631;106077;132843.33578332144;133062;135871;137390;10810.783784324269;10914.853940336308;11857.169998100222;12181.390930453443;12362.690932726573;12589.874101259053;15051.1721855299;15085;15090.622734431507;15115.470958516398;8543.572821358906;8753.299736021103;8822.558872056372;8876.112388761072;8886.955565222133;8689.261412779859;8741.475511469333;8748;8749.212570868525;8916.687915922912;4673.25227963531;4776.9761151194025;4780.82472475023;4801.567858892717;4818.566329030404;2011.7787043425212;2065.297798748433;2067.9379618611465;2073.2639912831028;2097.538541520863;828.6685325869701;934.9439033658002;936.7049379445452;971.1259866120548;981.4111533080095;11387.544498220144;11774.293542387484;11838.934495895412;12081.758364832654;19703.802961970483;437.35926867139796;451.81927229107964;453.56231236855933;458.08383233532936;475.76449657419107;4494.10117976406;5748.5688573357165;6229.065640154013;6312.400321969406;6316.115743795872;610478.9476052591;620826;628564.7145878447;630191;639507.8024609847;136150;140438;141878;143060.5693943068;143158.76239516458;453.46491140454583;477.0887604675123;480.1621171056459;483.1713611156883;484.8400027990755;10335.431551264752;11104.61133860311;11249.718757030812;11593.188476806543;11714.297142171497;34318;42772.786136069124;45621.08757824892;52446.73776631092;54414.36756897141;74092;74161;78297;78723.81914271406;80255.1974480271;7233.493655444279;8035.437519373588;8131.430799843954;8392.83214335722;8551.1448855114;7301.160366557701;9026.684066057656;10297.07326340633;11973.940130299292;12737.363131843185;10495.265331426726;11251.718707032389;11267.436628168723;11267.774644507228;11699.88300117022;10441.477926103817;10760.139188864716;10913.18151138652;11326.150538709466;11331.830022549504;8889.777755556162;10854.914508549082;10935.125189984941;11526.769464610494;11899.286042837457;7024.455437644724;8278.213569710868;8287.337013039063;8409.663613455637;8494.74515764528;10496;11301;11548.42257887119;11631.53473861036;11668 12:20:28 INFO - PROCESS | 1668 | |3;mootools.html;1491.4481641792536;1603.9518814435564;1855.396995976308;1966.891820949849;2009.889456079915;6945.166580010393;8932.59803308851;8982.236509896658;9462.006489318626;9469.242460603153;8975.50634715092;9038.051004644516;9054.04932482088;9117.726468205949;9210.756547866036;2092.9790702093014;2433.3916520869825;2471.1351027140536;2476.0590975429313;2479.603263477844;2424.9151279705216;2437.524682686877;2480.342709182068;2485.72657007729;2487.7139129000216;2454.153317105605;2456.5823809952262;2472.765087316711;2475.4306509502826;2480.1071614218868;2190.8356873234493;2428.854268743881;2431.6960379952507;2441.377448750557;2462.729099799021;2436.6954130733657;2438.67077944477;2441.292025312675;2447.4003869052017;2451.301379106946;9174.357794954356;9235.630574777068;9310.953445232799;9314.813703725955;9342.065793420672;5434.945650543523;5639.830805075854;5653.717314134276;5672.205891175238;5685.090385538294;3647.1429214134655;3658.652428019334;3705.055210921225;3750.1749615084636;3750.662440380379;1052.3422860712055;1064.3401091323428;1065.9946700266526;1067.7330667333167;1074.7796701676139;12201.572944946973;12344.321062341567;12362.938185309105;12367;12372.938135309356;4888.193448081027;4909.3126962225315;5039.4708555601865;5042.865355295103;5072.239164125411;10332.173426125892;10464.424456654957;10471.3193642413;10502.579896804195;10511.421871796972;5444.265024221759;5463.3717122530625;5567.721613919288;5577.163425486209;5588.441155884379;4533.161365147458;4890.633202509826;5037.395512538519;5063.620228482879;5094.872628184251;3658.670719635192;4103.692223083281;4172.290710579209;4206.726562773471;4219.831206751757;1160.2748282323548;1338.4044100375434;1341.704824938512;1346.2528296795176;1361.8297712785902;863.8445079885555;906.2478143141177;935.5415846235296;943.9339246252833;945.0691069296737;6080.604760690541;6084.0265557511475;6144.631322120687;6153.169322141453;6188.938110618837;463.1894185175942;471.1237098995865;472.1854800469232;481.1988039913505;484.5251653379637;4026.5973402660084;4094.385842123705;4283.528811830696;4297.527272000078;4353.085851971122;9836.655717050013;11115.166362522843;11335.829962550568;11410.258333208314;11432.428378581038;11098;11415.372154531415;11469.655910322703;11497.655070347902;11502.964733174054;8100.797480062928;8572.571371431528;8673.262772664268;8702.173293537104;8709.738707838775;6066.120412540069;6070.421690360627;6204.583009815126;6257.968710156602;6276.874462510816;9908.306418550632;10468.528916198942;10603.310784798963;10603.628872989408;10646.84029739539;10552.947235263775;10575.735606609896;10742.355458672504;10745.462726863781;10746.570137194425;6993.440524757905;7694.269044440769;7747.2640099190485;7822.726204582812;7984.481008734414;8664.566771661517;8719.607617657093;8747.125287471457;8775;8778.429402088845;7542.47202695806;7628.961855190689;7723.961380193288;7762.961185194038;7884.369250460064;7468.5145465544565;7593.506422082547;7639;7694.923050769645;7814.804629884298;6814.080099186546;6847.246802851782;6870.072540207008;6873.347032031949;6875.312468753084;4413.837219762358;4925.507449255046;5135.537801597725;5157.252198431282;5174.249733788504;5038.697678139323;5065.4174769900455;5083.644144909968;5122.078025955215;5344.144936810092 12:20:28 INFO - PROCESS | 1668 | |4;prototype.html;316.7893350921637;319.8145075856002;321.72974701250945;328.631932235896;332.2706658883751;4447.66642501812;4974.079795237883;5401.081816091264;5439.91840122398;5465.453454654533;4894.3392641993405;5310.6813591184555;5313.946860531393;5316.388615309231;5316.707581083039;3458.4639380896;3856.0745421098945;3977.582353852847;4086.16233672098;4091.813374121501;3615.2407994321225;4028.388644542177;4053.695972802037;4126.731762435432;4128.8529964418485;3330.800151990876;4058.6144316289906;4061.8984525386954;4091.1204091120403;4091.590840915914;3708.8687950175186;3805.469362873686;3863.2080423513394;4045.3527435610454;4081.265372232997;3552.342816578941;3555.822208889545;3563.82180890957;3578.4095624221973;3597.8846557566976;4083.958590559418;4552.772361381918;4720.315554244616;4745.810167593292;5126.61550383719;4505.189065968125;4554.54054810402;4712.858614241578;4726.243800991859;4745.600047985852;5049.6212784041345;5235.136202526579;5255.474452554752;5685.379666794985;5697.287839020122;3458.291050334675;3953.644172024503;3964.6630036446936;3979.985103798542;3987.8604248851443;5214.556762675177;5584.274044374246;5688.687122208277;5788.768449262025;5980.295615749491;3409.880654177092;3987.601239875989;3998.980005099956;4002.419649150857;4018.678505719535;3679.988003299114;3695.6304369562827;3953.347697629859;4099.134893623394;4113.568075352045;4245.915081698348;4252.4691111199945;4713.198756211384;4757.452892917289;4802.286708009963;5168.8449346519665;5684.8294551163535;5718;5759.884802303931;5762.769489220468;1310.344827586207;1383.3152589468077;1392.644875556747;1403.382511694851;1419.254891182117;815.8042069903299;829.6515463505342;840.8654615261529;895.8745775591423;896.4442045931564;5001.524855138756;5191.480851914854;5227.144363750814;5377.596680248997;5426.34883813937;4984.626153038536;5056.747162641853;5329.86675333112;5869.943410186207;5951.9107213391835;4694.568156712236;5089.338386009795;5236.450172731808;5436.483534064258;5465.535429488458;2753.7796976241852;2954.763618910482;3125.5311703244242;3132.6554079051284;3152.2907345847;5903.7048147593305;6111.57218994675;6172.8765424691255;6193.049189505291;6209.0131158029;5408.378036525771;5623.831285061455;5651.717414129277;5676.6659834938755;6151.907721384183;5264.8420547382875;5422.7017514036315;5648.655807839783;6149.631022138597;6161.3814128995;4630.976845115752;5486.478784515624;5629.746661400165;5887.499562537061;5896.823095307148;5016.874578135576;5599.692016939108;5738.426157384395;5780.508656764054;5836.299644042742;5262.842174721555;5533.620543166316;6117.449429551361;6139.754409823557;6148.354422785543;3887.222555488857;4026.9395959061226;4037.7375470594316;4070.5752986455686;4074.7555146691293;5278.413577347149;5786.768529258782;5800.477956983892;5834.824955251349;5880;4747.715137091785;4914.70511769295;5033.899322013613;5525.364583072998;5695.174199741097;5174.689518628894;5609.186667933209;5643.294588176478;5664.915026274692;5718.741097266639;4741.478437371818;5330.466953304638;5565.526930210977;5570.972145139248;5579.497845193952;5169.353830771153;5192.844214673566;5305.726625609903;5690.829275121588;5736.422483816984;5059.711187727859;5372;5550.888982220414;5573.637713548606;5574.749136288796 12:20:28 INFO - PROCESS | 1668 | |5;yui.html;668.2281964331196;693.4937495628188;713.7323503686117;718.9496735228535;763.5800309829592;14844.480443184477;15172.54482365528;15308.540743777676;15315.310811013504;15519.767203491943;12275;14039.455659877405;14223.217723025255;14314.35585398657;14357.13857168568;752.5597525447592;938.4791440750381;939.8966113727485;961.1589858873485;965.657191696948;931.9953400233023;943.9008904065043;944.1266828183936;944.7354740672588;947.5830634520823;866.1944391715701;952.6284748947916;983.7408117609472;1006.1849901579744;1009.8384258518638;948.7248697877607;991.2020823237256;991.454699915051;992.6575331510653;996.0238965813471;661.3650895140671;732.641005907107;741.7774667599753;743.156517352803;744.8733715268363;12325.93837030818;14672.339744711408;14884.553463396007;14894.180820054893;14902.105873647615;12340.259584424874;12539;12575.18261313021;12589.433475493624;12804.551840685532;9997.200223982063;9999.450030248334;10080.949595251977;10257.948710256476;10323.690289291259;3494.8102068428366;3546.804925729084;3610.4223324268255;3696.7597106188014;3741.681957033655;86844.82887927789;86846.56576717139;87143.56428217882;90174;90286;9289.907100928904;9414.529273536296;9489.762755931157;9535.952320238355;9644.18024467928;21963.901804909692;22184.112635494723;22233;22419.88790056072;22534.661980070312;7230.530015548972;7287.125544934642;7338.41292696583;7362.668679909417;7492.662830172655;3818.4845045918446;3863.5750067492554;3937.665298449664;3947.624975627311;3992.7404718693774;3577.713782897414;3674.283514714605;3687.2809802088336;3687.3547129252274;3690.612485689018;640.8878446271884;652.5627829354344;656.2322083162711;656.4879394072631;662.453475882399;482.98792530186324;581.1892410087904;585.4116612804105;601.9006863867413;606.5238787551839;8166.550839703873;8202.712905048415;8377.07254336258;8438.620262088221;8448.535330556879;526.689253340531;571.459970328036;595.7587177193244;609.4910749524197;611.3916652930362;3806.257779732981;3937.665298449664;3938.2911076006594;3986.0234242610168;3999.760014399087;88033;93963;95431.52284238534;95554.52222738841;96987;83516.74724879132;87065.56467217623;87084.56457717798;87564.56217718871;89912;77459;77512.44975100466;77575.8363624546;77822.61088694634;78426.6078669603;7709.267619576131;7793.727219547289;7900.6049697513745;7925.484843485155;7952.483088599222;47775.28337074877;49315.753421232665;49423.752881236804;49759.751201243766;49920.75039624924;61285.08072379007;61887.69056154691;63667.68166159139;69629;69713.65143174252;11044.834327485254;11189.216754827085;11304.547818087185;11347;11454.427278635869;11966.341851198267;11990.940045299718;12063.336516491678;12126.27242365461;12132;11666.883331166919;11712.648620541397;11792.29246245228;11840.407979601157;12034.879651203375;11478.713032173928;11551.942240288745;11607.825882611936;11861.525538978343;11905.796558527232;11429.314241145557;11574.88425115738;11684.88315116838;11823.826483972563;11850.585229516926;9628.422294662343;9701.854472183064;9907;10024.047715467019;10384.32501887375;9599.712008639752;9781.902180978384;9792.167665748491;9915.65295214664;9933.900660993586 12:20:28 INFO - PROCESS | 1668 | __end_tp_report 12:20:28 INFO - PROCESS | 1668 | __start_cc_report 12:20:28 INFO - PROCESS | 1668 | _x_x_mozilla_cycle_collect,413 12:20:28 INFO - PROCESS | 1668 | __end_cc_report 12:20:28 INFO - PROCESS | 1668 | __startTimestamp1463167228834__endTimestamp 12:20:28 INFO - PROCESS | 1668 | 12:20:28 INFO - PROCESS | 1668 | ------- Summary: start ------- 12:20:28 INFO - PROCESS | 1668 | Number of tests: 6 12:20:28 INFO - PROCESS | 1668 | 12:20:28 INFO - PROCESS | 1668 | [#0] dojo.html Cycles:165 Average:5742.93 Median:6034.91 stddev:814.60 (13.5%) stddev-sans-first:785.94 12:20:28 INFO - PROCESS | 1668 | Values: 2898.4 3236.2 3288.0 3465.8 3479.4 5620.0 5627.1 5709.0 5711.2 5720.3 5713.9 5819.7 5836.8 5848.6 5932.6 4059.4 4788.5 4795.6 5346.3 5363.4 399.5 4425.6 5204.2 5246.6 5295.8 5252.8 5259.7 5264.1 5290.8 5306.1 5271.0 5305.9 5316.4 5332.1 5380.2 4414.0 4804.4 5112.0 5166.6 5215.9 5526.5 5617.2 5630.5 5654.8 5706.3 6176.3 6254.0 6282.4 6287.4 6301.5 5304.0 6216.7 6250.2 6298.9 6325.0 5901.6 5949.2 5960.5 5969.1 5981.7 6212.4 6274.9 6355.6 6367.0 6367.7 6049.8 6050.0 6092.3 6093.2 6190.3 6238.9 6252.8 6267.0 6270.5 6279.6 6203.4 6238.7 6251.6 6270.8 6281.6 6246.6 6249.3 6256.1 6270.1 6276.6 4975.1 5004.7 5010.2 5013.7 5025.0 4401.7 4470.2 4488.5 4499.5 4533.6 5742.2 5869.0 5924.8 6025.4 6026.2 6292.2 6292.5 6295.2 6307.9 6313.5 4703.4 5716.5 5878.4 5960.6 6011.0 5464.2 5476.8 5489.9 5496.6 5501.5 6426.9 6456.0 6489.1 6489.6 6493.7 6183.2 6184.2 6230.9 6261.5 6287.7 6386.7 6443.0 6451.7 6457.4 6574.4 6138.2 6248.4 6259.8 6261.7 6287.2 5805.8 5892.8 6195.1 6280.5 6290.2 6061.8 6180.5 6229.3 6294.0 6302.9 6124.5 6147.6 6281.9 6287.1 6302.1 5915.5 6034.9 6096.5 6098.6 6103.1 6233.1 6235.6 6251.7 6268.6 6293.1 5924.6 5959.3 5986.0 6113.1 6147.4 12:20:28 INFO - PROCESS | 1668 | 12:20:28 INFO - PROCESS | 1668 | [#1] ext.html Cycles:180 Average:73302.74 Median:39329.82 stddev:168267.32 (427.8%) stddev-sans-first:168650.58 12:20:28 INFO - PROCESS | 1668 | Values: 504.6 539.0 547.9 551.5 560.8 41178.0 43468.7 43510.8 43515.8 43571.3 44413.6 44670.9 45151.0 45164.0 45168.0 9632.7 9762.7 9813.0 9823.9 9848.5 9654.5 9691.6 9761.2 9765.1 9796.1 9756.0 9785.9 9792.0 9800.6 9808.2 9735.0 9788.8 9792.4 9803.0 9807.9 9607.5 9683.0 9695.8 9711.5 9716.0 41108.4 41364.4 41398.0 41430.8 41449.4 2820.4 3097.7 3114.8 3212.7 3400.2 21111.2 21148.9 26610.6 27828.3 27901.3 2624.9 2704.5 2735.2 2750.9 2762.2 469176.0 469953.0 473004.6 474024.6 475177.0 29493.0 35261.3 35495.3 35581.6 35586.3 37365.1 38154.2 38279.0 38495.2 38673.8 16786.4 17121.0 17285.4 17320.7 17336.8 13116.5 13138.8 13169.7 13247.9 13262.5 20320.7 20437.2 20550.6 20586.6 20679.0 8467.3 8503.4 8554.5 8563.7 8622.7 943.6 979.2 1010.2 1022.5 1094.8 30692.8 30880.0 30955.4 31075.2 31175.4 1060.8 1073.9 1104.5 1133.2 1158.8 15745.4 15893.4 15925.4 16193.8 16261.2 740776.0 742211.0 747288.0 748055.0 751082.0 49873.8 62259.7 62716.0 63073.0 63126.4 1411.6 1462.7 1470.6 1476.9 1484.3 29097.9 29942.9 30080.1 30472.8 30644.7 55147.4 55448.0 55479.0 55602.2 55635.7 596059.0 623528.0 635721.9 648557.0 653019.0 27309.5 27414.7 27761.7 27804.7 27809.2 47428.8 47551.0 48981.5 49109.8 49238.8 39108.8 39482.4 39558.0 39945.8 40424.6 38277.4 38452.0 38936.4 39190.4 39888.8 38634.2 38961.8 39280.2 39379.4 39788.6 32033.8 33621.3 33855.7 34303.8 34320.0 22484.3 23233.1 23969.9 25719.5 25936.4 12:20:28 INFO - PROCESS | 1668 | 12:20:28 INFO - PROCESS | 1668 | [#2] jquery.html Cycles:180 Average:35412.36 Median:409.04 stddev:104980.29 (25665.3%) stddev-sans-first:105241.76 12:20:28 INFO - PROCESS | 1668 | Values: 341.3 389.9 392.4 405.9 412.1 17703.9 19839.4 20727.0 21756.1 21770.7 20514.6 20644.8 20938.2 21247.8 21592.9 5603.1 5879.0 6017.5 6027.3 6081.8 5859.4 5995.3 5996.5 6074.8 6115.5 5927.8 5970.2 6026.5 6043.2 6055.9 5233.5 5918.6 6005.9 6019.4 6020.1 5052.0 5134.4 5213.0 5235.6 5265.0 12848.7 13524.0 13526.3 13756.1 25386.5 2030.1 2330.8 2776.4 2784.2 2793.8 9917.8 10087.8 10091.0 11891.7 15655.8 1699.9 1786.8 1812.5 1816.7 1834.1 106077.0 132843.3 133062.0 135871.0 137390.0 10810.8 10914.9 11857.2 12181.4 12362.7 12589.9 15051.2 15085.0 15090.6 15115.5 8543.6 8753.3 8822.6 8876.1 8887.0 8689.3 8741.5 8748.0 8749.2 8916.7 4673.3 4777.0 4780.8 4801.6 4818.6 2011.8 2065.3 2067.9 2073.3 2097.5 828.7 934.9 936.7 971.1 981.4 11387.5 11774.3 11838.9 12081.8 19703.8 437.4 451.8 453.6 458.1 475.8 4494.1 5748.6 6229.1 6312.4 6316.1 610478.9 620826.0 628564.7 630191.0 639507.8 136150.0 140438.0 141878.0 143060.6 143158.8 453.5 477.1 480.2 483.2 484.8 10335.4 11104.6 11249.7 11593.2 11714.3 34318.0 42772.8 45621.1 52446.7 54414.4 74092.0 74161.0 78297.0 78723.8 80255.2 7233.5 8035.4 8131.4 8392.8 8551.1 7301.2 9026.7 10297.1 11973.9 12737.4 10495.3 11251.7 11267.4 11267.8 11699.9 10441.5 10760.1 10913.2 11326.2 11331.8 8889.8 10854.9 10935.1 11526.8 11899.3 7024.5 8278.2 8287.3 8409.7 8494.7 10496.0 11301.0 11548.4 11631.5 11668.0 12:20:28 INFO - PROCESS | 1668 | 12:20:28 INFO - PROCESS | 1668 | [#3] mootools.html Cycles:180 Average:5937.13 Median:4889.41 stddev:3381.30 (69.2%) stddev-sans-first:3374.28 12:20:28 INFO - PROCESS | 1668 | Values: 1491.4 1604.0 1855.4 1966.9 2009.9 6945.2 8932.6 8982.2 9462.0 9469.2 8975.5 9038.1 9054.0 9117.7 9210.8 2093.0 2433.4 2471.1 2476.1 2479.6 2424.9 2437.5 2480.3 2485.7 2487.7 2454.2 2456.6 2472.8 2475.4 2480.1 2190.8 2428.9 2431.7 2441.4 2462.7 2436.7 2438.7 2441.3 2447.4 2451.3 9174.4 9235.6 9311.0 9314.8 9342.1 5434.9 5639.8 5653.7 5672.2 5685.1 3647.1 3658.7 3705.1 3750.2 3750.7 1052.3 1064.3 1066.0 1067.7 1074.8 12201.6 12344.3 12362.9 12367.0 12372.9 4888.2 4909.3 5039.5 5042.9 5072.2 10332.2 10464.4 10471.3 10502.6 10511.4 5444.3 5463.4 5567.7 5577.2 5588.4 4533.2 4890.6 5037.4 5063.6 5094.9 3658.7 4103.7 4172.3 4206.7 4219.8 1160.3 1338.4 1341.7 1346.3 1361.8 863.8 906.2 935.5 943.9 945.1 6080.6 6084.0 6144.6 6153.2 6188.9 463.2 471.1 472.2 481.2 484.5 4026.6 4094.4 4283.5 4297.5 4353.1 9836.7 11115.2 11335.8 11410.3 11432.4 11098.0 11415.4 11469.7 11497.7 11503.0 8100.8 8572.6 8673.3 8702.2 8709.7 6066.1 6070.4 6204.6 6258.0 6276.9 9908.3 10468.5 10603.3 10603.6 10646.8 10552.9 10575.7 10742.4 10745.5 10746.6 6993.4 7694.3 7747.3 7822.7 7984.5 8664.6 8719.6 8747.1 8775.0 8778.4 7542.5 7629.0 7724.0 7763.0 7884.4 7468.5 7593.5 7639.0 7694.9 7814.8 6814.1 6847.2 6870.1 6873.3 6875.3 4413.8 4925.5 5135.5 5157.3 5174.2 5038.7 5065.4 5083.6 5122.1 5344.1 12:20:28 INFO - PROCESS | 1668 | 12:20:28 INFO - PROCESS | 1668 | [#4] prototype.html Cycles:180 Average:4568.07 Median:5147.73 stddev:1394.41 (27.1%) stddev-sans-first:1361.33 12:20:28 INFO - PROCESS | 1668 | Values: 316.8 319.8 321.7 328.6 332.3 4447.7 4974.1 5401.1 5439.9 5465.5 4894.3 5310.7 5313.9 5316.4 5316.7 3458.5 3856.1 3977.6 4086.2 4091.8 3615.2 4028.4 4053.7 4126.7 4128.9 3330.8 4058.6 4061.9 4091.1 4091.6 3708.9 3805.5 3863.2 4045.4 4081.3 3552.3 3555.8 3563.8 3578.4 3597.9 4084.0 4552.8 4720.3 4745.8 5126.6 4505.2 4554.5 4712.9 4726.2 4745.6 5049.6 5235.1 5255.5 5685.4 5697.3 3458.3 3953.6 3964.7 3980.0 3987.9 5214.6 5584.3 5688.7 5788.8 5980.3 3409.9 3987.6 3999.0 4002.4 4018.7 3680.0 3695.6 3953.3 4099.1 4113.6 4245.9 4252.5 4713.2 4757.5 4802.3 5168.8 5684.8 5718.0 5759.9 5762.8 1310.3 1383.3 1392.6 1403.4 1419.3 815.8 829.7 840.9 895.9 896.4 5001.5 5191.5 5227.1 5377.6 5426.3 4984.6 5056.7 5329.9 5869.9 5951.9 4694.6 5089.3 5236.5 5436.5 5465.5 2753.8 2954.8 3125.5 3132.7 3152.3 5903.7 6111.6 6172.9 6193.0 6209.0 5408.4 5623.8 5651.7 5676.7 6151.9 5264.8 5422.7 5648.7 6149.6 6161.4 4631.0 5486.5 5629.7 5887.5 5896.8 5016.9 5599.7 5738.4 5780.5 5836.3 5262.8 5533.6 6117.4 6139.8 6148.4 3887.2 4026.9 4037.7 4070.6 4074.8 5278.4 5786.8 5800.5 5834.8 5880.0 4747.7 4914.7 5033.9 5525.4 5695.2 5174.7 5609.2 5643.3 5664.9 5718.7 4741.5 5330.5 5565.5 5571.0 5579.5 5169.4 5192.8 5305.7 5690.8 5736.4 5059.7 5372.0 5550.9 5573.6 5574.7 12:20:28 INFO - PROCESS | 1668 | 12:20:28 INFO - PROCESS | 1668 | [#5] yui.html Cycles:180 Average:18980.26 Median:610.44 stddev:27254.18 (4464.7%) stddev-sans-first:27295.95 12:20:28 INFO - PROCESS | 1668 | Values: 668.2 693.5 713.7 718.9 763.6 14844.5 15172.5 15308.5 15315.3 15519.8 12275.0 14039.5 14223.2 14314.4 14357.1 752.6 938.5 939.9 961.2 965.7 932.0 943.9 944.1 944.7 947.6 866.2 952.6 983.7 1006.2 1009.8 948.7 991.2 991.5 992.7 996.0 661.4 732.6 741.8 743.2 744.9 12325.9 14672.3 14884.6 14894.2 14902.1 12340.3 12539.0 12575.2 12589.4 12804.6 9997.2 9999.5 10080.9 10257.9 10323.7 3494.8 3546.8 3610.4 3696.8 3741.7 86844.8 86846.6 87143.6 90174.0 90286.0 9289.9 9414.5 9489.8 9536.0 9644.2 21963.9 22184.1 22233.0 22419.9 22534.7 7230.5 7287.1 7338.4 7362.7 7492.7 3818.5 3863.6 3937.7 3947.6 3992.7 3577.7 3674.3 3687.3 3687.4 3690.6 640.9 652.6 656.2 656.5 662.5 483.0 581.2 585.4 601.9 606.5 8166.6 8202.7 8377.1 8438.6 8448.5 526.7 571.5 595.8 609.5 611.4 3806.3 3937.7 3938.3 3986.0 3999.8 88033.0 93963.0 95431.5 95554.5 96987.0 83516.7 87065.6 87084.6 87564.6 89912.0 77459.0 77512.4 77575.8 77822.6 78426.6 7709.3 7793.7 7900.6 7925.5 7952.5 47775.3 49315.8 49423.8 49759.8 49920.8 61285.1 61887.7 63667.7 69629.0 69713.7 11044.8 11189.2 11304.5 11347.0 11454.4 11966.3 11990.9 12063.3 12126.3 12132.0 11666.9 11712.6 11792.3 11840.4 12034.9 11478.7 11551.9 11607.8 11861.5 11905.8 11429.3 11574.9 11684.9 11823.8 11850.6 9628.4 9701.9 9907.0 10024.0 10384.3 9599.7 9781.9 9792.2 9915.7 9933.9 12:20:28 INFO - PROCESS | 1668 | -------- Summary: end -------- 12:20:28 INFO - PROCESS | 1668 | 12:20:29 INFO - TEST-INFO | 1668: exit 0 12:20:29 INFO - TEST-OK | dromaeo_css | took 1111662ms 12:20:29 INFO - TEST-START | kraken 12:20:29 INFO - operating with platform_type : mac_ 12:20:29 INFO - Initialising browser for kraken test... 12:20:29 INFO - TEST-INFO | started process 1675 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/tmpHK9Jd6/profile http://localhost:49275/getInfo.html) 12:20:32 INFO - PROCESS | 1675 | __metrics Screen width/height:1600/1200 12:20:32 INFO - PROCESS | 1675 | colorDepth:24 12:20:32 INFO - PROCESS | 1675 | Browser inner width/height: 1024/689 12:20:32 INFO - PROCESS | 1675 | __metrics 12:20:32 ERROR - PROCESS | 1675 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 12:20:33 INFO - TEST-INFO | 1675: exit 0 12:20:33 INFO - Browser initialized. 12:20:33 INFO - Running cycle 1/1 for kraken test... 12:20:33 INFO - TEST-INFO | started process 1678 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/tmpHK9Jd6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:20:45 INFO - PROCESS | 1678 | RSS: Main: 249827328 12:20:45 INFO - PROCESS | 1678 | 12:20:48 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 12:20:48 INFO - PROCESS | 1678 | RSS: Main: 261660672 12:20:48 INFO - PROCESS | 1678 | 12:20:51 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 12:20:52 INFO - PROCESS | 1678 | RSS: Main: 266399744 12:20:52 INFO - PROCESS | 1678 | 12:20:55 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 12:20:56 INFO - PROCESS | 1678 | RSS: Main: 266674176 12:20:56 INFO - PROCESS | 1678 | 12:20:58 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 12:20:58 INFO - PROCESS | 1678 | RSS: Main: 267395072 12:20:58 INFO - PROCESS | 1678 | 12:21:01 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:21:01 INFO - PROCESS | 1678 | RSS: Main: 267939840 12:21:01 INFO - PROCESS | 1678 | 12:21:04 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 12:21:05 INFO - PROCESS | 1678 | RSS: Main: 267948032 12:21:05 INFO - PROCESS | 1678 | 12:21:08 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 12:21:09 INFO - PROCESS | 1678 | RSS: Main: 268070912 12:21:09 INFO - PROCESS | 1678 | 12:21:12 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 12:21:13 INFO - PROCESS | 1678 | RSS: Main: 268341248 12:21:13 INFO - PROCESS | 1678 | 12:21:14 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:21:15 INFO - PROCESS | 1678 | RSS: Main: 267866112 12:21:15 INFO - PROCESS | 1678 | 12:21:17 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:21:17 INFO - PROCESS | 1678 | RSS: Main: 267919360 12:21:17 INFO - PROCESS | 1678 | 12:21:20 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:21:20 INFO - PROCESS | 1678 | RSS: Main: 268341248 12:21:20 INFO - PROCESS | 1678 | 12:21:23 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:21:24 INFO - PROCESS | 1678 | RSS: Main: 268386304 12:21:24 INFO - PROCESS | 1678 | 12:21:27 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:21:27 INFO - PROCESS | 1678 | RSS: Main: 268382208 12:21:27 INFO - PROCESS | 1678 | 12:21:29 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 12:21:29 INFO - PROCESS | 1678 | RSS: Main: 269922304 12:21:29 INFO - PROCESS | 1678 | 12:21:29 INFO - PROCESS | 1678 | __start_tp_report 12:21:29 INFO - PROCESS | 1678 | _x_x_mozilla_page_load 12:21:29 INFO - PROCESS | 1678 | _x_x_mozilla_page_load_details 12:21:29 INFO - PROCESS | 1678 | |i|pagename|runs| 12:21:29 INFO - PROCESS | 1678 | |0;ai-astar;111;138;108;116;116;116;107;115;116;115 12:21:29 INFO - PROCESS | 1678 | |1;audio-beat-detection;156;122;148;203;130;173;139;124;174;145 12:21:29 INFO - PROCESS | 1678 | |2;audio-dft;196;194;209;237;198;197;198;199;196;194 12:21:29 INFO - PROCESS | 1678 | |3;audio-fft;82;83;75;174;56;60;62;80;139;58 12:21:29 INFO - PROCESS | 1678 | |4;audio-oscillator;81;82;152;79;81;87;119;82;80;122 12:21:29 INFO - PROCESS | 1678 | |5;imaging-gaussian-blur;88;128;89;85;89;86;107;105;89;87 12:21:29 INFO - PROCESS | 1678 | |6;imaging-darkroom;94;123;150;95;93;94;95;95;94;94 12:21:29 INFO - PROCESS | 1678 | |7;imaging-desaturate;117;147;126;89;97;84;140;123;92;121 12:21:29 INFO - PROCESS | 1678 | |8;json-parse-financial;55;59;59;59;58;58;60;117;60;58 12:21:29 INFO - PROCESS | 1678 | |9;json-stringify-tinderbox;64;66;72;69;80;71;69;67;67;69 12:21:29 INFO - PROCESS | 1678 | |10;stanford-crypto-aes;84;85;88;87;87;85;91;75;84;106 12:21:29 INFO - PROCESS | 1678 | |11;stanford-crypto-ccm;150;148;145;140;137;135;135;130;208;142 12:21:29 INFO - PROCESS | 1678 | |12;stanford-crypto-pbkdf2;165;165;162;163;168;163;167;168;166;167 12:21:29 INFO - PROCESS | 1678 | |13;stanford-crypto-sha256-iterative;65;65;67;70;85;84;83;86;85;80 12:21:29 INFO - PROCESS | 1678 | __end_tp_report 12:21:29 INFO - PROCESS | 1678 | __start_cc_report 12:21:29 INFO - PROCESS | 1678 | _x_x_mozilla_cycle_collect,1066 12:21:29 INFO - PROCESS | 1678 | __end_cc_report 12:21:29 INFO - PROCESS | 1678 | __startTimestamp1463167289894__endTimestamp 12:21:29 INFO - PROCESS | 1678 | 12:21:29 INFO - PROCESS | 1678 | ------- Summary: start ------- 12:21:29 INFO - PROCESS | 1678 | Number of tests: 14 12:21:29 INFO - PROCESS | 1678 | 12:21:29 INFO - PROCESS | 1678 | [#0] ai-astar Cycles:10 Average:115.80 Median:116.00 stddev:8.53 (7.4%) stddev-sans-first:8.87 12:21:29 INFO - PROCESS | 1678 | Values: 111.0 138.0 108.0 116.0 116.0 116.0 107.0 115.0 116.0 115.0 12:21:29 INFO - PROCESS | 1678 | 12:21:29 INFO - PROCESS | 1678 | [#1] audio-beat-detection Cycles:10 Average:151.40 Median:152.00 stddev:25.69 (16.9%) stddev-sans-first:27.20 12:21:29 INFO - PROCESS | 1678 | Values: 156.0 122.0 148.0 203.0 130.0 173.0 139.0 124.0 174.0 145.0 12:21:29 INFO - PROCESS | 1678 | 12:21:29 INFO - PROCESS | 1678 | [#2] audio-dft Cycles:10 Average:201.80 Median:198.00 stddev:13.08 (6.6%) stddev-sans-first:13.70 12:21:29 INFO - PROCESS | 1678 | Values: 196.0 194.0 209.0 237.0 198.0 197.0 198.0 199.0 196.0 194.0 12:21:29 INFO - PROCESS | 1678 | 12:21:29 INFO - PROCESS | 1678 | [#3] audio-fft Cycles:10 Average:86.90 Median:68.50 stddev:38.96 (56.9%) stddev-sans-first:41.29 12:21:29 INFO - PROCESS | 1678 | Values: 82.0 83.0 75.0 174.0 56.0 60.0 62.0 80.0 139.0 58.0 12:21:29 INFO - PROCESS | 1678 | 12:21:29 INFO - PROCESS | 1678 | [#4] audio-oscillator Cycles:10 Average:96.50 Median:81.00 stddev:25.40 (31.4%) stddev-sans-first:26.31 12:21:29 INFO - PROCESS | 1678 | Values: 81.0 82.0 152.0 79.0 81.0 87.0 119.0 82.0 80.0 122.0 12:21:29 INFO - PROCESS | 1678 | 12:21:29 INFO - PROCESS | 1678 | [#5] imaging-gaussian-blur Cycles:10 Average:95.30 Median:87.50 stddev:13.88 (15.9%) stddev-sans-first:14.47 12:21:29 INFO - PROCESS | 1678 | Values: 88.0 128.0 89.0 85.0 89.0 86.0 107.0 105.0 89.0 87.0 12:21:29 INFO - PROCESS | 1678 | 12:21:29 INFO - PROCESS | 1678 | [#6] imaging-darkroom Cycles:10 Average:102.70 Median:94.00 stddev:18.93 (20.1%) stddev-sans-first:19.81 12:21:29 INFO - PROCESS | 1678 | Values: 94.0 123.0 150.0 95.0 93.0 94.0 95.0 95.0 94.0 94.0 12:21:29 INFO - PROCESS | 1678 | 12:21:29 INFO - PROCESS | 1678 | [#7] imaging-desaturate Cycles:10 Average:113.60 Median:115.50 stddev:21.97 (19.0%) stddev-sans-first:23.27 12:21:29 INFO - PROCESS | 1678 | Values: 117.0 147.0 126.0 89.0 97.0 84.0 140.0 123.0 92.0 121.0 12:21:29 INFO - PROCESS | 1678 | 12:21:29 INFO - PROCESS | 1678 | [#8] json-parse-financial Cycles:10 Average:64.30 Median:59.00 stddev:18.57 (31.5%) stddev-sans-first:19.39 12:21:29 INFO - PROCESS | 1678 | Values: 55.0 59.0 59.0 59.0 58.0 58.0 60.0 117.0 60.0 58.0 12:21:29 INFO - PROCESS | 1678 | 12:21:29 INFO - PROCESS | 1678 | [#9] json-stringify-tinderbox Cycles:10 Average:69.40 Median:69.00 stddev:4.40 (6.4%) stddev-sans-first:4.21 12:21:29 INFO - PROCESS | 1678 | Values: 64.0 66.0 72.0 69.0 80.0 71.0 69.0 67.0 67.0 69.0 12:21:29 INFO - PROCESS | 1678 | 12:21:29 INFO - PROCESS | 1678 | [#10] stanford-crypto-aes Cycles:10 Average:87.20 Median:86.00 stddev:7.80 (9.1%) stddev-sans-first:8.19 12:21:29 INFO - PROCESS | 1678 | Values: 84.0 85.0 88.0 87.0 87.0 85.0 91.0 75.0 84.0 106.0 12:21:29 INFO - PROCESS | 1678 | 12:21:29 INFO - PROCESS | 1678 | [#11] stanford-crypto-ccm Cycles:10 Average:147.00 Median:143.50 stddev:22.33 (15.6%) stddev-sans-first:23.65 12:21:29 INFO - PROCESS | 1678 | Values: 150.0 148.0 145.0 140.0 137.0 135.0 135.0 130.0 208.0 142.0 12:21:29 INFO - PROCESS | 1678 | 12:21:29 INFO - PROCESS | 1678 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.40 Median:166.50 stddev:2.17 (1.3%) stddev-sans-first:2.30 12:21:29 INFO - PROCESS | 1678 | Values: 165.0 165.0 162.0 163.0 168.0 163.0 167.0 168.0 166.0 167.0 12:21:29 INFO - PROCESS | 1678 | 12:21:29 INFO - PROCESS | 1678 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:77.00 Median:83.50 stddev:9.07 (10.9%) stddev-sans-first:8.51 12:21:29 INFO - PROCESS | 1678 | Values: 65.0 65.0 67.0 70.0 85.0 84.0 83.0 86.0 85.0 80.0 12:21:29 INFO - PROCESS | 1678 | -------- Summary: end -------- 12:21:29 INFO - PROCESS | 1678 | 12:21:30 INFO - TEST-INFO | 1678: exit 0 12:21:30 INFO - TEST-OK | kraken | took 61165ms 12:21:30 INFO - SUITE-END | took 1172s 12:21:31 INFO - Completed test suite (00:19:33) 12:21:31 INFO - javascript benchmark 12:21:31 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7150.3894329421255, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2898.362360280739, 3236.1909522619344, 3287.9671203287967, 3465.7747246428994, 3479.391106556352, 5620.0, 5627.127795191742, 5708.9999999999945, 5711.200431939532, 5720.313562372521, 5713.914291285623, 5819.738111784969, 5836.766529338822, 5848.6490810551395, 5932.584719069667, 4059.350503919381, 4788.521147885201, 4795.568398844103, 5346.304980352568, 5363.422921847001, 399.47483673901877, 4425.645948324126, 5204.245384419257, 5246.632735708502, 5295.761690723926, 5252.842414727565, 5259.710715910624, 5264.078786212417, 5290.761915713783, 5306.071437498423, 5271.0, 5305.920411193835, 5316.38861530925, 5332.066888294533, 5380.24676545284, 4413.977930110361, 4804.351412559329, 5111.952049829776, 5166.560842328407, 5215.895682086375, 5526.50261476465, 5617.185508101303, 5630.465105814941, 5654.830355089354, 5706.343770466408, 6176.320604733476, 6253.96873015632, 6282.403171698681, 6287.371262873676, 6301.464375528086, 5303.973480132613, 6216.720247588869, 6250.15622890913, 6298.905516417258, 6325.0, 5901.616394934315, 5949.226600541945, 5960.493358064569, 5969.074793406985, 5981.730822113015, 6212.409821066992, 6274.905876411857, 6355.618662880242, 6367.0449432584555, 6367.713452894631, 6049.848753781191, 6049.969750151309, 6092.268927728701, 6093.238345206849, 6190.3190649028575, 6238.937610623836, 6252.781152659635, 6266.968665156645, 6270.529710271748, 6279.591826531261, 6203.4416902479, 6238.65687387189, 6251.593646412968, 6270.843228919313, 6281.59169653971, 6246.593971391845, 6249.281332646713, 6256.061590761331, 6270.059491076284, 6276.592021518678, 4975.129352363394, 5004.724740139327, 5010.1733214020005, 5013.674111182761, 5025.045241404122, 4401.691881568324, 4470.150671372428, 4488.528704486047, 4499.505054444008, 4533.637309015271, 5742.167385729046, 5868.970655146697, 5924.763009479659, 6025.397460253939, 6026.156338112668, 6292.244930608264, 6292.46514046302, 6295.150154729145, 6307.905381419282, 6313.527067568169, 4703.3885594872445, 5716.456936591017, 5878.382769809194, 5960.552958528041, 6011.008183649737, 5464.207689884945, 5476.808311709071, 5489.917651235235, 5496.587755918322, 5501.477359650827, 6426.907425737636, 6456.0, 6489.059086432535, 6489.643069631215, 6493.740250390026, 6183.227096612924, 6184.165137706352, 6230.875382492415, 6261.499080073492, 6287.654179020198, 6386.744530218739, 6443.0, 6451.741930322923, 6457.354264573505, 6574.408303252731, 6138.232720909887, 6248.4064013918605, 6259.812205633838, 6261.7182226799, 6287.245530536365, 5805.82582522525, 5892.762519870704, 6195.101710252078, 6280.466160376236, 6290.2451705793765, 6061.8153520761625, 6180.536459765445, 6229.345918678474, 6294.0, 6302.905456418066, 6124.510039196943, 6147.647517546133, 6281.874362512633, 6287.088372185916, 6302.1492098566105, 5915.526757859275, 6034.9094763577705, 6096.512279017757, 6098.573099883143, 6103.145559621746, 6233.127362169391, 6235.625862448268, 6251.718672659832, 6268.561200715906, 6293.087502312231, 5924.555658325729, 5959.255093113361, 5985.970070149621, 6113.083037544404, 6147.415995480422], "value": 5688.188958329214, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [504.6417043898832, 538.9919151212732, 547.9041167795634, 551.4733429574757, 560.8261438953928, 41178.0, 43468.6959391218, 43510.78244608777, 43515.78242108785, 43571.346429803576, 44413.55586444135, 44670.883227919236, 45151.0, 45164.0, 45168.0, 9632.711018669452, 9762.658306959258, 9813.0, 9823.90176098237, 9848.458334791583, 9654.517274136266, 9691.612335506607, 9761.219102471785, 9765.12113909748, 9796.069373409515, 9755.95122024389, 9785.902140978606, 9792.020797920222, 9800.607975681, 9808.21534277256, 9734.977827328135, 9788.804223915553, 9792.363496372713, 9803.0, 9807.901920980841, 9607.4715890626, 9682.9515852421, 9695.806083878282, 9711.465869377182, 9716.0, 41108.38337424941, 41364.37953430701, 41398.0, 41430.792846035576, 41449.378259326135, 2820.393615372684, 3097.6871395445646, 3114.8131112133347, 3212.7429805615493, 3400.2009527760956, 21111.15555377783, 21148.933404656393, 26610.6008409874, 27828.30429239265, 27901.302467438272, 2624.8818803153904, 2704.5267078261422, 2735.179446166142, 2750.931226719328, 2762.1575419497044, 469176.0, 469953.0, 473004.63497682294, 474024.6298768484, 475177.0, 29493.0, 35261.294774104375, 35495.29009419797, 35581.64418355783, 35586.28827423437, 37365.06587335284, 38154.23691526154, 38279.0, 38495.230095397936, 38673.80663096667, 16786.412475563542, 17121.0, 17285.395011174547, 17320.65358692819, 17336.826631733773, 13116.540921067717, 13138.802917956238, 13169.683031696753, 13247.867521324664, 13262.469501219843, 20320.69518957217, 20437.182512699324, 20550.58898822015, 20586.58826823485, 20679.0, 8467.32261419085, 8503.447275927045, 8554.486730796172, 8563.743087707378, 8622.698205562776, 943.6364453364822, 979.1628157925014, 1010.161565900301, 1022.4683164754287, 1094.7902567662618, 30692.772289108187, 30880.0, 30955.380892382025, 31075.223119421742, 31175.376492470477, 1060.7560261139981, 1073.909981368911, 1104.4698544698429, 1133.2010932292715, 1158.8319693644373, 15745.44890928812, 15893.364265429485, 15925.442609508611, 16193.838061619468, 16261.18694065292, 740776.0, 742211.0, 747288.0, 748055.0, 751082.0, 49873.750631246614, 62259.6887015562, 62716.0, 63073.0, 63126.36873631205, 1411.5906387147613, 1462.6855226126434, 1470.5955862137785, 1476.852314768536, 1484.3320505772447, 29097.854510728157, 29942.85028574843, 30080.097597072123, 30472.84763576168, 30644.693553064182, 55147.44852551423, 55448.0, 55479.0, 55602.165967511326, 55635.72182139063, 596059.0, 623528.0, 635721.8566886001, 648557.0, 653019.0, 27309.453810923274, 27414.725852741216, 27761.722382775915, 27804.72195278021, 27809.16572502828, 47428.814279641905, 47551.0, 48981.51018489769, 49109.75445122751, 49238.76903077308, 39108.80445597754, 39482.40776388299, 39558.0, 39945.801625951266, 40424.59575404326, 38277.425838613, 38452.0, 38936.41595376128, 39190.412143817295, 39888.80055599703, 38634.227315454096, 38961.805190973864, 39280.2143957125, 39379.42482300676, 39788.6073987409, 32033.839830800698, 33621.3275734479, 33855.661443386234, 34303.82848085744, 34320.0, 22484.32547023592, 23233.07482184691, 23969.880150599136, 25719.485610288062, 25936.351591210372], "value": 19828.732717838833, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [341.330991257136, 389.89862635714707, 392.38003953753065, 405.93910913362976, 412.13246116923875, 17703.911480442614, 19839.40481785545, 20726.963651817423, 21756.12975480981, 21770.673439898375, 20514.589708205865, 20644.793552064475, 20938.16247350108, 21247.787522124774, 21592.920353982317, 5603.131514615241, 5878.970605146979, 6017.488513476338, 6027.336992930774, 6081.78713745019, 5859.355470898197, 5995.250593675791, 5996.520278377741, 6074.848128796772, 6115.48018418435, 5927.762889484416, 5970.194023806774, 6026.5178785697035, 6043.244594425697, 6055.878882422349, 5233.450487698794, 5918.585699001072, 6005.909911351333, 6019.367966363535, 6020.127081573148, 5052.0, 5134.358205224347, 5213.0, 5235.58115350771, 5265.0, 12848.743025139538, 13524.0, 13526.323683815868, 13756.105853119516, 25386.492270154493, 2030.1067530286628, 2330.7669233076726, 2776.403073339242, 2784.2343355577377, 2793.7904657150593, 9917.75205619858, 10087.798244035152, 10091.0, 11891.702707432298, 15655.843441565667, 1699.9065051422167, 1786.767720196379, 1812.465322729798, 1816.6548355812354, 1834.110456428631, 106077.0, 132843.33578332144, 133062.0, 135871.0, 137390.0, 10810.783784324269, 10914.853940336308, 11857.169998100222, 12181.390930453443, 12362.690932726573, 12589.874101259053, 15051.1721855299, 15085.0, 15090.622734431507, 15115.470958516398, 8543.572821358906, 8753.299736021103, 8822.558872056372, 8876.112388761072, 8886.955565222133, 8689.261412779859, 8741.475511469333, 8748.0, 8749.212570868525, 8916.687915922912, 4673.25227963531, 4776.9761151194025, 4780.82472475023, 4801.567858892717, 4818.566329030404, 2011.7787043425212, 2065.297798748433, 2067.9379618611465, 2073.2639912831028, 2097.538541520863, 828.6685325869701, 934.9439033658002, 936.7049379445452, 971.1259866120548, 981.4111533080095, 11387.544498220144, 11774.293542387484, 11838.934495895412, 12081.758364832654, 19703.802961970483, 437.35926867139796, 451.81927229107964, 453.56231236855933, 458.08383233532936, 475.76449657419107, 4494.10117976406, 5748.5688573357165, 6229.065640154013, 6312.400321969406, 6316.115743795872, 610478.9476052591, 620826.0, 628564.7145878447, 630191.0, 639507.8024609847, 136150.0, 140438.0, 141878.0, 143060.5693943068, 143158.76239516458, 453.46491140454583, 477.0887604675123, 480.1621171056459, 483.1713611156883, 484.8400027990755, 10335.431551264752, 11104.61133860311, 11249.718757030812, 11593.188476806543, 11714.297142171497, 34318.0, 42772.786136069124, 45621.08757824892, 52446.73776631092, 54414.36756897141, 74092.0, 74161.0, 78297.0, 78723.81914271406, 80255.1974480271, 7233.493655444279, 8035.437519373588, 8131.430799843954, 8392.83214335722, 8551.1448855114, 7301.160366557701, 9026.684066057656, 10297.07326340633, 11973.940130299292, 12737.363131843185, 10495.265331426726, 11251.718707032389, 11267.436628168723, 11267.774644507228, 11699.88300117022, 10441.477926103817, 10760.139188864716, 10913.18151138652, 11326.150538709466, 11331.830022549504, 8889.777755556162, 10854.914508549082, 10935.125189984941, 11526.769464610494, 11899.286042837457, 7024.455437644724, 8278.213569710868, 8287.337013039063, 8409.663613455637, 8494.74515764528, 10496.0, 11301.0, 11548.42257887119, 11631.53473861036, 11668.0], "value": 8751.432771275287, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1491.4481641792536, 1603.9518814435564, 1855.396995976308, 1966.891820949849, 2009.889456079915, 6945.166580010393, 8932.59803308851, 8982.236509896658, 9462.006489318626, 9469.242460603153, 8975.50634715092, 9038.051004644516, 9054.04932482088, 9117.726468205949, 9210.756547866036, 2092.9790702093014, 2433.3916520869825, 2471.1351027140536, 2476.0590975429313, 2479.603263477844, 2424.9151279705216, 2437.524682686877, 2480.342709182068, 2485.72657007729, 2487.7139129000216, 2454.153317105605, 2456.5823809952262, 2472.765087316711, 2475.4306509502826, 2480.1071614218868, 2190.8356873234493, 2428.854268743881, 2431.6960379952507, 2441.377448750557, 2462.729099799021, 2436.6954130733657, 2438.67077944477, 2441.292025312675, 2447.4003869052017, 2451.301379106946, 9174.357794954356, 9235.630574777068, 9310.953445232799, 9314.813703725955, 9342.065793420672, 5434.945650543523, 5639.830805075854, 5653.717314134276, 5672.205891175238, 5685.090385538294, 3647.1429214134655, 3658.652428019334, 3705.055210921225, 3750.1749615084636, 3750.662440380379, 1052.3422860712055, 1064.3401091323428, 1065.9946700266526, 1067.7330667333167, 1074.7796701676139, 12201.572944946973, 12344.321062341567, 12362.938185309105, 12367.0, 12372.938135309356, 4888.193448081027, 4909.3126962225315, 5039.4708555601865, 5042.865355295103, 5072.239164125411, 10332.173426125892, 10464.424456654957, 10471.3193642413, 10502.579896804195, 10511.421871796972, 5444.265024221759, 5463.3717122530625, 5567.721613919288, 5577.163425486209, 5588.441155884379, 4533.161365147458, 4890.633202509826, 5037.395512538519, 5063.620228482879, 5094.872628184251, 3658.670719635192, 4103.692223083281, 4172.290710579209, 4206.726562773471, 4219.831206751757, 1160.2748282323548, 1338.4044100375434, 1341.704824938512, 1346.2528296795176, 1361.8297712785902, 863.8445079885555, 906.2478143141177, 935.5415846235296, 943.9339246252833, 945.0691069296737, 6080.604760690541, 6084.0265557511475, 6144.631322120687, 6153.169322141453, 6188.938110618837, 463.1894185175942, 471.1237098995865, 472.1854800469232, 481.1988039913505, 484.5251653379637, 4026.5973402660084, 4094.385842123705, 4283.528811830696, 4297.527272000078, 4353.085851971122, 9836.655717050013, 11115.166362522843, 11335.829962550568, 11410.258333208314, 11432.428378581038, 11098.0, 11415.372154531415, 11469.655910322703, 11497.655070347902, 11502.964733174054, 8100.797480062928, 8572.571371431528, 8673.262772664268, 8702.173293537104, 8709.738707838775, 6066.120412540069, 6070.421690360627, 6204.583009815126, 6257.968710156602, 6276.874462510816, 9908.306418550632, 10468.528916198942, 10603.310784798963, 10603.628872989408, 10646.84029739539, 10552.947235263775, 10575.735606609896, 10742.355458672504, 10745.462726863781, 10746.570137194425, 6993.440524757905, 7694.269044440769, 7747.2640099190485, 7822.726204582812, 7984.481008734414, 8664.566771661517, 8719.607617657093, 8747.125287471457, 8775.0, 8778.429402088845, 7542.47202695806, 7628.961855190689, 7723.961380193288, 7762.961185194038, 7884.369250460064, 7468.5145465544565, 7593.506422082547, 7639.0, 7694.923050769645, 7814.804629884298, 6814.080099186546, 6847.246802851782, 6870.072540207008, 6873.347032031949, 6875.312468753084, 4413.837219762358, 4925.507449255046, 5135.537801597725, 5157.252198431282, 5174.249733788504, 5038.697678139323, 5065.4174769900455, 5083.644144909968, 5122.078025955215, 5344.144936810092], "value": 4678.3106224625135, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [316.7893350921637, 319.8145075856002, 321.72974701250945, 328.631932235896, 332.2706658883751, 4447.66642501812, 4974.079795237883, 5401.081816091264, 5439.91840122398, 5465.453454654533, 4894.3392641993405, 5310.6813591184555, 5313.946860531393, 5316.388615309231, 5316.707581083039, 3458.4639380896, 3856.0745421098945, 3977.582353852847, 4086.16233672098, 4091.813374121501, 3615.2407994321225, 4028.388644542177, 4053.695972802037, 4126.731762435432, 4128.8529964418485, 3330.800151990876, 4058.6144316289906, 4061.8984525386954, 4091.1204091120403, 4091.590840915914, 3708.8687950175186, 3805.469362873686, 3863.2080423513394, 4045.3527435610454, 4081.265372232997, 3552.342816578941, 3555.822208889545, 3563.82180890957, 3578.4095624221973, 3597.8846557566976, 4083.958590559418, 4552.772361381918, 4720.315554244616, 4745.810167593292, 5126.61550383719, 4505.189065968125, 4554.54054810402, 4712.858614241578, 4726.243800991859, 4745.600047985852, 5049.6212784041345, 5235.136202526579, 5255.474452554752, 5685.379666794985, 5697.287839020122, 3458.291050334675, 3953.644172024503, 3964.6630036446936, 3979.985103798542, 3987.8604248851443, 5214.556762675177, 5584.274044374246, 5688.687122208277, 5788.768449262025, 5980.295615749491, 3409.880654177092, 3987.601239875989, 3998.980005099956, 4002.419649150857, 4018.678505719535, 3679.988003299114, 3695.6304369562827, 3953.347697629859, 4099.134893623394, 4113.568075352045, 4245.915081698348, 4252.4691111199945, 4713.198756211384, 4757.452892917289, 4802.286708009963, 5168.8449346519665, 5684.8294551163535, 5718.0, 5759.884802303931, 5762.769489220468, 1310.344827586207, 1383.3152589468077, 1392.644875556747, 1403.382511694851, 1419.254891182117, 815.8042069903299, 829.6515463505342, 840.8654615261529, 895.8745775591423, 896.4442045931564, 5001.524855138756, 5191.480851914854, 5227.144363750814, 5377.596680248997, 5426.34883813937, 4984.626153038536, 5056.747162641853, 5329.86675333112, 5869.943410186207, 5951.9107213391835, 4694.568156712236, 5089.338386009795, 5236.450172731808, 5436.483534064258, 5465.535429488458, 2753.7796976241852, 2954.763618910482, 3125.5311703244242, 3132.6554079051284, 3152.2907345847, 5903.7048147593305, 6111.57218994675, 6172.8765424691255, 6193.049189505291, 6209.0131158029, 5408.378036525771, 5623.831285061455, 5651.717414129277, 5676.6659834938755, 6151.907721384183, 5264.8420547382875, 5422.7017514036315, 5648.655807839783, 6149.631022138597, 6161.3814128995, 4630.976845115752, 5486.478784515624, 5629.746661400165, 5887.499562537061, 5896.823095307148, 5016.874578135576, 5599.692016939108, 5738.426157384395, 5780.508656764054, 5836.299644042742, 5262.842174721555, 5533.620543166316, 6117.449429551361, 6139.754409823557, 6148.354422785543, 3887.222555488857, 4026.9395959061226, 4037.7375470594316, 4070.5752986455686, 4074.7555146691293, 5278.413577347149, 5786.768529258782, 5800.477956983892, 5834.824955251349, 5880.0, 4747.715137091785, 4914.70511769295, 5033.899322013613, 5525.364583072998, 5695.174199741097, 5174.689518628894, 5609.186667933209, 5643.294588176478, 5664.915026274692, 5718.741097266639, 4741.478437371818, 5330.466953304638, 5565.526930210977, 5570.972145139248, 5579.497845193952, 5169.353830771153, 5192.844214673566, 5305.726625609903, 5690.829275121588, 5736.422483816984, 5059.711187727859, 5372.0, 5550.888982220414, 5573.637713548606, 5574.749136288796], "value": 4127.0833493629125, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [668.2281964331196, 693.4937495628188, 713.7323503686117, 718.9496735228535, 763.5800309829592, 14844.480443184477, 15172.54482365528, 15308.540743777676, 15315.310811013504, 15519.767203491943, 12275.0, 14039.455659877405, 14223.217723025255, 14314.35585398657, 14357.13857168568, 752.5597525447592, 938.4791440750381, 939.8966113727485, 961.1589858873485, 965.657191696948, 931.9953400233023, 943.9008904065043, 944.1266828183936, 944.7354740672588, 947.5830634520823, 866.1944391715701, 952.6284748947916, 983.7408117609472, 1006.1849901579744, 1009.8384258518638, 948.7248697877607, 991.2020823237256, 991.454699915051, 992.6575331510653, 996.0238965813471, 661.3650895140671, 732.641005907107, 741.7774667599753, 743.156517352803, 744.8733715268363, 12325.93837030818, 14672.339744711408, 14884.553463396007, 14894.180820054893, 14902.105873647615, 12340.259584424874, 12539.0, 12575.18261313021, 12589.433475493624, 12804.551840685532, 9997.200223982063, 9999.450030248334, 10080.949595251977, 10257.948710256476, 10323.690289291259, 3494.8102068428366, 3546.804925729084, 3610.4223324268255, 3696.7597106188014, 3741.681957033655, 86844.82887927789, 86846.56576717139, 87143.56428217882, 90174.0, 90286.0, 9289.907100928904, 9414.529273536296, 9489.762755931157, 9535.952320238355, 9644.18024467928, 21963.901804909692, 22184.112635494723, 22233.0, 22419.88790056072, 22534.661980070312, 7230.530015548972, 7287.125544934642, 7338.41292696583, 7362.668679909417, 7492.662830172655, 3818.4845045918446, 3863.5750067492554, 3937.665298449664, 3947.624975627311, 3992.7404718693774, 3577.713782897414, 3674.283514714605, 3687.2809802088336, 3687.3547129252274, 3690.612485689018, 640.8878446271884, 652.5627829354344, 656.2322083162711, 656.4879394072631, 662.453475882399, 482.98792530186324, 581.1892410087904, 585.4116612804105, 601.9006863867413, 606.5238787551839, 8166.550839703873, 8202.712905048415, 8377.07254336258, 8438.620262088221, 8448.535330556879, 526.689253340531, 571.459970328036, 595.7587177193244, 609.4910749524197, 611.3916652930362, 3806.257779732981, 3937.665298449664, 3938.2911076006594, 3986.0234242610168, 3999.760014399087, 88033.0, 93963.0, 95431.52284238534, 95554.52222738841, 96987.0, 83516.74724879132, 87065.56467217623, 87084.56457717798, 87564.56217718871, 89912.0, 77459.0, 77512.44975100466, 77575.8363624546, 77822.61088694634, 78426.6078669603, 7709.267619576131, 7793.727219547289, 7900.6049697513745, 7925.484843485155, 7952.483088599222, 47775.28337074877, 49315.753421232665, 49423.752881236804, 49759.751201243766, 49920.75039624924, 61285.08072379007, 61887.69056154691, 63667.68166159139, 69629.0, 69713.65143174252, 11044.834327485254, 11189.216754827085, 11304.547818087185, 11347.0, 11454.427278635869, 11966.341851198267, 11990.940045299718, 12063.336516491678, 12126.27242365461, 12132.0, 11666.883331166919, 11712.648620541397, 11792.29246245228, 11840.407979601157, 12034.879651203375, 11478.713032173928, 11551.942240288745, 11607.825882611936, 11861.525538978343, 11905.796558527232, 11429.314241145557, 11574.88425115738, 11684.88315116838, 11823.826483972563, 11850.585229516926, 9628.422294662343, 9701.854472183064, 9907.0, 10024.047715467019, 10384.32501887375, 9599.712008639752, 9781.902180978384, 9792.167665748491, 9915.65295214664, 9933.900660993586], "value": 7012.20017733248, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1574.3000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 138.0, 108.0, 116.0, 116.0, 116.0, 107.0, 115.0, 116.0, 115.0], "value": 115.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 122.0, 148.0, 203.0, 130.0, 173.0, 139.0, 124.0, 174.0, 145.0], "value": 151.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 194.0, 209.0, 237.0, 198.0, 197.0, 198.0, 199.0, 196.0, 194.0], "value": 201.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 75.0, 174.0, 56.0, 60.0, 62.0, 80.0, 139.0, 58.0], "value": 86.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 82.0, 152.0, 79.0, 81.0, 87.0, 119.0, 82.0, 80.0, 122.0], "value": 96.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 128.0, 89.0, 85.0, 89.0, 86.0, 107.0, 105.0, 89.0, 87.0], "value": 95.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 123.0, 150.0, 95.0, 93.0, 94.0, 95.0, 95.0, 94.0, 94.0], "value": 102.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 147.0, 126.0, 89.0, 97.0, 84.0, 140.0, 123.0, 92.0, 121.0], "value": 113.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 59.0, 59.0, 59.0, 58.0, 58.0, 60.0, 117.0, 60.0, 58.0], "value": 64.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 66.0, 72.0, 69.0, 80.0, 71.0, 69.0, 67.0, 67.0, 69.0], "value": 69.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 88.0, 87.0, 87.0, 85.0, 91.0, 75.0, 84.0, 106.0], "value": 87.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 148.0, 145.0, 140.0, 137.0, 135.0, 135.0, 130.0, 208.0, 142.0], "value": 147.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 165.0, 162.0, 163.0, 168.0, 163.0, 167.0, 168.0, 166.0, 167.0], "value": 165.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 67.0, 70.0, 85.0, 84.0, 83.0, 86.0, 85.0, 80.0], "value": 77.0, "unit": "score"}]}]} 12:21:31 INFO - Return code: 0 12:21:31 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 12:21:31 ERROR - # TBPL SUCCESS # 12:21:31 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:21:31 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:21:31 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:21:31 INFO - 2016-05-13 12:21:31.207 screenresolution[1683:10978] starting screenresolution argv=screenresolution get 12:21:31 INFO - 2016-05-13 12:21:31.210 screenresolution[1683:10978] Display 0: 1600x1200x32@60 12:21:31 INFO - 2016-05-13 12:21:31.215 screenresolution[1684:10981] starting screenresolution argv=screenresolution list 12:21:31 INFO - Available Modes on Display 0 12:21:31 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:21:31 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:21:31 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:21:31 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:21:31 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:21:31 INFO - Intel Iris: 12:21:31 INFO - Chipset Model: Intel Iris 12:21:31 INFO - Type: GPU 12:21:31 INFO - Bus: Built-In 12:21:31 INFO - VRAM (Dynamic, Max): 1536 MB 12:21:31 INFO - Vendor: Intel (0x8086) 12:21:31 INFO - Device ID: 0x0a2e 12:21:31 INFO - Revision ID: 0x0009 12:21:31 INFO - Displays: 12:21:31 INFO - Display: 12:21:31 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:21:31 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:21:31 INFO - Display Serial Number: 1600x1200 60 12:21:31 INFO - Main Display: Yes 12:21:31 INFO - Mirror: Off 12:21:31 INFO - Online: Yes 12:21:31 INFO - Rotation: Supported 12:21:31 INFO - Return code: 0 12:21:31 INFO - Running post-action listener: _resource_record_post_action 12:21:31 INFO - ##### 12:21:31 INFO - ##### Finished run-tests step (success) 12:21:31 INFO - ##### 12:21:31 INFO - Running post-run listener: _resource_record_post_run 12:21:31 INFO - Total resource usage - Wall time: 1208s; CPU: 55.0%; Read bytes: 32566784; Write bytes: 700219392; Read time: 330; Write time: 2078 12:21:31 INFO - TinderboxPrint: CPU usage
54.7% 12:21:31 INFO - TinderboxPrint: I/O read bytes / time
32,566,784 / 330 12:21:31 INFO - TinderboxPrint: I/O write bytes / time
700,219,392 / 2,078 12:21:31 INFO - TinderboxPrint: CPU idle
2,191.2 (45.3%) 12:21:31 INFO - TinderboxPrint: CPU system
972.9 (20.1%) 12:21:31 INFO - TinderboxPrint: CPU user
1,669.5 (34.5%) 12:21:31 INFO - TinderboxPrint: Swap in / out
424,513,536 / 0 12:21:31 INFO - install - Wall time: 33s; CPU: 54.0%; Read bytes: 388813312; Write bytes: 382330368; Read time: 28638; Write time: 699 12:21:31 INFO - run-tests - Wall time: 1175s; CPU: 55.0%; Read bytes: 30768640; Write bytes: 302680576; Read time: 310; Write time: 1339 12:21:32 INFO - Running post-run listener: _upload_blobber_files 12:21:32 INFO - Blob upload gear active. 12:21:32 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:21:32 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 12:21:32 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'] 12:21:32 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 12:21:32 INFO - (blobuploader) - INFO - Open directory for files ... 12:21:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:21:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:21:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:21:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:21:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:21:33 INFO - (blobuploader) - INFO - Done attempting. 12:21:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:21:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:21:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:21:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:21:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:21:34 INFO - (blobuploader) - INFO - Done attempting. 12:21:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:21:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:21:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:21:36 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:21:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:21:36 INFO - (blobuploader) - INFO - Done attempting. 12:21:36 INFO - (blobuploader) - INFO - Iteration through files over. 12:21:36 INFO - Return code: 0 12:21:36 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:21:36 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:21:36 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a1027081313a97caf0a31b67c2a397a78b5f71a9fc8cfeaee38562af9c164967d44eaf1fecb4996b9e4f902ee95e808a7e940e1da213b6e9dfffe1ca7d636cf9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8f5fbec5740394c86e17b6bb22bb510ef26efecded317cefdb482470b2d3fe73c9ac552b976c6eee065df48438626b476c16a9102a545402bb2047c076cef08f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d6730c660647761754be13917c6ace4b582fe3821ab0e3a0a808156cdd34bae1b82160e07bed48714b9128ebf8dc708ff3c0f7dd85499e57de8248c86b992281"} 12:21:36 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:21:36 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:21:36 INFO - Contents: 12:21:36 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a1027081313a97caf0a31b67c2a397a78b5f71a9fc8cfeaee38562af9c164967d44eaf1fecb4996b9e4f902ee95e808a7e940e1da213b6e9dfffe1ca7d636cf9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8f5fbec5740394c86e17b6bb22bb510ef26efecded317cefdb482470b2d3fe73c9ac552b976c6eee065df48438626b476c16a9102a545402bb2047c076cef08f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d6730c660647761754be13917c6ace4b582fe3821ab0e3a0a808156cdd34bae1b82160e07bed48714b9128ebf8dc708ff3c0f7dd85499e57de8248c86b992281"} 12:21:36 INFO - Running post-run listener: copy_logs_to_upload_dir 12:21:36 INFO - Copying logs to upload dir... 12:21:36 INFO - mkdir: /builds/slave/test/build/upload/logs 12:21:36 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1299.880740 ========= master_lag: 0.33 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 40 secs) (at 2016-05-13 12:21:37.089625) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-13 12:21:37.097007) ========= 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.h6ALDLbO6z/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.ukaEkXkg49/Listeners TMPDIR=/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a1027081313a97caf0a31b67c2a397a78b5f71a9fc8cfeaee38562af9c164967d44eaf1fecb4996b9e4f902ee95e808a7e940e1da213b6e9dfffe1ca7d636cf9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8f5fbec5740394c86e17b6bb22bb510ef26efecded317cefdb482470b2d3fe73c9ac552b976c6eee065df48438626b476c16a9102a545402bb2047c076cef08f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d6730c660647761754be13917c6ace4b582fe3821ab0e3a0a808156cdd34bae1b82160e07bed48714b9128ebf8dc708ff3c0f7dd85499e57de8248c86b992281"} build_url:https://queue.taskcluster.net/v1/task/a-NOy8zESG2EKgTNwCXYbg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011129 build_url: 'https://queue.taskcluster.net/v1/task/a-NOy8zESG2EKgTNwCXYbg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a1027081313a97caf0a31b67c2a397a78b5f71a9fc8cfeaee38562af9c164967d44eaf1fecb4996b9e4f902ee95e808a7e940e1da213b6e9dfffe1ca7d636cf9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8f5fbec5740394c86e17b6bb22bb510ef26efecded317cefdb482470b2d3fe73c9ac552b976c6eee065df48438626b476c16a9102a545402bb2047c076cef08f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d6730c660647761754be13917c6ace4b582fe3821ab0e3a0a808156cdd34bae1b82160e07bed48714b9128ebf8dc708ff3c0f7dd85499e57de8248c86b992281"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-13 12:21:37.129454) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 12:21:37.130958) ========= 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.h6ALDLbO6z/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.ukaEkXkg49/Listeners TMPDIR=/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/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.005077 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 12:21:37.180244) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-13 12:21:37.183636) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-13 12:21:37.183993) ========= ========= Total master_lag: 0.57 =========