builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0095 starttime: 1464099490.17 results: success (0) buildid: 20160524052203 builduid: c4372d02bd8443a09e58551715c1dc5f revision: f6e1ee9ac46c73705320caa6a208e6a017f3bdd8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-24 07:18:10.165473) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-24 07:18:10.169507) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-24 07:18:10.169818) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-24 07:18:10.189452) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 07:18:10.189845) ========= 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.gqCvZldote/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.6OGCn0SiIs/Listeners TMPDIR=/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-24 07:18:10-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 14.0M=0.001s 2016-05-24 07:18:10 (14.0 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.141487 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 07:18:10.349459) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 07:18:10.349751) ========= 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.gqCvZldote/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.6OGCn0SiIs/Listeners TMPDIR=/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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.019990 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 07:18:10.398115) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-24 07:18:10.398449) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev f6e1ee9ac46c73705320caa6a208e6a017f3bdd8 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev f6e1ee9ac46c73705320caa6a208e6a017f3bdd8 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.gqCvZldote/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.6OGCn0SiIs/Listeners TMPDIR=/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-24 07:18:10,473 truncating revision to first 12 chars 2016-05-24 07:18:10,473 Setting DEBUG logging. 2016-05-24 07:18:10,473 attempt 1/10 2016-05-24 07:18:10,473 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/f6e1ee9ac46c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-24 07:18:11,746 unpacking tar archive at: mozilla-central-f6e1ee9ac46c/testing/mozharness/ program finished with exit code 0 elapsedTime=1.527507 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-24 07:18:11.943790) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-24 07:18:11.944062) ========= script_repo_revision: f6e1ee9ac46c73705320caa6a208e6a017f3bdd8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-24 07:18:11.944423) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-24 07:18:11.944703) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-24 07:18:11.959962) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 23 mins, 49 secs) (at 2016-05-24 07:18:11.960235) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.gqCvZldote/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.6OGCn0SiIs/Listeners TMPDIR=/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 07:18:12 INFO - MultiFileLogger online at 20160524 07:18:12 in /builds/slave/test 07:18:12 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 07:18:12 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:18:12 INFO - {'append_to_log': False, 07:18:12 INFO - 'base_work_dir': '/builds/slave/test', 07:18:12 INFO - 'blob_upload_branch': 'Firefox', 07:18:12 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:18:12 INFO - 'branch': 'Firefox', 07:18:12 INFO - 'buildbot_json_path': 'buildprops.json', 07:18:12 INFO - 'config_files': ('talos/mac_config.py',), 07:18:12 INFO - 'default_actions': ('clobber', 07:18:12 INFO - 'read-buildbot-config', 07:18:12 INFO - 'download-and-extract', 07:18:12 INFO - 'populate-webroot', 07:18:12 INFO - 'create-virtualenv', 07:18:12 INFO - 'install', 07:18:12 INFO - 'run-tests'), 07:18:12 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:18:12 INFO - 'download_minidump_stackwalk': True, 07:18:12 INFO - 'download_symbols': 'ondemand', 07:18:12 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:18:12 INFO - 'tooltool.py': '/tools/tooltool.py', 07:18:12 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:18:12 INFO - '/tools/misc-python/virtualenv.py')}, 07:18:12 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:18:12 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:18:12 INFO - 'installer_path': 'installer.exe', 07:18:12 INFO - 'log_level': 'info', 07:18:12 INFO - 'log_name': 'talos', 07:18:12 INFO - 'log_to_console': True, 07:18:12 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:18:12 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:18:12 INFO - 'opt_config_files': (), 07:18:12 INFO - 'pip_index': False, 07:18:12 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:18:12 INFO - 'cmd': ('bash', 07:18:12 INFO - '-c', 07:18:12 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:18:12 INFO - 'enabled': True, 07:18:12 INFO - 'halt_on_failure': False, 07:18:12 INFO - 'name': 'check_screen_resolution'},), 07:18:12 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:18:12 INFO - 'cmd': ('bash', 07:18:12 INFO - '-c', 07:18:12 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:18:12 INFO - 'enabled': True, 07:18:12 INFO - 'halt_on_failure': False, 07:18:12 INFO - 'name': 'check_screen_resolution'},), 07:18:12 INFO - 'run_cmd_checks_enabled': True, 07:18:12 INFO - 'sps_profile': False, 07:18:12 INFO - 'sps_profile_interval': 0, 07:18:12 INFO - 'suite': 'dromaeojs', 07:18:12 INFO - 'system_bits': '32', 07:18:12 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:18:12 INFO - 'title': 't-yosemite-r7-0095', 07:18:12 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:18:12 INFO - 'use_talos_json': True, 07:18:12 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:18:12 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:18:12 INFO - 'work_dir': 'build'} 07:18:12 INFO - ##### 07:18:12 INFO - ##### Running clobber step. 07:18:12 INFO - ##### 07:18:12 INFO - Running pre-action listener: _resource_record_pre_action 07:18:12 INFO - Running main action method: clobber 07:18:12 INFO - rmtree: /builds/slave/test/build 07:18:12 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:18:13 INFO - Running post-action listener: _resource_record_post_action 07:18:13 INFO - ##### 07:18:13 INFO - ##### Finished clobber step (success) 07:18:13 INFO - ##### 07:18:13 INFO - ##### 07:18:13 INFO - ##### Running read-buildbot-config step. 07:18:13 INFO - ##### 07:18:13 INFO - Running pre-action listener: _resource_record_pre_action 07:18:13 INFO - Running main action method: read_buildbot_config 07:18:13 INFO - Using buildbot properties: 07:18:13 INFO - { 07:18:13 INFO - "project": "", 07:18:13 INFO - "product": "firefox", 07:18:13 INFO - "script_repo_revision": "production", 07:18:13 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 07:18:13 INFO - "repository": "", 07:18:13 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 07:18:13 INFO - "buildid": "20160524052203", 07:18:13 INFO - "pgo_build": "False", 07:18:13 INFO - "basedir": "/builds/slave/test", 07:18:13 INFO - "buildnumber": 8, 07:18:13 INFO - "slavename": "t-yosemite-r7-0095", 07:18:13 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 07:18:13 INFO - "platform": "yosemite_r7", 07:18:13 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 07:18:13 INFO - "branch": "mozilla-central", 07:18:13 INFO - "repo_path": "mozilla-central", 07:18:13 INFO - "revision": "f6e1ee9ac46c73705320caa6a208e6a017f3bdd8", 07:18:13 INFO - "stage_platform": "macosx64", 07:18:13 INFO - "builduid": "c4372d02bd8443a09e58551715c1dc5f", 07:18:13 INFO - "slavebuilddir": "test" 07:18:13 INFO - } 07:18:13 INFO - Found installer url https://queue.taskcluster.net/v1/task/FtUJ_a-LRFuNVJzn27cJxw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 07:18:13 INFO - Running post-action listener: _resource_record_post_action 07:18:13 INFO - ##### 07:18:13 INFO - ##### Finished read-buildbot-config step (success) 07:18:13 INFO - ##### 07:18:13 INFO - ##### 07:18:13 INFO - ##### Running download-and-extract step. 07:18:13 INFO - ##### 07:18:13 INFO - Running pre-action listener: _resource_record_pre_action 07:18:13 INFO - Running main action method: download_and_extract 07:18:13 INFO - mkdir: /builds/slave/test/build/tests 07:18:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:13 INFO - https://queue.taskcluster.net/v1/task/FtUJ_a-LRFuNVJzn27cJxw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 07:18:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FtUJ_a-LRFuNVJzn27cJxw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 07:18:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FtUJ_a-LRFuNVJzn27cJxw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 07:18:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FtUJ_a-LRFuNVJzn27cJxw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 07:18:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FtUJ_a-LRFuNVJzn27cJxw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 07:18:15 INFO - Downloaded 1199 bytes. 07:18:15 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 07:18:15 INFO - Using the following test package requirements: 07:18:15 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 07:18:15 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:18:15 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 07:18:15 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:18:15 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 07:18:15 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:18:15 INFO - u'jsshell-mac.zip'], 07:18:15 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:18:15 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 07:18:15 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 07:18:15 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:18:15 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 07:18:15 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:18:15 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 07:18:15 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:18:15 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 07:18:15 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:18:15 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 07:18:15 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 07:18:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:15 INFO - https://queue.taskcluster.net/v1/task/FtUJ_a-LRFuNVJzn27cJxw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 07:18:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FtUJ_a-LRFuNVJzn27cJxw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 07:18:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FtUJ_a-LRFuNVJzn27cJxw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 07:18:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FtUJ_a-LRFuNVJzn27cJxw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 07:18:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FtUJ_a-LRFuNVJzn27cJxw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:18:16 INFO - Downloaded 18630512 bytes. 07:18:16 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 07:18:16 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 07:18:19 INFO - Return code: 0 07:18:19 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 07:18:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:19 INFO - https://queue.taskcluster.net/v1/task/FtUJ_a-LRFuNVJzn27cJxw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 07:18:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FtUJ_a-LRFuNVJzn27cJxw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 07:18:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FtUJ_a-LRFuNVJzn27cJxw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 07:18:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FtUJ_a-LRFuNVJzn27cJxw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 07:18:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FtUJ_a-LRFuNVJzn27cJxw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:18:21 INFO - Downloaded 18630512 bytes. 07:18:21 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 07:18:21 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 07:18:25 INFO - Return code: 0 07:18:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:25 INFO - https://queue.taskcluster.net/v1/task/FtUJ_a-LRFuNVJzn27cJxw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 07:18:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FtUJ_a-LRFuNVJzn27cJxw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 07:18:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FtUJ_a-LRFuNVJzn27cJxw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 07:18:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FtUJ_a-LRFuNVJzn27cJxw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 07:18:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FtUJ_a-LRFuNVJzn27cJxw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 07:18:26 INFO - Downloaded 10840359 bytes. 07:18:26 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 07:18:26 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 07:18:26 INFO - Return code: 0 07:18:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:26 INFO - https://queue.taskcluster.net/v1/task/FtUJ_a-LRFuNVJzn27cJxw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 07:18:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FtUJ_a-LRFuNVJzn27cJxw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 07:18:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FtUJ_a-LRFuNVJzn27cJxw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 07:18:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FtUJ_a-LRFuNVJzn27cJxw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 07:18:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FtUJ_a-LRFuNVJzn27cJxw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 07:18:32 INFO - Downloaded 110230790 bytes. 07:18:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FtUJ_a-LRFuNVJzn27cJxw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 07:18:32 INFO - mkdir: /builds/slave/test/properties 07:18:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:18:32 INFO - Writing to file /builds/slave/test/properties/build_url 07:18:32 INFO - Contents: 07:18:32 INFO - build_url:https://queue.taskcluster.net/v1/task/FtUJ_a-LRFuNVJzn27cJxw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 07:18:33 INFO - Running post-action listener: _resource_record_post_action 07:18:33 INFO - Running post-action listener: set_extra_try_arguments 07:18:33 INFO - ##### 07:18:33 INFO - ##### Finished download-and-extract step (success) 07:18:33 INFO - ##### 07:18:33 INFO - ##### 07:18:33 INFO - ##### Running populate-webroot step. 07:18:33 INFO - ##### 07:18:33 INFO - Running pre-action listener: _resource_record_pre_action 07:18:33 INFO - Running main action method: populate_webroot 07:18:33 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:18:33 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:18:33 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:18:33 INFO - '--tppagecycles', 07:18:33 INFO - '7'], 07:18:33 INFO - 'tests': ['tsvgm']}}, 07:18:33 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:18:33 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:18:33 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:18:33 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:18:33 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:18:33 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:18:33 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:33 INFO - 'talos_options': ['--disable-e10s'], 07:18:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:18:33 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:18:33 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:33 INFO - 'talos_options': ['--disable-e10s'], 07:18:33 INFO - 'tests': ['damp', 'tps']}, 07:18:33 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:33 INFO - 'tests': ['damp', 'tps']}, 07:18:33 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:18:33 INFO - 'tests': ['dromaeo_dom']}, 07:18:33 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:18:33 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:18:33 INFO - 'tests': ['a11yr', 07:18:33 INFO - 'ts_paint', 07:18:33 INFO - 'tpaint', 07:18:33 INFO - 'sessionrestore', 07:18:33 INFO - 'sessionrestore_no_auto_restore', 07:18:33 INFO - 'tabpaint']}, 07:18:33 INFO - 'other-e10s': {'tests': ['a11yr', 07:18:33 INFO - 'ts_paint', 07:18:33 INFO - 'tpaint', 07:18:33 INFO - 'sessionrestore', 07:18:33 INFO - 'sessionrestore_no_auto_restore', 07:18:33 INFO - 'tabpaint']}, 07:18:33 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:18:33 INFO - 'ts_paint', 07:18:33 INFO - 'tpaint', 07:18:33 INFO - 'sessionrestore', 07:18:33 INFO - 'sessionrestore_no_auto_restore', 07:18:33 INFO - 'tabpaint']}, 07:18:33 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:18:33 INFO - 'ts_paint', 07:18:33 INFO - 'tpaint', 07:18:33 INFO - 'sessionrestore', 07:18:33 INFO - 'sessionrestore_no_auto_restore', 07:18:33 INFO - 'tabpaint']}, 07:18:33 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:18:33 INFO - 'tests': ['a11yr', 07:18:33 INFO - 'ts_paint', 07:18:33 INFO - 'tpaint', 07:18:33 INFO - 'sessionrestore', 07:18:33 INFO - 'sessionrestore_no_auto_restore', 07:18:33 INFO - 'tabpaint']}, 07:18:33 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:18:33 INFO - 'tests': ['a11yr', 07:18:33 INFO - 'ts_paint', 07:18:33 INFO - 'tpaint', 07:18:33 INFO - 'sessionrestore', 07:18:33 INFO - 'sessionrestore_no_auto_restore', 07:18:33 INFO - 'tabpaint']}, 07:18:33 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:18:33 INFO - 'tests': ['tsvgx', 07:18:33 INFO - 'tsvgr_opacity', 07:18:33 INFO - 'tart', 07:18:33 INFO - 'tscrollx', 07:18:33 INFO - 'cart']}, 07:18:33 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:18:33 INFO - 'tsvgr_opacity', 07:18:33 INFO - 'tart', 07:18:33 INFO - 'tscrollx', 07:18:33 INFO - 'cart']}, 07:18:33 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:33 INFO - 'talos_options': ['--disable-e10s'], 07:18:33 INFO - 'tests': ['tp5o']}, 07:18:33 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:33 INFO - 'tests': ['tp5o']}, 07:18:33 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:33 INFO - 'talos_options': ['--disable-e10s', 07:18:33 INFO - '--xperf_path', 07:18:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:18:33 INFO - 'tests': ['tp5n']}, 07:18:33 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:18:33 INFO - 'talos_options': ['--xperf_path', 07:18:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:18:33 INFO - 'tests': ['tp5n']}}, 07:18:33 INFO - 'talos.zip': {'path': '', 07:18:33 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:18:33 INFO - Running post-action listener: _resource_record_post_action 07:18:33 INFO - ##### 07:18:33 INFO - ##### Finished populate-webroot step (success) 07:18:33 INFO - ##### 07:18:33 INFO - ##### 07:18:33 INFO - ##### Running create-virtualenv step. 07:18:33 INFO - ##### 07:18:33 INFO - Running pre-action listener: _resource_record_pre_action 07:18:33 INFO - Running main action method: create_virtualenv 07:18:33 INFO - Creating virtualenv /builds/slave/test/build/venv 07:18:33 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 07:18:33 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:18:33 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:18:33 INFO - Using real prefix '/tools/python27' 07:18:33 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:18:34 INFO - Installing distribute.............................................................................................................................................................................................done. 07:18:37 INFO - Installing pip.................done. 07:18:37 INFO - Return code: 0 07:18:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:18:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:37 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 0x10c9c5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9cb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe7ba584ac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c869d30>, '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 0x10c68ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c860b20>, '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.gqCvZldote/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.6OGCn0SiIs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:18:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 07:18:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 07:18:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gqCvZldote/Render', 07:18:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:37 INFO - 'HOME': '/Users/cltbld', 07:18:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:37 INFO - 'LOGNAME': 'cltbld', 07:18:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:37 INFO - 'MOZ_NO_REMOTE': '1', 07:18:37 INFO - 'NO_EM_RESTART': '1', 07:18:37 INFO - 'PAGER': '/bin/cat', 07:18:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:37 INFO - 'PWD': '/builds/slave/test', 07:18:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:37 INFO - 'SHELL': '/bin/bash', 07:18:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6OGCn0SiIs/Listeners', 07:18:37 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 07:18:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:37 INFO - 'USER': 'cltbld', 07:18:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:37 INFO - 'XPC_FLAGS': '0x0', 07:18:37 INFO - 'XPC_SERVICE_NAME': '0', 07:18:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:37 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 07:18:37 INFO - Cleaning up... 07:18:37 INFO - Return code: 0 07:18:37 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:18:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:37 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 0x10c9c5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9cb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe7ba584ac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c869d30>, '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 0x10c68ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c860b20>, '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.gqCvZldote/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.6OGCn0SiIs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:18:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 07:18:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 07:18:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gqCvZldote/Render', 07:18:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:37 INFO - 'HOME': '/Users/cltbld', 07:18:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:37 INFO - 'LOGNAME': 'cltbld', 07:18:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:37 INFO - 'MOZ_NO_REMOTE': '1', 07:18:37 INFO - 'NO_EM_RESTART': '1', 07:18:37 INFO - 'PAGER': '/bin/cat', 07:18:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:37 INFO - 'PWD': '/builds/slave/test', 07:18:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:37 INFO - 'SHELL': '/bin/bash', 07:18:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6OGCn0SiIs/Listeners', 07:18:37 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 07:18:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:37 INFO - 'USER': 'cltbld', 07:18:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:37 INFO - 'XPC_FLAGS': '0x0', 07:18:37 INFO - 'XPC_SERVICE_NAME': '0', 07:18:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:37 INFO - Downloading/unpacking psutil>=3.1.1 07:18:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:18:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:18:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:18:40 INFO - Installing collected packages: psutil 07:18:40 INFO - Running setup.py install for psutil 07:18:41 INFO - building 'psutil._psutil_osx' extension 07:18:41 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 07:18:41 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 07:18:41 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 07:18:41 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 07:18:41 INFO - building 'psutil._psutil_posix' extension 07:18:41 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 07:18:41 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:18:41 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:18:41 INFO - ^ 07:18:41 INFO - 1 warning generated. 07:18:41 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 07:18:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:18:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:18:41 INFO - Successfully installed psutil 07:18:41 INFO - Cleaning up... 07:18:41 INFO - Return code: 0 07:18:41 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:18:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c9c5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9cb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe7ba584ac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c869d30>, '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 0x10c68ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c860b20>, '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.gqCvZldote/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.6OGCn0SiIs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:18:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 07:18:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 07:18:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gqCvZldote/Render', 07:18:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:41 INFO - 'HOME': '/Users/cltbld', 07:18:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:41 INFO - 'LOGNAME': 'cltbld', 07:18:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:41 INFO - 'MOZ_NO_REMOTE': '1', 07:18:41 INFO - 'NO_EM_RESTART': '1', 07:18:41 INFO - 'PAGER': '/bin/cat', 07:18:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:41 INFO - 'PWD': '/builds/slave/test', 07:18:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:41 INFO - 'SHELL': '/bin/bash', 07:18:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6OGCn0SiIs/Listeners', 07:18:41 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 07:18:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:41 INFO - 'USER': 'cltbld', 07:18:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:41 INFO - 'XPC_FLAGS': '0x0', 07:18:41 INFO - 'XPC_SERVICE_NAME': '0', 07:18:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:42 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:18:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:44 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:18:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:18:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:18:44 INFO - Installing collected packages: mozsystemmonitor 07:18:44 INFO - Running setup.py install for mozsystemmonitor 07:18:44 INFO - Successfully installed mozsystemmonitor 07:18:44 INFO - Cleaning up... 07:18:44 INFO - Return code: 0 07:18:44 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:18:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10c9c5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9cb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe7ba584ac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c869d30>, '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 0x10c68ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c860b20>, '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.gqCvZldote/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.6OGCn0SiIs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:18:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 07:18:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 07:18:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gqCvZldote/Render', 07:18:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:44 INFO - 'HOME': '/Users/cltbld', 07:18:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:44 INFO - 'LOGNAME': 'cltbld', 07:18:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:44 INFO - 'MOZ_NO_REMOTE': '1', 07:18:44 INFO - 'NO_EM_RESTART': '1', 07:18:44 INFO - 'PAGER': '/bin/cat', 07:18:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:44 INFO - 'PWD': '/builds/slave/test', 07:18:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:44 INFO - 'SHELL': '/bin/bash', 07:18:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6OGCn0SiIs/Listeners', 07:18:44 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 07:18:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:44 INFO - 'USER': 'cltbld', 07:18:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:44 INFO - 'XPC_FLAGS': '0x0', 07:18:44 INFO - 'XPC_SERVICE_NAME': '0', 07:18:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:45 INFO - Downloading/unpacking blobuploader==1.2.4 07:18:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:47 INFO - Downloading blobuploader-1.2.4.tar.gz 07:18:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:18:47 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:18:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:18:48 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:18:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:18:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:18:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:18:48 INFO - Downloading docopt-0.6.1.tar.gz 07:18:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:18:48 INFO - Installing collected packages: blobuploader, requests, docopt 07:18:48 INFO - Running setup.py install for blobuploader 07:18:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:18:48 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:18:48 INFO - Running setup.py install for requests 07:18:49 INFO - Running setup.py install for docopt 07:18:49 INFO - Successfully installed blobuploader requests docopt 07:18:49 INFO - Cleaning up... 07:18:49 INFO - Return code: 0 07:18:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:18:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:49 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 0x10c9c5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9cb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe7ba584ac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c869d30>, '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 0x10c68ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c860b20>, '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.gqCvZldote/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.6OGCn0SiIs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:18:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 07:18:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:18:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gqCvZldote/Render', 07:18:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:49 INFO - 'HOME': '/Users/cltbld', 07:18:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:49 INFO - 'LOGNAME': 'cltbld', 07:18:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:49 INFO - 'MOZ_NO_REMOTE': '1', 07:18:49 INFO - 'NO_EM_RESTART': '1', 07:18:49 INFO - 'PAGER': '/bin/cat', 07:18:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:49 INFO - 'PWD': '/builds/slave/test', 07:18:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:49 INFO - 'SHELL': '/bin/bash', 07:18:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6OGCn0SiIs/Listeners', 07:18:49 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 07:18:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:49 INFO - 'USER': 'cltbld', 07:18:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:49 INFO - 'XPC_FLAGS': '0x0', 07:18:49 INFO - 'XPC_SERVICE_NAME': '0', 07:18:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:18:49 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-r3M5zR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:18:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:18:50 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-asVCYw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:18:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:18:50 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-6zXFBN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:18:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:18:50 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-6j7NbO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:18:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:18:50 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-lqWke9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:18:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:18:50 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-gz3InQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:18:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:18:50 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-BLVvig-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:18:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:18:50 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-rboywQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:18:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:18:50 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-nmhoTk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:18:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:18:50 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-3aH94Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:18:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:18:50 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-E3vrOP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:18:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:18:51 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-QYPBqL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:18:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:18:51 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-o5A9qG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:18:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:18:51 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-HGPSZU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:18:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:18:51 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-BmBbYJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:18:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:18:51 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-zqCCcW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:18:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:18:51 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-s2knot-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:18:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:18:51 INFO - Running setup.py install for manifestparser 07:18:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:18:51 INFO - Running setup.py install for mozcrash 07:18:51 INFO - Running setup.py install for mozdebug 07:18:52 INFO - Running setup.py install for mozdevice 07:18:52 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:18:52 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:18:52 INFO - Running setup.py install for mozfile 07:18:52 INFO - Running setup.py install for mozhttpd 07:18:52 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:18:52 INFO - Running setup.py install for mozinfo 07:18:52 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:18:52 INFO - Running setup.py install for mozInstall 07:18:52 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:18:52 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:18:52 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:18:52 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:18:52 INFO - Running setup.py install for mozleak 07:18:52 INFO - Running setup.py install for mozlog 07:18:53 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:18:53 INFO - Running setup.py install for moznetwork 07:18:53 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:18:53 INFO - Running setup.py install for mozprocess 07:18:53 INFO - Running setup.py install for mozprofile 07:18:53 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:18:53 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:18:53 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:18:53 INFO - Running setup.py install for mozrunner 07:18:53 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:18:53 INFO - Running setup.py install for mozscreenshot 07:18:53 INFO - Running setup.py install for moztest 07:18:54 INFO - Running setup.py install for mozversion 07:18:54 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:18:54 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:18:54 INFO - Cleaning up... 07:18:54 INFO - Return code: 0 07:18:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:18:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:18:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:54 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 0x10c9c5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9cb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe7ba584ac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c869d30>, '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 0x10c68ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c860b20>, '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.gqCvZldote/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.6OGCn0SiIs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:18:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 07:18:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:18:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gqCvZldote/Render', 07:18:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:54 INFO - 'HOME': '/Users/cltbld', 07:18:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:54 INFO - 'LOGNAME': 'cltbld', 07:18:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:54 INFO - 'MOZ_NO_REMOTE': '1', 07:18:54 INFO - 'NO_EM_RESTART': '1', 07:18:54 INFO - 'PAGER': '/bin/cat', 07:18:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:54 INFO - 'PWD': '/builds/slave/test', 07:18:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:54 INFO - 'SHELL': '/bin/bash', 07:18:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6OGCn0SiIs/Listeners', 07:18:54 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 07:18:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:54 INFO - 'USER': 'cltbld', 07:18:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:54 INFO - 'XPC_FLAGS': '0x0', 07:18:54 INFO - 'XPC_SERVICE_NAME': '0', 07:18:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:18:54 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-n5si0W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:18:54 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 07:18:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:18:54 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-fbHfRd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:18:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:18:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:18:54 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-rrvxqX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:18:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 07:18:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:18:54 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-22ben5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:18:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:18:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:18:54 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-QjkCQq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:18:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 07:18:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:18:55 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-yMxd_N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:18:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 07:18:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:18:55 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-Bq30Dd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:18:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 07:18:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:18:55 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-yGmom5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:18:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 07:18:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:18:55 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-70yM6o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:18:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 07:18:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:18:55 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-QQcNBz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:18:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:18:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:18:55 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-Wsi2km-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:18:55 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 07:18:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:18:55 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-ZMvMRF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:18:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 07:18:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:18:55 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-CWJ2t6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:18:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 07:18:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:18:55 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-Ng6Ywf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:18:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 07:18:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:18:56 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-fG9CpU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:18:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 07:18:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:18:56 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-2jnCuK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:18:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 07:18:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:18:56 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-H282c6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:18:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 07:18:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:18:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:18:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 07:18:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:18:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:18:56 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:18: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 07:18: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 07:18: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 07:18: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 07:18: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 07:18: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 07:18:58 INFO - Downloading blessings-1.6.tar.gz 07:18:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:18:58 INFO - Installing collected packages: blessings 07:18:58 INFO - Running setup.py install for blessings 07:18:59 INFO - Successfully installed blessings 07:18:59 INFO - Cleaning up... 07:18:59 INFO - Return code: 0 07:18:59 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:18:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:18:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:18:59 INFO - Reading from file tmpfile_stdout 07:18:59 INFO - Current package versions: 07:18:59 INFO - blessings == 1.6 07:18:59 INFO - blobuploader == 1.2.4 07:18:59 INFO - docopt == 0.6.1 07:18:59 INFO - manifestparser == 1.1 07:18:59 INFO - mozInstall == 1.12 07:18:59 INFO - mozcrash == 0.17 07:18:59 INFO - mozdebug == 0.1 07:18:59 INFO - mozdevice == 0.48 07:18:59 INFO - mozfile == 1.2 07:18:59 INFO - mozhttpd == 0.7 07:18:59 INFO - mozinfo == 0.9 07:18:59 INFO - mozleak == 0.1 07:18:59 INFO - mozlog == 3.2 07:18:59 INFO - moznetwork == 0.27 07:18:59 INFO - mozprocess == 0.22 07:18:59 INFO - mozprofile == 0.28 07:18:59 INFO - mozrunner == 6.11 07:18:59 INFO - mozscreenshot == 0.1 07:18:59 INFO - mozsystemmonitor == 0.3 07:18:59 INFO - moztest == 0.7 07:18:59 INFO - mozversion == 1.4 07:18:59 INFO - psutil == 3.1.1 07:18:59 INFO - requests == 1.2.3 07:18:59 INFO - wsgiref == 0.1.2 07:18:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:18: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')]} 07:18:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18: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')]} 07:18:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18:59 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 0x10c9c5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9cb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe7ba584ac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c869d30>, '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 0x10c68ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c860b20>, '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.gqCvZldote/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.6OGCn0SiIs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:18:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 07:18:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:18:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gqCvZldote/Render', 07:18:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:59 INFO - 'HOME': '/Users/cltbld', 07:18:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:59 INFO - 'LOGNAME': 'cltbld', 07:18:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:59 INFO - 'MOZ_NO_REMOTE': '1', 07:18:59 INFO - 'NO_EM_RESTART': '1', 07:18:59 INFO - 'PAGER': '/bin/cat', 07:18:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:59 INFO - 'PWD': '/builds/slave/test', 07:18:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:59 INFO - 'SHELL': '/bin/bash', 07:18:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6OGCn0SiIs/Listeners', 07:18:59 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 07:18:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:59 INFO - 'USER': 'cltbld', 07:18:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:59 INFO - 'XPC_FLAGS': '0x0', 07:18:59 INFO - 'XPC_SERVICE_NAME': '0', 07:18:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 07:18:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 07:18:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 07:18:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 07:18:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 07:18:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 07:18:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 07:18:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 07:18:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 07:18:59 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 07:18:59 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 07:18:59 INFO - Cleaning up... 07:18:59 INFO - Return code: 0 07:18:59 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:18: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')]} 07:18:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:18:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18: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')]} 07:18:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:18:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:18: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c9c5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9cb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe7ba584ac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c869d30>, '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 0x10c68ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c860b20>, '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.gqCvZldote/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.6OGCn0SiIs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:18: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', 'jsonschema'] in /builds/slave/test/build 07:18: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 jsonschema 07:18:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gqCvZldote/Render', 07:18:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:18:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:18:59 INFO - 'HOME': '/Users/cltbld', 07:18:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:18:59 INFO - 'LOGNAME': 'cltbld', 07:18:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:18:59 INFO - 'MOZ_NO_REMOTE': '1', 07:18:59 INFO - 'NO_EM_RESTART': '1', 07:18:59 INFO - 'PAGER': '/bin/cat', 07:18:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:18:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:18:59 INFO - 'PWD': '/builds/slave/test', 07:18:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:18:59 INFO - 'SHELL': '/bin/bash', 07:18:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6OGCn0SiIs/Listeners', 07:18:59 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 07:18:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:18:59 INFO - 'USER': 'cltbld', 07:18:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:18:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:18:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:18:59 INFO - 'XPC_FLAGS': '0x0', 07:18:59 INFO - 'XPC_SERVICE_NAME': '0', 07:18:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:18:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:18:59 INFO - Downloading/unpacking jsonschema 07:18: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 07:18: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 07:18: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 07:18: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 07:18: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 07:18: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 07:19:02 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:19:02 INFO - Downloading/unpacking functools32 (from jsonschema) 07:19: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 07:19: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 07:19: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 07:19: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 07:19: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 07:19: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 07:19:02 INFO - Downloading functools32-3.2.3-2.tar.gz 07:19:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:19:02 INFO - warning: no files found matching '*.txt' 07:19:02 INFO - no previously-included directories found matching 'build' 07:19:02 INFO - no previously-included directories found matching 'dist' 07:19:02 INFO - no previously-included directories found matching '.git*' 07:19:02 INFO - Installing collected packages: jsonschema, functools32 07:19:02 INFO - Running setup.py install for functools32 07:19:03 INFO - warning: no files found matching '*.txt' 07:19:03 INFO - no previously-included directories found matching 'build' 07:19:03 INFO - no previously-included directories found matching 'dist' 07:19:03 INFO - no previously-included directories found matching '.git*' 07:19:03 INFO - Successfully installed jsonschema functools32 07:19:03 INFO - Cleaning up... 07:19:03 INFO - Return code: 0 07:19:03 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 07:19:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:19:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:19:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:19:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:19:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:19:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:19:03 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 0x10c9c5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9cb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe7ba584ac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c869d30>, '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 0x10c68ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c860b20>, '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.gqCvZldote/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.6OGCn0SiIs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:19:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 07:19:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 07:19:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gqCvZldote/Render', 07:19:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:19:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:19:03 INFO - 'HOME': '/Users/cltbld', 07:19:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:19:03 INFO - 'LOGNAME': 'cltbld', 07:19:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:19:03 INFO - 'MOZ_NO_REMOTE': '1', 07:19:03 INFO - 'NO_EM_RESTART': '1', 07:19:03 INFO - 'PAGER': '/bin/cat', 07:19:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:19:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:19:03 INFO - 'PWD': '/builds/slave/test', 07:19:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:19:03 INFO - 'SHELL': '/bin/bash', 07:19:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6OGCn0SiIs/Listeners', 07:19:03 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 07:19:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:19:03 INFO - 'USER': 'cltbld', 07:19:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:19:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:19:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:19:03 INFO - 'XPC_FLAGS': '0x0', 07:19:03 INFO - 'XPC_SERVICE_NAME': '0', 07:19:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:19:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:19:03 INFO - Downloading/unpacking flake8 07:19: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 07:19: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 07:19: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 07:19: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 07:19: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 07:19: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 07:19:05 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 07:19:05 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 07:19:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:19:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:19:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:19:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:19:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:19:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:19:06 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 07:19:06 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 07:19:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:19:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:19:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:19:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:19:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:19:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:19:06 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 07:19:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:19:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:19:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:19:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:19:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:19:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:19:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 07:19:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:19:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:19:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:19:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:19:07 INFO - no previously-included directories found matching 'docs/_build' 07:19:07 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 07:19:07 INFO - Running setup.py install for pep8 07:19:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:19:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:19:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:19:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:19:07 INFO - no previously-included directories found matching 'docs/_build' 07:19:07 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 07:19:07 INFO - Successfully installed flake8 mccabe pyflakes pep8 07:19:07 INFO - Cleaning up... 07:19:07 INFO - Return code: 0 07:19:07 INFO - Running post-action listener: _resource_record_post_action 07:19:07 INFO - Running post-action listener: _start_resource_monitoring 07:19:07 INFO - Starting resource monitoring. 07:19:07 INFO - ##### 07:19:07 INFO - ##### Finished create-virtualenv step (success) 07:19:07 INFO - ##### 07:19:07 INFO - ##### 07:19:07 INFO - ##### Running install step. 07:19:07 INFO - ##### 07:19:07 INFO - Running pre-action listener: _resource_record_pre_action 07:19:07 INFO - Running main action method: install 07:19:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:19:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:19:07 INFO - Reading from file tmpfile_stdout 07:19:08 INFO - Detecting whether we're running mozinstall >=1.0... 07:19:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:19:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:19:08 INFO - Reading from file tmpfile_stdout 07:19:08 INFO - Output received: 07:19:08 INFO - Usage: mozinstall [options] installer 07:19:08 INFO - Options: 07:19:08 INFO - -h, --help show this help message and exit 07:19:08 INFO - -d DEST, --destination=DEST 07:19:08 INFO - Directory to install application into. [default: 07:19:08 INFO - "/builds/slave/test"] 07:19:08 INFO - --app=APP Application being installed. [default: firefox] 07:19:08 INFO - mkdir: /builds/slave/test/build/application 07:19:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 07:19:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 07:19:42 INFO - Reading from file tmpfile_stdout 07:19:42 INFO - Output received: 07:19:42 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 07:19:42 INFO - Running post-action listener: _resource_record_post_action 07:19:42 INFO - ##### 07:19:42 INFO - ##### Finished install step (success) 07:19:42 INFO - ##### 07:19:42 INFO - ##### 07:19:42 INFO - ##### Running run-tests step. 07:19:42 INFO - ##### 07:19:42 INFO - Running pre-action listener: _resource_record_pre_action 07:19:42 INFO - Running main action method: run_tests 07:19:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:19:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:19:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:19:42 INFO - 2016-05-24 07:19:42.185 screenresolution[1927:14021] starting screenresolution argv=screenresolution get 07:19:42 INFO - 2016-05-24 07:19:42.187 screenresolution[1927:14021] Display 0: 1600x1200x32@60 07:19:42 INFO - 2016-05-24 07:19:42.193 screenresolution[1928:14023] starting screenresolution argv=screenresolution list 07:19:42 INFO - Available Modes on Display 0 07:19:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:19:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:19:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:19:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:19:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:19:42 INFO - Intel Iris: 07:19:42 INFO - Chipset Model: Intel Iris 07:19:42 INFO - Type: GPU 07:19:42 INFO - Bus: Built-In 07:19:42 INFO - VRAM (Dynamic, Max): 1536 MB 07:19:42 INFO - Vendor: Intel (0x8086) 07:19:42 INFO - Device ID: 0x0a2e 07:19:42 INFO - Revision ID: 0x0009 07:19:42 INFO - Displays: 07:19:42 INFO - Display: 07:19:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:19:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:19:42 INFO - Display Serial Number: 1600x1200 60 07:19:42 INFO - Main Display: Yes 07:19:42 INFO - Mirror: Off 07:19:42 INFO - Online: Yes 07:19:42 INFO - Rotation: Supported 07:19:42 INFO - Return code: 0 07:19:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:19:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:19:42 INFO - Python 2.7.3 07:19:42 INFO - Return code: 0 07:19:42 INFO - grabbing minidump binary from tooltool 07:19:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:19:42 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 0x10c869d30>, '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 0x10c68ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c860b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:19:42 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 07:19:42 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 07:19:42 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 07:19:42 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:19:42 INFO - Return code: 0 07:19:42 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:19:42 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:19:42 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:19:42 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:19:42 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:19:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 07:19:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 07:19:43 INFO - Return code: 0 07:19:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FtUJ_a-LRFuNVJzn27cJxw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0095', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 07:19:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/FtUJ_a-LRFuNVJzn27cJxw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0095 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 07:19:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gqCvZldote/Render', 07:19:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:19:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:19:43 INFO - 'HOME': '/Users/cltbld', 07:19:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:19:43 INFO - 'LOGNAME': 'cltbld', 07:19:43 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:19:43 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:19:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:19:43 INFO - 'MOZ_NO_REMOTE': '1', 07:19:43 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:19:43 INFO - 'NO_EM_RESTART': '1', 07:19:43 INFO - 'PAGER': '/bin/cat', 07:19:43 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:19:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:19:43 INFO - 'PWD': '/builds/slave/test', 07:19:43 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:19:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:19:43 INFO - 'SHELL': '/bin/bash', 07:19:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6OGCn0SiIs/Listeners', 07:19:43 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 07:19:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:19:43 INFO - 'USER': 'cltbld', 07:19:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:19:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:19:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:19:43 INFO - 'XPC_FLAGS': '0x0', 07:19:43 INFO - 'XPC_SERVICE_NAME': '0', 07:19:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:19:43 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FtUJ_a-LRFuNVJzn27cJxw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0095', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 07:19:43 INFO - mozversion application_buildid: 20160524052203 07:19:43 INFO - mozversion application_changeset: f6e1ee9ac46c73705320caa6a208e6a017f3bdd8 07:19:43 INFO - mozversion application_display_name: Nightly 07:19:43 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:19:43 INFO - mozversion application_name: Firefox 07:19:43 INFO - mozversion application_remotingname: firefox 07:19:43 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 07:19:43 INFO - mozversion application_vendor: Mozilla 07:19:43 INFO - mozversion application_version: 49.0a1 07:19:43 INFO - mozversion platform_buildid: 20160524052203 07:19:43 INFO - mozversion platform_changeset: f6e1ee9ac46c73705320caa6a208e6a017f3bdd8 07:19:43 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 07:19:43 INFO - mozversion platform_version: 49.0a1 07:19:43 INFO - using testdate: 1464099583 07:19:43 INFO - actual date: 1464099583 07:19:43 INFO - starting webserver on 'localhost:51414' 07:19:43 INFO - SUITE-START | Running 2 tests 07:19:43 INFO - TEST-START | dromaeo_css 07:19:43 INFO - operating with platform_type : mac_ 07:19:43 INFO - Initialising browser for dromaeo_css test... 07:19:43 INFO - TEST-INFO | started process 1946 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/tmpWof2ZL/profile http://localhost:51414/getInfo.html) 07:19:46 INFO - PROCESS | 1946 | __metrics Screen width/height:1600/1200 07:19:46 INFO - PROCESS | 1946 | colorDepth:24 07:19:46 INFO - PROCESS | 1946 | Browser inner width/height: 1024/689 07:19:46 INFO - PROCESS | 1946 | __metrics 07:19:46 INFO - PROCESS | 1946 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:19:46 ERROR - PROCESS | 1946 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 07:19:46 INFO - TEST-INFO | 1946: exit 0 07:19:46 INFO - Browser initialized. 07:19:46 INFO - Running cycle 1/1 for dromaeo_css test... 07:19:46 INFO - TEST-INFO | started process 1949 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/tmpWof2ZL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:19:58 INFO - PROCESS | 1949 | RSS: Main: 239157248 07:19:58 INFO - PROCESS | 1949 | 07:20:49 INFO - PROCESS | 1949 | 1464099649629 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 07:23:00 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:51414/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51414/tests/dromaeo/cssquery-ext.html) 07:23:01 INFO - PROCESS | 1949 | RSS: Main: 335671296 07:23:01 INFO - PROCESS | 1949 | 07:26:04 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:51414/tests/dromaeo/cssquery-ext.html (next: http://localhost:51414/tests/dromaeo/cssquery-jquery.html) 07:26:05 INFO - PROCESS | 1949 | RSS: Main: 374583296 07:26:05 INFO - PROCESS | 1949 | 07:29:07 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:51414/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51414/tests/dromaeo/cssquery-mootools.html) 07:29:08 INFO - PROCESS | 1949 | RSS: Main: 366055424 07:29:08 INFO - PROCESS | 1949 | 07:32:10 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:51414/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51414/tests/dromaeo/cssquery-prototype.html) 07:32:10 INFO - PROCESS | 1949 | RSS: Main: 369115136 07:32:10 INFO - PROCESS | 1949 | 07:35:14 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:51414/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51414/tests/dromaeo/cssquery-yui.html) 07:35:14 INFO - PROCESS | 1949 | RSS: Main: 341913600 07:35:14 INFO - PROCESS | 1949 | 07:38:17 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:51414/tests/dromaeo/cssquery-yui.html (next: http://localhost:51414/tests/dromaeo/cssquery-dojo.html) 07:38:17 INFO - PROCESS | 1949 | RSS: Main: 382205952 07:38:17 INFO - PROCESS | 1949 | 07:38:17 INFO - PROCESS | 1949 | __start_tp_report 07:38:17 INFO - PROCESS | 1949 | _x_x_mozilla_page_load 07:38:17 INFO - PROCESS | 1949 | _x_x_mozilla_page_load_details 07:38:17 INFO - PROCESS | 1949 | |i|pagename|runs| 07:38:17 INFO - PROCESS | 1949 | |0;dojo.html;3429.365567370037;3437.3812713711523;3441.5525981622395;3488.3372159289747;3493.056874643846;5487.259220005298;5543.667379957205;5579.609427340083;5606.523445507131;5629.831105066844;5582.49757521823;5660.2075709400715;5660.462256085676;5699.971500142504;5773.220615216955;3969.325214713506;5115.667481613702;5213.4004589472315;5214.504622060898;5237.580993520519;5080.974595127019;5119;5202.401723801754;5231.2153177023565;5269.235960785683;4655.4878963314;5263.236830659571;5269.499397557245;5276.657017293883;5284.762185701653;4259.70182087254;5017.096922553938;5097.745112744348;5176.637635365526;5179.715115668637;5090.363704536933;5163.612729045299;5167.9224811627855;5174.611904107207;5230.529252367305;5529.364123125812;5555.8888822223325;5622.57830662702;5635.098384258499;5646.26598542191;6042.335343112254;6071.572776622972;6207.503399728011;6215.68921553925;6224.906626400608;6107.46191531438;6135.87728245437;6144.463884028993;6172.598781079216;6231.844203894893;5625.6905870177125;5727.112297593838;5797.884042319214;5798.971005144989;5801.419858014249;6129.63387754696;6167.414095660905;6185.350538193515;6200.007998720183;6209.379062093845;6080.042960067982;6143.692815359214;6148.800983808204;6152.077188421773;6161.815145545551;6156.5998210117095;6191.535634827406;6203.472704820141;6220.409061139185;6230.813075607739;5831.562632802472;5869.295684517885;6134.938650613526;6207.441330280297;6217.720202590895;6162.414570615784;6206.131141640174;6209.223847019122;6216.378362163838;6233.189685340877;4953.826616068421;4964.627652925973;4969.652124351261;4978.800847966041;4982.252662100714;4359.80380882861;4362.345648152803;4443.622292105208;4451.376807246988;4466.441694788152;5661.405552417019;5692.544596432275;5693.572982026365;5702.857428564319;5755.539556835443;6165.32181460039;6191.659458729813;6192.845178870474;6193;6222.315545290015;5549.112142057252;5592.608517403824;5604.943950560442;5634.1830434586755;5665.178549110356;5377.8117765878005;5381.461853814587;5388.110961691277;5399.136138217867;5416.891662166813;6282.842928926722;6319.24169099711;6338.429541341301;6350.237971443456;6353.936460635335;6099.085137229451;6112.0171844544475;6137.683438940172;6202.906956395567;6228.22147231596;6326.145970293951;6327.145835312174;6328.525360597881;6337.7781777637565;6339.714712837841;6081.057436097412;6175.4750846176785;6175.722092505758;6197.659128747962;6206.193194884636;5544.861378465571;5767.68277744728;6075.574709770273;6181.412765787243;6213.658248796179;5977.372375900468;6035.064564992434;6046.09308603713;6061.909071364022;6078.179445774764;5904.409559044061;6024.3975602439405;6057.242844644419;6068.4538391544975;6075.969620152047;5723.427657234243;5850.385709500441;5914.34942156371;5942.435468630473;5945.732442040032;6041.577089603686;6066.7269972850445;6067.423594758491;6114.015434403784;6119.539863234955;5945.554083443845;5965.761369545343;5967.910481342697;5992.610480318765;6008.669523176267 07:38:17 INFO - PROCESS | 1949 | |1;ext.html;481.9734914579698;482.5174825174825;492.6896055485044;497.14242930944147;545.6725964421348;41447.585524144786;43944.901377465576;44051.559484405145;44112.11775764487;46445;45513.317300240444;46116.538834611645;46152.76923615386;46194.76902615482;46202.30696539546;8706.434081784695;8904.198622124008;9656.324057315973;9895.802083958353;9900.752481187956;11654.417279136052;11958.820617690742;11968.581099661513;12213.328266945358;12214.083943704214;11819.468123934443;11927.58253461129;12012.519499220065;12022;12030.037596992262;8701.912980870236;11726.824097638542;11961.700957476132;12026.819597706042;12113.15207935445;11946.10404219687;12042.337671428068;12043.217190882564;12059.21615095016;12061.095417843697;34705.82647086774;36083.63916360829;41778.79110604458;42028.159436811395;42203.36694949578;3189.3142974260413;3225.3065590897836;3301.4552598821165;3380.171857894807;3598.298331825295;29155.125346239645;30213.395732085235;30266.54600180999;30408.087757367313;30866.07401777928;2652.350174207312;2711.7288271172924;2713.8619617118034;2737.3840885800537;2790.2466334089886;497451.512742434;497547.9999999964;498838;499225.9999999964;499261;34947;67335.98996015063;67630;67742.66128669423;67964;36229.63770362316;37433.62566374301;37468.437973430424;37471.250574988895;37500;15314.387424502895;15493.14787686688;15705.371785128695;15705.764413533807;15736.213189340488;11937.820932686016;12218.26690398579;12794.936025319814;13050.673733156556;13058.020648451215;7994.400419968526;8010.359171266285;8031.397645176518;8078.555679437687;8081.797955051171;2795.343094372821;3235.7411407087375;3246.2209069823543;3246.4481038223557;3291.967080329166;935.4200395754675;968.1480297338438;977.4917043137528;982.6708052802392;1067.9839802402971;29912.10263692093;29924.952626658396;30548;30595;30767.538486922713;950.5247376311844;995.8157740817971;1015.9847602285971;1040.1678657074463;1067.3649178738792;12863.614091577268;14442.350094245785;15407.921445498932;15505.9224703878;15818.446354377542;685534;690965;695756.1697532042;697439;697850;61748.69125654343;61793.69103154456;63578;64082;64251.03623445652;1280.295837289487;1400.180894176918;1415.1014106042737;1447.6236178593851;1452.644102194969;25707;30308.090757277318;30332.545011824397;30792;30895;54725;55453;55467.999999998385;55483;55680.44319556915;463480;466545;479107;479348;479455;23672.93471793739;26067.608985865605;26343.20970370892;26929;27895;46309.76845115753;47062.764351208556;47363;47364.7631761839;48090;38563;38573.61426385812;38865.805670971466;39481.605183947795;40066;37823.62176378201;37881;38837.80581097076;38972.6102738969;39109.82670519889;38258.42612360761;38887.6111238884;39238.82283531499;39956.00109997273;39963;33231.667683322856;34170.120643364855;34934.475982860786;34998.47502287519;35058.298834022666;19852.90073549623;24052.27843164638;25058.12296569611;25259.49481010406;25841.095561655253 07:38:17 INFO - PROCESS | 1949 | |2;jquery.html;382.779901556605;384.11461581055676;385.1468996173476;399.82207917476734;409.4799604502282;19551.60896782065;20974.580508389823;21215;21251.893740531275;22094.116235350568;20612.072456739446;20842.166313347447;20874.791252087514;21580.676289855666;21706;5675.460831221038;5919.437653422918;6043.637381757108;6055.455009049185;6060.545459090564;4107.424960505532;4358.280883654209;5876.764929402819;5930.288365396159;6305.211848518935;6582.3746744059235;6637.336266373372;6641.203055633307;6642.966785166067;6647.135872336589;5591.217229587861;6455.870882582346;6530.0204969254755;6551.344865513411;6644.734210631569;3664.450332450127;4521.231390663595;4791.4250289965075;5745.396733343023;5759.942400576025;25253.11614093498;25485.490290194277;25837.870810646014;26042.088526901658;26391;2102.2431924507164;2317.188983855637;2365.3731761083327;2404.6393041043807;2544.300317412708;6785.72857085716;7440.069991251094;10232.590696372135;15484.612884677861;15522.922385388001;1705.6588682263598;1740.416960318295;1777.4045694692293;1833.0834582708646;1848.7042073268212;96664.03335966691;114754.27868581978;115718;115791;117609;9714.368566043326;10840.945795270973;11067.50196241171;11077.446127693744;11287.717807054889;13539;13848.238346890816;13924.930375348058;13944.047833303346;13960.650983725489;8526.40315177944;8810.691625793066;8824.117588241124;8956.193942545202;8956.776080597907;8708.78228044304;8813.471191728517;8854.557272136368;8934.910650893538;8950.641974320954;5602.38373778884;6662.96890381322;6745.662716864137;6784.59292442455;6818.761343352959;1695.3303445139102;1764.1708397053364;1850.80566540514;1863.2733234038735;1872.438268519466;919.4758987377131;993.344392700814;996.1283876607968;1041.094248004226;1078.8651418572679;19855.106520206624;20321.695174572393;20862;21005.579888402146;21056.578868422854;967.0571193086824;986.9654562090292;998.5606333213362;1029.3772267778036;1069.9679009629724;8560.61477233526;11076.723081922855;11439;11485.942570287261;11531.71170720722;610518;610609.7347566347;620570;627466;637397;75743;78835;108933.19601235927;113948.86051139548;131859;991.0584944302941;995.785906030207;1013.0318167768362;1034.9637762678421;1036.533559898049;13819.444788022873;18334.808237464524;18336.08319584042;18652.44042678722;18901.243950241835;51749.741251293504;53058.2041269389;53319.46680533145;53457;53724.46275537351;78069.21930780847;78413.43173136619;79503.2049679519;79869.40261194628;81784.18215817766;7057.788266352018;7595.772126836204;7985.560794156145;8040.919590804251;8266.462679925786;11789.941050294694;11857.881421185677;12179.147459677737;12257.14200005991;12373.93813030965;10347.327423717434;10409.95900409953;10572.625558677322;10636.042756151983;10647.787044258917;10072.647457338959;10104.494775261355;10121.645742398981;10401.375917444977;10538.262321637412;9513.23894088485;9774.951125244614;10276.22928280397;10309.175265978854;10533;8016.358691304565;8087.231712987257;8089.271965523131;8135.308498777434;8484.915150848412;9308.767280818034;10531.473426328806;10585.576576936835;10772.24594278393;11006.394648294421 07:38:17 INFO - PROCESS | 1949 | |3;mootools.html;1390.4716207841018;1545.5517899809056;1572.0724772384292;1798.6312805874798;1948.6005368899368;7459.888101678479;8547.829043419128;8756.949166100061;8783.297336213103;8905.064968178345;8512.404131710782;8658.220760131588;8729.519876406795;8744.737657870259;8751.518666473341;2353.5881220786296;2369.893354799038;2390.6533552634855;2392.473655795722;2394.772496612819;2334.2297041976194;2351.400392899817;2372.9881350593223;2380.976190238093;2386.033656369173;2330.3591512334074;2347.7417484076736;2360.1503458754833;2374.169040835711;2378.7859092681656;2289.1415719105335;2349.800266977309;2352.729436114843;2353.411647088228;2361.9881900590385;1975.3481351154085;2295.8737269450176;2325.395397196724;2326.918557850484;2328.7205535335697;8570.142985701443;8678.826423471495;8704.651813927436;8776.385653004292;8856.158664926821;4429.623482004034;5385.380681221632;5391.083515802323;5395;5397.001554712391;3435.278591495789;3510.017195185352;3551.6803487686234;3559.003479025877;3591.5331006969186;1021.5249908792447;1024.6925922223304;1031.1080915008538;1034.4517405774952;1036.1658864613983;11429.542818287342;11593.826092608617;11774;11778.823317650242;11783.116266280063;4455.26488129455;4513.50351461339;4553.59017688403;4633.698809577367;4643.512431194744;7914.525128492309;9022.548872556346;9110.453372797654;9239.30705197113;9253.398529095588;5181.766820493087;5212.713300768418;5217.660852044605;5219.921701174486;5223.373195216598;3681.9263614727556;4497.707649002804;4699.530046995274;4706.788194531255;4858.46556878743;3744.794036328028;3787.659110680052;3846.1346197105427;3874.6900247980093;3893.980530097388;1142.2232881640398;1161.6398916335963;1165.8426112474876;1174.653477224221;1175.7060734816296;605.742559412248;794.6265255329986;882.7263548300048;916.1891725822695;954.5752140297501;5538.944610553924;5790.7973220937065;5889;5904.350521442638;5910.793122240787;927.4342650982895;932.5756780664917;942.6088173408097;944.8110377924443;986.7335819328885;3838.865639702597;3909.5504017038406;3993.660538854172;4004.0390306326854;4024.5170579530645;10138.290319677699;10598.417087060287;10774.838377424345;10779.568817247378;10809.729756755987;10207.897921020844;10616.416097114732;10772.515236814363;10775.407352595526;10784.029437350677;8079.192080791991;8236.588170591447;8244.670213191524;8287.171282871785;8297.128801475757;5044.218146187347;5059.443461219189;5795.913061304;5843.357230704534;5925.200097986716;9504.47725375111;9705.417674939526;10095.293329466867;10101.343412678152;10140;10056.69829905104;10151.898481015096;10250.948745256226;10341.689749307532;10372.636957706443;5909.438603332676;6824.283450237852;7078.823029424306;7086.610236437045;7091.184513780775;8149.266566009088;8191.344692424473;8207.876881846658;8284.834303313779;8304.418690691593;7320.743773967885;7429.4799364044;7470.738524151629;7635.88546171819;7645.082590089002;7184.856302874018;7252.492325537372;7290.380317673057;7424.554526728413;7525.360344370571;6889.517733758589;6936.410405115622;6956.2000369958105;7031.859362812613;7031.964840175767;4648.465426475863;4878.317035614946;4896.4124305083615;4902.436219834764;4941.9621879404285;4845.176320025534;4911.066897289504;4917.950820491892;4985.102681517362;5162.922556161586 07:38:17 INFO - PROCESS | 1949 | |4;prototype.html;305.9372828570143;310.76226686584766;314.49209526614527;318.5906110647818;319.77935224694943;4855.635827312957;5303.469653034699;5338.813141540047;5344.331958505187;5357.705326207057;5187.844364669066;5266.736663166836;5267.920076384342;5295.735213239333;5313.468653134694;3547.219611685438;3827.9043023924414;3854.2291541691634;3964.2467931093;3964.612385665023;3418.3676019936265;3955.9604403956023;4032.6169013943627;4056.6146216109423;4062.53280872699;3990.38149086892;3996.520417549898;4017.9754162688473;4018.5332353690837;4027.8791636250958;3731.589525152231;3779.9622003780023;3944.7633142011573;4009.0779120802085;4043.898902527431;2943.45546073977;3440.1915549845767;3471.9826400868087;3486.6339034401276;3489.4940233665975;4965.634450526098;4974.726390048546;4976.253561965734;4980.9252861207115;5027.988804478201;4158.792060396968;4191.952011997001;4483.730976141442;4538.795754191036;4575.748333841638;4203.012292111382;4889.848466674652;4934.728589927553;5381.112116500773;5521.377345760771;3221.822799746013;3529.3294274087843;3749.2314075614704;3785.4889589905288;3796.4115562087923;4755.762211889426;5301.840944771701;5837.678927658977;5925.555583331267;5941.851453713649;3902.4731661225937;3965.8413663453716;3974.7813870237414;3979.7413168143976;3995.300822356076;3897.376419772823;4045.806487086317;4064.3700226464944;4070.2673518766906;4071.959280407158;4001.8399264029113;4647.95352046482;4648.187206989277;4659.184642687584;4695.694779839299;4729.1014707206205;5185.447968141835;5382.434844341366;5614.775408983595;5702.258706368229;1356.9389377478037;1392.7910813378073;1393.7142885708406;1408.936597853099;1415.2145559214734;830.9542975136426;864.5979619476973;875.6366108065209;880.5778667452147;880.665347168072;4654.953450465519;4916.950830491649;5033.7734801934;5043.4956504349275;5152.613553983466;4483.730976141442;4907.190313598217;5109.182530795055;5483.396826349099;5660.122680984454;4323.437953066082;4585.518520555293;4728.71627702339;5139.12634852076;5152.819651312261;2524.406764410362;2710.6717708323176;2759;2772.6811416686937;3033.833139177322;5599.744194412276;5898.793542226087;5905.114232865105;5924.348321684612;5926.7925622603;4423.447069116361;5511.807086751945;5720.713964301768;5828.242328497268;5832.533397328203;5644.181593668895;5829.7376618052285;5875.970620146872;5925.762969481259;5933.139694744238;5236.6072544560075;5561.860953476195;5613.214150018919;5647.632903861236;5722.570807189394;4489.9775501122285;4578.359029735773;5531.640443371168;5546.61173717852;5591.888162236651;4537.523560026149;5608.887067677479;5830.484074140839;5922.940770592239;5950.375210602824;3878.7672739635714;3903.765774053566;3907.6092390760696;3916.412538119305;3919.529656441245;4533.841315554072;4685.8594242172785;4833.371661683958;5642.492175704207;5646.407127251579;4630.814767409401;5033.370828646419;5413.431589683027;5430.185472179205;5487.4238204987905;4210.621044106045;4996.326230712752;5273.782992374572;5503.752331145188;5528.364238112668;4365.367021781761;5346.85042715818;5373.382061062872;5390.218418329242;5396.541293989898;4942.416747857775;5200.4279529251;5343.385510666323;5369;5512.6968016759465;4787.880302992453;5361.597880158925;5397.406285308534;5398.892022159614;5411.945880541301 07:38:17 INFO - PROCESS | 1949 | |5;yui.html;640.9807705768826;699.8740226759181;713.436385255648;723.2622724820679;729.6935287179384;14760.409583616656;15001.39994400224;15244.542663720078;15244.695106097872;15248.618784530392;14112.364943577539;14169.220692861889;15114.093154410742;15769.053856768602;15789.921050394762;886.9068747781487;887.7292425810125;902.5803001604253;907.2696479334134;918.6646873891015;717.5264325545141;740.8147963009247;879.5162660536711;904.8959369672473;909.7043460875209;932.5989824375516;935.7473482159813;958.7219706285169;958.9520523973808;965.2037069417723;940.3511577011908;960.4093482508242;960.9711708648751;961.125375907924;961.6441916490874;550.8980838544842;676.5230512488683;680.5406350713249;703.1667474043275;704.32384910486;14965.925170374187;15148.848511514854;15176.317065732068;15191.924040379727;15219.61950951224;11882;11928.880711192951;12027.398630068461;12149.453274602663;12182.817257741051;10760.78478430435;10786.298890572089;11302.378369189692;11381.658550243506;11579.768404631945;3935.724499285051;4097.221527909687;4102.835886564534;4108.36320370343;4227.809748561292;71209.75149574231;73027.63486182535;73327.44502163201;73378.53242935111;73558.63220683862;9031.867548485881;9103.089691030977;9202.217811486098;9222.677206297747;9298.488583127993;20018.59962800765;20792.27227047046;20890.373288801362;21220.469488262916;21235.78764212369;7152.499325047301;7182.317679820513;7363.005994190823;7393.741219057307;7441.776746697607;3763.397856343027;3848.692104631623;3868.439076333972;3895.474110995036;3912.491376121143;3286.7206287465356;3395.2530443302435;3410.113370523632;3415.79505229687;3420.0423881313272;609.2658346692225;619.8563650065622;621.018790311307;631.3623240527008;643.433778275115;543.4348277791053;558.801625422969;562.2690502346933;573.3240914942106;596.150485558078;7849.843003139905;7980.640871160812;7989.44073914832;8014.438989270695;8211.36628066655;548.4953842464942;563.3408911573468;575.4533193466224;579.5334755521845;595.919550860637;3647.927041459156;3731.850725970931;3758.4362345648374;3808.2002779416634;3842.51968503937;84593;85270;85690;86489;88483.1151688475;79795.60102199568;81031.18968810237;81200.59399702963;81626;81669.59165204136;71304.64347678228;71722.64138679378;72844.63577682078;73366.26633733702;74002;7204.747833826001;7562.697492100255;7739.18738532446;7756.185600512091;8125.268725814705;38968;42358;44233.778831105636;44358;45811.083778323584;62281.37718622937;62409.68795155995;62911.685441572496;63627.6818615904;64138.67930660317;9263.953680231556;10890.618828341287;10987.121030317716;11060.004599586076;11106.944465277622;11915.82126268124;12029.75940481203;12157.392130393622;12171.695707607381;12549.87450125487;11152.163587731115;11188.944055279997;11275.379854108103;11456.484458199568;11980.46087926028;11037.889621103686;11223.382713950485;11452.942735286604;11561.88438115608;11618.979065442876;11003.61487347939;11090.722731931766;11223.55105795759;11302.378369189773;11573.305601663928;9131.406458580172;9419.952900235456;9496;9535.284853636143;9553.283503737386;8873.600687968928;9309.581068851783;9425.293103017439;9456.19622332082;9534.761630959281 07:38:17 INFO - PROCESS | 1949 | __end_tp_report 07:38:17 INFO - PROCESS | 1949 | __start_cc_report 07:38:17 INFO - PROCESS | 1949 | _x_x_mozilla_cycle_collect,1298 07:38:17 INFO - PROCESS | 1949 | __end_cc_report 07:38:17 INFO - PROCESS | 1949 | __startTimestamp1464100697894__endTimestamp 07:38:17 INFO - PROCESS | 1949 | 07:38:17 INFO - PROCESS | 1949 | ------- Summary: start ------- 07:38:17 INFO - PROCESS | 1949 | Number of tests: 6 07:38:17 INFO - PROCESS | 1949 | 07:38:17 INFO - PROCESS | 1949 | [#0] dojo.html Cycles:165 Average:5689.39 Median:5914.35 stddev:638.46 (10.8%) stddev-sans-first:615.31 07:38:17 INFO - PROCESS | 1949 | Values: 3429.4 3437.4 3441.6 3488.3 3493.1 5487.3 5543.7 5579.6 5606.5 5629.8 5582.5 5660.2 5660.5 5700.0 5773.2 3969.3 5115.7 5213.4 5214.5 5237.6 5081.0 5119.0 5202.4 5231.2 5269.2 4655.5 5263.2 5269.5 5276.7 5284.8 4259.7 5017.1 5097.7 5176.6 5179.7 5090.4 5163.6 5167.9 5174.6 5230.5 5529.4 5555.9 5622.6 5635.1 5646.3 6042.3 6071.6 6207.5 6215.7 6224.9 6107.5 6135.9 6144.5 6172.6 6231.8 5625.7 5727.1 5797.9 5799.0 5801.4 6129.6 6167.4 6185.4 6200.0 6209.4 6080.0 6143.7 6148.8 6152.1 6161.8 6156.6 6191.5 6203.5 6220.4 6230.8 5831.6 5869.3 6134.9 6207.4 6217.7 6162.4 6206.1 6209.2 6216.4 6233.2 4953.8 4964.6 4969.7 4978.8 4982.3 4359.8 4362.3 4443.6 4451.4 4466.4 5661.4 5692.5 5693.6 5702.9 5755.5 6165.3 6191.7 6192.8 6193.0 6222.3 5549.1 5592.6 5604.9 5634.2 5665.2 5377.8 5381.5 5388.1 5399.1 5416.9 6282.8 6319.2 6338.4 6350.2 6353.9 6099.1 6112.0 6137.7 6202.9 6228.2 6326.1 6327.1 6328.5 6337.8 6339.7 6081.1 6175.5 6175.7 6197.7 6206.2 5544.9 5767.7 6075.6 6181.4 6213.7 5977.4 6035.1 6046.1 6061.9 6078.2 5904.4 6024.4 6057.2 6068.5 6076.0 5723.4 5850.4 5914.3 5942.4 5945.7 6041.6 6066.7 6067.4 6114.0 6119.5 5945.6 5965.8 5967.9 5992.6 6008.7 07:38:17 INFO - PROCESS | 1949 | 07:38:17 INFO - PROCESS | 1949 | [#1] ext.html Cycles:180 Average:68655.14 Median:36156.64 stddev:150917.03 (417.4%) stddev-sans-first:151253.59 07:38:17 INFO - PROCESS | 1949 | Values: 482.0 482.5 492.7 497.1 545.7 41447.6 43944.9 44051.6 44112.1 46445.0 45513.3 46116.5 46152.8 46194.8 46202.3 8706.4 8904.2 9656.3 9895.8 9900.8 11654.4 11958.8 11968.6 12213.3 12214.1 11819.5 11927.6 12012.5 12022.0 12030.0 8701.9 11726.8 11961.7 12026.8 12113.2 11946.1 12042.3 12043.2 12059.2 12061.1 34705.8 36083.6 41778.8 42028.2 42203.4 3189.3 3225.3 3301.5 3380.2 3598.3 29155.1 30213.4 30266.5 30408.1 30866.1 2652.4 2711.7 2713.9 2737.4 2790.2 497451.5 497548.0 498838.0 499226.0 499261.0 34947.0 67336.0 67630.0 67742.7 67964.0 36229.6 37433.6 37468.4 37471.3 37500.0 15314.4 15493.1 15705.4 15705.8 15736.2 11937.8 12218.3 12794.9 13050.7 13058.0 7994.4 8010.4 8031.4 8078.6 8081.8 2795.3 3235.7 3246.2 3246.4 3292.0 935.4 968.1 977.5 982.7 1068.0 29912.1 29925.0 30548.0 30595.0 30767.5 950.5 995.8 1016.0 1040.2 1067.4 12863.6 14442.4 15407.9 15505.9 15818.4 685534.0 690965.0 695756.2 697439.0 697850.0 61748.7 61793.7 63578.0 64082.0 64251.0 1280.3 1400.2 1415.1 1447.6 1452.6 25707.0 30308.1 30332.5 30792.0 30895.0 54725.0 55453.0 55468.0 55483.0 55680.4 463480.0 466545.0 479107.0 479348.0 479455.0 23672.9 26067.6 26343.2 26929.0 27895.0 46309.8 47062.8 47363.0 47364.8 48090.0 38563.0 38573.6 38865.8 39481.6 40066.0 37823.6 37881.0 38837.8 38972.6 39109.8 38258.4 38887.6 39238.8 39956.0 39963.0 33231.7 34170.1 34934.5 34998.5 35058.3 19852.9 24052.3 25058.1 25259.5 25841.1 07:38:17 INFO - PROCESS | 1949 | 07:38:17 INFO - PROCESS | 1949 | [#2] jquery.html Cycles:180 Average:34795.31 Median:26216.54 stddev:102905.28 (392.5%) stddev-sans-first:103161.52 07:38:17 INFO - PROCESS | 1949 | Values: 382.8 384.1 385.1 399.8 409.5 19551.6 20974.6 21215.0 21251.9 22094.1 20612.1 20842.2 20874.8 21580.7 21706.0 5675.5 5919.4 6043.6 6055.5 6060.5 4107.4 4358.3 5876.8 5930.3 6305.2 6582.4 6637.3 6641.2 6643.0 6647.1 5591.2 6455.9 6530.0 6551.3 6644.7 3664.5 4521.2 4791.4 5745.4 5759.9 25253.1 25485.5 25837.9 26042.1 26391.0 2102.2 2317.2 2365.4 2404.6 2544.3 6785.7 7440.1 10232.6 15484.6 15522.9 1705.7 1740.4 1777.4 1833.1 1848.7 96664.0 114754.3 115718.0 115791.0 117609.0 9714.4 10840.9 11067.5 11077.4 11287.7 13539.0 13848.2 13924.9 13944.0 13960.7 8526.4 8810.7 8824.1 8956.2 8956.8 8708.8 8813.5 8854.6 8934.9 8950.6 5602.4 6663.0 6745.7 6784.6 6818.8 1695.3 1764.2 1850.8 1863.3 1872.4 919.5 993.3 996.1 1041.1 1078.9 19855.1 20321.7 20862.0 21005.6 21056.6 967.1 987.0 998.6 1029.4 1070.0 8560.6 11076.7 11439.0 11485.9 11531.7 610518.0 610609.7 620570.0 627466.0 637397.0 75743.0 78835.0 108933.2 113948.9 131859.0 991.1 995.8 1013.0 1035.0 1036.5 13819.4 18334.8 18336.1 18652.4 18901.2 51749.7 53058.2 53319.5 53457.0 53724.5 78069.2 78413.4 79503.2 79869.4 81784.2 7057.8 7595.8 7985.6 8040.9 8266.5 11789.9 11857.9 12179.1 12257.1 12373.9 10347.3 10410.0 10572.6 10636.0 10647.8 10072.6 10104.5 10121.6 10401.4 10538.3 9513.2 9775.0 10276.2 10309.2 10533.0 8016.4 8087.2 8089.3 8135.3 8484.9 9308.8 10531.5 10585.6 10772.2 11006.4 07:38:17 INFO - PROCESS | 1949 | 07:38:17 INFO - PROCESS | 1949 | [#3] mootools.html Cycles:180 Average:5615.86 Median:4868.39 stddev:3165.82 (65.0%) stddev-sans-first:3158.78 07:38:17 INFO - PROCESS | 1949 | Values: 1390.5 1545.6 1572.1 1798.6 1948.6 7459.9 8547.8 8756.9 8783.3 8905.1 8512.4 8658.2 8729.5 8744.7 8751.5 2353.6 2369.9 2390.7 2392.5 2394.8 2334.2 2351.4 2373.0 2381.0 2386.0 2330.4 2347.7 2360.2 2374.2 2378.8 2289.1 2349.8 2352.7 2353.4 2362.0 1975.3 2295.9 2325.4 2326.9 2328.7 8570.1 8678.8 8704.7 8776.4 8856.2 4429.6 5385.4 5391.1 5395.0 5397.0 3435.3 3510.0 3551.7 3559.0 3591.5 1021.5 1024.7 1031.1 1034.5 1036.2 11429.5 11593.8 11774.0 11778.8 11783.1 4455.3 4513.5 4553.6 4633.7 4643.5 7914.5 9022.5 9110.5 9239.3 9253.4 5181.8 5212.7 5217.7 5219.9 5223.4 3681.9 4497.7 4699.5 4706.8 4858.5 3744.8 3787.7 3846.1 3874.7 3894.0 1142.2 1161.6 1165.8 1174.7 1175.7 605.7 794.6 882.7 916.2 954.6 5538.9 5790.8 5889.0 5904.4 5910.8 927.4 932.6 942.6 944.8 986.7 3838.9 3909.6 3993.7 4004.0 4024.5 10138.3 10598.4 10774.8 10779.6 10809.7 10207.9 10616.4 10772.5 10775.4 10784.0 8079.2 8236.6 8244.7 8287.2 8297.1 5044.2 5059.4 5795.9 5843.4 5925.2 9504.5 9705.4 10095.3 10101.3 10140.0 10056.7 10151.9 10250.9 10341.7 10372.6 5909.4 6824.3 7078.8 7086.6 7091.2 8149.3 8191.3 8207.9 8284.8 8304.4 7320.7 7429.5 7470.7 7635.9 7645.1 7184.9 7252.5 7290.4 7424.6 7525.4 6889.5 6936.4 6956.2 7031.9 7032.0 4648.5 4878.3 4896.4 4902.4 4942.0 4845.2 4911.1 4918.0 4985.1 5162.9 07:38:17 INFO - PROCESS | 1949 | 07:38:17 INFO - PROCESS | 1949 | [#4] prototype.html Cycles:180 Average:4431.46 Median:4898.52 stddev:1343.71 (27.4%) stddev-sans-first:1311.31 07:38:17 INFO - PROCESS | 1949 | Values: 305.9 310.8 314.5 318.6 319.8 4855.6 5303.5 5338.8 5344.3 5357.7 5187.8 5266.7 5267.9 5295.7 5313.5 3547.2 3827.9 3854.2 3964.2 3964.6 3418.4 3956.0 4032.6 4056.6 4062.5 3990.4 3996.5 4018.0 4018.5 4027.9 3731.6 3780.0 3944.8 4009.1 4043.9 2943.5 3440.2 3472.0 3486.6 3489.5 4965.6 4974.7 4976.3 4980.9 5028.0 4158.8 4192.0 4483.7 4538.8 4575.7 4203.0 4889.8 4934.7 5381.1 5521.4 3221.8 3529.3 3749.2 3785.5 3796.4 4755.8 5301.8 5837.7 5925.6 5941.9 3902.5 3965.8 3974.8 3979.7 3995.3 3897.4 4045.8 4064.4 4070.3 4072.0 4001.8 4648.0 4648.2 4659.2 4695.7 4729.1 5185.4 5382.4 5614.8 5702.3 1356.9 1392.8 1393.7 1408.9 1415.2 831.0 864.6 875.6 880.6 880.7 4655.0 4917.0 5033.8 5043.5 5152.6 4483.7 4907.2 5109.2 5483.4 5660.1 4323.4 4585.5 4728.7 5139.1 5152.8 2524.4 2710.7 2759.0 2772.7 3033.8 5599.7 5898.8 5905.1 5924.3 5926.8 4423.4 5511.8 5720.7 5828.2 5832.5 5644.2 5829.7 5876.0 5925.8 5933.1 5236.6 5561.9 5613.2 5647.6 5722.6 4490.0 4578.4 5531.6 5546.6 5591.9 4537.5 5608.9 5830.5 5922.9 5950.4 3878.8 3903.8 3907.6 3916.4 3919.5 4533.8 4685.9 4833.4 5642.5 5646.4 4630.8 5033.4 5413.4 5430.2 5487.4 4210.6 4996.3 5273.8 5503.8 5528.4 4365.4 5346.9 5373.4 5390.2 5396.5 4942.4 5200.4 5343.4 5369.0 5512.7 4787.9 5361.6 5397.4 5398.9 5411.9 07:38:17 INFO - PROCESS | 1949 | 07:38:17 INFO - PROCESS | 1949 | [#5] yui.html Cycles:180 Average:17717.41 Median:614.56 stddev:24597.75 (4002.5%) stddev-sans-first:24633.33 07:38:17 INFO - PROCESS | 1949 | Values: 641.0 699.9 713.4 723.3 729.7 14760.4 15001.4 15244.5 15244.7 15248.6 14112.4 14169.2 15114.1 15769.1 15789.9 886.9 887.7 902.6 907.3 918.7 717.5 740.8 879.5 904.9 909.7 932.6 935.7 958.7 959.0 965.2 940.4 960.4 961.0 961.1 961.6 550.9 676.5 680.5 703.2 704.3 14965.9 15148.8 15176.3 15191.9 15219.6 11882.0 11928.9 12027.4 12149.5 12182.8 10760.8 10786.3 11302.4 11381.7 11579.8 3935.7 4097.2 4102.8 4108.4 4227.8 71209.8 73027.6 73327.4 73378.5 73558.6 9031.9 9103.1 9202.2 9222.7 9298.5 20018.6 20792.3 20890.4 21220.5 21235.8 7152.5 7182.3 7363.0 7393.7 7441.8 3763.4 3848.7 3868.4 3895.5 3912.5 3286.7 3395.3 3410.1 3415.8 3420.0 609.3 619.9 621.0 631.4 643.4 543.4 558.8 562.3 573.3 596.2 7849.8 7980.6 7989.4 8014.4 8211.4 548.5 563.3 575.5 579.5 595.9 3647.9 3731.9 3758.4 3808.2 3842.5 84593.0 85270.0 85690.0 86489.0 88483.1 79795.6 81031.2 81200.6 81626.0 81669.6 71304.6 71722.6 72844.6 73366.3 74002.0 7204.7 7562.7 7739.2 7756.2 8125.3 38968.0 42358.0 44233.8 44358.0 45811.1 62281.4 62409.7 62911.7 63627.7 64138.7 9264.0 10890.6 10987.1 11060.0 11106.9 11915.8 12029.8 12157.4 12171.7 12549.9 11152.2 11188.9 11275.4 11456.5 11980.5 11037.9 11223.4 11452.9 11561.9 11619.0 11003.6 11090.7 11223.6 11302.4 11573.3 9131.4 9420.0 9496.0 9535.3 9553.3 8873.6 9309.6 9425.3 9456.2 9534.8 07:38:17 INFO - PROCESS | 1949 | -------- Summary: end -------- 07:38:17 INFO - PROCESS | 1949 | 07:38:18 INFO - TEST-INFO | 1949: exit 0 07:38:18 INFO - TEST-OK | dromaeo_css | took 1114611ms 07:38:18 INFO - TEST-START | kraken 07:38:18 INFO - operating with platform_type : mac_ 07:38:18 INFO - Initialising browser for kraken test... 07:38:18 INFO - TEST-INFO | started process 1952 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/tmpET7ko8/profile http://localhost:51414/getInfo.html) 07:38:20 INFO - PROCESS | 1952 | __metrics Screen width/height:1600/1200 07:38:20 INFO - PROCESS | 1952 | colorDepth:24 07:38:20 INFO - PROCESS | 1952 | Browser inner width/height: 1024/689 07:38:20 INFO - PROCESS | 1952 | __metrics 07:38:21 INFO - PROCESS | 1952 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:38:21 ERROR - PROCESS | 1952 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 07:38:21 INFO - TEST-INFO | 1952: exit 0 07:38:21 INFO - Browser initialized. 07:38:21 INFO - Running cycle 1/1 for kraken test... 07:38:21 INFO - TEST-INFO | started process 1954 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/tmpET7ko8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:38:33 INFO - PROCESS | 1954 | RSS: Main: 239169536 07:38:33 INFO - PROCESS | 1954 | 07:38:36 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:51414/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51414/tests/kraken/driver.html?testName=audio-beat-detection) 07:38:37 INFO - PROCESS | 1954 | RSS: Main: 286822400 07:38:37 INFO - PROCESS | 1954 | 07:38:40 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:51414/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51414/tests/kraken/driver.html?testName=audio-dft) 07:38:41 INFO - PROCESS | 1954 | RSS: Main: 296022016 07:38:41 INFO - PROCESS | 1954 | 07:38:44 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:51414/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51414/tests/kraken/driver.html?testName=audio-fft) 07:38:45 INFO - PROCESS | 1954 | RSS: Main: 385634304 07:38:45 INFO - PROCESS | 1954 | 07:38:47 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:51414/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51414/tests/kraken/driver.html?testName=audio-oscillator) 07:38:48 INFO - PROCESS | 1954 | RSS: Main: 284164096 07:38:48 INFO - PROCESS | 1954 | 07:38:50 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:51414/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51414/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:38:50 INFO - PROCESS | 1954 | RSS: Main: 288694272 07:38:50 INFO - PROCESS | 1954 | 07:38:53 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:51414/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51414/tests/kraken/driver.html?testName=imaging-darkroom) 07:38:54 INFO - PROCESS | 1954 | RSS: Main: 328146944 07:38:54 INFO - PROCESS | 1954 | 07:38:57 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:51414/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51414/tests/kraken/driver.html?testName=imaging-desaturate) 07:38:58 INFO - PROCESS | 1954 | RSS: Main: 330240000 07:38:58 INFO - PROCESS | 1954 | 07:39:01 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:51414/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51414/tests/kraken/driver.html?testName=json-parse-financial) 07:39:02 INFO - PROCESS | 1954 | RSS: Main: 327319552 07:39:02 INFO - PROCESS | 1954 | 07:39:03 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:51414/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51414/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:39:04 INFO - PROCESS | 1954 | RSS: Main: 316399616 07:39:04 INFO - PROCESS | 1954 | 07:39:06 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:51414/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51414/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:39:07 INFO - PROCESS | 1954 | RSS: Main: 323620864 07:39:07 INFO - PROCESS | 1954 | 07:39:09 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:51414/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51414/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:39:10 INFO - PROCESS | 1954 | RSS: Main: 326344704 07:39:10 INFO - PROCESS | 1954 | 07:39:13 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:51414/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51414/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:39:13 INFO - PROCESS | 1954 | RSS: Main: 330731520 07:39:13 INFO - PROCESS | 1954 | 07:39:16 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:51414/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51414/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:39:17 INFO - PROCESS | 1954 | RSS: Main: 328634368 07:39:17 INFO - PROCESS | 1954 | 07:39:19 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:51414/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51414/tests/kraken/driver.html?testName=ai-astar) 07:39:19 INFO - PROCESS | 1954 | RSS: Main: 375963648 07:39:19 INFO - PROCESS | 1954 | 07:39:19 INFO - PROCESS | 1954 | __start_tp_report 07:39:19 INFO - PROCESS | 1954 | _x_x_mozilla_page_load 07:39:19 INFO - PROCESS | 1954 | _x_x_mozilla_page_load_details 07:39:19 INFO - PROCESS | 1954 | |i|pagename|runs| 07:39:19 INFO - PROCESS | 1954 | |0;ai-astar;115;148;163;112;113;112;112;113;111;112 07:39:19 INFO - PROCESS | 1954 | |1;audio-beat-detection;157;124;118;203;119;120;140;118;153;118 07:39:19 INFO - PROCESS | 1954 | |2;audio-dft;178;188;183;185;183;186;184;184;182;184 07:39:19 INFO - PROCESS | 1954 | |3;audio-fft;80;57;59;119;57;57;63;62;82;56 07:39:19 INFO - PROCESS | 1954 | |4;audio-oscillator;97;78;140;80;92;77;104;79;78;110 07:39:19 INFO - PROCESS | 1954 | |5;imaging-gaussian-blur;89;87;89;88;90;87;90;87;90;86 07:39:19 INFO - PROCESS | 1954 | |6;imaging-darkroom;95;95;96;95;95;95;96;96;95;100 07:39:19 INFO - PROCESS | 1954 | |7;imaging-desaturate;88;108;129;140;86;91;86;85;84;86 07:39:19 INFO - PROCESS | 1954 | |8;json-parse-financial;57;57;58;55;55;57;56;120;55;55 07:39:19 INFO - PROCESS | 1954 | |9;json-stringify-tinderbox;65;69;78;76;73;69;69;71;70;71 07:39:19 INFO - PROCESS | 1954 | |10;stanford-crypto-aes;85;84;85;83;84;84;74;101;75;85 07:39:19 INFO - PROCESS | 1954 | |11;stanford-crypto-ccm;142;140;153;133;138;141;132;136;241;147 07:39:19 INFO - PROCESS | 1954 | |12;stanford-crypto-pbkdf2;170;219;192;178;222;213;166;167;164;162 07:39:19 INFO - PROCESS | 1954 | |13;stanford-crypto-sha256-iterative;68;64;67;70;81;82;83;79;66;65 07:39:19 INFO - PROCESS | 1954 | __end_tp_report 07:39:19 INFO - PROCESS | 1954 | __start_cc_report 07:39:19 INFO - PROCESS | 1954 | _x_x_mozilla_cycle_collect,2327 07:39:19 INFO - PROCESS | 1954 | __end_cc_report 07:39:19 INFO - PROCESS | 1954 | __startTimestamp1464100759560__endTimestamp 07:39:19 INFO - PROCESS | 1954 | 07:39:19 INFO - PROCESS | 1954 | ------- Summary: start ------- 07:39:19 INFO - PROCESS | 1954 | Number of tests: 14 07:39:19 INFO - PROCESS | 1954 | 07:39:19 INFO - PROCESS | 1954 | [#0] ai-astar Cycles:10 Average:121.10 Median:113.00 stddev:18.50 (16.4%) stddev-sans-first:19.49 07:39:19 INFO - PROCESS | 1954 | Values: 115.0 148.0 163.0 112.0 113.0 112.0 112.0 113.0 111.0 112.0 07:39:19 INFO - PROCESS | 1954 | 07:39:19 INFO - PROCESS | 1954 | [#1] audio-beat-detection Cycles:10 Average:137.00 Median:132.00 stddev:27.66 (21.0%) stddev-sans-first:28.38 07:39:19 INFO - PROCESS | 1954 | Values: 157.0 124.0 118.0 203.0 119.0 120.0 140.0 118.0 153.0 118.0 07:39:19 INFO - PROCESS | 1954 | 07:39:19 INFO - PROCESS | 1954 | [#2] audio-dft Cycles:10 Average:183.70 Median:184.00 stddev:2.63 (1.4%) stddev-sans-first:1.80 07:39:19 INFO - PROCESS | 1954 | Values: 178.0 188.0 183.0 185.0 183.0 186.0 184.0 184.0 182.0 184.0 07:39:19 INFO - PROCESS | 1954 | 07:39:19 INFO - PROCESS | 1954 | [#3] audio-fft Cycles:10 Average:69.20 Median:60.50 stddev:19.93 (32.9%) stddev-sans-first:20.75 07:39:19 INFO - PROCESS | 1954 | Values: 80.0 57.0 59.0 119.0 57.0 57.0 63.0 62.0 82.0 56.0 07:39:19 INFO - PROCESS | 1954 | 07:39:19 INFO - PROCESS | 1954 | [#4] audio-oscillator Cycles:10 Average:93.50 Median:78.50 stddev:20.29 (25.8%) stddev-sans-first:21.48 07:39:19 INFO - PROCESS | 1954 | Values: 97.0 78.0 140.0 80.0 92.0 77.0 104.0 79.0 78.0 110.0 07:39:19 INFO - PROCESS | 1954 | 07:39:19 INFO - PROCESS | 1954 | [#5] imaging-gaussian-blur Cycles:10 Average:88.30 Median:89.00 stddev:1.49 (1.7%) stddev-sans-first:1.56 07:39:19 INFO - PROCESS | 1954 | Values: 89.0 87.0 89.0 88.0 90.0 87.0 90.0 87.0 90.0 86.0 07:39:19 INFO - PROCESS | 1954 | 07:39:19 INFO - PROCESS | 1954 | [#6] imaging-darkroom Cycles:10 Average:95.80 Median:95.00 stddev:1.55 (1.6%) stddev-sans-first:1.62 07:39:19 INFO - PROCESS | 1954 | Values: 95.0 95.0 96.0 95.0 95.0 95.0 96.0 96.0 95.0 100.0 07:39:19 INFO - PROCESS | 1954 | 07:39:19 INFO - PROCESS | 1954 | [#7] imaging-desaturate Cycles:10 Average:98.30 Median:86.00 stddev:20.47 (23.8%) stddev-sans-first:21.37 07:39:19 INFO - PROCESS | 1954 | Values: 88.0 108.0 129.0 140.0 86.0 91.0 86.0 85.0 84.0 86.0 07:39:19 INFO - PROCESS | 1954 | 07:39:19 INFO - PROCESS | 1954 | [#8] json-parse-financial Cycles:10 Average:62.50 Median:56.50 stddev:20.23 (35.8%) stddev-sans-first:21.36 07:39:19 INFO - PROCESS | 1954 | Values: 57.0 57.0 58.0 55.0 55.0 57.0 56.0 120.0 55.0 55.0 07:39:19 INFO - PROCESS | 1954 | 07:39:19 INFO - PROCESS | 1954 | [#9] json-stringify-tinderbox Cycles:10 Average:71.10 Median:71.00 stddev:3.75 (5.3%) stddev-sans-first:3.27 07:39:19 INFO - PROCESS | 1954 | Values: 65.0 69.0 78.0 76.0 73.0 69.0 69.0 71.0 70.0 71.0 07:39:19 INFO - PROCESS | 1954 | 07:39:19 INFO - PROCESS | 1954 | [#10] stanford-crypto-aes Cycles:10 Average:84.00 Median:84.00 stddev:7.26 (8.6%) stddev-sans-first:7.69 07:39:19 INFO - PROCESS | 1954 | Values: 85.0 84.0 85.0 83.0 84.0 84.0 74.0 101.0 75.0 85.0 07:39:19 INFO - PROCESS | 1954 | 07:39:19 INFO - PROCESS | 1954 | [#11] stanford-crypto-ccm Cycles:10 Average:150.30 Median:141.50 stddev:32.48 (23.0%) stddev-sans-first:34.31 07:39:19 INFO - PROCESS | 1954 | Values: 142.0 140.0 153.0 133.0 138.0 141.0 132.0 136.0 241.0 147.0 07:39:19 INFO - PROCESS | 1954 | 07:39:19 INFO - PROCESS | 1954 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:185.30 Median:185.00 stddev:24.24 (13.1%) stddev-sans-first:25.06 07:39:19 INFO - PROCESS | 1954 | Values: 170.0 219.0 192.0 178.0 222.0 213.0 166.0 167.0 164.0 162.0 07:39:19 INFO - PROCESS | 1954 | 07:39:19 INFO - PROCESS | 1954 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.50 Median:74.50 stddev:7.76 (10.4%) stddev-sans-first:8.06 07:39:19 INFO - PROCESS | 1954 | Values: 68.0 64.0 67.0 70.0 81.0 82.0 83.0 79.0 66.0 65.0 07:39:19 INFO - PROCESS | 1954 | -------- Summary: end -------- 07:39:19 INFO - PROCESS | 1954 | 07:39:20 INFO - TEST-INFO | 1954: exit 0 07:39:20 INFO - TEST-OK | kraken | took 61937ms 07:39:20 INFO - SUITE-END | took 1176s 07:39:20 INFO - Completed test suite (00:19:36) 07:39:20 INFO - javascript benchmark 07:39:20 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3429.365567370037, 3437.3812713711523, 3441.5525981622395, 3488.3372159289747, 3493.056874643846, 5487.259220005298, 5543.667379957205, 5579.609427340083, 5606.523445507131, 5629.831105066844, 5582.49757521823, 5660.2075709400715, 5660.462256085676, 5699.971500142504, 5773.220615216955, 3969.325214713506, 5115.667481613702, 5213.4004589472315, 5214.504622060898, 5237.580993520519, 5080.974595127019, 5119.0, 5202.401723801754, 5231.2153177023565, 5269.235960785683, 4655.4878963314, 5263.236830659571, 5269.499397557245, 5276.657017293883, 5284.762185701653, 4259.70182087254, 5017.096922553938, 5097.745112744348, 5176.637635365526, 5179.715115668637, 5090.363704536933, 5163.612729045299, 5167.9224811627855, 5174.611904107207, 5230.529252367305, 5529.364123125812, 5555.8888822223325, 5622.57830662702, 5635.098384258499, 5646.26598542191, 6042.335343112254, 6071.572776622972, 6207.503399728011, 6215.68921553925, 6224.906626400608, 6107.46191531438, 6135.87728245437, 6144.463884028993, 6172.598781079216, 6231.844203894893, 5625.6905870177125, 5727.112297593838, 5797.884042319214, 5798.971005144989, 5801.419858014249, 6129.63387754696, 6167.414095660905, 6185.350538193515, 6200.007998720183, 6209.379062093845, 6080.042960067982, 6143.692815359214, 6148.800983808204, 6152.077188421773, 6161.815145545551, 6156.5998210117095, 6191.535634827406, 6203.472704820141, 6220.409061139185, 6230.813075607739, 5831.562632802472, 5869.295684517885, 6134.938650613526, 6207.441330280297, 6217.720202590895, 6162.414570615784, 6206.131141640174, 6209.223847019122, 6216.378362163838, 6233.189685340877, 4953.826616068421, 4964.627652925973, 4969.652124351261, 4978.800847966041, 4982.252662100714, 4359.80380882861, 4362.345648152803, 4443.622292105208, 4451.376807246988, 4466.441694788152, 5661.405552417019, 5692.544596432275, 5693.572982026365, 5702.857428564319, 5755.539556835443, 6165.32181460039, 6191.659458729813, 6192.845178870474, 6193.0, 6222.315545290015, 5549.112142057252, 5592.608517403824, 5604.943950560442, 5634.1830434586755, 5665.178549110356, 5377.8117765878005, 5381.461853814587, 5388.110961691277, 5399.136138217867, 5416.891662166813, 6282.842928926722, 6319.24169099711, 6338.429541341301, 6350.237971443456, 6353.936460635335, 6099.085137229451, 6112.0171844544475, 6137.683438940172, 6202.906956395567, 6228.22147231596, 6326.145970293951, 6327.145835312174, 6328.525360597881, 6337.7781777637565, 6339.714712837841, 6081.057436097412, 6175.4750846176785, 6175.722092505758, 6197.659128747962, 6206.193194884636, 5544.861378465571, 5767.68277744728, 6075.574709770273, 6181.412765787243, 6213.658248796179, 5977.372375900468, 6035.064564992434, 6046.09308603713, 6061.909071364022, 6078.179445774764, 5904.409559044061, 6024.3975602439405, 6057.242844644419, 6068.4538391544975, 6075.969620152047, 5723.427657234243, 5850.385709500441, 5914.34942156371, 5942.435468630473, 5945.732442040032, 6041.577089603686, 6066.7269972850445, 6067.423594758491, 6114.015434403784, 6119.539863234955, 5945.554083443845, 5965.761369545343, 5967.910481342697, 5992.610480318765, 6008.669523176267], "value": 5649.680223226141, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [481.9734914579698, 482.5174825174825, 492.6896055485044, 497.14242930944147, 545.6725964421348, 41447.585524144786, 43944.901377465576, 44051.559484405145, 44112.11775764487, 46445.0, 45513.317300240444, 46116.538834611645, 46152.76923615386, 46194.76902615482, 46202.30696539546, 8706.434081784695, 8904.198622124008, 9656.324057315973, 9895.802083958353, 9900.752481187956, 11654.417279136052, 11958.820617690742, 11968.581099661513, 12213.328266945358, 12214.083943704214, 11819.468123934443, 11927.58253461129, 12012.519499220065, 12022.0, 12030.037596992262, 8701.912980870236, 11726.824097638542, 11961.700957476132, 12026.819597706042, 12113.15207935445, 11946.10404219687, 12042.337671428068, 12043.217190882564, 12059.21615095016, 12061.095417843697, 34705.82647086774, 36083.63916360829, 41778.79110604458, 42028.159436811395, 42203.36694949578, 3189.3142974260413, 3225.3065590897836, 3301.4552598821165, 3380.171857894807, 3598.298331825295, 29155.125346239645, 30213.395732085235, 30266.54600180999, 30408.087757367313, 30866.07401777928, 2652.350174207312, 2711.7288271172924, 2713.8619617118034, 2737.3840885800537, 2790.2466334089886, 497451.512742434, 497547.9999999964, 498838.0, 499225.9999999964, 499261.0, 34947.0, 67335.98996015063, 67630.0, 67742.66128669423, 67964.0, 36229.63770362316, 37433.62566374301, 37468.437973430424, 37471.250574988895, 37500.0, 15314.387424502895, 15493.14787686688, 15705.371785128695, 15705.764413533807, 15736.213189340488, 11937.820932686016, 12218.26690398579, 12794.936025319814, 13050.673733156556, 13058.020648451215, 7994.400419968526, 8010.359171266285, 8031.397645176518, 8078.555679437687, 8081.797955051171, 2795.343094372821, 3235.7411407087375, 3246.2209069823543, 3246.4481038223557, 3291.967080329166, 935.4200395754675, 968.1480297338438, 977.4917043137528, 982.6708052802392, 1067.9839802402971, 29912.10263692093, 29924.952626658396, 30548.0, 30595.0, 30767.538486922713, 950.5247376311844, 995.8157740817971, 1015.9847602285971, 1040.1678657074463, 1067.3649178738792, 12863.614091577268, 14442.350094245785, 15407.921445498932, 15505.9224703878, 15818.446354377542, 685534.0, 690965.0, 695756.1697532042, 697439.0, 697850.0, 61748.69125654343, 61793.69103154456, 63578.0, 64082.0, 64251.03623445652, 1280.295837289487, 1400.180894176918, 1415.1014106042737, 1447.6236178593851, 1452.644102194969, 25707.0, 30308.090757277318, 30332.545011824397, 30792.0, 30895.0, 54725.0, 55453.0, 55467.999999998385, 55483.0, 55680.44319556915, 463480.0, 466545.0, 479107.0, 479348.0, 479455.0, 23672.93471793739, 26067.608985865605, 26343.20970370892, 26929.0, 27895.0, 46309.76845115753, 47062.764351208556, 47363.0, 47364.7631761839, 48090.0, 38563.0, 38573.61426385812, 38865.805670971466, 39481.605183947795, 40066.0, 37823.62176378201, 37881.0, 38837.80581097076, 38972.6102738969, 39109.82670519889, 38258.42612360761, 38887.6111238884, 39238.82283531499, 39956.00109997273, 39963.0, 33231.667683322856, 34170.120643364855, 34934.475982860786, 34998.47502287519, 35058.298834022666, 19852.90073549623, 24052.27843164638, 25058.12296569611, 25259.49481010406, 25841.095561655253], "value": 19165.48348565944, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [382.779901556605, 384.11461581055676, 385.1468996173476, 399.82207917476734, 409.4799604502282, 19551.60896782065, 20974.580508389823, 21215.0, 21251.893740531275, 22094.116235350568, 20612.072456739446, 20842.166313347447, 20874.791252087514, 21580.676289855666, 21706.0, 5675.460831221038, 5919.437653422918, 6043.637381757108, 6055.455009049185, 6060.545459090564, 4107.424960505532, 4358.280883654209, 5876.764929402819, 5930.288365396159, 6305.211848518935, 6582.3746744059235, 6637.336266373372, 6641.203055633307, 6642.966785166067, 6647.135872336589, 5591.217229587861, 6455.870882582346, 6530.0204969254755, 6551.344865513411, 6644.734210631569, 3664.450332450127, 4521.231390663595, 4791.4250289965075, 5745.396733343023, 5759.942400576025, 25253.11614093498, 25485.490290194277, 25837.870810646014, 26042.088526901658, 26391.0, 2102.2431924507164, 2317.188983855637, 2365.3731761083327, 2404.6393041043807, 2544.300317412708, 6785.72857085716, 7440.069991251094, 10232.590696372135, 15484.612884677861, 15522.922385388001, 1705.6588682263598, 1740.416960318295, 1777.4045694692293, 1833.0834582708646, 1848.7042073268212, 96664.03335966691, 114754.27868581978, 115718.0, 115791.0, 117609.0, 9714.368566043326, 10840.945795270973, 11067.50196241171, 11077.446127693744, 11287.717807054889, 13539.0, 13848.238346890816, 13924.930375348058, 13944.047833303346, 13960.650983725489, 8526.40315177944, 8810.691625793066, 8824.117588241124, 8956.193942545202, 8956.776080597907, 8708.78228044304, 8813.471191728517, 8854.557272136368, 8934.910650893538, 8950.641974320954, 5602.38373778884, 6662.96890381322, 6745.662716864137, 6784.59292442455, 6818.761343352959, 1695.3303445139102, 1764.1708397053364, 1850.80566540514, 1863.2733234038735, 1872.438268519466, 919.4758987377131, 993.344392700814, 996.1283876607968, 1041.094248004226, 1078.8651418572679, 19855.106520206624, 20321.695174572393, 20862.0, 21005.579888402146, 21056.578868422854, 967.0571193086824, 986.9654562090292, 998.5606333213362, 1029.3772267778036, 1069.9679009629724, 8560.61477233526, 11076.723081922855, 11439.0, 11485.942570287261, 11531.71170720722, 610518.0, 610609.7347566347, 620570.0, 627466.0, 637397.0, 75743.0, 78835.0, 108933.19601235927, 113948.86051139548, 131859.0, 991.0584944302941, 995.785906030207, 1013.0318167768362, 1034.9637762678421, 1036.533559898049, 13819.444788022873, 18334.808237464524, 18336.08319584042, 18652.44042678722, 18901.243950241835, 51749.741251293504, 53058.2041269389, 53319.46680533145, 53457.0, 53724.46275537351, 78069.21930780847, 78413.43173136619, 79503.2049679519, 79869.40261194628, 81784.18215817766, 7057.788266352018, 7595.772126836204, 7985.560794156145, 8040.919590804251, 8266.462679925786, 11789.941050294694, 11857.881421185677, 12179.147459677737, 12257.14200005991, 12373.93813030965, 10347.327423717434, 10409.95900409953, 10572.625558677322, 10636.042756151983, 10647.787044258917, 10072.647457338959, 10104.494775261355, 10121.645742398981, 10401.375917444977, 10538.262321637412, 9513.23894088485, 9774.951125244614, 10276.22928280397, 10309.175265978854, 10533.0, 8016.358691304565, 8087.231712987257, 8089.271965523131, 8135.308498777434, 8484.915150848412, 9308.767280818034, 10531.473426328806, 10585.576576936835, 10772.24594278393, 11006.394648294421], "value": 9561.64935719085, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1390.4716207841018, 1545.5517899809056, 1572.0724772384292, 1798.6312805874798, 1948.6005368899368, 7459.888101678479, 8547.829043419128, 8756.949166100061, 8783.297336213103, 8905.064968178345, 8512.404131710782, 8658.220760131588, 8729.519876406795, 8744.737657870259, 8751.518666473341, 2353.5881220786296, 2369.893354799038, 2390.6533552634855, 2392.473655795722, 2394.772496612819, 2334.2297041976194, 2351.400392899817, 2372.9881350593223, 2380.976190238093, 2386.033656369173, 2330.3591512334074, 2347.7417484076736, 2360.1503458754833, 2374.169040835711, 2378.7859092681656, 2289.1415719105335, 2349.800266977309, 2352.729436114843, 2353.411647088228, 2361.9881900590385, 1975.3481351154085, 2295.8737269450176, 2325.395397196724, 2326.918557850484, 2328.7205535335697, 8570.142985701443, 8678.826423471495, 8704.651813927436, 8776.385653004292, 8856.158664926821, 4429.623482004034, 5385.380681221632, 5391.083515802323, 5395.0, 5397.001554712391, 3435.278591495789, 3510.017195185352, 3551.6803487686234, 3559.003479025877, 3591.5331006969186, 1021.5249908792447, 1024.6925922223304, 1031.1080915008538, 1034.4517405774952, 1036.1658864613983, 11429.542818287342, 11593.826092608617, 11774.0, 11778.823317650242, 11783.116266280063, 4455.26488129455, 4513.50351461339, 4553.59017688403, 4633.698809577367, 4643.512431194744, 7914.525128492309, 9022.548872556346, 9110.453372797654, 9239.30705197113, 9253.398529095588, 5181.766820493087, 5212.713300768418, 5217.660852044605, 5219.921701174486, 5223.373195216598, 3681.9263614727556, 4497.707649002804, 4699.530046995274, 4706.788194531255, 4858.46556878743, 3744.794036328028, 3787.659110680052, 3846.1346197105427, 3874.6900247980093, 3893.980530097388, 1142.2232881640398, 1161.6398916335963, 1165.8426112474876, 1174.653477224221, 1175.7060734816296, 605.742559412248, 794.6265255329986, 882.7263548300048, 916.1891725822695, 954.5752140297501, 5538.944610553924, 5790.7973220937065, 5889.0, 5904.350521442638, 5910.793122240787, 927.4342650982895, 932.5756780664917, 942.6088173408097, 944.8110377924443, 986.7335819328885, 3838.865639702597, 3909.5504017038406, 3993.660538854172, 4004.0390306326854, 4024.5170579530645, 10138.290319677699, 10598.417087060287, 10774.838377424345, 10779.568817247378, 10809.729756755987, 10207.897921020844, 10616.416097114732, 10772.515236814363, 10775.407352595526, 10784.029437350677, 8079.192080791991, 8236.588170591447, 8244.670213191524, 8287.171282871785, 8297.128801475757, 5044.218146187347, 5059.443461219189, 5795.913061304, 5843.357230704534, 5925.200097986716, 9504.47725375111, 9705.417674939526, 10095.293329466867, 10101.343412678152, 10140.0, 10056.69829905104, 10151.898481015096, 10250.948745256226, 10341.689749307532, 10372.636957706443, 5909.438603332676, 6824.283450237852, 7078.823029424306, 7086.610236437045, 7091.184513780775, 8149.266566009088, 8191.344692424473, 8207.876881846658, 8284.834303313779, 8304.418690691593, 7320.743773967885, 7429.4799364044, 7470.738524151629, 7635.88546171819, 7645.082590089002, 7184.856302874018, 7252.492325537372, 7290.380317673057, 7424.554526728413, 7525.360344370571, 6889.517733758589, 6936.410405115622, 6956.2000369958105, 7031.859362812613, 7031.964840175767, 4648.465426475863, 4878.317035614946, 4896.4124305083615, 4902.436219834764, 4941.9621879404285, 4845.176320025534, 4911.066897289504, 4917.950820491892, 4985.102681517362, 5162.922556161586], "value": 4503.921772092308, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [305.9372828570143, 310.76226686584766, 314.49209526614527, 318.5906110647818, 319.77935224694943, 4855.635827312957, 5303.469653034699, 5338.813141540047, 5344.331958505187, 5357.705326207057, 5187.844364669066, 5266.736663166836, 5267.920076384342, 5295.735213239333, 5313.468653134694, 3547.219611685438, 3827.9043023924414, 3854.2291541691634, 3964.2467931093, 3964.612385665023, 3418.3676019936265, 3955.9604403956023, 4032.6169013943627, 4056.6146216109423, 4062.53280872699, 3990.38149086892, 3996.520417549898, 4017.9754162688473, 4018.5332353690837, 4027.8791636250958, 3731.589525152231, 3779.9622003780023, 3944.7633142011573, 4009.0779120802085, 4043.898902527431, 2943.45546073977, 3440.1915549845767, 3471.9826400868087, 3486.6339034401276, 3489.4940233665975, 4965.634450526098, 4974.726390048546, 4976.253561965734, 4980.9252861207115, 5027.988804478201, 4158.792060396968, 4191.952011997001, 4483.730976141442, 4538.795754191036, 4575.748333841638, 4203.012292111382, 4889.848466674652, 4934.728589927553, 5381.112116500773, 5521.377345760771, 3221.822799746013, 3529.3294274087843, 3749.2314075614704, 3785.4889589905288, 3796.4115562087923, 4755.762211889426, 5301.840944771701, 5837.678927658977, 5925.555583331267, 5941.851453713649, 3902.4731661225937, 3965.8413663453716, 3974.7813870237414, 3979.7413168143976, 3995.300822356076, 3897.376419772823, 4045.806487086317, 4064.3700226464944, 4070.2673518766906, 4071.959280407158, 4001.8399264029113, 4647.95352046482, 4648.187206989277, 4659.184642687584, 4695.694779839299, 4729.1014707206205, 5185.447968141835, 5382.434844341366, 5614.775408983595, 5702.258706368229, 1356.9389377478037, 1392.7910813378073, 1393.7142885708406, 1408.936597853099, 1415.2145559214734, 830.9542975136426, 864.5979619476973, 875.6366108065209, 880.5778667452147, 880.665347168072, 4654.953450465519, 4916.950830491649, 5033.7734801934, 5043.4956504349275, 5152.613553983466, 4483.730976141442, 4907.190313598217, 5109.182530795055, 5483.396826349099, 5660.122680984454, 4323.437953066082, 4585.518520555293, 4728.71627702339, 5139.12634852076, 5152.819651312261, 2524.406764410362, 2710.6717708323176, 2759.0, 2772.6811416686937, 3033.833139177322, 5599.744194412276, 5898.793542226087, 5905.114232865105, 5924.348321684612, 5926.7925622603, 4423.447069116361, 5511.807086751945, 5720.713964301768, 5828.242328497268, 5832.533397328203, 5644.181593668895, 5829.7376618052285, 5875.970620146872, 5925.762969481259, 5933.139694744238, 5236.6072544560075, 5561.860953476195, 5613.214150018919, 5647.632903861236, 5722.570807189394, 4489.9775501122285, 4578.359029735773, 5531.640443371168, 5546.61173717852, 5591.888162236651, 4537.523560026149, 5608.887067677479, 5830.484074140839, 5922.940770592239, 5950.375210602824, 3878.7672739635714, 3903.765774053566, 3907.6092390760696, 3916.412538119305, 3919.529656441245, 4533.841315554072, 4685.8594242172785, 4833.371661683958, 5642.492175704207, 5646.407127251579, 4630.814767409401, 5033.370828646419, 5413.431589683027, 5430.185472179205, 5487.4238204987905, 4210.621044106045, 4996.326230712752, 5273.782992374572, 5503.752331145188, 5528.364238112668, 4365.367021781761, 5346.85042715818, 5373.382061062872, 5390.218418329242, 5396.541293989898, 4942.416747857775, 5200.4279529251, 5343.385510666323, 5369.0, 5512.6968016759465, 4787.880302992453, 5361.597880158925, 5397.406285308534, 5398.892022159614, 5411.945880541301], "value": 4012.24350172303, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [640.9807705768826, 699.8740226759181, 713.436385255648, 723.2622724820679, 729.6935287179384, 14760.409583616656, 15001.39994400224, 15244.542663720078, 15244.695106097872, 15248.618784530392, 14112.364943577539, 14169.220692861889, 15114.093154410742, 15769.053856768602, 15789.921050394762, 886.9068747781487, 887.7292425810125, 902.5803001604253, 907.2696479334134, 918.6646873891015, 717.5264325545141, 740.8147963009247, 879.5162660536711, 904.8959369672473, 909.7043460875209, 932.5989824375516, 935.7473482159813, 958.7219706285169, 958.9520523973808, 965.2037069417723, 940.3511577011908, 960.4093482508242, 960.9711708648751, 961.125375907924, 961.6441916490874, 550.8980838544842, 676.5230512488683, 680.5406350713249, 703.1667474043275, 704.32384910486, 14965.925170374187, 15148.848511514854, 15176.317065732068, 15191.924040379727, 15219.61950951224, 11882.0, 11928.880711192951, 12027.398630068461, 12149.453274602663, 12182.817257741051, 10760.78478430435, 10786.298890572089, 11302.378369189692, 11381.658550243506, 11579.768404631945, 3935.724499285051, 4097.221527909687, 4102.835886564534, 4108.36320370343, 4227.809748561292, 71209.75149574231, 73027.63486182535, 73327.44502163201, 73378.53242935111, 73558.63220683862, 9031.867548485881, 9103.089691030977, 9202.217811486098, 9222.677206297747, 9298.488583127993, 20018.59962800765, 20792.27227047046, 20890.373288801362, 21220.469488262916, 21235.78764212369, 7152.499325047301, 7182.317679820513, 7363.005994190823, 7393.741219057307, 7441.776746697607, 3763.397856343027, 3848.692104631623, 3868.439076333972, 3895.474110995036, 3912.491376121143, 3286.7206287465356, 3395.2530443302435, 3410.113370523632, 3415.79505229687, 3420.0423881313272, 609.2658346692225, 619.8563650065622, 621.018790311307, 631.3623240527008, 643.433778275115, 543.4348277791053, 558.801625422969, 562.2690502346933, 573.3240914942106, 596.150485558078, 7849.843003139905, 7980.640871160812, 7989.44073914832, 8014.438989270695, 8211.36628066655, 548.4953842464942, 563.3408911573468, 575.4533193466224, 579.5334755521845, 595.919550860637, 3647.927041459156, 3731.850725970931, 3758.4362345648374, 3808.2002779416634, 3842.51968503937, 84593.0, 85270.0, 85690.0, 86489.0, 88483.1151688475, 79795.60102199568, 81031.18968810237, 81200.59399702963, 81626.0, 81669.59165204136, 71304.64347678228, 71722.64138679378, 72844.63577682078, 73366.26633733702, 74002.0, 7204.747833826001, 7562.697492100255, 7739.18738532446, 7756.185600512091, 8125.268725814705, 38968.0, 42358.0, 44233.778831105636, 44358.0, 45811.083778323584, 62281.37718622937, 62409.68795155995, 62911.685441572496, 63627.6818615904, 64138.67930660317, 9263.953680231556, 10890.618828341287, 10987.121030317716, 11060.004599586076, 11106.944465277622, 11915.82126268124, 12029.75940481203, 12157.392130393622, 12171.695707607381, 12549.87450125487, 11152.163587731115, 11188.944055279997, 11275.379854108103, 11456.484458199568, 11980.46087926028, 11037.889621103686, 11223.382713950485, 11452.942735286604, 11561.88438115608, 11618.979065442876, 11003.61487347939, 11090.722731931766, 11223.55105795759, 11302.378369189773, 11573.305601663928, 9131.406458580172, 9419.952900235456, 9496.0, 9535.284853636143, 9553.283503737386, 8873.600687968928, 9309.581068851783, 9425.293103017439, 9456.19622332082, 9534.761630959281], "value": 6784.614191297518, "unit": "score"}], "name": "dromaeo_css", "value": 7089.3154092773975, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 148.0, 163.0, 112.0, 113.0, 112.0, 112.0, 113.0, 111.0, 112.0], "value": 121.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 124.0, 118.0, 203.0, 119.0, 120.0, 140.0, 118.0, 153.0, 118.0], "value": 137.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 188.0, 183.0, 185.0, 183.0, 186.0, 184.0, 184.0, 182.0, 184.0], "value": 183.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 57.0, 59.0, 119.0, 57.0, 57.0, 63.0, 62.0, 82.0, 56.0], "value": 69.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 78.0, 140.0, 80.0, 92.0, 77.0, 104.0, 79.0, 78.0, 110.0], "value": 93.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 89.0, 88.0, 90.0, 87.0, 90.0, 87.0, 90.0, 86.0], "value": 88.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 96.0, 96.0, 95.0, 100.0], "value": 95.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 108.0, 129.0, 140.0, 86.0, 91.0, 86.0, 85.0, 84.0, 86.0], "value": 98.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 57.0, 58.0, 55.0, 55.0, 57.0, 56.0, 120.0, 55.0, 55.0], "value": 62.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 69.0, 78.0, 76.0, 73.0, 69.0, 69.0, 71.0, 70.0, 71.0], "value": 71.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 85.0, 83.0, 84.0, 84.0, 74.0, 101.0, 75.0, 85.0], "value": 84.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 140.0, 153.0, 133.0, 138.0, 141.0, 132.0, 136.0, 241.0, 147.0], "value": 150.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 219.0, 192.0, 178.0, 222.0, 213.0, 166.0, 167.0, 164.0, 162.0], "value": 185.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 64.0, 67.0, 70.0, 81.0, 82.0, 83.0, 79.0, 66.0, 65.0], "value": 72.5, "unit": "score"}], "name": "kraken", "value": 1512.6, "alertThreshold": 2.0}]} 07:39:20 INFO - Return code: 0 07:39:20 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 07:39:20 ERROR - # TBPL SUCCESS # 07:39:20 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:39:20 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:39:20 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:39:20 INFO - 2016-05-24 07:39:20.877 screenresolution[1958:16302] starting screenresolution argv=screenresolution get 07:39:20 INFO - 2016-05-24 07:39:20.879 screenresolution[1958:16302] Display 0: 1600x1200x32@60 07:39:20 INFO - 2016-05-24 07:39:20.885 screenresolution[1959:16306] starting screenresolution argv=screenresolution list 07:39:20 INFO - Available Modes on Display 0 07:39:20 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:39:20 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:39:20 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:39:20 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:39:21 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:39:21 INFO - Intel Iris: 07:39:21 INFO - Chipset Model: Intel Iris 07:39:21 INFO - Type: GPU 07:39:21 INFO - Bus: Built-In 07:39:21 INFO - VRAM (Dynamic, Max): 1536 MB 07:39:21 INFO - Vendor: Intel (0x8086) 07:39:21 INFO - Device ID: 0x0a2e 07:39:21 INFO - Revision ID: 0x0009 07:39:21 INFO - Displays: 07:39:21 INFO - Display: 07:39:21 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:39:21 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:39:21 INFO - Display Serial Number: 1600x1200 60 07:39:21 INFO - Main Display: Yes 07:39:21 INFO - Mirror: Off 07:39:21 INFO - Online: Yes 07:39:21 INFO - Rotation: Supported 07:39:21 INFO - Return code: 0 07:39:21 INFO - Running post-action listener: _resource_record_post_action 07:39:21 INFO - ##### 07:39:21 INFO - ##### Finished run-tests step (success) 07:39:21 INFO - ##### 07:39:21 INFO - Running post-run listener: _resource_record_post_run 07:39:21 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 479232; Write bytes: 693705728; Read time: 7; Write time: 1931 07:39:21 INFO - TinderboxPrint: CPU usage
53.5% 07:39:21 INFO - TinderboxPrint: I/O read bytes / time
479,232 / 7 07:39:21 INFO - TinderboxPrint: I/O write bytes / time
693,705,728 / 1,931 07:39:21 INFO - TinderboxPrint: CPU idle
2,253.1 (46.5%) 07:39:21 INFO - TinderboxPrint: CPU system
967.7 (20.0%) 07:39:21 INFO - TinderboxPrint: CPU user
1,628.8 (33.6%) 07:39:21 INFO - TinderboxPrint: Swap in / out
396,251,136 / 0 07:39:21 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 390044160; Write bytes: 384322560; Read time: 29549; Write time: 722 07:39:21 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 479232; Write bytes: 293019648; Read time: 7; Write time: 1165 07:39:21 INFO - Running post-run listener: _upload_blobber_files 07:39:21 INFO - Blob upload gear active. 07:39:21 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:39:21 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 07:39:21 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 07:39:21 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 07:39:21 INFO - (blobuploader) - INFO - Open directory for files ... 07:39:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:39:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:39:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:39:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:39:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:39:22 INFO - (blobuploader) - INFO - Done attempting. 07:39:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:39:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:39:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:39:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:39:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:39:23 INFO - (blobuploader) - INFO - Done attempting. 07:39:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:39:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:39:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:39:26 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:39:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:39:26 INFO - (blobuploader) - INFO - Done attempting. 07:39:26 INFO - (blobuploader) - INFO - Iteration through files over. 07:39:26 INFO - Return code: 0 07:39:26 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:39:26 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:39:26 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9dcbed8b32ec55c638728072ba3037c88599bd31d5fd9a177ecad1aa7f61aadbfe8895145ec0205b8ddb25b3b56fe8b742e8b16cb528cf4f1ba095efac125a47", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1af2b3bea6f2b620df842b46aede25544695d2cf33bca513ec42cb20b8bc305d10b20aeae3b4b79b4216002873d93b2ee3754d9e47226092e0e7a6ca4c207cea", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cdea0273efdf7f85565ea75b55891ebfa47a4e26a16328d8045fb4375f7bfe4c895d173e81f7ee955196a6531ac350b57f4d252577920dccefb5ffbd30b06e5c"} 07:39:26 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:39:26 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:39:26 INFO - Contents: 07:39:26 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9dcbed8b32ec55c638728072ba3037c88599bd31d5fd9a177ecad1aa7f61aadbfe8895145ec0205b8ddb25b3b56fe8b742e8b16cb528cf4f1ba095efac125a47", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1af2b3bea6f2b620df842b46aede25544695d2cf33bca513ec42cb20b8bc305d10b20aeae3b4b79b4216002873d93b2ee3754d9e47226092e0e7a6ca4c207cea", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cdea0273efdf7f85565ea75b55891ebfa47a4e26a16328d8045fb4375f7bfe4c895d173e81f7ee955196a6531ac350b57f4d252577920dccefb5ffbd30b06e5c"} 07:39:26 INFO - Running post-run listener: copy_logs_to_upload_dir 07:39:26 INFO - Copying logs to upload dir... 07:39:26 INFO - mkdir: /builds/slave/test/build/upload/logs 07:39:26 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.124311 ========= master_lag: 155.14 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 23 mins, 49 secs) (at 2016-05-24 07:42:01.222265) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-05-24 07:42:01.226473) ========= 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.gqCvZldote/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.6OGCn0SiIs/Listeners TMPDIR=/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9dcbed8b32ec55c638728072ba3037c88599bd31d5fd9a177ecad1aa7f61aadbfe8895145ec0205b8ddb25b3b56fe8b742e8b16cb528cf4f1ba095efac125a47", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1af2b3bea6f2b620df842b46aede25544695d2cf33bca513ec42cb20b8bc305d10b20aeae3b4b79b4216002873d93b2ee3754d9e47226092e0e7a6ca4c207cea", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cdea0273efdf7f85565ea75b55891ebfa47a4e26a16328d8045fb4375f7bfe4c895d173e81f7ee955196a6531ac350b57f4d252577920dccefb5ffbd30b06e5c"} build_url:https://queue.taskcluster.net/v1/task/FtUJ_a-LRFuNVJzn27cJxw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011808 build_url: 'https://queue.taskcluster.net/v1/task/FtUJ_a-LRFuNVJzn27cJxw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9dcbed8b32ec55c638728072ba3037c88599bd31d5fd9a177ecad1aa7f61aadbfe8895145ec0205b8ddb25b3b56fe8b742e8b16cb528cf4f1ba095efac125a47", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1af2b3bea6f2b620df842b46aede25544695d2cf33bca513ec42cb20b8bc305d10b20aeae3b4b79b4216002873d93b2ee3754d9e47226092e0e7a6ca4c207cea", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cdea0273efdf7f85565ea75b55891ebfa47a4e26a16328d8045fb4375f7bfe4c895d173e81f7ee955196a6531ac350b57f4d252577920dccefb5ffbd30b06e5c"}' ========= master_lag: 1.68 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-05-24 07:42:02.916179) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 2 secs) (at 2016-05-24 07:42:02.916476) ========= 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.gqCvZldote/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.6OGCn0SiIs/Listeners TMPDIR=/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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.005104 ========= master_lag: 2.84 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 2 secs) (at 2016-05-24 07:42:05.761772) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-24 07:42:05.762042) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-24 07:42:05.787292) ========= ========= Total master_lag: 159.72 =========