builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0369 starttime: 1466497576.22 results: success (0) buildid: 20160621000402 builduid: 78563c1f72ab4879add13462fea88926 revision: 634aedddd904cf6b9004590dbc2f5491fe4474ff ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-21 01:26:16.219572) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-21 01:26:16.220036) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-21 01:26:16.220362) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-21 01:26:16.242465) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-21 01:26:16.242831) ========= 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.Ah25J24ptz/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.Ll9LRXdiJb/Listeners TMPDIR=/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/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-06-21 01:26:16-- 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% 8.09M=0.001s 2016-06-21 01:26:16 (8.09 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.103454 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-21 01:26:16.366912) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-21 01:26:16.367242) ========= 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.Ah25J24ptz/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.Ll9LRXdiJb/Listeners TMPDIR=/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/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.020098 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-21 01:26:16.415266) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-21 01:26:16.415643) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 634aedddd904cf6b9004590dbc2f5491fe4474ff --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 634aedddd904cf6b9004590dbc2f5491fe4474ff --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Ah25J24ptz/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.Ll9LRXdiJb/Listeners TMPDIR=/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/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-06-21 01:26:16,498 truncating revision to first 12 chars 2016-06-21 01:26:16,498 Setting DEBUG logging. 2016-06-21 01:26:16,499 attempt 1/10 2016-06-21 01:26:16,499 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/634aedddd904?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-21 01:26:16,790 unpacking tar archive at: mozilla-beta-634aedddd904/testing/mozharness/ program finished with exit code 0 elapsedTime=0.565357 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-21 01:26:17.004842) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-21 01:26:17.005170) ========= script_repo_revision: 634aedddd904cf6b9004590dbc2f5491fe4474ff ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-21 01:26:17.005585) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-21 01:26:17.005863) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-21 01:26:17.023578) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2016-06-21 01:26:17.023952) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Ah25J24ptz/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.Ll9LRXdiJb/Listeners TMPDIR=/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/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 01:26:17 INFO - MultiFileLogger online at 20160621 01:26:17 in /builds/slave/test 01:26:17 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 01:26:17 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:26:17 INFO - {'append_to_log': False, 01:26:17 INFO - 'base_work_dir': '/builds/slave/test', 01:26:17 INFO - 'blob_upload_branch': 'Mozilla-Beta', 01:26:17 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:26:17 INFO - 'branch': 'Mozilla-Beta', 01:26:17 INFO - 'buildbot_json_path': 'buildprops.json', 01:26:17 INFO - 'config_files': ('talos/mac_config.py',), 01:26:17 INFO - 'default_actions': ('clobber', 01:26:17 INFO - 'read-buildbot-config', 01:26:17 INFO - 'download-and-extract', 01:26:17 INFO - 'populate-webroot', 01:26:17 INFO - 'create-virtualenv', 01:26:17 INFO - 'install', 01:26:17 INFO - 'run-tests'), 01:26:17 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:26:17 INFO - 'download_minidump_stackwalk': True, 01:26:17 INFO - 'download_symbols': 'ondemand', 01:26:17 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:26:17 INFO - 'tooltool.py': '/tools/tooltool.py', 01:26:17 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:26:17 INFO - '/tools/misc-python/virtualenv.py')}, 01:26:17 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:26:17 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:26:17 INFO - 'installer_path': 'installer.exe', 01:26:17 INFO - 'log_level': 'info', 01:26:17 INFO - 'log_name': 'talos', 01:26:17 INFO - 'log_to_console': True, 01:26:17 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 01:26:17 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 01:26:17 INFO - 'opt_config_files': (), 01:26:17 INFO - 'pip_index': False, 01:26:17 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:26:17 INFO - 'cmd': ('bash', 01:26:17 INFO - '-c', 01:26:17 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:26:17 INFO - 'enabled': True, 01:26:17 INFO - 'halt_on_failure': False, 01:26:17 INFO - 'name': 'check_screen_resolution'},), 01:26:17 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:26:17 INFO - 'cmd': ('bash', 01:26:17 INFO - '-c', 01:26:17 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:26:17 INFO - 'enabled': True, 01:26:17 INFO - 'halt_on_failure': False, 01:26:17 INFO - 'name': 'check_screen_resolution'},), 01:26:17 INFO - 'run_cmd_checks_enabled': True, 01:26:17 INFO - 'sps_profile': False, 01:26:17 INFO - 'sps_profile_interval': 0, 01:26:17 INFO - 'suite': 'dromaeojs', 01:26:17 INFO - 'system_bits': '32', 01:26:17 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:26:17 INFO - 'title': 't-yosemite-r7-0369', 01:26:17 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:26:17 INFO - 'use_talos_json': True, 01:26:17 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:26:17 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:26:17 INFO - 'work_dir': 'build'} 01:26:17 INFO - ##### 01:26:17 INFO - ##### Running clobber step. 01:26:17 INFO - ##### 01:26:17 INFO - Running pre-action listener: _resource_record_pre_action 01:26:17 INFO - Running main action method: clobber 01:26:17 INFO - rmtree: /builds/slave/test/build 01:26:17 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:26:18 INFO - Running post-action listener: _resource_record_post_action 01:26:18 INFO - ##### 01:26:18 INFO - ##### Running read-buildbot-config step. 01:26:18 INFO - ##### 01:26:18 INFO - Running pre-action listener: _resource_record_pre_action 01:26:18 INFO - Running main action method: read_buildbot_config 01:26:18 INFO - Using buildbot properties: 01:26:18 INFO - { 01:26:18 INFO - "project": "", 01:26:18 INFO - "product": "firefox", 01:26:18 INFO - "script_repo_revision": "production", 01:26:18 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 01:26:18 INFO - "repository": "", 01:26:18 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 01:26:18 INFO - "buildid": "20160621000402", 01:26:18 INFO - "pgo_build": "False", 01:26:18 INFO - "basedir": "/builds/slave/test", 01:26:18 INFO - "buildnumber": 6, 01:26:18 INFO - "slavename": "t-yosemite-r7-0369", 01:26:18 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 01:26:18 INFO - "platform": "yosemite_r7", 01:26:18 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 01:26:18 INFO - "branch": "mozilla-beta", 01:26:18 INFO - "repo_path": "releases/mozilla-beta", 01:26:18 INFO - "revision": "634aedddd904cf6b9004590dbc2f5491fe4474ff", 01:26:18 INFO - "stage_platform": "macosx64", 01:26:18 INFO - "builduid": "78563c1f72ab4879add13462fea88926", 01:26:18 INFO - "slavebuilddir": "test" 01:26:18 INFO - } 01:26:18 INFO - Found installer url https://queue.taskcluster.net/v1/task/GXHMd--YTjOKyt7o2H8C-A/artifacts/public/build/firefox-48.0.en-US.mac.dmg. 01:26:18 INFO - Running post-action listener: _resource_record_post_action 01:26:18 INFO - ##### 01:26:18 INFO - ##### Running download-and-extract step. 01:26:18 INFO - ##### 01:26:18 INFO - Running pre-action listener: _resource_record_pre_action 01:26:18 INFO - Running main action method: download_and_extract 01:26:18 INFO - mkdir: /builds/slave/test/build/tests 01:26:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:26:18 INFO - https://queue.taskcluster.net/v1/task/GXHMd--YTjOKyt7o2H8C-A/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json matches https://queue.taskcluster.net 01:26:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GXHMd--YTjOKyt7o2H8C-A/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json 01:26:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GXHMd--YTjOKyt7o2H8C-A/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json 01:26:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GXHMd--YTjOKyt7o2H8C-A/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json 01:26:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GXHMd--YTjOKyt7o2H8C-A/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json'}, attempt #1 01:26:20 INFO - Downloaded 1165 bytes. 01:26:20 INFO - Reading from file /builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json 01:26:20 INFO - Using the following test package requirements: 01:26:20 INFO - {u'common': [u'firefox-48.0.en-US.mac.common.tests.zip'], 01:26:20 INFO - u'cppunittest': [u'firefox-48.0.en-US.mac.common.tests.zip', 01:26:20 INFO - u'firefox-48.0.en-US.mac.cppunittest.tests.zip'], 01:26:20 INFO - u'gtest': [u'firefox-48.0.en-US.mac.common.tests.zip', 01:26:20 INFO - u'firefox-48.0.en-US.mac.gtest.tests.zip'], 01:26:20 INFO - u'jittest': [u'firefox-48.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 01:26:20 INFO - u'mochitest': [u'firefox-48.0.en-US.mac.common.tests.zip', 01:26:20 INFO - u'firefox-48.0.en-US.mac.mochitest.tests.zip'], 01:26:20 INFO - u'mozbase': [u'firefox-48.0.en-US.mac.common.tests.zip'], 01:26:20 INFO - u'reftest': [u'firefox-48.0.en-US.mac.common.tests.zip', 01:26:20 INFO - u'firefox-48.0.en-US.mac.reftest.tests.zip'], 01:26:20 INFO - u'talos': [u'firefox-48.0.en-US.mac.common.tests.zip', 01:26:20 INFO - u'firefox-48.0.en-US.mac.talos.tests.zip'], 01:26:20 INFO - u'web-platform': [u'firefox-48.0.en-US.mac.common.tests.zip', 01:26:20 INFO - u'firefox-48.0.en-US.mac.web-platform.tests.zip'], 01:26:20 INFO - u'xpcshell': [u'firefox-48.0.en-US.mac.common.tests.zip', 01:26:20 INFO - u'firefox-48.0.en-US.mac.xpcshell.tests.zip']} 01:26:20 INFO - Downloading packages: [u'firefox-48.0.en-US.mac.common.tests.zip'] for test suite category: common 01:26:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:26:20 INFO - https://queue.taskcluster.net/v1/task/GXHMd--YTjOKyt7o2H8C-A/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 01:26:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GXHMd--YTjOKyt7o2H8C-A/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 01:26:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GXHMd--YTjOKyt7o2H8C-A/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 01:26:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GXHMd--YTjOKyt7o2H8C-A/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip 01:26:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GXHMd--YTjOKyt7o2H8C-A/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip'}, attempt #1 01:26:21 INFO - Downloaded 18300395 bytes. 01:26:21 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 01:26:21 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 01:26:24 INFO - Return code: 0 01:26:24 INFO - Downloading packages: [u'firefox-48.0.en-US.mac.common.tests.zip', u'firefox-48.0.en-US.mac.talos.tests.zip'] for test suite category: talos 01:26:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:26:24 INFO - https://queue.taskcluster.net/v1/task/GXHMd--YTjOKyt7o2H8C-A/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 01:26:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GXHMd--YTjOKyt7o2H8C-A/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 01:26:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GXHMd--YTjOKyt7o2H8C-A/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 01:26:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GXHMd--YTjOKyt7o2H8C-A/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip 01:26:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GXHMd--YTjOKyt7o2H8C-A/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip'}, attempt #1 01:26:25 INFO - Downloaded 18300395 bytes. 01:26:25 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 01:26:25 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 01:26:29 INFO - Return code: 0 01:26:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:26:29 INFO - https://queue.taskcluster.net/v1/task/GXHMd--YTjOKyt7o2H8C-A/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 01:26:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GXHMd--YTjOKyt7o2H8C-A/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip 01:26:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GXHMd--YTjOKyt7o2H8C-A/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip 01:26:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GXHMd--YTjOKyt7o2H8C-A/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip 01:26:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GXHMd--YTjOKyt7o2H8C-A/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip'}, attempt #1 01:26:30 INFO - Downloaded 10840145 bytes. 01:26:30 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 01:26:30 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 01:26:30 INFO - Return code: 0 01:26:30 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')]} 01:26:30 INFO - https://queue.taskcluster.net/v1/task/GXHMd--YTjOKyt7o2H8C-A/artifacts/public/build/firefox-48.0.en-US.mac.dmg matches https://queue.taskcluster.net 01:26:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GXHMd--YTjOKyt7o2H8C-A/artifacts/public/build/firefox-48.0.en-US.mac.dmg 01:26:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GXHMd--YTjOKyt7o2H8C-A/artifacts/public/build/firefox-48.0.en-US.mac.dmg 01:26:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GXHMd--YTjOKyt7o2H8C-A/artifacts/public/build/firefox-48.0.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0.en-US.mac.dmg 01:26:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GXHMd--YTjOKyt7o2H8C-A/artifacts/public/build/firefox-48.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.mac.dmg'}, attempt #1 01:26:37 INFO - Downloaded 86251417 bytes. 01:26:37 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GXHMd--YTjOKyt7o2H8C-A/artifacts/public/build/firefox-48.0.en-US.mac.dmg 01:26:37 INFO - mkdir: /builds/slave/test/properties 01:26:37 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:26:37 INFO - Writing to file /builds/slave/test/properties/build_url 01:26:37 INFO - Contents: 01:26:37 INFO - build_url:https://queue.taskcluster.net/v1/task/GXHMd--YTjOKyt7o2H8C-A/artifacts/public/build/firefox-48.0.en-US.mac.dmg 01:26:39 INFO - Running post-action listener: _resource_record_post_action 01:26:39 INFO - Running post-action listener: set_extra_try_arguments 01:26:39 INFO - ##### 01:26:39 INFO - ##### Running populate-webroot step. 01:26:39 INFO - ##### 01:26:39 INFO - Running pre-action listener: _resource_record_pre_action 01:26:39 INFO - Running main action method: populate_webroot 01:26:39 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:26:39 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:26:39 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:26:39 INFO - '--tppagecycles', 01:26:39 INFO - '7'], 01:26:39 INFO - 'tests': ['tsvgm']}}, 01:26:39 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 01:26:39 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:26:39 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 01:26:39 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 01:26:39 INFO - 'tests': ['dromaeo_css', 'kraken']}, 01:26:39 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 01:26:39 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:26:39 INFO - 'talos_options': ['--disable-e10s'], 01:26:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:26:39 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:26:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:26:39 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:26:39 INFO - 'talos_options': ['--disable-e10s'], 01:26:39 INFO - 'tests': ['damp', 'tps']}, 01:26:39 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:26:39 INFO - 'tests': ['damp', 'tps']}, 01:26:39 INFO - 'g3': {'talos_options': ['--disable-e10s'], 01:26:39 INFO - 'tests': ['dromaeo_dom']}, 01:26:39 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:26:39 INFO - 'other': {'talos_options': ['--disable-e10s'], 01:26:39 INFO - 'tests': ['a11yr', 01:26:39 INFO - 'ts_paint', 01:26:39 INFO - 'tpaint', 01:26:39 INFO - 'sessionrestore', 01:26:39 INFO - 'sessionrestore_no_auto_restore', 01:26:39 INFO - 'tabpaint']}, 01:26:39 INFO - 'other-e10s': {'tests': ['a11yr', 01:26:39 INFO - 'ts_paint', 01:26:39 INFO - 'tpaint', 01:26:39 INFO - 'sessionrestore', 01:26:39 INFO - 'sessionrestore_no_auto_restore', 01:26:39 INFO - 'tabpaint']}, 01:26:39 INFO - 'other-e10s_l64': {'tests': ['a11yr', 01:26:39 INFO - 'ts_paint', 01:26:39 INFO - 'tpaint', 01:26:39 INFO - 'sessionrestore', 01:26:39 INFO - 'sessionrestore_no_auto_restore', 01:26:39 INFO - 'tabpaint']}, 01:26:39 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 01:26:39 INFO - 'ts_paint', 01:26:39 INFO - 'tpaint', 01:26:39 INFO - 'sessionrestore', 01:26:39 INFO - 'sessionrestore_no_auto_restore', 01:26:39 INFO - 'tabpaint']}, 01:26:39 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 01:26:39 INFO - 'tests': ['a11yr', 01:26:39 INFO - 'ts_paint', 01:26:39 INFO - 'tpaint', 01:26:39 INFO - 'sessionrestore', 01:26:39 INFO - 'sessionrestore_no_auto_restore', 01:26:39 INFO - 'tabpaint']}, 01:26:39 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 01:26:39 INFO - 'tests': ['a11yr', 01:26:39 INFO - 'ts_paint', 01:26:39 INFO - 'tpaint', 01:26:39 INFO - 'sessionrestore', 01:26:39 INFO - 'sessionrestore_no_auto_restore', 01:26:39 INFO - 'tabpaint']}, 01:26:39 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 01:26:39 INFO - 'tests': ['tsvgx', 01:26:39 INFO - 'tsvgr_opacity', 01:26:39 INFO - 'tart', 01:26:39 INFO - 'tscrollx', 01:26:39 INFO - 'cart']}, 01:26:39 INFO - 'svgr-e10s': {'tests': ['tsvgx', 01:26:39 INFO - 'tsvgr_opacity', 01:26:39 INFO - 'tart', 01:26:39 INFO - 'tscrollx', 01:26:39 INFO - 'cart']}, 01:26:39 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:26:39 INFO - 'talos_options': ['--disable-e10s'], 01:26:39 INFO - 'tests': ['tp5o']}, 01:26:39 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:26:39 INFO - 'tests': ['tp5o']}, 01:26:39 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:26:39 INFO - 'talos_options': ['--disable-e10s', 01:26:39 INFO - '--xperf_path', 01:26:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:26:39 INFO - 'tests': ['tp5n']}, 01:26:39 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:26:39 INFO - 'talos_options': ['--xperf_path', 01:26:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:26:39 INFO - 'tests': ['tp5n']}}, 01:26:39 INFO - 'talos.zip': {'path': '', 01:26:39 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:26:39 INFO - Running post-action listener: _resource_record_post_action 01:26:39 INFO - ##### 01:26:39 INFO - ##### Running create-virtualenv step. 01:26:39 INFO - ##### 01:26:39 INFO - Running pre-action listener: _resource_record_pre_action 01:26:39 INFO - Running main action method: create_virtualenv 01:26:39 INFO - Creating virtualenv /builds/slave/test/build/venv 01:26:39 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 01:26:39 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 01:26:39 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:26:39 INFO - Using real prefix '/tools/python27' 01:26:39 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:26:40 INFO - Installing distribute.............................................................................................................................................................................................done. 01:26:43 INFO - Installing pip.................done. 01:26:43 INFO - Return code: 0 01:26:43 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 01:26:43 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')]} 01:26:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:26:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:26:43 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')]} 01:26:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:26:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:26:43 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 0x10cd83a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd93420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3934a4700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc22e00>, '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 0x10ca8bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc1db20>, '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.Ah25J24ptz/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.Ll9LRXdiJb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/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 01:26:43 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 01:26:43 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 01:26:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ah25J24ptz/Render', 01:26:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:26:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:26:43 INFO - 'HOME': '/Users/cltbld', 01:26:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:26:43 INFO - 'LOGNAME': 'cltbld', 01:26:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:26:43 INFO - 'MOZ_NO_REMOTE': '1', 01:26:43 INFO - 'NO_EM_RESTART': '1', 01:26:43 INFO - 'PAGER': '/bin/cat', 01:26:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:26:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:26:43 INFO - 'PWD': '/builds/slave/test', 01:26:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:26:43 INFO - 'SHELL': '/bin/bash', 01:26:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ll9LRXdiJb/Listeners', 01:26:43 INFO - 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/', 01:26:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:26:43 INFO - 'USER': 'cltbld', 01:26:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:26:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:26:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:26:43 INFO - 'XPC_FLAGS': '0x0', 01:26:43 INFO - 'XPC_SERVICE_NAME': '0', 01:26:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:26:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:26:43 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 01:26:43 INFO - Cleaning up... 01:26:43 INFO - Return code: 0 01:26:43 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 01:26:43 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')]} 01:26:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:26:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:26:43 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')]} 01:26:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:26:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:26:43 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>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cd83a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd93420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3934a4700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc22e00>, '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 0x10ca8bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc1db20>, '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.Ah25J24ptz/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.Ll9LRXdiJb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/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 01:26:43 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>=0.7.1'] in /builds/slave/test/build 01:26:43 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>=0.7.1 01:26:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ah25J24ptz/Render', 01:26:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:26:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:26:43 INFO - 'HOME': '/Users/cltbld', 01:26:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:26:43 INFO - 'LOGNAME': 'cltbld', 01:26:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:26:43 INFO - 'MOZ_NO_REMOTE': '1', 01:26:43 INFO - 'NO_EM_RESTART': '1', 01:26:43 INFO - 'PAGER': '/bin/cat', 01:26:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:26:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:26:43 INFO - 'PWD': '/builds/slave/test', 01:26:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:26:43 INFO - 'SHELL': '/bin/bash', 01:26:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ll9LRXdiJb/Listeners', 01:26:43 INFO - 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/', 01:26:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:26:43 INFO - 'USER': 'cltbld', 01:26:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:26:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:26:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:26:43 INFO - 'XPC_FLAGS': '0x0', 01:26:43 INFO - 'XPC_SERVICE_NAME': '0', 01:26:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:26:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:26:43 INFO - Downloading/unpacking psutil>=0.7.1 01:26:43 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 01:26:43 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 01:26:43 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 01:26:43 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 01:26:43 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 01:26:43 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 01:26:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:26:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:26:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:26:46 INFO - Installing collected packages: psutil 01:26:46 INFO - Running setup.py install for psutil 01:26:46 INFO - building 'psutil._psutil_osx' extension 01:26:46 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 01:26:46 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 01:26:46 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 01:26:46 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 01:26:46 INFO - building 'psutil._psutil_posix' extension 01:26:46 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 01:26:46 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 01:26:46 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 01:26:46 INFO - ^ 01:26:47 INFO - 1 warning generated. 01:26:47 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 01:26:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:26:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:26:47 INFO - Successfully installed psutil 01:26:47 INFO - Cleaning up... 01:26:47 INFO - Return code: 0 01:26:47 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 01:26:47 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')]} 01:26:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:26:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:26:47 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')]} 01:26:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:26:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:26:47 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.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cd83a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd93420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3934a4700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc22e00>, '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 0x10ca8bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc1db20>, '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.Ah25J24ptz/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.Ll9LRXdiJb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/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 01:26:47 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.0.0'] in /builds/slave/test/build 01:26:47 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.0.0 01:26:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ah25J24ptz/Render', 01:26:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:26:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:26:47 INFO - 'HOME': '/Users/cltbld', 01:26:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:26:47 INFO - 'LOGNAME': 'cltbld', 01:26:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:26:47 INFO - 'MOZ_NO_REMOTE': '1', 01:26:47 INFO - 'NO_EM_RESTART': '1', 01:26:47 INFO - 'PAGER': '/bin/cat', 01:26:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:26:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:26:47 INFO - 'PWD': '/builds/slave/test', 01:26:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:26:47 INFO - 'SHELL': '/bin/bash', 01:26:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ll9LRXdiJb/Listeners', 01:26:47 INFO - 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/', 01:26:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:26:47 INFO - 'USER': 'cltbld', 01:26:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:26:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:26:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:26:47 INFO - 'XPC_FLAGS': '0x0', 01:26:47 INFO - 'XPC_SERVICE_NAME': '0', 01:26:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:26:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:26:47 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 01:26: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 01:26: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 01:26: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 01:26: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 01:26: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 01:26: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 01:26:49 INFO - Downloading mozsystemmonitor-0.0.tar.gz 01:26:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:26:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 01:26:50 INFO - Installing collected packages: mozsystemmonitor 01:26:50 INFO - Running setup.py install for mozsystemmonitor 01:26:50 INFO - Successfully installed mozsystemmonitor 01:26:50 INFO - Cleaning up... 01:26:50 INFO - Return code: 0 01:26:50 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 01:26:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:26:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:26:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:26:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:26:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:26:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:26:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10cd83a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd93420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3934a4700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc22e00>, '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 0x10ca8bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc1db20>, '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.Ah25J24ptz/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.Ll9LRXdiJb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/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 01:26:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 01:26:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 01:26:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ah25J24ptz/Render', 01:26:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:26:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:26:50 INFO - 'HOME': '/Users/cltbld', 01:26:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:26:50 INFO - 'LOGNAME': 'cltbld', 01:26:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:26:50 INFO - 'MOZ_NO_REMOTE': '1', 01:26:50 INFO - 'NO_EM_RESTART': '1', 01:26:50 INFO - 'PAGER': '/bin/cat', 01:26:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:26:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:26:50 INFO - 'PWD': '/builds/slave/test', 01:26:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:26:50 INFO - 'SHELL': '/bin/bash', 01:26:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ll9LRXdiJb/Listeners', 01:26:50 INFO - 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/', 01:26:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:26:50 INFO - 'USER': 'cltbld', 01:26:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:26:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:26:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:26:50 INFO - 'XPC_FLAGS': '0x0', 01:26:50 INFO - 'XPC_SERVICE_NAME': '0', 01:26:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:26:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:26:50 INFO - Downloading/unpacking blobuploader==1.2.4 01:26:50 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 01:26:50 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 01:26:50 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 01:26:50 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 01:26:50 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 01:26:50 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 01:26:52 INFO - Downloading blobuploader-1.2.4.tar.gz 01:26:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:26:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:26:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:26:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:26:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:26:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:26:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:26:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:26:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:26:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:26:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:26:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:26:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:26:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:26:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:26:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:26:54 INFO - Downloading docopt-0.6.1.tar.gz 01:26:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:26:54 INFO - Installing collected packages: blobuploader, requests, docopt 01:26:54 INFO - Running setup.py install for blobuploader 01:26:54 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:26:54 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:26:54 INFO - Running setup.py install for requests 01:26:54 INFO - Running setup.py install for docopt 01:26:54 INFO - Successfully installed blobuploader requests docopt 01:26:54 INFO - Cleaning up... 01:26:55 INFO - Return code: 0 01:26:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:26:55 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')]} 01:26:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:26:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:26:55 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')]} 01:26:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:26:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:26:55 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 0x10cd83a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd93420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3934a4700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc22e00>, '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 0x10ca8bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc1db20>, '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.Ah25J24ptz/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.Ll9LRXdiJb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/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 01:26:55 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 01:26:55 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 01:26:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ah25J24ptz/Render', 01:26:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:26:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:26:55 INFO - 'HOME': '/Users/cltbld', 01:26:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:26:55 INFO - 'LOGNAME': 'cltbld', 01:26:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:26:55 INFO - 'MOZ_NO_REMOTE': '1', 01:26:55 INFO - 'NO_EM_RESTART': '1', 01:26:55 INFO - 'PAGER': '/bin/cat', 01:26:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:26:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:26:55 INFO - 'PWD': '/builds/slave/test', 01:26:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:26:55 INFO - 'SHELL': '/bin/bash', 01:26:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ll9LRXdiJb/Listeners', 01:26:55 INFO - 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/', 01:26:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:26:55 INFO - 'USER': 'cltbld', 01:26:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:26:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:26:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:26:55 INFO - 'XPC_FLAGS': '0x0', 01:26:55 INFO - 'XPC_SERVICE_NAME': '0', 01:26:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:26:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:26:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:26:55 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-TBovoQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:26:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:26:55 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-wEJ5AN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:26:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:26:55 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-N3F6bJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:26:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:26:55 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-qxDEBP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:26:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:26:55 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-Vlw7zW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:26:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:26:55 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-pANt5t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:26:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:26:55 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-ux1j8K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:26:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:26:56 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-4MRMuD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:26:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:26:56 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-h5WEih-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:26:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:26:56 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-82b954-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:26:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:26:56 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-ZF7eZz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:26:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:26:56 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-P_J_KD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:26:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:26:56 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-Yx58JO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:26:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:26:56 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-VH9dBo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:26:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:26:56 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-kGBA98-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:26:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:26:56 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-bXv0KX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:26:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:26:56 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-OzMbSI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:26:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:26:57 INFO - Running setup.py install for manifestparser 01:26:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:26:57 INFO - Running setup.py install for mozcrash 01:26:57 INFO - Running setup.py install for mozdebug 01:26:57 INFO - Running setup.py install for mozdevice 01:26:57 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 01:26:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:26:57 INFO - Running setup.py install for mozfile 01:26:57 INFO - Running setup.py install for mozhttpd 01:26:57 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:26:57 INFO - Running setup.py install for mozinfo 01:26:58 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:26:58 INFO - Running setup.py install for mozInstall 01:26:58 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:26:58 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:26:58 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:26:58 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:26:58 INFO - Running setup.py install for mozleak 01:26:58 INFO - Running setup.py install for mozlog 01:26:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:26:58 INFO - Running setup.py install for moznetwork 01:26:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:26:58 INFO - Running setup.py install for mozprocess 01:26:58 INFO - Running setup.py install for mozprofile 01:26:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:26:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:26:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:26:59 INFO - Running setup.py install for mozrunner 01:26:59 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:26:59 INFO - Running setup.py install for mozscreenshot 01:26:59 INFO - Running setup.py install for moztest 01:26:59 INFO - Running setup.py install for mozversion 01:26:59 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:26:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:26:59 INFO - Cleaning up... 01:26:59 INFO - Return code: 0 01:26:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:26: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')]} 01:26:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:26:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:26: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')]} 01:26:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:26:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:26:59 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 0x10cd83a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd93420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3934a4700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc22e00>, '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 0x10ca8bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc1db20>, '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.Ah25J24ptz/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.Ll9LRXdiJb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/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 01:26:59 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 01:26:59 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 01:26:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ah25J24ptz/Render', 01:26:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:26:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:26:59 INFO - 'HOME': '/Users/cltbld', 01:26:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:26:59 INFO - 'LOGNAME': 'cltbld', 01:26:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:26:59 INFO - 'MOZ_NO_REMOTE': '1', 01:26:59 INFO - 'NO_EM_RESTART': '1', 01:26:59 INFO - 'PAGER': '/bin/cat', 01:26:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:26:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:26:59 INFO - 'PWD': '/builds/slave/test', 01:26:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:26:59 INFO - 'SHELL': '/bin/bash', 01:26:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ll9LRXdiJb/Listeners', 01:26:59 INFO - 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/', 01:26:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:26:59 INFO - 'USER': 'cltbld', 01:26:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:26:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:26:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:26:59 INFO - 'XPC_FLAGS': '0x0', 01:26:59 INFO - 'XPC_SERVICE_NAME': '0', 01:26:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:26:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:26:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:26:59 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-u_GXWu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:27:00 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)) 01:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:27:00 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-rIHPm4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:27:00 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)) 01:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:27:00 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-nT3WL0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:27:00 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)) 01:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:27:00 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-M2UY3T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:27:00 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)) 01:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:27:00 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-BWXctd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:27:00 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)) 01:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:27:00 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-No0o3O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:27:00 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)) 01:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:27:00 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-i5TUsK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:27:00 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)) 01:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:27:00 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-tcSpD0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:27:00 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)) 01:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:27:00 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-jUHvfk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:27:00 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)) 01:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:27:00 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-gAOxrg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:27:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 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)) 01:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:27:00 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-ujg6DB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:27:01 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)) 01:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:27:01 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-WUljQu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:27:01 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)) 01:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:27:01 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-IGeKpR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:27:01 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)) 01:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:27:01 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-_jP6bR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:27:01 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)) 01:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:27:01 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-FsSaRH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:27:01 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)) 01:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:27:01 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-ZRfQ9N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:27:01 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)) 01:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:27:01 INFO - Running setup.py (path:/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/pip-dcrH2X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:27:01 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)) 01:27:01 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)) 01:27:01 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)) 01:27:01 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)) 01:27:01 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)) 01:27:01 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)) 01:27:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:27:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:27:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:27:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:27:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:27:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:27:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:27:04 INFO - Downloading blessings-1.6.tar.gz 01:27:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:27:04 INFO - Installing collected packages: blessings 01:27:04 INFO - Running setup.py install for blessings 01:27:04 INFO - Successfully installed blessings 01:27:04 INFO - Cleaning up... 01:27:04 INFO - Return code: 0 01:27:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:27:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:27:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:27:04 INFO - Reading from file tmpfile_stdout 01:27:04 INFO - Current package versions: 01:27:04 INFO - blessings == 1.6 01:27:04 INFO - blobuploader == 1.2.4 01:27:04 INFO - docopt == 0.6.1 01:27:04 INFO - manifestparser == 1.1 01:27:04 INFO - mozInstall == 1.12 01:27:04 INFO - mozcrash == 0.17 01:27:04 INFO - mozdebug == 0.1 01:27:04 INFO - mozdevice == 0.48 01:27:04 INFO - mozfile == 1.2 01:27:04 INFO - mozhttpd == 0.7 01:27:04 INFO - mozinfo == 0.9 01:27:04 INFO - mozleak == 0.1 01:27:04 INFO - mozlog == 3.1 01:27:04 INFO - moznetwork == 0.27 01:27:04 INFO - mozprocess == 0.22 01:27:04 INFO - mozprofile == 0.28 01:27:04 INFO - mozrunner == 6.11 01:27:04 INFO - mozscreenshot == 0.1 01:27:04 INFO - mozsystemmonitor == 0.0 01:27:04 INFO - moztest == 0.7 01:27:04 INFO - mozversion == 1.4 01:27:04 INFO - psutil == 3.1.1 01:27:04 INFO - requests == 1.2.3 01:27:04 INFO - wsgiref == 0.1.2 01:27:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:27:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:27:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:27:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:27:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:27:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27:04 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 0x10cd83a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd93420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3934a4700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc22e00>, '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 0x10ca8bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc1db20>, '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.Ah25J24ptz/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.Ll9LRXdiJb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/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 01:27:04 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 01:27:04 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 01:27:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ah25J24ptz/Render', 01:27:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:27:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:27:04 INFO - 'HOME': '/Users/cltbld', 01:27:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:27:04 INFO - 'LOGNAME': 'cltbld', 01:27:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:27:04 INFO - 'MOZ_NO_REMOTE': '1', 01:27:04 INFO - 'NO_EM_RESTART': '1', 01:27:04 INFO - 'PAGER': '/bin/cat', 01:27:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:27:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:27:04 INFO - 'PWD': '/builds/slave/test', 01:27:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:27:04 INFO - 'SHELL': '/bin/bash', 01:27:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ll9LRXdiJb/Listeners', 01:27:04 INFO - 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/', 01:27:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:27:04 INFO - 'USER': 'cltbld', 01:27:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:27:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:27:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:27:04 INFO - 'XPC_FLAGS': '0x0', 01:27:04 INFO - 'XPC_SERVICE_NAME': '0', 01:27:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:27:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:27:05 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)) 01:27:05 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)) 01:27:05 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)) 01:27:05 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)) 01:27:05 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)) 01:27:05 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)) 01:27:05 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)) 01:27:05 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)) 01:27:05 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)) 01:27:05 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)) 01:27:05 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)) 01:27:05 INFO - Cleaning up... 01:27:05 INFO - Return code: 0 01:27:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 01:27:05 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')]} 01:27:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:27:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27:05 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')]} 01:27:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:27:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27:05 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 0x10cd83a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd93420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3934a4700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc22e00>, '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 0x10ca8bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc1db20>, '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.Ah25J24ptz/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.Ll9LRXdiJb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/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 01:27:05 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 01:27:05 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 01:27:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ah25J24ptz/Render', 01:27:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:27:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:27:05 INFO - 'HOME': '/Users/cltbld', 01:27:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:27:05 INFO - 'LOGNAME': 'cltbld', 01:27:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:27:05 INFO - 'MOZ_NO_REMOTE': '1', 01:27:05 INFO - 'NO_EM_RESTART': '1', 01:27:05 INFO - 'PAGER': '/bin/cat', 01:27:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:27:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:27:05 INFO - 'PWD': '/builds/slave/test', 01:27:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:27:05 INFO - 'SHELL': '/bin/bash', 01:27:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ll9LRXdiJb/Listeners', 01:27:05 INFO - 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/', 01:27:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:27:05 INFO - 'USER': 'cltbld', 01:27:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:27:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:27:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:27:05 INFO - 'XPC_FLAGS': '0x0', 01:27:05 INFO - 'XPC_SERVICE_NAME': '0', 01:27:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:27:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:27:05 INFO - Downloading/unpacking jsonschema 01:27: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 01:27: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 01:27: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 01:27: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 01:27: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 01:27: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 01:27:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:27:07 INFO - Downloading/unpacking functools32 (from jsonschema) 01:27:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:27:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:27:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:27:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:27:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:27:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:27:08 INFO - Downloading functools32-3.2.3-2.tar.gz 01:27:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 01:27:08 INFO - warning: no files found matching '*.txt' 01:27:08 INFO - no previously-included directories found matching 'build' 01:27:08 INFO - no previously-included directories found matching 'dist' 01:27:08 INFO - no previously-included directories found matching '.git*' 01:27:08 INFO - Installing collected packages: jsonschema, functools32 01:27:08 INFO - Running setup.py install for functools32 01:27:08 INFO - warning: no files found matching '*.txt' 01:27:08 INFO - no previously-included directories found matching 'build' 01:27:08 INFO - no previously-included directories found matching 'dist' 01:27:08 INFO - no previously-included directories found matching '.git*' 01:27:08 INFO - Successfully installed jsonschema functools32 01:27:08 INFO - Cleaning up... 01:27:08 INFO - Return code: 0 01:27:08 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 01:27:08 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')]} 01:27:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:27:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27:08 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')]} 01:27:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:27:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27:08 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 0x10cd83a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd93420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3934a4700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc22e00>, '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 0x10ca8bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc1db20>, '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.Ah25J24ptz/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.Ll9LRXdiJb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/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 01:27:08 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 01:27:08 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 01:27:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ah25J24ptz/Render', 01:27:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:27:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:27:08 INFO - 'HOME': '/Users/cltbld', 01:27:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:27:08 INFO - 'LOGNAME': 'cltbld', 01:27:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:27:08 INFO - 'MOZ_NO_REMOTE': '1', 01:27:08 INFO - 'NO_EM_RESTART': '1', 01:27:08 INFO - 'PAGER': '/bin/cat', 01:27:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:27:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:27:08 INFO - 'PWD': '/builds/slave/test', 01:27:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:27:08 INFO - 'SHELL': '/bin/bash', 01:27:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ll9LRXdiJb/Listeners', 01:27:08 INFO - 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/', 01:27:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:27:08 INFO - 'USER': 'cltbld', 01:27:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:27:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:27:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:27:08 INFO - 'XPC_FLAGS': '0x0', 01:27:08 INFO - 'XPC_SERVICE_NAME': '0', 01:27:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:27:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:27:08 INFO - Downloading/unpacking flake8 01:27:08 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 01:27:08 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 01:27:08 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 01:27:08 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 01:27:08 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 01:27:08 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 01:27:11 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 01:27:11 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 01:27:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:27:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:27:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:27:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:27:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:27:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:27:11 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 01:27:11 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 01:27:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:27:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:27:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:27:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:27:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:27:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:27:11 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 01:27:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:27:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:27:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:27:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:27:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:27:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:27:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 01:27:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 01:27:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 01:27:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 01:27:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 01:27:12 INFO - no previously-included directories found matching 'docs/_build' 01:27:12 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 01:27:12 INFO - Running setup.py install for pep8 01:27:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 01:27:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 01:27:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 01:27:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 01:27:12 INFO - no previously-included directories found matching 'docs/_build' 01:27:12 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 01:27:12 INFO - Successfully installed flake8 mccabe pyflakes pep8 01:27:12 INFO - Cleaning up... 01:27:12 INFO - Return code: 0 01:27:12 INFO - Running post-action listener: _resource_record_post_action 01:27:12 INFO - Running post-action listener: _start_resource_monitoring 01:27:12 INFO - Starting resource monitoring. 01:27:12 INFO - ##### 01:27:12 INFO - ##### Running install step. 01:27:12 INFO - ##### 01:27:12 INFO - Running pre-action listener: _resource_record_pre_action 01:27:12 INFO - Running main action method: install 01:27:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:27:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:27:13 INFO - Reading from file tmpfile_stdout 01:27:13 INFO - Detecting whether we're running mozinstall >=1.0... 01:27:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:27:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:27:13 INFO - Reading from file tmpfile_stdout 01:27:13 INFO - Output received: 01:27:13 INFO - Usage: mozinstall [options] installer 01:27:13 INFO - Options: 01:27:13 INFO - -h, --help show this help message and exit 01:27:13 INFO - -d DEST, --destination=DEST 01:27:13 INFO - Directory to install application into. [default: 01:27:13 INFO - "/builds/slave/test"] 01:27:13 INFO - --app=APP Application being installed. [default: firefox] 01:27:13 INFO - mkdir: /builds/slave/test/build/application 01:27:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 01:27:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0.en-US.mac.dmg --destination /builds/slave/test/build/application 01:27:34 INFO - Reading from file tmpfile_stdout 01:27:34 INFO - Output received: 01:27:34 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 01:27:34 INFO - Running post-action listener: _resource_record_post_action 01:27:34 INFO - ##### 01:27:34 INFO - ##### Running run-tests step. 01:27:34 INFO - ##### 01:27:34 INFO - Running pre-action listener: _resource_record_pre_action 01:27:34 INFO - Running main action method: run_tests 01:27:34 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:27:34 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:27:34 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:27:34 INFO - 2016-06-21 01:27:34.852 screenresolution[1925:13653] starting screenresolution argv=screenresolution get 01:27:34 INFO - 2016-06-21 01:27:34.854 screenresolution[1925:13653] Display 0: 1600x1200x32@60 01:27:34 INFO - 2016-06-21 01:27:34.860 screenresolution[1926:13656] starting screenresolution argv=screenresolution list 01:27:34 INFO - Available Modes on Display 0 01:27:34 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:27:34 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:27:34 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:27:34 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:27:35 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:27:35 INFO - Intel Iris: 01:27:35 INFO - Chipset Model: Intel Iris 01:27:35 INFO - Type: GPU 01:27:35 INFO - Bus: Built-In 01:27:35 INFO - VRAM (Dynamic, Max): 1536 MB 01:27:35 INFO - Vendor: Intel (0x8086) 01:27:35 INFO - Device ID: 0x0a2e 01:27:35 INFO - Revision ID: 0x0009 01:27:35 INFO - Displays: 01:27:35 INFO - Display: 01:27:35 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:27:35 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:27:35 INFO - Display Serial Number: 1600x1200 60 01:27:35 INFO - Main Display: Yes 01:27:35 INFO - Mirror: Off 01:27:35 INFO - Online: Yes 01:27:35 INFO - Rotation: Supported 01:27:35 INFO - Return code: 0 01:27:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 01:27:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 01:27:35 INFO - Python 2.7.3 01:27:35 INFO - Return code: 0 01:27:35 INFO - grabbing minidump binary from tooltool 01:27:35 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')]} 01:27:35 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: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc22e00>, '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 0x10ca8bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc1db20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:27:35 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 01:27:35 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 01:27:35 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:27:35 INFO - Return code: 0 01:27:35 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 01:27:35 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 01:27:35 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 01:27:35 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 01:27:35 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 01:27:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 01:27:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 01:27:36 INFO - Return code: 0 01:27:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GXHMd--YTjOKyt7o2H8C-A/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0369', '--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 01:27:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/GXHMd--YTjOKyt7o2H8C-A/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0369 --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 01:27:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ah25J24ptz/Render', 01:27:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:27:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:27:36 INFO - 'HOME': '/Users/cltbld', 01:27:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:27:36 INFO - 'LOGNAME': 'cltbld', 01:27:36 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:27:36 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 01:27:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:27:36 INFO - 'MOZ_NO_REMOTE': '1', 01:27:36 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:27:36 INFO - 'NO_EM_RESTART': '1', 01:27:36 INFO - 'PAGER': '/bin/cat', 01:27:36 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:27:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:27:36 INFO - 'PWD': '/builds/slave/test', 01:27:36 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:27:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:27:36 INFO - 'SHELL': '/bin/bash', 01:27:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ll9LRXdiJb/Listeners', 01:27:36 INFO - 'TMPDIR': '/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/', 01:27:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:27:36 INFO - 'USER': 'cltbld', 01:27:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:27:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:27:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:27:36 INFO - 'XPC_FLAGS': '0x0', 01:27:36 INFO - 'XPC_SERVICE_NAME': '0', 01:27:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:27:36 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GXHMd--YTjOKyt7o2H8C-A/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0369', '--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 01:27:36 INFO - mozversion application_buildid: 20160621000402 01:27:36 INFO - mozversion application_changeset: 634aedddd904cf6b9004590dbc2f5491fe4474ff 01:27:36 INFO - mozversion application_display_name: Firefox 01:27:36 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:27:36 INFO - mozversion application_name: Firefox 01:27:36 INFO - mozversion application_remotingname: firefox 01:27:36 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 01:27:36 INFO - mozversion application_vendor: Mozilla 01:27:36 INFO - mozversion application_version: 48.0 01:27:36 INFO - mozversion platform_buildid: 20160621000402 01:27:36 INFO - mozversion platform_changeset: 634aedddd904cf6b9004590dbc2f5491fe4474ff 01:27:36 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 01:27:36 INFO - mozversion platform_version: 48.0 01:27:36 INFO - using testdate: 1466497656 01:27:36 INFO - actual date: 1466497656 01:27:36 INFO - starting webserver on 'localhost:51429' 01:27:36 INFO - SUITE-START | Running 2 tests 01:27:36 INFO - TEST-START | dromaeo_css 01:27:36 INFO - operating with platform_type : mac_ 01:27:36 INFO - Initialising browser for dromaeo_css test... 01:27:36 INFO - TEST-INFO | started process 1944 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/tmpZsrnBK/profile http://localhost:51429/getInfo.html) 01:27:38 INFO - PROCESS | 1944 | __metrics Screen width/height:1600/1200 01:27:38 INFO - PROCESS | 1944 | colorDepth:24 01:27:38 INFO - PROCESS | 1944 | Browser inner width/height: 1024/689 01:27:38 INFO - PROCESS | 1944 | __metrics 01:27:38 INFO - PROCESS | 1944 | 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" 01:27:38 ERROR - PROCESS | 1944 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 01:27:39 INFO - TEST-INFO | 1944: exit 0 01:27:39 INFO - Browser initialized. 01:27:39 INFO - Running cycle 1/1 for dromaeo_css test... 01:27:39 INFO - TEST-INFO | started process 1947 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/tmpZsrnBK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:27:51 INFO - PROCESS | 1947 | RSS: Main: 161304576 01:27:51 INFO - PROCESS | 1947 | 01:28:42 INFO - PROCESS | 1947 | 1466497722102 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] 01:30:51 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:51429/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51429/tests/dromaeo/cssquery-ext.html) 01:30:53 INFO - PROCESS | 1947 | RSS: Main: 308703232 01:30:53 INFO - PROCESS | 1947 | 01:33:55 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:51429/tests/dromaeo/cssquery-ext.html (next: http://localhost:51429/tests/dromaeo/cssquery-jquery.html) 01:33:56 INFO - PROCESS | 1947 | RSS: Main: 297041920 01:33:56 INFO - PROCESS | 1947 | 01:36:58 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:51429/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51429/tests/dromaeo/cssquery-mootools.html) 01:36:59 INFO - PROCESS | 1947 | RSS: Main: 298602496 01:36:59 INFO - PROCESS | 1947 | 01:40:01 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:51429/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51429/tests/dromaeo/cssquery-prototype.html) 01:40:02 INFO - PROCESS | 1947 | RSS: Main: 298893312 01:40:02 INFO - PROCESS | 1947 | 01:43:05 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:51429/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51429/tests/dromaeo/cssquery-yui.html) 01:43:05 INFO - PROCESS | 1947 | RSS: Main: 266465280 01:43:05 INFO - PROCESS | 1947 | 01:46:08 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:51429/tests/dromaeo/cssquery-yui.html (next: http://localhost:51429/tests/dromaeo/cssquery-dojo.html) 01:46:08 INFO - PROCESS | 1947 | RSS: Main: 335646720 01:46:08 INFO - PROCESS | 1947 | 01:46:08 INFO - PROCESS | 1947 | __start_tp_report 01:46:08 INFO - PROCESS | 1947 | _x_x_mozilla_page_load 01:46:08 INFO - PROCESS | 1947 | _x_x_mozilla_page_load_details 01:46:08 INFO - PROCESS | 1947 | |i|pagename|runs| 01:46:08 INFO - PROCESS | 1947 | |0;dojo.html;2927.516959701649;3581.5881173665034;3611.729120315977;3612.7109831213506;3678.558572971244;5667.631603945741;5677.43225677432;5679.318481782187;5685.602007859451;5700.828975130742;5701.315842098944;5740.311162660476;5743.770249190027;5748.712564371785;5752.3384810746675;5117.437081920985;5148.433672296044;5164.457731938149;5268.130758424855;5271.894562108756;5180;5231.372235331765;5232.476752324776;5236.528712415882;5252.52727254547;5139.0749665060275;5202.7658755355915;5225.555827754626;5239.869003274911;5280.128778751501;5190.143626301656;5252.290940722992;5267.315249017642;5279.868003299948;5299.8410047698635;5070.29015937769;5083.6441449098575;5086.872828179288;5168.715720635401;5196.168613021936;5473.151661492475;5479.698616576086;5542.002439560877;5545.805896793633;5565.387807341189;6187.969060154715;6231.345708700612;6233.283172435137;6238.656873871935;6243.656598887105;6144.402455361594;6195.535334849904;6268.905966410507;6295.401936815995;6330.778422755227;5793.594448388657;5795.971020144914;5803.303603567514;5835.737391817336;5836.066229403275;6346.174997250328;6350.491960643138;6354.745810167541;6361.9045714314325;6368.331325210879;5949;6044.546659000504;6078.422549857756;6136.846578835475;6138.017917133327;5760.567957403212;6163.8150855474405;6193.225846769154;6194.690265486708;6214.098955651405;6036.63780173191;6103.908441373383;6113.296970848321;6119.938800612026;6142.754289828446;6097.146399504073;6158.630482170995;6168.568200226031;6174.8147555572505;6195.101710252078;4930.827421040318;4968.056069346812;4974.129527332702;4982.128127577732;4982.277569752366;4475.48531918827;4476.597106260387;4497.2804351304285;4502.009557897323;4521.434820647419;5690.658560486301;5786.623869448473;5798.536117110621;5843.649381037151;5891.440313170242;6273.309935907047;6285.43431091204;6296.370362963667;6299.96850015747;6313.494920406357;5854.736536855852;5857.355690874;5858.56060795442;5863.091220860688;5905.527557795452;5279.947200527946;5375.381831089397;5427.592930530147;5433.293671822637;5441.755121019563;6382.425581697669;6417.326180751047;6436.937905245582;6440.484761218998;6444.806655800334;6290.370962903856;6294.181756371826;6309.90535141964;6311.652859092794;6319.842003949754;6242.875142497215;6345;6388.361163883575;6406.775762848278;6408.198975128109;6210.347913469021;6211.720472578654;6211.844703882439;6218.253809542884;6222.408871157233;6238.126662267377;6266.498680105672;6287.842803929939;6288.6855657217875;6319.747210111544;5843.590948633555;5857.9121313179485;5871.207387002871;5934.228550288435;5968;4753.738544380092;6073.058675905242;6074.90887636677;6083.756649733961;6094.969525152346;5771.249737534262;5886.323072846506;5886.676232807065;5895.67573783446;5914.467697907209;5969.253843269591;6014.8195554133445;6086.452219300284;6092.482139018056;6133;5945;5949.613275137102;5982.461578457785;5984.611000284967;5992.64044157352 01:46:08 INFO - PROCESS | 1947 | |1;ext.html;522.4383787428515;557.5177471487162;565.5921014301144;590.889128438536;604.809485012221;23717.407064823386;33586.83206583967;33641.663583364156;33682.83158584207;40976;44584;45021.549784502145;45122;45151.322730159074;45155.77422112893;9616.759581010496;9806.362586431858;9838.65564705232;9840.852387214198;9868.45723485208;9209.309301802357;9395.57719902606;9412.0117387674;9427.24582033439;9626.278029147807;9266.583003764814;11808.704782380466;12097.939510302436;12101.757964840697;12130.878691213064;11684.649460516199;11791.882081179165;11797.410129493492;11798.292102473879;11820;11554.364509951949;11669.358185299805;11694.883051169549;11696.18126731129;11713.238639488405;40797.79601102005;41193.382099268536;41343.7932810337;41371.7931410344;41415.75752727393;3362.1258472797003;3502.807345595991;3527.5237842891393;3541.6812486876047;3926.725129240954;29728.554071688726;29904.55143172854;29905.252368690955;29981;30269.848650756827;2768.6677598688084;2769.8476583787883;2825.6185414968922;2851.900183493568;2854.215090850012;458617;459464.9999999967;459574;459952;460105;66261.66869165623;66735.99896001564;66809.66595166993;66945;67023;67696.32303676999;70099.64950175319;70905;74499.62750186323;75835.62082189663;19104.80895191058;19120.043997800054;19295.90352048259;19317.710234346498;19356.90321548383;14699.706005879823;14776.926115369355;14777.852221477862;14798.852011479963;14806.407743690348;15590.688186236212;18993.81006189948;19148.80851191498;20001.099950502263;21205.78794212038;6747.110808973495;7716.839285000209;8181.936348274805;8426.705065322683;8503.107173746668;994.4828689081637;1026.856240126368;1074.8606477134106;1173.1260211142753;1234.8271242026124;30331.090067298017;31269.8436507816;31823.204419889687;31847.840760796513;32353.35293294121;1129.5312445335094;1178.9646310610694;1195.7070517723214;1203.8314635950974;1282.3203702037936;13503.717146871031;13909.721805564035;15008.549743507712;15091.169985650895;15370.846291537166;675900;678553.6072319705;683211;683583;686968;63771;64590.677046614466;64597.354026459136;64606.67696661487;64642.67678661671;1545.1810540413599;1562.6015366081576;1574.5984773882587;1578.842115788412;1579.312998845506;29571.704282956896;29705.702942970292;30022.64898079635;30533.694663053084;30789.846050769604;55841.72079139578;55971.16043259273;56709.432905670415;56804;56937;511865.44067279424;512067;512437;514125;514131;25079;25644.358891027874;26943.461130776883;28426.85786571054;28831.279218019718;37412.812935936236;45008.549914501746;45119.548804511534;46647.83380415517;46664.7666761664;29451;35310.823445882605;37317;37702.811485942395;38113.61886381212;36458.453123203704;36753.816230918674;36761;37399.626003740705;37527.624723752415;36653.63346366609;37344.81327593453;38503;39337.01657458586;39358.01604959899;27537.724622753514;31918.88283910052;33729.662703372655;33935.32129357448;34091.48862767011;25214.873925630254;25248.947336912024;25328.620070699322;25860.482790344464;25996.8700156498 01:46:08 INFO - PROCESS | 1947 | |2;jquery.html;426.1200620718217;428.6827747466875;429.4717497478102;430.03028171473323;441.0737451352161;20481.385558433234;20595.07322170502;21388.358349249527;21810.563788724216;21837.454063648416;16934.661306773858;20823.583528329462;21943.231986880422;22174.667379989314;22218.777812221873;5925.31858836234;6289.591176573531;6292.7482900684145;6294.937050629504;6301.590396624228;4558.2251017327135;4645.814167433298;4740.810367585293;6089.908651370233;6284.340144284853;6086.421789929949;6886.552374095668;6902.5858448493;6908.3437073477935;6908.965455172717;5820;6531.575447595915;6845.5892646441125;6853.965730171367;6862.176538815324;4076.3885417187657;5705.258316418881;5996.2504686914135;5998.940010599926;6015.097735339734;27046.864765676244;27810;28009.159725208276;28246.435071298663;28254.576181357297;2253.707018087638;2402.3153401280724;2429.2347910408344;2599.8180127391092;3081.953770693419;15180.544583662508;15797.842021579752;16124.435644752495;16265.105419201938;16656.67767931872;1822.4988128264833;1857.1085878778126;1861.385742704904;1879.0698604191014;1893.6307420053095;119984;123540;124492.95788726579;126072;127368;11623.76752464946;11782.175247732748;11820.881791181979;11839.940800296115;11880.990115840077;15219.695606087816;15248.618784530254;15288.77066843976;15294.617634559225;15314.923425382802;9506.762330941781;9526.761830954143;9537.570809313598;9666.903330966741;9671.371360861522;9646.517674116267;9706.611735530641;9752.073553012453;9773.51132443375;9856.112949834405;6499.772507962199;6928.61892595902;7171.318724721144;7322.267773222635;7322.816929576697;1634.3625985865522;1781.885934135064;1871.9719204211947;1959.9706004409945;2008.8192062714427;972.9708108756748;1038.8389799581078;1050.6847945616437;1056.762228498597;1124.4771181400688;21881.234156804436;22596.435089122573;22739.772602273766;22777.430564236027;22823.429414264443;1061.0927656853435;1067.8398240263866;1076.3810808784824;1077.1167642533205;1139.2765593847967;11477.827832582518;11977;12023.699407514707;12038.518459261531;12080.637580872588;632763;645670;651631;674583;676459;124027;130204;130985.34507327402;132985;135075;1000.60976219276;1024.3802499487851;1058.714147180257;1061.4714810672606;1065.4459680965854;16715;19186.904065479583;19923.203071876964;19989.900050499655;19998.90000549988;55818.441815581325;57568.424315757984;58383.124253135386;58415.70792146012;59816.70091649514;83150;83366.16633833584;84096.31807363695;84601;84874.57562712394;8139.552324622203;8458.06961234276;8474.237318641352;8613.48319100856;8869.911300886908;11361.659150225507;13203.933980330037;13340.933295333462;13636;13659.79510307366;11128.944355278172;11643.53425862956;11662.183647144619;11765.588204412805;11775.23460975034;10977.83533246986;11271.436428178395;11462.65612031607;11542.769144617228;11606.419679015846;10077.496125193858;10953.671389858317;11453.427328633368;11869.465874035517;12003.879961200277;8316.708915188181;8363.874541881998;8375.664973400995;8487.533185674847;8773.122687731431;11264.09887209005;11305.604303849326;11421;11497.712557185803;11871.762564748484 01:46:08 INFO - PROCESS | 1947 | |3;mootools.html;1519.9848001519981;1625.3336132185802;1660.2279939827981;1946.3771593090207;2027.7059826325183;7179.246179151192;8204.712835050774;8394.202550757669;8424;8753;8758.605862736176;8807.119288071206;8912.376133670645;8915.598798054087;8933.106689331064;2520.2439268219505;2530.9240722778345;2544.37662772621;2546.261584140597;2549.5155920375164;2485.9005639774387;2554.846709197454;2558.2581051495044;2559.731228221038;2559.9744002560014;2470;2497.9750202497926;2528.582783840673;2529.4055896864224;2531.1014589820625;2128.1061953979365;2495.6007038873877;2529.6584961030208;2532.962005569918;2536.2137737301496;2241.9215327463626;2503.7871780898645;2547.197632745679;2555.0418593027616;2556.0670355320176;8541.017782954978;8879.600417981206;8887.688930887389;8889.644414223423;8942.239909607626;4486.416765820422;5373.731313434313;5432.70120143392;5635.690037047961;5640.5487560995025;3567.8929632111076;3649.9635003649887;3685.5208822853124;3696.094456858087;3710.77735335878;1033.2095946600782;1046.3983209654402;1079.098952374768;1080.935143891369;1081.567373050778;11980.58067967617;12116.999999999913;12128.939355303255;12140.392980351035;12145.514179432812;4707.129181101439;4709.599684026896;4788.976055119771;4845.75771211438;4868.9269660955115;9457;9555.378900371454;9558.187554057844;9579.377340472847;9581.568829402693;5491.066518691752;5542.695151766691;5543.085390910535;5547.5007249346945;5551.555875529948;4621.607163391082;4650.697704649187;4841.733704646278;4938.975305123523;5118.923216151761;3943.881683549498;3968.3452230381663;4024.5170579530645;4066.064805094845;4076.205139997672;1104.536094840169;1224.4489979509078;1254.8431446069242;1263.298869127643;1263.4630282130058;839.6389552492487;844.7202488230336;901.7114523352595;907.1744712311896;941.8540126280437;5934.762609495659;6045.244344456943;6065.150878876961;6126.172966649535;6223.626582405071;848.8896443462311;905.4657751926395;906.1889608800087;908.4549270437684;1007.5843439966858;3994.1612261424843;4011.9799401003393;4059.959400405958;4069.7354671946227;4151.896202594899;10605.734856628646;10964.122870170526;10996.780064398667;11058.11535077192;11103.722406939729;10859.619913302997;10901.727456813485;10910.61812836547;10934.343939363664;10972.725681858017;8202.220789024914;8326.333893288522;8452.74641760748;8475.1524847516;8490.575471226413;5119.6416250862085;5896.262967129109;6019.6990150493175;6036.0644100164545;6127.590654149481;9589.520523973913;9646.9035309646;9761.365511241747;9941.850872236777;9957.900420995988;9635.277354198603;9788.559514821707;9790.020997900438;10009;10118.836333821704;6982;7113.608751518716;7120.643967801486;7578.621068946641;7737.883931740916;7830.2952734254195;8353;8522.019967703545;8577.528235946833;8579.485230886166;7796.4932279401655;7903.9209607903185;7992.920070799218;8087.8382432349845;8120.269175774209;6659.267480577036;7681.385489160742;7683.961580192064;7766.951818150305;7946.125926148004;7159;7165.605891676008;7210.89183662256;7222;7224.169220539705;5132.6150538709;5205.947940520547;5265.447128051499;5340.5994550408095;5369.301990741179;5319.122344813217;5353.705546194841;5432.821799247326;5483.972580137074;5521.420250873601 01:46:08 INFO - PROCESS | 1947 | |4;prototype.html;328.18446161289205;332.828443877551;337.6319811405567;343.7336064549974;346.5391029930193;5198.92201616976;6353.650549219791;6409.26293476251;6461.741530338787;6545.312742162076;6091.878162436759;6157.5689701720785;6315.084312774657;6339.556231063816;6411.916127258648;4787.80848766049;4821.349117869096;4826.541478559531;4836.056968891059;4851.611871050325;4830.516948305177;4836.661433699643;4844.2733589961435;4854.441739200002;4857.198562237227;4648.790804413793;4697.9060418791605;4776.235802271637;4785.425748910136;4809.7835597398025;4774.594159496447;4787.736674482902;4791.880202994918;4792.161371759963;4800.423949126092;3398.3882901077795;4155.439015732898;4177.122804211089;4185.455890734185;4186.706930514865;6165.2601687797305;6169.753209871645;6176.505879529672;6178.752849885999;6202.069689546604;5664.065429204217;5676.233708449347;5679.204911312419;5683.829485115453;5685.545156387479;6647.700853461557;6834.589924604539;6871.793846184622;6887.586744795329;6900.689468973858;4672.345871577982;4687.484376718558;4689.95310046902;4697.812087516495;4704.952950470486;7236.638168091575;7249.855002899965;7253.528520646141;7253.782386528413;7267.236940121317;4752.215884379108;4762.547557981986;4767.523247675204;4782.976085119552;4789.592884604778;3848.634379733921;4040.6363427291685;4815.422149342101;4829.227323628203;4831.227003679394;5478.278198485421;5645.2096706461125;5652.8869422611315;5653.689047102366;5668.461496157858;6864.691088900911;6921.307869213139;6926.861462770717;6936.687849046805;6939.618320992293;1592.3789722008426;1628.7475441306617;1658.6185177409263;1686.325469812085;1687.4768821665077;960.7454024683465;1028.8148133335922;1044.5637248782923;1052.1214785653913;1118.59730497021;5293.655912365684;6477.611343319321;6534.607923524509;6551.574147680385;6594.878870592011;7051.576905385693;7078.186008608973;7093.184283807325;7094.255103214088;7099.858002839915;6540.542162048611;6551.213854337415;6552.967235163889;6556.016597510316;6559.639219842859;3019.788614796987;3635.763675361093;3743.7753734776;3745.700343972476;3781.357169281229;6995.230524642286;7067.540609860343;7584.848303033908;7660.502067365604;7669.808254793675;6656.633885136364;7389.482736208522;7394.926050739531;7400.925990740131;7407.592582407911;6137.938620613826;6449.226092868792;7165.319294666998;7646.3882889368715;7666.19504951972;5732.455416735404;5881.5588830838715;6889.689963951534;7107.2637034740965;7145.714171433085;7065.6113913735235;7119.394851437479;7138.64306784669;7142.821429464305;7144.3570078693165;7670.348020418327;7673.155952845071;7679.078510578543;7681.078270607562;7691.30778229962;4500.549945005604;4546.5226151254965;4556.2937744649635;4559.726416415026;4570.542945705402;6942.965285173542;7165.570065796069;7195.820104497429;7197.064381630355;7201.387882029877;6914;6915.93084069173;6928.099347084847;6929.861402772017;6952.374286314256;6492.415682588589;6924.099867017457;6931.0643063187845;6931.584104953719;6934.826629334307;6762.053312536151;6783;6795.762148324982;6802.557833740791;6806.659667016728;6612.173478315211;6727.93272067273;6950.582965022115;6951;6959.512834101564;6622.768203113061;6664.333566643297;6735.562188457735;6814.727410903508;6837.316268373322 01:46:08 INFO - PROCESS | 1947 | |5;yui.html;712.2343480758185;734.1483878700708;777.2383064596695;778.4161878591057;787.8188016756146;15580;15691.529254122366;15833.287502062392;15848;15853.286602102904;14542.854571454282;14669.559913202622;14685.853141468582;14695.971282010263;14711.779323310158;779.8089468080328;800.4116974024088;944.7118628818208;968.467342961372;972.5526257921365;937.357909831764;954.8090381923608;954.9474778887159;958.836997710391;961;840.6091167607061;1022.2741853284177;1023.52406131149;1025.1849779425338;1028.2185538990384;1002.6941782756256;1023.5803320638503;1025.0262250861674;1026.1482969135675;1027.9434631095287;774.8256642255503;774.9922500774976;775.170567492783;776.4215659333776;776.9976730018269;15255.694886102327;15290.541283761506;15305.846941530664;15309.46416875404;15338.156401397919;13061.412236449383;13092.279924604143;13353.66615834612;13525.39135738894;13667.043306968517;10168.694939151837;10257.230707696877;10416;10617.946910265477;10768.192385571114;3536.734744894143;3563.821808909544;3594.101474631342;3624.3113808376324;3725.925481490382;78390.21609783886;78784.81822772663;83748.58125709393;84642.57678711628;84942;9350.345475816628;9384.530773461436;9391.060893910691;9528.11175203284;10092.798144037079;21882.89058554697;22105.115795368005;22142.889285553792;22166.22418215387;22169.113235470402;7539.585322807188;7722;7841.176676449005;7990.12108668046;7996.360291176692;4246.150769846043;4300.053988122608;4388.29787234041;4394.1651086293505;4592.03567250881;3784.6404591563755;3837.4052021936645;3847.634474724897;3849.3263678856647;3855.845766169322;622.7944778223175;671.452765995712;689.7585844954226;723.7322190789441;736.054170391045;604.7429842316998;606.0817860940679;624.2883113250898;639.402158981354;651.4723074309824;8269.338452923752;8403.70587029451;8544.74365769028;8620.698275560326;8689.217970382695;523.4242333433269;557.5400294756842;575.9107338362561;622.3993845938691;637.3148864970176;3942;3995.880123596297;4081.2653722330265;4113.609207125378;4140.4617399737845;87704.12295877087;93613;96607.51696241474;97000.51499742456;97024.51487742657;79005;79918.61526263002;80066.39867202623;82756.58621706853;86585.83535411666;70618.64690676617;70904.64547677229;71036.64481677559;71797;73852.63073684597;7189.676464559212;8495.235428811437;8649.524276164871;8671.956640216758;8979.50612716307;48808.02383952372;49832.00335993187;49853.01175905846;50334.244986324506;51211.487885120674;67845;67947.66026170035;70646.64676676584;72678.90981635173;72872.63563682148;11080.335179889233;11185.776284474103;11192.216544841784;11275.943620281847;11527.88472115268;12000.819987700366;12038;12092.637220883387;12459;12533.310667912987;10218.335808172445;10862;11262.211645184758;11507.942460287644;11879.524819007143;10935.562577497129;11042.613508527164;11824.1131915105;11948.34284114382;12078.75842483163;11139.387333696724;11650.30098194111;11720.41397930083;11856.881431185579;12188.57339993096;8631;9456.479893605643;9776;10034.69895903124;10370.637027704295;9814.312998090065;10070.748231294276;10192.184625230113;10203.38779673222;10218.080372766486 01:46:08 INFO - PROCESS | 1947 | __end_tp_report 01:46:08 INFO - PROCESS | 1947 | __start_cc_report 01:46:08 INFO - PROCESS | 1947 | _x_x_mozilla_cycle_collect,1410 01:46:08 INFO - PROCESS | 1947 | __end_cc_report 01:46:08 INFO - PROCESS | 1947 | __startTimestamp1466498768881__endTimestamp 01:46:08 INFO - PROCESS | 1947 | 01:46:08 INFO - PROCESS | 1947 | ------- Summary: start ------- 01:46:08 INFO - PROCESS | 1947 | Number of tests: 6 01:46:08 INFO - PROCESS | 1947 | 01:46:08 INFO - PROCESS | 1947 | [#0] dojo.html Cycles:165 Average:5743.41 Median:5895.68 stddev:628.36 (10.7%) stddev-sans-first:590.19 01:46:08 INFO - PROCESS | 1947 | Values: 2927.5 3581.6 3611.7 3612.7 3678.6 5667.6 5677.4 5679.3 5685.6 5700.8 5701.3 5740.3 5743.8 5748.7 5752.3 5117.4 5148.4 5164.5 5268.1 5271.9 5180.0 5231.4 5232.5 5236.5 5252.5 5139.1 5202.8 5225.6 5239.9 5280.1 5190.1 5252.3 5267.3 5279.9 5299.8 5070.3 5083.6 5086.9 5168.7 5196.2 5473.2 5479.7 5542.0 5545.8 5565.4 6188.0 6231.3 6233.3 6238.7 6243.7 6144.4 6195.5 6268.9 6295.4 6330.8 5793.6 5796.0 5803.3 5835.7 5836.1 6346.2 6350.5 6354.7 6361.9 6368.3 5949.0 6044.5 6078.4 6136.8 6138.0 5760.6 6163.8 6193.2 6194.7 6214.1 6036.6 6103.9 6113.3 6119.9 6142.8 6097.1 6158.6 6168.6 6174.8 6195.1 4930.8 4968.1 4974.1 4982.1 4982.3 4475.5 4476.6 4497.3 4502.0 4521.4 5690.7 5786.6 5798.5 5843.6 5891.4 6273.3 6285.4 6296.4 6300.0 6313.5 5854.7 5857.4 5858.6 5863.1 5905.5 5279.9 5375.4 5427.6 5433.3 5441.8 6382.4 6417.3 6436.9 6440.5 6444.8 6290.4 6294.2 6309.9 6311.7 6319.8 6242.9 6345.0 6388.4 6406.8 6408.2 6210.3 6211.7 6211.8 6218.3 6222.4 6238.1 6266.5 6287.8 6288.7 6319.7 5843.6 5857.9 5871.2 5934.2 5968.0 4753.7 6073.1 6074.9 6083.8 6095.0 5771.2 5886.3 5886.7 5895.7 5914.5 5969.3 6014.8 6086.5 6092.5 6133.0 5945.0 5949.6 5982.5 5984.6 5992.6 01:46:08 INFO - PROCESS | 1947 | 01:46:08 INFO - PROCESS | 1947 | [#1] ext.html Cycles:180 Average:69575.01 Median:19419.17 stddev:149451.48 (769.6%) stddev-sans-first:149780.80 01:46:08 INFO - PROCESS | 1947 | Values: 522.4 557.5 565.6 590.9 604.8 23717.4 33586.8 33641.7 33682.8 40976.0 44584.0 45021.5 45122.0 45151.3 45155.8 9616.8 9806.4 9838.7 9840.9 9868.5 9209.3 9395.6 9412.0 9427.2 9626.3 9266.6 11808.7 12097.9 12101.8 12130.9 11684.6 11791.9 11797.4 11798.3 11820.0 11554.4 11669.4 11694.9 11696.2 11713.2 40797.8 41193.4 41343.8 41371.8 41415.8 3362.1 3502.8 3527.5 3541.7 3926.7 29728.6 29904.6 29905.3 29981.0 30269.8 2768.7 2769.8 2825.6 2851.9 2854.2 458617.0 459465.0 459574.0 459952.0 460105.0 66261.7 66736.0 66809.7 66945.0 67023.0 67696.3 70099.6 70905.0 74499.6 75835.6 19104.8 19120.0 19295.9 19317.7 19356.9 14699.7 14776.9 14777.9 14798.9 14806.4 15590.7 18993.8 19148.8 20001.1 21205.8 6747.1 7716.8 8181.9 8426.7 8503.1 994.5 1026.9 1074.9 1173.1 1234.8 30331.1 31269.8 31823.2 31847.8 32353.4 1129.5 1179.0 1195.7 1203.8 1282.3 13503.7 13909.7 15008.5 15091.2 15370.8 675900.0 678553.6 683211.0 683583.0 686968.0 63771.0 64590.7 64597.4 64606.7 64642.7 1545.2 1562.6 1574.6 1578.8 1579.3 29571.7 29705.7 30022.6 30533.7 30789.8 55841.7 55971.2 56709.4 56804.0 56937.0 511865.4 512067.0 512437.0 514125.0 514131.0 25079.0 25644.4 26943.5 28426.9 28831.3 37412.8 45008.5 45119.5 46647.8 46664.8 29451.0 35310.8 37317.0 37702.8 38113.6 36458.5 36753.8 36761.0 37399.6 37527.6 36653.6 37344.8 38503.0 39337.0 39358.0 27537.7 31918.9 33729.7 33935.3 34091.5 25214.9 25248.9 25328.6 25860.5 25996.9 01:46:08 INFO - PROCESS | 1947 | 01:46:08 INFO - PROCESS | 1947 | [#2] jquery.html Cycles:180 Average:37837.68 Median:21859.34 stddev:109203.46 (499.6%) stddev-sans-first:109473.67 01:46:08 INFO - PROCESS | 1947 | Values: 426.1 428.7 429.5 430.0 441.1 20481.4 20595.1 21388.4 21810.6 21837.5 16934.7 20823.6 21943.2 22174.7 22218.8 5925.3 6289.6 6292.7 6294.9 6301.6 4558.2 4645.8 4740.8 6089.9 6284.3 6086.4 6886.6 6902.6 6908.3 6909.0 5820.0 6531.6 6845.6 6854.0 6862.2 4076.4 5705.3 5996.3 5998.9 6015.1 27046.9 27810.0 28009.2 28246.4 28254.6 2253.7 2402.3 2429.2 2599.8 3082.0 15180.5 15797.8 16124.4 16265.1 16656.7 1822.5 1857.1 1861.4 1879.1 1893.6 119984.0 123540.0 124493.0 126072.0 127368.0 11623.8 11782.2 11820.9 11839.9 11881.0 15219.7 15248.6 15288.8 15294.6 15314.9 9506.8 9526.8 9537.6 9666.9 9671.4 9646.5 9706.6 9752.1 9773.5 9856.1 6499.8 6928.6 7171.3 7322.3 7322.8 1634.4 1781.9 1872.0 1960.0 2008.8 973.0 1038.8 1050.7 1056.8 1124.5 21881.2 22596.4 22739.8 22777.4 22823.4 1061.1 1067.8 1076.4 1077.1 1139.3 11477.8 11977.0 12023.7 12038.5 12080.6 632763.0 645670.0 651631.0 674583.0 676459.0 124027.0 130204.0 130985.3 132985.0 135075.0 1000.6 1024.4 1058.7 1061.5 1065.4 16715.0 19186.9 19923.2 19989.9 19998.9 55818.4 57568.4 58383.1 58415.7 59816.7 83150.0 83366.2 84096.3 84601.0 84874.6 8139.6 8458.1 8474.2 8613.5 8869.9 11361.7 13203.9 13340.9 13636.0 13659.8 11128.9 11643.5 11662.2 11765.6 11775.2 10977.8 11271.4 11462.7 11542.8 11606.4 10077.5 10953.7 11453.4 11869.5 12003.9 8316.7 8363.9 8375.7 8487.5 8773.1 11264.1 11305.6 11421.0 11497.7 11871.8 01:46:08 INFO - PROCESS | 1947 | 01:46:08 INFO - PROCESS | 1947 | [#3] mootools.html Cycles:180 Average:5805.70 Median:5361.50 stddev:3194.56 (59.6%) stddev-sans-first:3187.29 01:46:08 INFO - PROCESS | 1947 | Values: 1520.0 1625.3 1660.2 1946.4 2027.7 7179.2 8204.7 8394.2 8424.0 8753.0 8758.6 8807.1 8912.4 8915.6 8933.1 2520.2 2530.9 2544.4 2546.3 2549.5 2485.9 2554.8 2558.3 2559.7 2560.0 2470.0 2498.0 2528.6 2529.4 2531.1 2128.1 2495.6 2529.7 2533.0 2536.2 2241.9 2503.8 2547.2 2555.0 2556.1 8541.0 8879.6 8887.7 8889.6 8942.2 4486.4 5373.7 5432.7 5635.7 5640.5 3567.9 3650.0 3685.5 3696.1 3710.8 1033.2 1046.4 1079.1 1080.9 1081.6 11980.6 12117.0 12128.9 12140.4 12145.5 4707.1 4709.6 4789.0 4845.8 4868.9 9457.0 9555.4 9558.2 9579.4 9581.6 5491.1 5542.7 5543.1 5547.5 5551.6 4621.6 4650.7 4841.7 4939.0 5118.9 3943.9 3968.3 4024.5 4066.1 4076.2 1104.5 1224.4 1254.8 1263.3 1263.5 839.6 844.7 901.7 907.2 941.9 5934.8 6045.2 6065.2 6126.2 6223.6 848.9 905.5 906.2 908.5 1007.6 3994.2 4012.0 4060.0 4069.7 4151.9 10605.7 10964.1 10996.8 11058.1 11103.7 10859.6 10901.7 10910.6 10934.3 10972.7 8202.2 8326.3 8452.7 8475.2 8490.6 5119.6 5896.3 6019.7 6036.1 6127.6 9589.5 9646.9 9761.4 9941.9 9957.9 9635.3 9788.6 9790.0 10009.0 10118.8 6982.0 7113.6 7120.6 7578.6 7737.9 7830.3 8353.0 8522.0 8577.5 8579.5 7796.5 7903.9 7992.9 8087.8 8120.3 6659.3 7681.4 7684.0 7767.0 7946.1 7159.0 7165.6 7210.9 7222.0 7224.2 5132.6 5205.9 5265.4 5340.6 5369.3 5319.1 5353.7 5432.8 5484.0 5521.4 01:46:08 INFO - PROCESS | 1947 | 01:46:08 INFO - PROCESS | 1947 | [#4] prototype.html Cycles:180 Average:5651.77 Median:6430.57 stddev:1792.38 (27.9%) stddev-sans-first:1752.30 01:46:08 INFO - PROCESS | 1947 | Values: 328.2 332.8 337.6 343.7 346.5 5198.9 6353.7 6409.3 6461.7 6545.3 6091.9 6157.6 6315.1 6339.6 6411.9 4787.8 4821.3 4826.5 4836.1 4851.6 4830.5 4836.7 4844.3 4854.4 4857.2 4648.8 4697.9 4776.2 4785.4 4809.8 4774.6 4787.7 4791.9 4792.2 4800.4 3398.4 4155.4 4177.1 4185.5 4186.7 6165.3 6169.8 6176.5 6178.8 6202.1 5664.1 5676.2 5679.2 5683.8 5685.5 6647.7 6834.6 6871.8 6887.6 6900.7 4672.3 4687.5 4690.0 4697.8 4705.0 7236.6 7249.9 7253.5 7253.8 7267.2 4752.2 4762.5 4767.5 4783.0 4789.6 3848.6 4040.6 4815.4 4829.2 4831.2 5478.3 5645.2 5652.9 5653.7 5668.5 6864.7 6921.3 6926.9 6936.7 6939.6 1592.4 1628.7 1658.6 1686.3 1687.5 960.7 1028.8 1044.6 1052.1 1118.6 5293.7 6477.6 6534.6 6551.6 6594.9 7051.6 7078.2 7093.2 7094.3 7099.9 6540.5 6551.2 6553.0 6556.0 6559.6 3019.8 3635.8 3743.8 3745.7 3781.4 6995.2 7067.5 7584.8 7660.5 7669.8 6656.6 7389.5 7394.9 7400.9 7407.6 6137.9 6449.2 7165.3 7646.4 7666.2 5732.5 5881.6 6889.7 7107.3 7145.7 7065.6 7119.4 7138.6 7142.8 7144.4 7670.3 7673.2 7679.1 7681.1 7691.3 4500.5 4546.5 4556.3 4559.7 4570.5 6943.0 7165.6 7195.8 7197.1 7201.4 6914.0 6915.9 6928.1 6929.9 6952.4 6492.4 6924.1 6931.1 6931.6 6934.8 6762.1 6783.0 6795.8 6802.6 6806.7 6612.2 6727.9 6950.6 6951.0 6959.5 6622.8 6664.3 6735.6 6814.7 6837.3 01:46:08 INFO - PROCESS | 1947 | 01:46:08 INFO - PROCESS | 1947 | [#5] yui.html Cycles:180 Average:18802.78 Median:26700.03 stddev:26384.72 (98.8%) stddev-sans-first:26423.77 01:46:08 INFO - PROCESS | 1947 | Values: 712.2 734.1 777.2 778.4 787.8 15580.0 15691.5 15833.3 15848.0 15853.3 14542.9 14669.6 14685.9 14696.0 14711.8 779.8 800.4 944.7 968.5 972.6 937.4 954.8 954.9 958.8 961.0 840.6 1022.3 1023.5 1025.2 1028.2 1002.7 1023.6 1025.0 1026.1 1027.9 774.8 775.0 775.2 776.4 777.0 15255.7 15290.5 15305.8 15309.5 15338.2 13061.4 13092.3 13353.7 13525.4 13667.0 10168.7 10257.2 10416.0 10617.9 10768.2 3536.7 3563.8 3594.1 3624.3 3725.9 78390.2 78784.8 83748.6 84642.6 84942.0 9350.3 9384.5 9391.1 9528.1 10092.8 21882.9 22105.1 22142.9 22166.2 22169.1 7539.6 7722.0 7841.2 7990.1 7996.4 4246.2 4300.1 4388.3 4394.2 4592.0 3784.6 3837.4 3847.6 3849.3 3855.8 622.8 671.5 689.8 723.7 736.1 604.7 606.1 624.3 639.4 651.5 8269.3 8403.7 8544.7 8620.7 8689.2 523.4 557.5 575.9 622.4 637.3 3942.0 3995.9 4081.3 4113.6 4140.5 87704.1 93613.0 96607.5 97000.5 97024.5 79005.0 79918.6 80066.4 82756.6 86585.8 70618.6 70904.6 71036.6 71797.0 73852.6 7189.7 8495.2 8649.5 8672.0 8979.5 48808.0 49832.0 49853.0 50334.2 51211.5 67845.0 67947.7 70646.6 72678.9 72872.6 11080.3 11185.8 11192.2 11275.9 11527.9 12000.8 12038.0 12092.6 12459.0 12533.3 10218.3 10862.0 11262.2 11507.9 11879.5 10935.6 11042.6 11824.1 11948.3 12078.8 11139.4 11650.3 11720.4 11856.9 12188.6 8631.0 9456.5 9776.0 10034.7 10370.6 9814.3 10070.7 10192.2 10203.4 10218.1 01:46:08 INFO - PROCESS | 1947 | -------- Summary: end -------- 01:46:08 INFO - PROCESS | 1947 | 01:46:09 INFO - TEST-INFO | 1947: exit 0 01:46:09 INFO - TEST-OK | dromaeo_css | took 1112975ms 01:46:09 INFO - TEST-START | kraken 01:46:09 INFO - operating with platform_type : mac_ 01:46:09 INFO - Initialising browser for kraken test... 01:46:09 INFO - TEST-INFO | started process 1951 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/tmpIc12qt/profile http://localhost:51429/getInfo.html) 01:46:11 INFO - PROCESS | 1951 | __metrics Screen width/height:1600/1200 01:46:11 INFO - PROCESS | 1951 | colorDepth:24 01:46:11 INFO - PROCESS | 1951 | Browser inner width/height: 1024/689 01:46:11 INFO - PROCESS | 1951 | __metrics 01:46:11 INFO - PROCESS | 1951 | 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" 01:46:11 ERROR - PROCESS | 1951 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 01:46:12 INFO - TEST-INFO | 1951: exit 0 01:46:12 INFO - Browser initialized. 01:46:12 INFO - Running cycle 1/1 for kraken test... 01:46:12 INFO - TEST-INFO | started process 1954 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/T/tmpIc12qt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:46:24 INFO - PROCESS | 1954 | RSS: Main: 161050624 01:46:24 INFO - PROCESS | 1954 | 01:46:26 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:51429/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51429/tests/kraken/driver.html?testName=audio-beat-detection) 01:46:27 INFO - PROCESS | 1954 | RSS: Main: 224317440 01:46:27 INFO - PROCESS | 1954 | 01:46:30 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:51429/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51429/tests/kraken/driver.html?testName=audio-dft) 01:46:30 INFO - PROCESS | 1954 | RSS: Main: 239173632 01:46:30 INFO - PROCESS | 1954 | 01:46:34 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:51429/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51429/tests/kraken/driver.html?testName=audio-fft) 01:46:34 INFO - PROCESS | 1954 | RSS: Main: 325390336 01:46:34 INFO - PROCESS | 1954 | 01:46:36 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:51429/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51429/tests/kraken/driver.html?testName=audio-oscillator) 01:46:37 INFO - PROCESS | 1954 | RSS: Main: 230432768 01:46:37 INFO - PROCESS | 1954 | 01:46:39 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:51429/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51429/tests/kraken/driver.html?testName=imaging-gaussian-blur) 01:46:40 INFO - PROCESS | 1954 | RSS: Main: 232062976 01:46:40 INFO - PROCESS | 1954 | 01:46:43 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:51429/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51429/tests/kraken/driver.html?testName=imaging-darkroom) 01:46:43 INFO - PROCESS | 1954 | RSS: Main: 268353536 01:46:43 INFO - PROCESS | 1954 | 01:46:46 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:51429/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51429/tests/kraken/driver.html?testName=imaging-desaturate) 01:46:47 INFO - PROCESS | 1954 | RSS: Main: 271532032 01:46:47 INFO - PROCESS | 1954 | 01:46:49 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:51429/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51429/tests/kraken/driver.html?testName=json-parse-financial) 01:46:50 INFO - PROCESS | 1954 | RSS: Main: 269254656 01:46:50 INFO - PROCESS | 1954 | 01:46:51 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:51429/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51429/tests/kraken/driver.html?testName=json-stringify-tinderbox) 01:46:52 INFO - PROCESS | 1954 | RSS: Main: 260485120 01:46:52 INFO - PROCESS | 1954 | 01:46:54 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:51429/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51429/tests/kraken/driver.html?testName=stanford-crypto-aes) 01:46:55 INFO - PROCESS | 1954 | RSS: Main: 267587584 01:46:55 INFO - PROCESS | 1954 | 01:46:57 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:51429/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51429/tests/kraken/driver.html?testName=stanford-crypto-ccm) 01:46:57 INFO - PROCESS | 1954 | RSS: Main: 271532032 01:46:57 INFO - PROCESS | 1954 | 01:47:00 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:51429/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51429/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 01:47:01 INFO - PROCESS | 1954 | RSS: Main: 277884928 01:47:01 INFO - PROCESS | 1954 | 01:47:04 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:51429/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51429/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 01:47:04 INFO - PROCESS | 1954 | RSS: Main: 275095552 01:47:04 INFO - PROCESS | 1954 | 01:47:06 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:51429/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51429/tests/kraken/driver.html?testName=ai-astar) 01:47:06 INFO - PROCESS | 1954 | RSS: Main: 336089088 01:47:06 INFO - PROCESS | 1954 | 01:47:06 INFO - PROCESS | 1954 | __start_tp_report 01:47:06 INFO - PROCESS | 1954 | _x_x_mozilla_page_load 01:47:06 INFO - PROCESS | 1954 | _x_x_mozilla_page_load_details 01:47:06 INFO - PROCESS | 1954 | |i|pagename|runs| 01:47:06 INFO - PROCESS | 1954 | |0;ai-astar;119;111;118;112;111;110;116;114;112;134 01:47:06 INFO - PROCESS | 1954 | |1;audio-beat-detection;153;122;143;162;122;169;118;155;152;122 01:47:06 INFO - PROCESS | 1954 | |2;audio-dft;186;191;262;225;202;259;187;185;188;182 01:47:06 INFO - PROCESS | 1954 | |3;audio-fft;71;78;73;156;56;55;57;66;148;68 01:47:06 INFO - PROCESS | 1954 | |4;audio-oscillator;89;88;143;80;86;86;123;79;80;124 01:47:06 INFO - PROCESS | 1954 | |5;imaging-gaussian-blur;88;85;79;113;135;104;88;85;87;84 01:47:06 INFO - PROCESS | 1954 | |6;imaging-darkroom;93;94;93;94;93;93;94;94;93;93 01:47:06 INFO - PROCESS | 1954 | |7;imaging-desaturate;84;82;84;86;85;85;84;86;84;85 01:47:06 INFO - PROCESS | 1954 | |8;json-parse-financial;57;62;61;61;60;61;61;76;57;54 01:47:06 INFO - PROCESS | 1954 | |9;json-stringify-tinderbox;61;64;61;76;71;68;66;68;65;66 01:47:06 INFO - PROCESS | 1954 | |10;stanford-crypto-aes;80;78;82;79;79;79;78;80;81;84 01:47:06 INFO - PROCESS | 1954 | |11;stanford-crypto-ccm;126;140;147;142;125;127;145;156;145;137 01:47:06 INFO - PROCESS | 1954 | |12;stanford-crypto-pbkdf2;163;166;165;162;218;173;172;159;190;167 01:47:06 INFO - PROCESS | 1954 | |13;stanford-crypto-sha256-iterative;68;65;66;68;68;66;67;69;66;64 01:47:06 INFO - PROCESS | 1954 | __end_tp_report 01:47:06 INFO - PROCESS | 1954 | __start_cc_report 01:47:06 INFO - PROCESS | 1954 | _x_x_mozilla_cycle_collect,1905 01:47:06 INFO - PROCESS | 1954 | __end_cc_report 01:47:06 INFO - PROCESS | 1954 | __startTimestamp1466498826616__endTimestamp 01:47:06 INFO - PROCESS | 1954 | 01:47:06 INFO - PROCESS | 1954 | ------- Summary: start ------- 01:47:06 INFO - PROCESS | 1954 | Number of tests: 14 01:47:06 INFO - PROCESS | 1954 | 01:47:06 INFO - PROCESS | 1954 | [#0] ai-astar Cycles:10 Average:115.70 Median:115.00 stddev:7.13 (6.2%) stddev-sans-first:7.47 01:47:06 INFO - PROCESS | 1954 | Values: 119.0 111.0 118.0 112.0 111.0 110.0 116.0 114.0 112.0 134.0 01:47:06 INFO - PROCESS | 1954 | 01:47:06 INFO - PROCESS | 1954 | [#1] audio-beat-detection Cycles:10 Average:141.80 Median:152.50 stddev:19.14 (12.5%) stddev-sans-first:19.86 01:47:06 INFO - PROCESS | 1954 | Values: 153.0 122.0 143.0 162.0 122.0 169.0 118.0 155.0 152.0 122.0 01:47:06 INFO - PROCESS | 1954 | 01:47:06 INFO - PROCESS | 1954 | [#2] audio-dft Cycles:10 Average:206.70 Median:196.50 stddev:30.99 (15.8%) stddev-sans-first:31.95 01:47:06 INFO - PROCESS | 1954 | Values: 186.0 191.0 262.0 225.0 202.0 259.0 187.0 185.0 188.0 182.0 01:47:06 INFO - PROCESS | 1954 | 01:47:06 INFO - PROCESS | 1954 | [#3] audio-fft Cycles:10 Average:82.80 Median:67.00 stddev:37.31 (55.7%) stddev-sans-first:39.32 01:47:06 INFO - PROCESS | 1954 | Values: 71.0 78.0 73.0 156.0 56.0 55.0 57.0 66.0 148.0 68.0 01:47:06 INFO - PROCESS | 1954 | 01:47:06 INFO - PROCESS | 1954 | [#4] audio-oscillator Cycles:10 Average:97.80 Median:83.00 stddev:23.10 (27.8%) stddev-sans-first:24.28 01:47:06 INFO - PROCESS | 1954 | Values: 89.0 88.0 143.0 80.0 86.0 86.0 123.0 79.0 80.0 124.0 01:47:06 INFO - PROCESS | 1954 | 01:47:06 INFO - PROCESS | 1954 | [#5] imaging-gaussian-blur Cycles:10 Average:94.80 Median:85.00 stddev:17.46 (20.5%) stddev-sans-first:18.34 01:47:06 INFO - PROCESS | 1954 | Values: 88.0 85.0 79.0 113.0 135.0 104.0 88.0 85.0 87.0 84.0 01:47:06 INFO - PROCESS | 1954 | 01:47:06 INFO - PROCESS | 1954 | [#6] imaging-darkroom Cycles:10 Average:93.40 Median:93.50 stddev:0.52 (0.6%) stddev-sans-first:0.53 01:47:06 INFO - PROCESS | 1954 | Values: 93.0 94.0 93.0 94.0 93.0 93.0 94.0 94.0 93.0 93.0 01:47:06 INFO - PROCESS | 1954 | 01:47:06 INFO - PROCESS | 1954 | [#7] imaging-desaturate Cycles:10 Average:84.50 Median:85.00 stddev:1.18 (1.4%) stddev-sans-first:1.24 01:47:06 INFO - PROCESS | 1954 | Values: 84.0 82.0 84.0 86.0 85.0 85.0 84.0 86.0 84.0 85.0 01:47:06 INFO - PROCESS | 1954 | 01:47:06 INFO - PROCESS | 1954 | [#8] json-parse-financial Cycles:10 Average:61.00 Median:61.00 stddev:5.85 (9.6%) stddev-sans-first:6.02 01:47:06 INFO - PROCESS | 1954 | Values: 57.0 62.0 61.0 61.0 60.0 61.0 61.0 76.0 57.0 54.0 01:47:06 INFO - PROCESS | 1954 | 01:47:06 INFO - PROCESS | 1954 | [#9] json-stringify-tinderbox Cycles:10 Average:66.60 Median:67.00 stddev:4.53 (6.8%) stddev-sans-first:4.32 01:47:06 INFO - PROCESS | 1954 | Values: 61.0 64.0 61.0 76.0 71.0 68.0 66.0 68.0 65.0 66.0 01:47:06 INFO - PROCESS | 1954 | 01:47:06 INFO - PROCESS | 1954 | [#10] stanford-crypto-aes Cycles:10 Average:80.00 Median:80.00 stddev:1.89 (2.4%) stddev-sans-first:2.00 01:47:06 INFO - PROCESS | 1954 | Values: 80.0 78.0 82.0 79.0 79.0 79.0 78.0 80.0 81.0 84.0 01:47:06 INFO - PROCESS | 1954 | 01:47:06 INFO - PROCESS | 1954 | [#11] stanford-crypto-ccm Cycles:10 Average:139.00 Median:143.50 stddev:10.26 (7.2%) stddev-sans-first:9.75 01:47:06 INFO - PROCESS | 1954 | Values: 126.0 140.0 147.0 142.0 125.0 127.0 145.0 156.0 145.0 137.0 01:47:06 INFO - PROCESS | 1954 | 01:47:06 INFO - PROCESS | 1954 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.50 Median:169.50 stddev:17.88 (10.6%) stddev-sans-first:18.56 01:47:06 INFO - PROCESS | 1954 | Values: 163.0 166.0 165.0 162.0 218.0 173.0 172.0 159.0 190.0 167.0 01:47:06 INFO - PROCESS | 1954 | 01:47:06 INFO - PROCESS | 1954 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.70 Median:67.50 stddev:1.57 (2.3%) stddev-sans-first:1.59 01:47:06 INFO - PROCESS | 1954 | Values: 68.0 65.0 66.0 68.0 68.0 66.0 67.0 69.0 66.0 64.0 01:47:06 INFO - PROCESS | 1954 | -------- Summary: end -------- 01:47:06 INFO - PROCESS | 1954 | 01:47:07 INFO - TEST-INFO | 1954: exit 0 01:47:07 INFO - TEST-OK | kraken | took 57927ms 01:47:07 INFO - SUITE-END | took 1170s 01:47:07 INFO - Completed test suite (00:19:31) 01:47:07 INFO - javascript benchmark 01:47:07 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2927.516959701649, 3581.5881173665034, 3611.729120315977, 3612.7109831213506, 3678.558572971244, 5667.631603945741, 5677.43225677432, 5679.318481782187, 5685.602007859451, 5700.828975130742, 5701.315842098944, 5740.311162660476, 5743.770249190027, 5748.712564371785, 5752.3384810746675, 5117.437081920985, 5148.433672296044, 5164.457731938149, 5268.130758424855, 5271.894562108756, 5180.0, 5231.372235331765, 5232.476752324776, 5236.528712415882, 5252.52727254547, 5139.0749665060275, 5202.7658755355915, 5225.555827754626, 5239.869003274911, 5280.128778751501, 5190.143626301656, 5252.290940722992, 5267.315249017642, 5279.868003299948, 5299.8410047698635, 5070.29015937769, 5083.6441449098575, 5086.872828179288, 5168.715720635401, 5196.168613021936, 5473.151661492475, 5479.698616576086, 5542.002439560877, 5545.805896793633, 5565.387807341189, 6187.969060154715, 6231.345708700612, 6233.283172435137, 6238.656873871935, 6243.656598887105, 6144.402455361594, 6195.535334849904, 6268.905966410507, 6295.401936815995, 6330.778422755227, 5793.594448388657, 5795.971020144914, 5803.303603567514, 5835.737391817336, 5836.066229403275, 6346.174997250328, 6350.491960643138, 6354.745810167541, 6361.9045714314325, 6368.331325210879, 5949.0, 6044.546659000504, 6078.422549857756, 6136.846578835475, 6138.017917133327, 5760.567957403212, 6163.8150855474405, 6193.225846769154, 6194.690265486708, 6214.098955651405, 6036.63780173191, 6103.908441373383, 6113.296970848321, 6119.938800612026, 6142.754289828446, 6097.146399504073, 6158.630482170995, 6168.568200226031, 6174.8147555572505, 6195.101710252078, 4930.827421040318, 4968.056069346812, 4974.129527332702, 4982.128127577732, 4982.277569752366, 4475.48531918827, 4476.597106260387, 4497.2804351304285, 4502.009557897323, 4521.434820647419, 5690.658560486301, 5786.623869448473, 5798.536117110621, 5843.649381037151, 5891.440313170242, 6273.309935907047, 6285.43431091204, 6296.370362963667, 6299.96850015747, 6313.494920406357, 5854.736536855852, 5857.355690874, 5858.56060795442, 5863.091220860688, 5905.527557795452, 5279.947200527946, 5375.381831089397, 5427.592930530147, 5433.293671822637, 5441.755121019563, 6382.425581697669, 6417.326180751047, 6436.937905245582, 6440.484761218998, 6444.806655800334, 6290.370962903856, 6294.181756371826, 6309.90535141964, 6311.652859092794, 6319.842003949754, 6242.875142497215, 6345.0, 6388.361163883575, 6406.775762848278, 6408.198975128109, 6210.347913469021, 6211.720472578654, 6211.844703882439, 6218.253809542884, 6222.408871157233, 6238.126662267377, 6266.498680105672, 6287.842803929939, 6288.6855657217875, 6319.747210111544, 5843.590948633555, 5857.9121313179485, 5871.207387002871, 5934.228550288435, 5968.0, 4753.738544380092, 6073.058675905242, 6074.90887636677, 6083.756649733961, 6094.969525152346, 5771.249737534262, 5886.323072846506, 5886.676232807065, 5895.67573783446, 5914.467697907209, 5969.253843269591, 6014.8195554133445, 6086.452219300284, 6092.482139018056, 6133.0, 5945.0, 5949.613275137102, 5982.461578457785, 5984.611000284967, 5992.64044157352], "value": 5704.845808445103, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [522.4383787428515, 557.5177471487162, 565.5921014301144, 590.889128438536, 604.809485012221, 23717.407064823386, 33586.83206583967, 33641.663583364156, 33682.83158584207, 40976.0, 44584.0, 45021.549784502145, 45122.0, 45151.322730159074, 45155.77422112893, 9616.759581010496, 9806.362586431858, 9838.65564705232, 9840.852387214198, 9868.45723485208, 9209.309301802357, 9395.57719902606, 9412.0117387674, 9427.24582033439, 9626.278029147807, 9266.583003764814, 11808.704782380466, 12097.939510302436, 12101.757964840697, 12130.878691213064, 11684.649460516199, 11791.882081179165, 11797.410129493492, 11798.292102473879, 11820.0, 11554.364509951949, 11669.358185299805, 11694.883051169549, 11696.18126731129, 11713.238639488405, 40797.79601102005, 41193.382099268536, 41343.7932810337, 41371.7931410344, 41415.75752727393, 3362.1258472797003, 3502.807345595991, 3527.5237842891393, 3541.6812486876047, 3926.725129240954, 29728.554071688726, 29904.55143172854, 29905.252368690955, 29981.0, 30269.848650756827, 2768.6677598688084, 2769.8476583787883, 2825.6185414968922, 2851.900183493568, 2854.215090850012, 458617.0, 459464.9999999967, 459574.0, 459952.0, 460105.0, 66261.66869165623, 66735.99896001564, 66809.66595166993, 66945.0, 67023.0, 67696.32303676999, 70099.64950175319, 70905.0, 74499.62750186323, 75835.62082189663, 19104.80895191058, 19120.043997800054, 19295.90352048259, 19317.710234346498, 19356.90321548383, 14699.706005879823, 14776.926115369355, 14777.852221477862, 14798.852011479963, 14806.407743690348, 15590.688186236212, 18993.81006189948, 19148.80851191498, 20001.099950502263, 21205.78794212038, 6747.110808973495, 7716.839285000209, 8181.936348274805, 8426.705065322683, 8503.107173746668, 994.4828689081637, 1026.856240126368, 1074.8606477134106, 1173.1260211142753, 1234.8271242026124, 30331.090067298017, 31269.8436507816, 31823.204419889687, 31847.840760796513, 32353.35293294121, 1129.5312445335094, 1178.9646310610694, 1195.7070517723214, 1203.8314635950974, 1282.3203702037936, 13503.717146871031, 13909.721805564035, 15008.549743507712, 15091.169985650895, 15370.846291537166, 675900.0, 678553.6072319705, 683211.0, 683583.0, 686968.0, 63771.0, 64590.677046614466, 64597.354026459136, 64606.67696661487, 64642.67678661671, 1545.1810540413599, 1562.6015366081576, 1574.5984773882587, 1578.842115788412, 1579.312998845506, 29571.704282956896, 29705.702942970292, 30022.64898079635, 30533.694663053084, 30789.846050769604, 55841.72079139578, 55971.16043259273, 56709.432905670415, 56804.0, 56937.0, 511865.44067279424, 512067.0, 512437.0, 514125.0, 514131.0, 25079.0, 25644.358891027874, 26943.461130776883, 28426.85786571054, 28831.279218019718, 37412.812935936236, 45008.549914501746, 45119.548804511534, 46647.83380415517, 46664.7666761664, 29451.0, 35310.823445882605, 37317.0, 37702.811485942395, 38113.61886381212, 36458.453123203704, 36753.816230918674, 36761.0, 37399.626003740705, 37527.624723752415, 36653.63346366609, 37344.81327593453, 38503.0, 39337.01657458586, 39358.01604959899, 27537.724622753514, 31918.88283910052, 33729.662703372655, 33935.32129357448, 34091.48862767011, 25214.873925630254, 25248.947336912024, 25328.620070699322, 25860.482790344464, 25996.8700156498], "value": 20752.70530053156, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [426.1200620718217, 428.6827747466875, 429.4717497478102, 430.03028171473323, 441.0737451352161, 20481.385558433234, 20595.07322170502, 21388.358349249527, 21810.563788724216, 21837.454063648416, 16934.661306773858, 20823.583528329462, 21943.231986880422, 22174.667379989314, 22218.777812221873, 5925.31858836234, 6289.591176573531, 6292.7482900684145, 6294.937050629504, 6301.590396624228, 4558.2251017327135, 4645.814167433298, 4740.810367585293, 6089.908651370233, 6284.340144284853, 6086.421789929949, 6886.552374095668, 6902.5858448493, 6908.3437073477935, 6908.965455172717, 5820.0, 6531.575447595915, 6845.5892646441125, 6853.965730171367, 6862.176538815324, 4076.3885417187657, 5705.258316418881, 5996.2504686914135, 5998.940010599926, 6015.097735339734, 27046.864765676244, 27810.0, 28009.159725208276, 28246.435071298663, 28254.576181357297, 2253.707018087638, 2402.3153401280724, 2429.2347910408344, 2599.8180127391092, 3081.953770693419, 15180.544583662508, 15797.842021579752, 16124.435644752495, 16265.105419201938, 16656.67767931872, 1822.4988128264833, 1857.1085878778126, 1861.385742704904, 1879.0698604191014, 1893.6307420053095, 119984.0, 123540.0, 124492.95788726579, 126072.0, 127368.0, 11623.76752464946, 11782.175247732748, 11820.881791181979, 11839.940800296115, 11880.990115840077, 15219.695606087816, 15248.618784530254, 15288.77066843976, 15294.617634559225, 15314.923425382802, 9506.762330941781, 9526.761830954143, 9537.570809313598, 9666.903330966741, 9671.371360861522, 9646.517674116267, 9706.611735530641, 9752.073553012453, 9773.51132443375, 9856.112949834405, 6499.772507962199, 6928.61892595902, 7171.318724721144, 7322.267773222635, 7322.816929576697, 1634.3625985865522, 1781.885934135064, 1871.9719204211947, 1959.9706004409945, 2008.8192062714427, 972.9708108756748, 1038.8389799581078, 1050.6847945616437, 1056.762228498597, 1124.4771181400688, 21881.234156804436, 22596.435089122573, 22739.772602273766, 22777.430564236027, 22823.429414264443, 1061.0927656853435, 1067.8398240263866, 1076.3810808784824, 1077.1167642533205, 1139.2765593847967, 11477.827832582518, 11977.0, 12023.699407514707, 12038.518459261531, 12080.637580872588, 632763.0, 645670.0, 651631.0, 674583.0, 676459.0, 124027.0, 130204.0, 130985.34507327402, 132985.0, 135075.0, 1000.60976219276, 1024.3802499487851, 1058.714147180257, 1061.4714810672606, 1065.4459680965854, 16715.0, 19186.904065479583, 19923.203071876964, 19989.900050499655, 19998.90000549988, 55818.441815581325, 57568.424315757984, 58383.124253135386, 58415.70792146012, 59816.70091649514, 83150.0, 83366.16633833584, 84096.31807363695, 84601.0, 84874.57562712394, 8139.552324622203, 8458.06961234276, 8474.237318641352, 8613.48319100856, 8869.911300886908, 11361.659150225507, 13203.933980330037, 13340.933295333462, 13636.0, 13659.79510307366, 11128.944355278172, 11643.53425862956, 11662.183647144619, 11765.588204412805, 11775.23460975034, 10977.83533246986, 11271.436428178395, 11462.65612031607, 11542.769144617228, 11606.419679015846, 10077.496125193858, 10953.671389858317, 11453.427328633368, 11869.465874035517, 12003.879961200277, 8316.708915188181, 8363.874541881998, 8375.664973400995, 8487.533185674847, 8773.122687731431, 11264.09887209005, 11305.604303849326, 11421.0, 11497.712557185803, 11871.762564748484], "value": 10366.829737321792, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1519.9848001519981, 1625.3336132185802, 1660.2279939827981, 1946.3771593090207, 2027.7059826325183, 7179.246179151192, 8204.712835050774, 8394.202550757669, 8424.0, 8753.0, 8758.605862736176, 8807.119288071206, 8912.376133670645, 8915.598798054087, 8933.106689331064, 2520.2439268219505, 2530.9240722778345, 2544.37662772621, 2546.261584140597, 2549.5155920375164, 2485.9005639774387, 2554.846709197454, 2558.2581051495044, 2559.731228221038, 2559.9744002560014, 2470.0, 2497.9750202497926, 2528.582783840673, 2529.4055896864224, 2531.1014589820625, 2128.1061953979365, 2495.6007038873877, 2529.6584961030208, 2532.962005569918, 2536.2137737301496, 2241.9215327463626, 2503.7871780898645, 2547.197632745679, 2555.0418593027616, 2556.0670355320176, 8541.017782954978, 8879.600417981206, 8887.688930887389, 8889.644414223423, 8942.239909607626, 4486.416765820422, 5373.731313434313, 5432.70120143392, 5635.690037047961, 5640.5487560995025, 3567.8929632111076, 3649.9635003649887, 3685.5208822853124, 3696.094456858087, 3710.77735335878, 1033.2095946600782, 1046.3983209654402, 1079.098952374768, 1080.935143891369, 1081.567373050778, 11980.58067967617, 12116.999999999913, 12128.939355303255, 12140.392980351035, 12145.514179432812, 4707.129181101439, 4709.599684026896, 4788.976055119771, 4845.75771211438, 4868.9269660955115, 9457.0, 9555.378900371454, 9558.187554057844, 9579.377340472847, 9581.568829402693, 5491.066518691752, 5542.695151766691, 5543.085390910535, 5547.5007249346945, 5551.555875529948, 4621.607163391082, 4650.697704649187, 4841.733704646278, 4938.975305123523, 5118.923216151761, 3943.881683549498, 3968.3452230381663, 4024.5170579530645, 4066.064805094845, 4076.205139997672, 1104.536094840169, 1224.4489979509078, 1254.8431446069242, 1263.298869127643, 1263.4630282130058, 839.6389552492487, 844.7202488230336, 901.7114523352595, 907.1744712311896, 941.8540126280437, 5934.762609495659, 6045.244344456943, 6065.150878876961, 6126.172966649535, 6223.626582405071, 848.8896443462311, 905.4657751926395, 906.1889608800087, 908.4549270437684, 1007.5843439966858, 3994.1612261424843, 4011.9799401003393, 4059.959400405958, 4069.7354671946227, 4151.896202594899, 10605.734856628646, 10964.122870170526, 10996.780064398667, 11058.11535077192, 11103.722406939729, 10859.619913302997, 10901.727456813485, 10910.61812836547, 10934.343939363664, 10972.725681858017, 8202.220789024914, 8326.333893288522, 8452.74641760748, 8475.1524847516, 8490.575471226413, 5119.6416250862085, 5896.262967129109, 6019.6990150493175, 6036.0644100164545, 6127.590654149481, 9589.520523973913, 9646.9035309646, 9761.365511241747, 9941.850872236777, 9957.900420995988, 9635.277354198603, 9788.559514821707, 9790.020997900438, 10009.0, 10118.836333821704, 6982.0, 7113.608751518716, 7120.643967801486, 7578.621068946641, 7737.883931740916, 7830.2952734254195, 8353.0, 8522.019967703545, 8577.528235946833, 8579.485230886166, 7796.4932279401655, 7903.9209607903185, 7992.920070799218, 8087.8382432349845, 8120.269175774209, 6659.267480577036, 7681.385489160742, 7683.961580192064, 7766.951818150305, 7946.125926148004, 7159.0, 7165.605891676008, 7210.89183662256, 7222.0, 7224.169220539705, 5132.6150538709, 5205.947940520547, 5265.447128051499, 5340.5994550408095, 5369.301990741179, 5319.122344813217, 5353.705546194841, 5432.821799247326, 5483.972580137074, 5521.420250873601], "value": 4687.793852178496, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [328.18446161289205, 332.828443877551, 337.6319811405567, 343.7336064549974, 346.5391029930193, 5198.92201616976, 6353.650549219791, 6409.26293476251, 6461.741530338787, 6545.312742162076, 6091.878162436759, 6157.5689701720785, 6315.084312774657, 6339.556231063816, 6411.916127258648, 4787.80848766049, 4821.349117869096, 4826.541478559531, 4836.056968891059, 4851.611871050325, 4830.516948305177, 4836.661433699643, 4844.2733589961435, 4854.441739200002, 4857.198562237227, 4648.790804413793, 4697.9060418791605, 4776.235802271637, 4785.425748910136, 4809.7835597398025, 4774.594159496447, 4787.736674482902, 4791.880202994918, 4792.161371759963, 4800.423949126092, 3398.3882901077795, 4155.439015732898, 4177.122804211089, 4185.455890734185, 4186.706930514865, 6165.2601687797305, 6169.753209871645, 6176.505879529672, 6178.752849885999, 6202.069689546604, 5664.065429204217, 5676.233708449347, 5679.204911312419, 5683.829485115453, 5685.545156387479, 6647.700853461557, 6834.589924604539, 6871.793846184622, 6887.586744795329, 6900.689468973858, 4672.345871577982, 4687.484376718558, 4689.95310046902, 4697.812087516495, 4704.952950470486, 7236.638168091575, 7249.855002899965, 7253.528520646141, 7253.782386528413, 7267.236940121317, 4752.215884379108, 4762.547557981986, 4767.523247675204, 4782.976085119552, 4789.592884604778, 3848.634379733921, 4040.6363427291685, 4815.422149342101, 4829.227323628203, 4831.227003679394, 5478.278198485421, 5645.2096706461125, 5652.8869422611315, 5653.689047102366, 5668.461496157858, 6864.691088900911, 6921.307869213139, 6926.861462770717, 6936.687849046805, 6939.618320992293, 1592.3789722008426, 1628.7475441306617, 1658.6185177409263, 1686.325469812085, 1687.4768821665077, 960.7454024683465, 1028.8148133335922, 1044.5637248782923, 1052.1214785653913, 1118.59730497021, 5293.655912365684, 6477.611343319321, 6534.607923524509, 6551.574147680385, 6594.878870592011, 7051.576905385693, 7078.186008608973, 7093.184283807325, 7094.255103214088, 7099.858002839915, 6540.542162048611, 6551.213854337415, 6552.967235163889, 6556.016597510316, 6559.639219842859, 3019.788614796987, 3635.763675361093, 3743.7753734776, 3745.700343972476, 3781.357169281229, 6995.230524642286, 7067.540609860343, 7584.848303033908, 7660.502067365604, 7669.808254793675, 6656.633885136364, 7389.482736208522, 7394.926050739531, 7400.925990740131, 7407.592582407911, 6137.938620613826, 6449.226092868792, 7165.319294666998, 7646.3882889368715, 7666.19504951972, 5732.455416735404, 5881.5588830838715, 6889.689963951534, 7107.2637034740965, 7145.714171433085, 7065.6113913735235, 7119.394851437479, 7138.64306784669, 7142.821429464305, 7144.3570078693165, 7670.348020418327, 7673.155952845071, 7679.078510578543, 7681.078270607562, 7691.30778229962, 4500.549945005604, 4546.5226151254965, 4556.2937744649635, 4559.726416415026, 4570.542945705402, 6942.965285173542, 7165.570065796069, 7195.820104497429, 7197.064381630355, 7201.387882029877, 6914.0, 6915.93084069173, 6928.099347084847, 6929.861402772017, 6952.374286314256, 6492.415682588589, 6924.099867017457, 6931.0643063187845, 6931.584104953719, 6934.826629334307, 6762.053312536151, 6783.0, 6795.762148324982, 6802.557833740791, 6806.659667016728, 6612.173478315211, 6727.93272067273, 6950.582965022115, 6951.0, 6959.512834101564, 6622.768203113061, 6664.333566643297, 6735.562188457735, 6814.727410903508, 6837.316268373322], "value": 5058.582338593187, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [712.2343480758185, 734.1483878700708, 777.2383064596695, 778.4161878591057, 787.8188016756146, 15580.0, 15691.529254122366, 15833.287502062392, 15848.0, 15853.286602102904, 14542.854571454282, 14669.559913202622, 14685.853141468582, 14695.971282010263, 14711.779323310158, 779.8089468080328, 800.4116974024088, 944.7118628818208, 968.467342961372, 972.5526257921365, 937.357909831764, 954.8090381923608, 954.9474778887159, 958.836997710391, 961.0, 840.6091167607061, 1022.2741853284177, 1023.52406131149, 1025.1849779425338, 1028.2185538990384, 1002.6941782756256, 1023.5803320638503, 1025.0262250861674, 1026.1482969135675, 1027.9434631095287, 774.8256642255503, 774.9922500774976, 775.170567492783, 776.4215659333776, 776.9976730018269, 15255.694886102327, 15290.541283761506, 15305.846941530664, 15309.46416875404, 15338.156401397919, 13061.412236449383, 13092.279924604143, 13353.66615834612, 13525.39135738894, 13667.043306968517, 10168.694939151837, 10257.230707696877, 10416.0, 10617.946910265477, 10768.192385571114, 3536.734744894143, 3563.821808909544, 3594.101474631342, 3624.3113808376324, 3725.925481490382, 78390.21609783886, 78784.81822772663, 83748.58125709393, 84642.57678711628, 84942.0, 9350.345475816628, 9384.530773461436, 9391.060893910691, 9528.11175203284, 10092.798144037079, 21882.89058554697, 22105.115795368005, 22142.889285553792, 22166.22418215387, 22169.113235470402, 7539.585322807188, 7722.0, 7841.176676449005, 7990.12108668046, 7996.360291176692, 4246.150769846043, 4300.053988122608, 4388.29787234041, 4394.1651086293505, 4592.03567250881, 3784.6404591563755, 3837.4052021936645, 3847.634474724897, 3849.3263678856647, 3855.845766169322, 622.7944778223175, 671.452765995712, 689.7585844954226, 723.7322190789441, 736.054170391045, 604.7429842316998, 606.0817860940679, 624.2883113250898, 639.402158981354, 651.4723074309824, 8269.338452923752, 8403.70587029451, 8544.74365769028, 8620.698275560326, 8689.217970382695, 523.4242333433269, 557.5400294756842, 575.9107338362561, 622.3993845938691, 637.3148864970176, 3942.0, 3995.880123596297, 4081.2653722330265, 4113.609207125378, 4140.4617399737845, 87704.12295877087, 93613.0, 96607.51696241474, 97000.51499742456, 97024.51487742657, 79005.0, 79918.61526263002, 80066.39867202623, 82756.58621706853, 86585.83535411666, 70618.64690676617, 70904.64547677229, 71036.64481677559, 71797.0, 73852.63073684597, 7189.676464559212, 8495.235428811437, 8649.524276164871, 8671.956640216758, 8979.50612716307, 48808.02383952372, 49832.00335993187, 49853.01175905846, 50334.244986324506, 51211.487885120674, 67845.0, 67947.66026170035, 70646.64676676584, 72678.90981635173, 72872.63563682148, 11080.335179889233, 11185.776284474103, 11192.216544841784, 11275.943620281847, 11527.88472115268, 12000.819987700366, 12038.0, 12092.637220883387, 12459.0, 12533.310667912987, 10218.335808172445, 10862.0, 11262.211645184758, 11507.942460287644, 11879.524819007143, 10935.562577497129, 11042.613508527164, 11824.1131915105, 11948.34284114382, 12078.75842483163, 11139.387333696724, 11650.30098194111, 11720.41397930083, 11856.881431185579, 12188.57339993096, 8631.0, 9456.479893605643, 9776.0, 10034.69895903124, 10370.637027704295, 9814.312998090065, 10070.748231294276, 10192.184625230113, 10203.38779673222, 10218.080372766486], "value": 7157.519243395178, "unit": "score"}], "name": "dromaeo_css", "value": 7699.4674512310185, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 111.0, 118.0, 112.0, 111.0, 110.0, 116.0, 114.0, 112.0, 134.0], "value": 115.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 122.0, 143.0, 162.0, 122.0, 169.0, 118.0, 155.0, 152.0, 122.0], "value": 141.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 191.0, 262.0, 225.0, 202.0, 259.0, 187.0, 185.0, 188.0, 182.0], "value": 206.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 78.0, 73.0, 156.0, 56.0, 55.0, 57.0, 66.0, 148.0, 68.0], "value": 82.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 88.0, 143.0, 80.0, 86.0, 86.0, 123.0, 79.0, 80.0, 124.0], "value": 97.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 79.0, 113.0, 135.0, 104.0, 88.0, 85.0, 87.0, 84.0], "value": 94.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 93.0, 94.0, 93.0, 93.0, 94.0, 94.0, 93.0, 93.0], "value": 93.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 82.0, 84.0, 86.0, 85.0, 85.0, 84.0, 86.0, 84.0, 85.0], "value": 84.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 62.0, 61.0, 61.0, 60.0, 61.0, 61.0, 76.0, 57.0, 54.0], "value": 61.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 64.0, 61.0, 76.0, 71.0, 68.0, 66.0, 68.0, 65.0, 66.0], "value": 66.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 78.0, 82.0, 79.0, 79.0, 79.0, 78.0, 80.0, 81.0, 84.0], "value": 80.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 140.0, 147.0, 142.0, 125.0, 127.0, 145.0, 156.0, 145.0, 137.0], "value": 139.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 166.0, 165.0, 162.0, 218.0, 173.0, 172.0, 159.0, 190.0, 167.0], "value": 173.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 65.0, 66.0, 68.0, 68.0, 66.0, 67.0, 69.0, 66.0, 64.0], "value": 66.7, "unit": "score"}], "name": "kraken", "value": 1504.3, "alertThreshold": 2.0}]} 01:47:07 INFO - Return code: 0 01:47:07 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 01:47:07 ERROR - # TBPL SUCCESS # 01:47:07 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:47:07 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:47:07 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:47:07 INFO - 2016-06-21 01:47:07.921 screenresolution[1958:15653] starting screenresolution argv=screenresolution get 01:47:07 INFO - 2016-06-21 01:47:07.923 screenresolution[1958:15653] Display 0: 1600x1200x32@60 01:47:07 INFO - 2016-06-21 01:47:07.929 screenresolution[1959:15656] starting screenresolution argv=screenresolution list 01:47:07 INFO - Available Modes on Display 0 01:47:07 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:47:07 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:47:07 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:47:07 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:47:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:47:08 INFO - Intel Iris: 01:47:08 INFO - Chipset Model: Intel Iris 01:47:08 INFO - Type: GPU 01:47:08 INFO - Bus: Built-In 01:47:08 INFO - VRAM (Dynamic, Max): 1536 MB 01:47:08 INFO - Vendor: Intel (0x8086) 01:47:08 INFO - Device ID: 0x0a2e 01:47:08 INFO - Revision ID: 0x0009 01:47:08 INFO - Displays: 01:47:08 INFO - Display: 01:47:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:47:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:47:08 INFO - Display Serial Number: 1600x1200 60 01:47:08 INFO - Main Display: Yes 01:47:08 INFO - Mirror: Off 01:47:08 INFO - Online: Yes 01:47:08 INFO - Rotation: Supported 01:47:08 INFO - Return code: 0 01:47:08 INFO - Running post-action listener: _resource_record_post_action 01:47:08 INFO - Running post-run listener: _resource_record_post_run 01:47:09 INFO - Total resource usage - Wall time: 1194s; CPU: 53.0%; Read bytes: 540672; Write bytes: 494372352; Read time: 6; Write time: 1494 01:47:09 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 180654592; Write bytes: 177489920; Read time: 16383; Write time: 360 01:47:09 INFO - run-tests - Wall time: 1173s; CPU: 52.0%; Read bytes: 540672; Write bytes: 296504832; Read time: 6; Write time: 1075 01:47:09 INFO - Running post-run listener: _upload_blobber_files 01:47:09 INFO - Blob upload gear active. 01:47:09 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 01:47:09 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 01:47:09 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', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 01:47:09 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 Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 01:47:09 INFO - (blobuploader) - INFO - Open directory for files ... 01:47:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 01:47:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:47:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:47:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 01:47:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:47:10 INFO - (blobuploader) - INFO - Done attempting. 01:47:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 01:47:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:47:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:47:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 01:47:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:47:11 INFO - (blobuploader) - INFO - Done attempting. 01:47:11 INFO - (blobuploader) - INFO - Iteration through files over. 01:47:11 INFO - Return code: 0 01:47:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 01:47:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 01:47:11 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/13a938de753a132123ba4e8ba9b598c70fa61316dbb6200fae7e1cacc6b5777d6d887b58e48ac68c79ca396331d8ac0b338643273274c647804874ac03837149", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5c8e5cbbb2df99e6f97aa8b4a32ca74b28035f50cc1b15a144a581b99acd3e016f4b77fd84fdba1f25aba72a93776db8822bade35fc4a83383f399bef50bea7b"} 01:47:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 01:47:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 01:47:11 INFO - Contents: 01:47:11 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/13a938de753a132123ba4e8ba9b598c70fa61316dbb6200fae7e1cacc6b5777d6d887b58e48ac68c79ca396331d8ac0b338643273274c647804874ac03837149", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5c8e5cbbb2df99e6f97aa8b4a32ca74b28035f50cc1b15a144a581b99acd3e016f4b77fd84fdba1f25aba72a93776db8822bade35fc4a83383f399bef50bea7b"} 01:47:11 INFO - Running post-run listener: copy_logs_to_upload_dir 01:47:11 INFO - Copying logs to upload dir... 01:47:11 INFO - mkdir: /builds/slave/test/build/upload/logs 01:47:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1254.298952 ========= master_lag: 0.18 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2016-06-21 01:47:11.499394) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-21 01:47:11.502740) ========= 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.Ah25J24ptz/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.Ll9LRXdiJb/Listeners TMPDIR=/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/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/Mozilla-Beta/sha512/13a938de753a132123ba4e8ba9b598c70fa61316dbb6200fae7e1cacc6b5777d6d887b58e48ac68c79ca396331d8ac0b338643273274c647804874ac03837149", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5c8e5cbbb2df99e6f97aa8b4a32ca74b28035f50cc1b15a144a581b99acd3e016f4b77fd84fdba1f25aba72a93776db8822bade35fc4a83383f399bef50bea7b"} build_url:https://queue.taskcluster.net/v1/task/GXHMd--YTjOKyt7o2H8C-A/artifacts/public/build/firefox-48.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010438 build_url: 'https://queue.taskcluster.net/v1/task/GXHMd--YTjOKyt7o2H8C-A/artifacts/public/build/firefox-48.0.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/13a938de753a132123ba4e8ba9b598c70fa61316dbb6200fae7e1cacc6b5777d6d887b58e48ac68c79ca396331d8ac0b338643273274c647804874ac03837149", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5c8e5cbbb2df99e6f97aa8b4a32ca74b28035f50cc1b15a144a581b99acd3e016f4b77fd84fdba1f25aba72a93776db8822bade35fc4a83383f399bef50bea7b"}' ========= master_lag: 0.08 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-21 01:47:11.589003) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 3 secs) (at 2016-06-21 01:47:11.589303) ========= 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.Ah25J24ptz/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.Ll9LRXdiJb/Listeners TMPDIR=/var/folders/n3/6lv2w58n1hbdpjv4x880h_7c00000w/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.005232 ========= master_lag: 3.83 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 3 secs) (at 2016-06-21 01:47:15.423380) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-21 01:47:15.423718) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-21 01:47:15.429693) ========= ========= Total master_lag: 4.15 =========