builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0298 starttime: 1467102383.94 results: success (0) buildid: 20160627053125 builduid: 91d531b5383642ed8679b7f776c5ef70 revision: 8664ad5c52883cb66cb9b46879eb062fb6600756 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-28 01:26:23.940365) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-28 01:26:23.941130) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-28 01:26:23.941471) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-28 01:26:23.964492) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-28 01:26:23.964865) ========= 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.xgLCviu1vt/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.TwZ7EedStd/Listeners TMPDIR=/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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-28 01:26:23-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 129M=0s 2016-06-28 01:26:24 (129 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.117683 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-28 01:26:24.104497) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-28 01:26:24.104813) ========= 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.xgLCviu1vt/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.TwZ7EedStd/Listeners TMPDIR=/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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.021201 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-28 01:26:24.153684) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-06-28 01:26:24.154032) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 8664ad5c52883cb66cb9b46879eb062fb6600756 --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 8664ad5c52883cb66cb9b46879eb062fb6600756 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.xgLCviu1vt/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.TwZ7EedStd/Listeners TMPDIR=/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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-28 01:26:24,236 truncating revision to first 12 chars 2016-06-28 01:26:24,236 Setting DEBUG logging. 2016-06-28 01:26:24,236 attempt 1/10 2016-06-28 01:26:24,236 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/8664ad5c5288?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-28 01:26:26,779 unpacking tar archive at: mozilla-beta-8664ad5c5288/testing/mozharness/ program finished with exit code 0 elapsedTime=2.977978 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-06-28 01:26:27.155589) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-28 01:26:27.155895) ========= script_repo_revision: 8664ad5c52883cb66cb9b46879eb062fb6600756 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-28 01:26:27.156294) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-28 01:26:27.156607) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-28 01:26:27.174555) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 24 mins, 47 secs) (at 2016-06-28 01:26:27.174889) ========= /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.xgLCviu1vt/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.TwZ7EedStd/Listeners TMPDIR=/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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:27 INFO - MultiFileLogger online at 20160628 01:26:27 in /builds/slave/test 01:26:27 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:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:26:27 INFO - {'append_to_log': False, 01:26:27 INFO - 'base_work_dir': '/builds/slave/test', 01:26:27 INFO - 'blob_upload_branch': 'Mozilla-Beta', 01:26:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:26:27 INFO - 'branch': 'Mozilla-Beta', 01:26:27 INFO - 'buildbot_json_path': 'buildprops.json', 01:26:27 INFO - 'config_files': ('talos/mac_config.py',), 01:26:27 INFO - 'default_actions': ('clobber', 01:26:27 INFO - 'read-buildbot-config', 01:26:27 INFO - 'download-and-extract', 01:26:27 INFO - 'populate-webroot', 01:26:27 INFO - 'create-virtualenv', 01:26:27 INFO - 'install', 01:26:27 INFO - 'run-tests'), 01:26:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:26:27 INFO - 'download_minidump_stackwalk': True, 01:26:27 INFO - 'download_symbols': 'ondemand', 01:26:27 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:26:27 INFO - 'tooltool.py': '/tools/tooltool.py', 01:26:27 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:26:27 INFO - '/tools/misc-python/virtualenv.py')}, 01:26:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:26:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:26:27 INFO - 'installer_path': 'installer.exe', 01:26:27 INFO - 'log_level': 'info', 01:26:27 INFO - 'log_name': 'talos', 01:26:27 INFO - 'log_to_console': True, 01:26:27 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 01:26:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 01:26:27 INFO - 'opt_config_files': (), 01:26:27 INFO - 'pip_index': False, 01:26:27 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:26:27 INFO - 'cmd': ('bash', 01:26:27 INFO - '-c', 01:26:27 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:26:27 INFO - 'enabled': True, 01:26:27 INFO - 'halt_on_failure': False, 01:26:27 INFO - 'name': 'check_screen_resolution'},), 01:26:27 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:26:27 INFO - 'cmd': ('bash', 01:26:27 INFO - '-c', 01:26:27 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:26:27 INFO - 'enabled': True, 01:26:27 INFO - 'halt_on_failure': False, 01:26:27 INFO - 'name': 'check_screen_resolution'},), 01:26:27 INFO - 'run_cmd_checks_enabled': True, 01:26:27 INFO - 'sps_profile': False, 01:26:27 INFO - 'sps_profile_interval': 0, 01:26:27 INFO - 'suite': 'dromaeojs', 01:26:27 INFO - 'system_bits': '32', 01:26:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:26:27 INFO - 'title': 't-yosemite-r7-0298', 01:26:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:26:27 INFO - 'use_talos_json': True, 01:26:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:26:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:26:27 INFO - 'work_dir': 'build'} 01:26:27 INFO - ##### 01:26:27 INFO - ##### Running clobber step. 01:26:27 INFO - ##### 01:26:27 INFO - Running pre-action listener: _resource_record_pre_action 01:26:27 INFO - Running main action method: clobber 01:26:27 INFO - rmtree: /builds/slave/test/build 01:26:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:26:28 INFO - Running post-action listener: _resource_record_post_action 01:26:28 INFO - ##### 01:26:28 INFO - ##### Running read-buildbot-config step. 01:26:28 INFO - ##### 01:26:28 INFO - Running pre-action listener: _resource_record_pre_action 01:26:28 INFO - Running main action method: read_buildbot_config 01:26:28 INFO - Using buildbot properties: 01:26:28 INFO - { 01:26:28 INFO - "project": "", 01:26:28 INFO - "product": "firefox", 01:26:28 INFO - "script_repo_revision": "production", 01:26:28 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 01:26:28 INFO - "repository": "", 01:26:28 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 01:26:28 INFO - "buildid": "20160627053125", 01:26:28 INFO - "pgo_build": "False", 01:26:28 INFO - "basedir": "/builds/slave/test", 01:26:28 INFO - "buildnumber": 18, 01:26:28 INFO - "slavename": "t-yosemite-r7-0298", 01:26:28 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 01:26:28 INFO - "platform": "yosemite_r7", 01:26:28 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 01:26:28 INFO - "branch": "mozilla-beta", 01:26:28 INFO - "repo_path": "releases/mozilla-beta", 01:26:28 INFO - "revision": "8664ad5c52883cb66cb9b46879eb062fb6600756", 01:26:28 INFO - "stage_platform": "macosx64", 01:26:28 INFO - "builduid": "91d531b5383642ed8679b7f776c5ef70", 01:26:28 INFO - "slavebuilddir": "test" 01:26:28 INFO - } 01:26:28 INFO - Found installer url https://queue.taskcluster.net/v1/task/FO6Gl0R-S5eBjr032mt9RA/artifacts/public/build/firefox-48.0.en-US.mac.dmg. 01:26:28 INFO - Running post-action listener: _resource_record_post_action 01:26:28 INFO - ##### 01:26:28 INFO - ##### Running download-and-extract step. 01:26:28 INFO - ##### 01:26:28 INFO - Running pre-action listener: _resource_record_pre_action 01:26:28 INFO - Running main action method: download_and_extract 01:26:28 INFO - mkdir: /builds/slave/test/build/tests 01:26:28 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:28 INFO - https://queue.taskcluster.net/v1/task/FO6Gl0R-S5eBjr032mt9RA/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json matches https://queue.taskcluster.net 01:26:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FO6Gl0R-S5eBjr032mt9RA/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json 01:26:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FO6Gl0R-S5eBjr032mt9RA/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json 01:26:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FO6Gl0R-S5eBjr032mt9RA/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:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FO6Gl0R-S5eBjr032mt9RA/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:30 INFO - Downloaded 1165 bytes. 01:26:30 INFO - Reading from file /builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json 01:26:30 INFO - Using the following test package requirements: 01:26:30 INFO - {u'common': [u'firefox-48.0.en-US.mac.common.tests.zip'], 01:26:30 INFO - u'cppunittest': [u'firefox-48.0.en-US.mac.common.tests.zip', 01:26:30 INFO - u'firefox-48.0.en-US.mac.cppunittest.tests.zip'], 01:26:30 INFO - u'gtest': [u'firefox-48.0.en-US.mac.common.tests.zip', 01:26:30 INFO - u'firefox-48.0.en-US.mac.gtest.tests.zip'], 01:26:30 INFO - u'jittest': [u'firefox-48.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 01:26:30 INFO - u'mochitest': [u'firefox-48.0.en-US.mac.common.tests.zip', 01:26:30 INFO - u'firefox-48.0.en-US.mac.mochitest.tests.zip'], 01:26:30 INFO - u'mozbase': [u'firefox-48.0.en-US.mac.common.tests.zip'], 01:26:30 INFO - u'reftest': [u'firefox-48.0.en-US.mac.common.tests.zip', 01:26:30 INFO - u'firefox-48.0.en-US.mac.reftest.tests.zip'], 01:26:30 INFO - u'talos': [u'firefox-48.0.en-US.mac.common.tests.zip', 01:26:30 INFO - u'firefox-48.0.en-US.mac.talos.tests.zip'], 01:26:30 INFO - u'web-platform': [u'firefox-48.0.en-US.mac.common.tests.zip', 01:26:30 INFO - u'firefox-48.0.en-US.mac.web-platform.tests.zip'], 01:26:30 INFO - u'xpcshell': [u'firefox-48.0.en-US.mac.common.tests.zip', 01:26:30 INFO - u'firefox-48.0.en-US.mac.xpcshell.tests.zip']} 01:26:30 INFO - Downloading packages: [u'firefox-48.0.en-US.mac.common.tests.zip'] for test suite category: common 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/FO6Gl0R-S5eBjr032mt9RA/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 01:26:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FO6Gl0R-S5eBjr032mt9RA/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 01:26:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FO6Gl0R-S5eBjr032mt9RA/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 01:26:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FO6Gl0R-S5eBjr032mt9RA/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:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FO6Gl0R-S5eBjr032mt9RA/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:31 INFO - Downloaded 18344127 bytes. 01:26:31 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:31 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:34 INFO - Return code: 0 01:26:34 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:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:26:34 INFO - https://queue.taskcluster.net/v1/task/FO6Gl0R-S5eBjr032mt9RA/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 01:26:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FO6Gl0R-S5eBjr032mt9RA/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 01:26:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FO6Gl0R-S5eBjr032mt9RA/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 01:26:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FO6Gl0R-S5eBjr032mt9RA/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:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FO6Gl0R-S5eBjr032mt9RA/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:36 INFO - Downloaded 18344127 bytes. 01:26:36 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:36 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:39 INFO - Return code: 0 01:26:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:26:39 INFO - https://queue.taskcluster.net/v1/task/FO6Gl0R-S5eBjr032mt9RA/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 01:26:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FO6Gl0R-S5eBjr032mt9RA/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip 01:26:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FO6Gl0R-S5eBjr032mt9RA/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip 01:26:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FO6Gl0R-S5eBjr032mt9RA/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:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FO6Gl0R-S5eBjr032mt9RA/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:40 INFO - Downloaded 10840145 bytes. 01:26:40 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:40 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:41 INFO - Return code: 0 01:26:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:26:41 INFO - https://queue.taskcluster.net/v1/task/FO6Gl0R-S5eBjr032mt9RA/artifacts/public/build/firefox-48.0.en-US.mac.dmg matches https://queue.taskcluster.net 01:26:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FO6Gl0R-S5eBjr032mt9RA/artifacts/public/build/firefox-48.0.en-US.mac.dmg 01:26:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FO6Gl0R-S5eBjr032mt9RA/artifacts/public/build/firefox-48.0.en-US.mac.dmg 01:26:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FO6Gl0R-S5eBjr032mt9RA/artifacts/public/build/firefox-48.0.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0.en-US.mac.dmg 01:26:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FO6Gl0R-S5eBjr032mt9RA/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:43 INFO - Downloaded 86228614 bytes. 01:26:43 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FO6Gl0R-S5eBjr032mt9RA/artifacts/public/build/firefox-48.0.en-US.mac.dmg 01:26:43 INFO - mkdir: /builds/slave/test/properties 01:26:43 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:26:43 INFO - Writing to file /builds/slave/test/properties/build_url 01:26:43 INFO - Contents: 01:26:43 INFO - build_url:https://queue.taskcluster.net/v1/task/FO6Gl0R-S5eBjr032mt9RA/artifacts/public/build/firefox-48.0.en-US.mac.dmg 01:26:44 INFO - Running post-action listener: _resource_record_post_action 01:26:44 INFO - Running post-action listener: set_extra_try_arguments 01:26:44 INFO - ##### 01:26:44 INFO - ##### Running populate-webroot step. 01:26:44 INFO - ##### 01:26:44 INFO - Running pre-action listener: _resource_record_pre_action 01:26:44 INFO - Running main action method: populate_webroot 01:26:44 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:26:44 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:26:44 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:26:44 INFO - '--tppagecycles', 01:26:44 INFO - '7'], 01:26:44 INFO - 'tests': ['tsvgm']}}, 01:26:44 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 01:26:44 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:26:44 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 01:26:44 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 01:26:44 INFO - 'tests': ['dromaeo_css', 'kraken']}, 01:26:44 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 01:26:44 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:26:44 INFO - 'talos_options': ['--disable-e10s'], 01:26:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:26:44 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:26:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:26:44 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:26:44 INFO - 'talos_options': ['--disable-e10s'], 01:26:44 INFO - 'tests': ['damp', 'tps']}, 01:26:44 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:26:44 INFO - 'tests': ['damp', 'tps']}, 01:26:44 INFO - 'g3': {'talos_options': ['--disable-e10s'], 01:26:44 INFO - 'tests': ['dromaeo_dom']}, 01:26:44 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:26:44 INFO - 'other': {'talos_options': ['--disable-e10s'], 01:26:44 INFO - 'tests': ['a11yr', 01:26:44 INFO - 'ts_paint', 01:26:44 INFO - 'tpaint', 01:26:44 INFO - 'sessionrestore', 01:26:44 INFO - 'sessionrestore_no_auto_restore', 01:26:44 INFO - 'tabpaint']}, 01:26:44 INFO - 'other-e10s': {'tests': ['a11yr', 01:26:44 INFO - 'ts_paint', 01:26:44 INFO - 'tpaint', 01:26:44 INFO - 'sessionrestore', 01:26:44 INFO - 'sessionrestore_no_auto_restore', 01:26:44 INFO - 'tabpaint']}, 01:26:44 INFO - 'other-e10s_l64': {'tests': ['a11yr', 01:26:44 INFO - 'ts_paint', 01:26:44 INFO - 'tpaint', 01:26:44 INFO - 'sessionrestore', 01:26:44 INFO - 'sessionrestore_no_auto_restore', 01:26:44 INFO - 'tabpaint']}, 01:26:44 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 01:26:44 INFO - 'ts_paint', 01:26:44 INFO - 'tpaint', 01:26:44 INFO - 'sessionrestore', 01:26:44 INFO - 'sessionrestore_no_auto_restore', 01:26:44 INFO - 'tabpaint']}, 01:26:44 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 01:26:44 INFO - 'tests': ['a11yr', 01:26:44 INFO - 'ts_paint', 01:26:44 INFO - 'tpaint', 01:26:44 INFO - 'sessionrestore', 01:26:44 INFO - 'sessionrestore_no_auto_restore', 01:26:44 INFO - 'tabpaint']}, 01:26:44 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 01:26:44 INFO - 'tests': ['a11yr', 01:26:44 INFO - 'ts_paint', 01:26:44 INFO - 'tpaint', 01:26:44 INFO - 'sessionrestore', 01:26:44 INFO - 'sessionrestore_no_auto_restore', 01:26:44 INFO - 'tabpaint']}, 01:26:44 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 01:26:44 INFO - 'tests': ['tsvgx', 01:26:44 INFO - 'tsvgr_opacity', 01:26:44 INFO - 'tart', 01:26:44 INFO - 'tscrollx', 01:26:44 INFO - 'cart']}, 01:26:44 INFO - 'svgr-e10s': {'tests': ['tsvgx', 01:26:44 INFO - 'tsvgr_opacity', 01:26:44 INFO - 'tart', 01:26:44 INFO - 'tscrollx', 01:26:44 INFO - 'cart']}, 01:26:44 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:26:44 INFO - 'talos_options': ['--disable-e10s'], 01:26:44 INFO - 'tests': ['tp5o']}, 01:26:44 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:26:44 INFO - 'tests': ['tp5o']}, 01:26:44 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:26:44 INFO - 'talos_options': ['--disable-e10s', 01:26:44 INFO - '--xperf_path', 01:26:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:26:44 INFO - 'tests': ['tp5n']}, 01:26:44 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:26:44 INFO - 'talos_options': ['--xperf_path', 01:26:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:26:44 INFO - 'tests': ['tp5n']}}, 01:26:44 INFO - 'talos.zip': {'path': '', 01:26:44 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:26:44 INFO - Running post-action listener: _resource_record_post_action 01:26:44 INFO - ##### 01:26:44 INFO - ##### Running create-virtualenv step. 01:26:44 INFO - ##### 01:26:44 INFO - Running pre-action listener: _resource_record_pre_action 01:26:44 INFO - Running main action method: create_virtualenv 01:26:44 INFO - Creating virtualenv /builds/slave/test/build/venv 01:26:44 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:44 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 01:26:44 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:26:44 INFO - Using real prefix '/tools/python27' 01:26:44 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:26:45 INFO - Installing distribute.............................................................................................................................................................................................done. 01:26:48 INFO - Installing pip.................done. 01:26:48 INFO - Return code: 0 01:26:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 01:26:48 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:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:26:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:26:48 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:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:26:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:26:48 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 0x10ce62a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce72420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd170c81480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd01e00>, '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 0x10cb6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ccfcb20>, '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.xgLCviu1vt/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.TwZ7EedStd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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:48 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:48 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:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xgLCviu1vt/Render', 01:26:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:26:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:26:48 INFO - 'HOME': '/Users/cltbld', 01:26:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:26:48 INFO - 'LOGNAME': 'cltbld', 01:26:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:26:48 INFO - 'MOZ_NO_REMOTE': '1', 01:26:48 INFO - 'NO_EM_RESTART': '1', 01:26:48 INFO - 'PAGER': '/bin/cat', 01:26:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:26:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:26:48 INFO - 'PWD': '/builds/slave/test', 01:26:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:26:48 INFO - 'SHELL': '/bin/bash', 01:26:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TwZ7EedStd/Listeners', 01:26:48 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 01:26:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:26:48 INFO - 'USER': 'cltbld', 01:26:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:26:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:26:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:26:48 INFO - 'XPC_FLAGS': '0x0', 01:26:48 INFO - 'XPC_SERVICE_NAME': '0', 01:26:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:26:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:26:48 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:48 INFO - Cleaning up... 01:26:48 INFO - Return code: 0 01:26:48 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 01:26:48 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:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:26:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:26:48 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:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:26:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:26:48 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 0x10ce62a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce72420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd170c81480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd01e00>, '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 0x10cb6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ccfcb20>, '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.xgLCviu1vt/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.TwZ7EedStd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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:48 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:48 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:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xgLCviu1vt/Render', 01:26:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:26:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:26:48 INFO - 'HOME': '/Users/cltbld', 01:26:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:26:48 INFO - 'LOGNAME': 'cltbld', 01:26:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:26:48 INFO - 'MOZ_NO_REMOTE': '1', 01:26:48 INFO - 'NO_EM_RESTART': '1', 01:26:48 INFO - 'PAGER': '/bin/cat', 01:26:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:26:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:26:48 INFO - 'PWD': '/builds/slave/test', 01:26:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:26:48 INFO - 'SHELL': '/bin/bash', 01:26:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TwZ7EedStd/Listeners', 01:26:48 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 01:26:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:26:48 INFO - 'USER': 'cltbld', 01:26:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:26:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:26:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:26:48 INFO - 'XPC_FLAGS': '0x0', 01:26:48 INFO - 'XPC_SERVICE_NAME': '0', 01:26:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:26:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:26:48 INFO - Downloading/unpacking psutil>=0.7.1 01:26:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:26:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:26:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:26:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:26:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:26:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:26:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:26:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:26:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:26:51 INFO - Installing collected packages: psutil 01:26:51 INFO - Running setup.py install for psutil 01:26:52 INFO - building 'psutil._psutil_osx' extension 01:26:52 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:52 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:52 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:52 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:52 INFO - building 'psutil._psutil_posix' extension 01:26:52 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:52 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 01:26:52 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 01:26:52 INFO - ^ 01:26:52 INFO - 1 warning generated. 01:26:52 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:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:26:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:26:52 INFO - Successfully installed psutil 01:26:52 INFO - Cleaning up... 01:26:52 INFO - Return code: 0 01:26:52 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 01:26:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:26:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:26:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:26:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:26:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:26:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:26:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10ce62a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce72420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd170c81480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd01e00>, '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 0x10cb6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ccfcb20>, '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.xgLCviu1vt/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.TwZ7EedStd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 01:26:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 01:26:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xgLCviu1vt/Render', 01:26:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:26:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:26:52 INFO - 'HOME': '/Users/cltbld', 01:26:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:26:52 INFO - 'LOGNAME': 'cltbld', 01:26:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:26:52 INFO - 'MOZ_NO_REMOTE': '1', 01:26:52 INFO - 'NO_EM_RESTART': '1', 01:26:52 INFO - 'PAGER': '/bin/cat', 01:26:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:26:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:26:52 INFO - 'PWD': '/builds/slave/test', 01:26:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:26:52 INFO - 'SHELL': '/bin/bash', 01:26:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TwZ7EedStd/Listeners', 01:26:52 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 01:26:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:26:52 INFO - 'USER': 'cltbld', 01:26:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:26:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:26:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:26:52 INFO - 'XPC_FLAGS': '0x0', 01:26:52 INFO - 'XPC_SERVICE_NAME': '0', 01:26:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:26:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:26:53 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 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:55 INFO - Downloading mozsystemmonitor-0.0.tar.gz 01:26:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:26:55 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:55 INFO - Installing collected packages: mozsystemmonitor 01:26:55 INFO - Running setup.py install for mozsystemmonitor 01:26:55 INFO - Successfully installed mozsystemmonitor 01:26:55 INFO - Cleaning up... 01:26:55 INFO - Return code: 0 01:26:55 INFO - Installing blobuploader==1.2.4 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', '--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 0x10ce62a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce72420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd170c81480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd01e00>, '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 0x10cb6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ccfcb20>, '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.xgLCviu1vt/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.TwZ7EedStd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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', '--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:55 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:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xgLCviu1vt/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.TwZ7EedStd/Listeners', 01:26:55 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:26:56 INFO - Downloading/unpacking blobuploader==1.2.4 01:26:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:26:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:26:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:26:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:26:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:26:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:26:58 INFO - Downloading blobuploader-1.2.4.tar.gz 01:26:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:26:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:26:58 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:58 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:58 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:58 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:58 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:58 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:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:26:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:26:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:26:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:26:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:26:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:26:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:26:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:26:59 INFO - Downloading docopt-0.6.1.tar.gz 01:26:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:26:59 INFO - Installing collected packages: blobuploader, requests, docopt 01:26:59 INFO - Running setup.py install for blobuploader 01:26:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:26:59 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:26:59 INFO - Running setup.py install for requests 01:27:00 INFO - Running setup.py install for docopt 01:27:00 INFO - Successfully installed blobuploader requests docopt 01:27:00 INFO - Cleaning up... 01:27:00 INFO - Return code: 0 01:27:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:27:00 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:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:27:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27:00 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:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:27:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27:00 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 0x10ce62a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce72420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd170c81480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd01e00>, '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 0x10cb6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ccfcb20>, '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.xgLCviu1vt/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.TwZ7EedStd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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:00 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:27:00 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:27:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xgLCviu1vt/Render', 01:27:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:27:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:27:00 INFO - 'HOME': '/Users/cltbld', 01:27:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:27:00 INFO - 'LOGNAME': 'cltbld', 01:27:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:27:00 INFO - 'MOZ_NO_REMOTE': '1', 01:27:00 INFO - 'NO_EM_RESTART': '1', 01:27:00 INFO - 'PAGER': '/bin/cat', 01:27:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:27:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:27:00 INFO - 'PWD': '/builds/slave/test', 01:27:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:27:00 INFO - 'SHELL': '/bin/bash', 01:27:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TwZ7EedStd/Listeners', 01:27:00 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 01:27:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:27:00 INFO - 'USER': 'cltbld', 01:27:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:27:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:27:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:27:00 INFO - 'XPC_FLAGS': '0x0', 01:27:00 INFO - 'XPC_SERVICE_NAME': '0', 01:27:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:27:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:27:01 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-LwgeFi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:27:01 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-E35SXm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:27:01 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-f90kg0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:27:01 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-SFwgha-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:27:01 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-r1FDUo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:27:01 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-zNS9ls-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:27:01 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-A8F3GQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:27:01 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-iVxdu1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:27:01 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-4y1iif-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:27:01 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-2jB0aJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:27:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:27:02 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-JQo1Js-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:27:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:27:02 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-8kG4eN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:27:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:27:02 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-nog7co-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:27:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:27:02 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-r3q9hO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:27:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:27:02 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-0IT0Cr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:27:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:27:02 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-iKfwPz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:27:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:27:02 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-dtjL3k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:27:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:27:02 INFO - Running setup.py install for manifestparser 01:27:02 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:27:02 INFO - Running setup.py install for mozcrash 01:27:03 INFO - Running setup.py install for mozdebug 01:27:03 INFO - Running setup.py install for mozdevice 01:27:03 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 01:27:03 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:27:03 INFO - Running setup.py install for mozfile 01:27:03 INFO - Running setup.py install for mozhttpd 01:27:03 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:27:03 INFO - Running setup.py install for mozinfo 01:27:03 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:27:03 INFO - Running setup.py install for mozInstall 01:27:03 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:27:03 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:27:03 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:27:03 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:27:03 INFO - Running setup.py install for mozleak 01:27:04 INFO - Running setup.py install for mozlog 01:27:04 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:27:04 INFO - Running setup.py install for moznetwork 01:27:04 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:27:04 INFO - Running setup.py install for mozprocess 01:27:04 INFO - Running setup.py install for mozprofile 01:27:04 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:27:04 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:27:04 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:27:04 INFO - Running setup.py install for mozrunner 01:27:04 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:27:04 INFO - Running setup.py install for mozscreenshot 01:27:05 INFO - Running setup.py install for moztest 01:27:05 INFO - Running setup.py install for mozversion 01:27:05 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:27:05 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:27:05 INFO - Cleaning up... 01:27:05 INFO - Return code: 0 01:27:05 INFO - Installing None 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', '-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 0x10ce62a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce72420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd170c81480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd01e00>, '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 0x10cb6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ccfcb20>, '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.xgLCviu1vt/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.TwZ7EedStd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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', '-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:27:05 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:27:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xgLCviu1vt/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.TwZ7EedStd/Listeners', 01:27:05 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:27:05 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-SpeZ3Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:27:05 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:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:27:05 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-VCsPSk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:27:05 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:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:27:05 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-dSiPIV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:27:05 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:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:27:05 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-9cTEaf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:27:06 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:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:27:06 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-o7XRX7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:27:06 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:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:27:06 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-anVOdO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:27:06 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:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:27:06 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-ltrZjg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:27:06 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:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:27:06 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-Bs2vo2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:27:06 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:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:27:06 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-KVVtg0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:27:06 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:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:27:06 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-VFYiUe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:27:06 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:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:27:06 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-JNz7le-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:27:06 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:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:27:06 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-rdg6Nx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:27:06 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:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:27:06 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-M9BMZO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:27:07 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:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:27:07 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-O3A74G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:27:07 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:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:27:07 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-O510EB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:27:07 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:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:27:07 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-9qVwYf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:27:07 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:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:27:07 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-RVA7Ql-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:27:07 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:07 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:07 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:07 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:07 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:07 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:07 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 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:09 INFO - Downloading blessings-1.6.tar.gz 01:27:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:27:09 INFO - Installing collected packages: blessings 01:27:09 INFO - Running setup.py install for blessings 01:27:10 INFO - Successfully installed blessings 01:27:10 INFO - Cleaning up... 01:27:10 INFO - Return code: 0 01:27:10 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:27:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:27:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:27:10 INFO - Reading from file tmpfile_stdout 01:27:10 INFO - Current package versions: 01:27:10 INFO - blessings == 1.6 01:27:10 INFO - blobuploader == 1.2.4 01:27:10 INFO - docopt == 0.6.1 01:27:10 INFO - manifestparser == 1.1 01:27:10 INFO - mozInstall == 1.12 01:27:10 INFO - mozcrash == 0.17 01:27:10 INFO - mozdebug == 0.1 01:27:10 INFO - mozdevice == 0.48 01:27:10 INFO - mozfile == 1.2 01:27:10 INFO - mozhttpd == 0.7 01:27:10 INFO - mozinfo == 0.9 01:27:10 INFO - mozleak == 0.1 01:27:10 INFO - mozlog == 3.1 01:27:10 INFO - moznetwork == 0.27 01:27:10 INFO - mozprocess == 0.22 01:27:10 INFO - mozprofile == 0.28 01:27:10 INFO - mozrunner == 6.11 01:27:10 INFO - mozscreenshot == 0.1 01:27:10 INFO - mozsystemmonitor == 0.0 01:27:10 INFO - moztest == 0.7 01:27:10 INFO - mozversion == 1.4 01:27:10 INFO - psutil == 3.1.1 01:27:10 INFO - requests == 1.2.3 01:27:10 INFO - wsgiref == 0.1.2 01:27:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:27:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:27:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:27:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:27:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:27:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27:10 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 0x10ce62a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce72420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd170c81480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd01e00>, '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 0x10cb6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ccfcb20>, '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.xgLCviu1vt/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.TwZ7EedStd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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:10 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:10 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:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xgLCviu1vt/Render', 01:27:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:27:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:27:10 INFO - 'HOME': '/Users/cltbld', 01:27:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:27:10 INFO - 'LOGNAME': 'cltbld', 01:27:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:27:10 INFO - 'MOZ_NO_REMOTE': '1', 01:27:10 INFO - 'NO_EM_RESTART': '1', 01:27:10 INFO - 'PAGER': '/bin/cat', 01:27:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:27:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:27:10 INFO - 'PWD': '/builds/slave/test', 01:27:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:27:10 INFO - 'SHELL': '/bin/bash', 01:27:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TwZ7EedStd/Listeners', 01:27:10 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 01:27:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:27:10 INFO - 'USER': 'cltbld', 01:27:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:27:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:27:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:27:10 INFO - 'XPC_FLAGS': '0x0', 01:27:10 INFO - 'XPC_SERVICE_NAME': '0', 01:27:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:27:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:27:10 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:10 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:10 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:10 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:10 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:10 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:10 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:10 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:10 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:10 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:10 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:10 INFO - Cleaning up... 01:27:10 INFO - Return code: 0 01:27:10 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 01:27:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:27:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:27:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:27:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:27:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ce62a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce72420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd170c81480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd01e00>, '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 0x10cb6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ccfcb20>, '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.xgLCviu1vt/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.TwZ7EedStd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 01:27:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 01:27:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xgLCviu1vt/Render', 01:27:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:27:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:27:10 INFO - 'HOME': '/Users/cltbld', 01:27:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:27:10 INFO - 'LOGNAME': 'cltbld', 01:27:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:27:10 INFO - 'MOZ_NO_REMOTE': '1', 01:27:10 INFO - 'NO_EM_RESTART': '1', 01:27:10 INFO - 'PAGER': '/bin/cat', 01:27:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:27:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:27:10 INFO - 'PWD': '/builds/slave/test', 01:27:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:27:10 INFO - 'SHELL': '/bin/bash', 01:27:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TwZ7EedStd/Listeners', 01:27:10 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 01:27:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:27:10 INFO - 'USER': 'cltbld', 01:27:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:27:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:27:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:27:10 INFO - 'XPC_FLAGS': '0x0', 01:27:10 INFO - 'XPC_SERVICE_NAME': '0', 01:27:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:27:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:27:11 INFO - Downloading/unpacking jsonschema 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:13 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:27:13 INFO - Downloading/unpacking functools32 (from jsonschema) 01:27:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:27:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:27:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:27:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:27:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:27:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:27:13 INFO - Downloading functools32-3.2.3-2.tar.gz 01:27:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 01:27:13 INFO - warning: no files found matching '*.txt' 01:27:13 INFO - no previously-included directories found matching 'build' 01:27:13 INFO - no previously-included directories found matching 'dist' 01:27:13 INFO - no previously-included directories found matching '.git*' 01:27:13 INFO - Installing collected packages: jsonschema, functools32 01:27:14 INFO - Running setup.py install for functools32 01:27:14 INFO - warning: no files found matching '*.txt' 01:27:14 INFO - no previously-included directories found matching 'build' 01:27:14 INFO - no previously-included directories found matching 'dist' 01:27:14 INFO - no previously-included directories found matching '.git*' 01:27:14 INFO - Successfully installed jsonschema functools32 01:27:14 INFO - Cleaning up... 01:27:14 INFO - Return code: 0 01:27:14 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 01:27:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:27:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:27:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:27:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:27:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:27:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ce62a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce72420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd170c81480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd01e00>, '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 0x10cb6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ccfcb20>, '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.xgLCviu1vt/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.TwZ7EedStd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 01:27:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 01:27:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xgLCviu1vt/Render', 01:27:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:27:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:27:14 INFO - 'HOME': '/Users/cltbld', 01:27:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:27:14 INFO - 'LOGNAME': 'cltbld', 01:27:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:27:14 INFO - 'MOZ_NO_REMOTE': '1', 01:27:14 INFO - 'NO_EM_RESTART': '1', 01:27:14 INFO - 'PAGER': '/bin/cat', 01:27:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:27:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:27:14 INFO - 'PWD': '/builds/slave/test', 01:27:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:27:14 INFO - 'SHELL': '/bin/bash', 01:27:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TwZ7EedStd/Listeners', 01:27:14 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 01:27:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:27:14 INFO - 'USER': 'cltbld', 01:27:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:27:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:27:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:27:14 INFO - 'XPC_FLAGS': '0x0', 01:27:14 INFO - 'XPC_SERVICE_NAME': '0', 01:27:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:27:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:27:14 INFO - Downloading/unpacking flake8 01:27:14 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:14 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:14 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:14 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:14 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:14 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:16 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 01:27:16 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 01:27:16 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:16 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:16 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:16 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:16 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:16 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:17 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 01:27:17 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 01:27:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:27:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:27:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:27:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:27:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:27:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:27:17 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 01:27:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:27:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:27:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:27:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:27:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:27:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:27:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 01:27:18 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 01:27:18 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 01:27:18 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 01:27:18 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 01:27:18 INFO - no previously-included directories found matching 'docs/_build' 01:27:18 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 01:27:18 INFO - Running setup.py install for pep8 01:27:18 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 01:27:18 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 01:27:18 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 01:27:18 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 01:27:18 INFO - no previously-included directories found matching 'docs/_build' 01:27:18 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 01:27:18 INFO - Successfully installed flake8 mccabe pyflakes pep8 01:27:18 INFO - Cleaning up... 01:27:18 INFO - Return code: 0 01:27:18 INFO - Running post-action listener: _resource_record_post_action 01:27:18 INFO - Running post-action listener: _start_resource_monitoring 01:27:18 INFO - Starting resource monitoring. 01:27:18 INFO - ##### 01:27:18 INFO - ##### Running install step. 01:27:18 INFO - ##### 01:27:18 INFO - Running pre-action listener: _resource_record_pre_action 01:27:18 INFO - Running main action method: install 01:27:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:27:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:27:18 INFO - Reading from file tmpfile_stdout 01:27:18 INFO - Detecting whether we're running mozinstall >=1.0... 01:27:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:27:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:27:18 INFO - Reading from file tmpfile_stdout 01:27:18 INFO - Output received: 01:27:18 INFO - Usage: mozinstall [options] installer 01:27:18 INFO - Options: 01:27:18 INFO - -h, --help show this help message and exit 01:27:18 INFO - -d DEST, --destination=DEST 01:27:18 INFO - Directory to install application into. [default: 01:27:18 INFO - "/builds/slave/test"] 01:27:18 INFO - --app=APP Application being installed. [default: firefox] 01:27:18 INFO - mkdir: /builds/slave/test/build/application 01:27:18 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:18 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:40 INFO - Reading from file tmpfile_stdout 01:27:40 INFO - Output received: 01:27:40 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 01:27:40 INFO - Running post-action listener: _resource_record_post_action 01:27:40 INFO - ##### 01:27:40 INFO - ##### Running run-tests step. 01:27:40 INFO - ##### 01:27:40 INFO - Running pre-action listener: _resource_record_pre_action 01:27:40 INFO - Running main action method: run_tests 01:27:40 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:27:40 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:27:40 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:27:40 INFO - 2016-06-28 01:27:40.701 screenresolution[2517:31271] starting screenresolution argv=screenresolution get 01:27:40 INFO - 2016-06-28 01:27:40.704 screenresolution[2517:31271] Display 0: 1600x1200x32@60 01:27:40 INFO - 2016-06-28 01:27:40.710 screenresolution[2518:31274] starting screenresolution argv=screenresolution list 01:27:40 INFO - Available Modes on Display 0 01:27:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:27:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:27:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:27:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:27:40 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:27:40 INFO - Intel Iris: 01:27:40 INFO - Chipset Model: Intel Iris 01:27:40 INFO - Type: GPU 01:27:40 INFO - Bus: Built-In 01:27:40 INFO - VRAM (Dynamic, Max): 1536 MB 01:27:40 INFO - Vendor: Intel (0x8086) 01:27:40 INFO - Device ID: 0x0a2e 01:27:40 INFO - Revision ID: 0x0009 01:27:40 INFO - Displays: 01:27:40 INFO - Display: 01:27:40 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:27:40 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:27:40 INFO - Display Serial Number: 1600x1200 60 01:27:40 INFO - Main Display: Yes 01:27:40 INFO - Mirror: Off 01:27:40 INFO - Online: Yes 01:27:40 INFO - Rotation: Supported 01:27:40 INFO - Return code: 0 01:27:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 01:27:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 01:27:40 INFO - Python 2.7.3 01:27:40 INFO - Return code: 0 01:27:40 INFO - grabbing minidump binary from tooltool 01:27:40 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:40 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 0x10cd01e00>, '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 0x10cb6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ccfcb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:27:40 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:40 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:41 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:27:41 INFO - Return code: 0 01:27:41 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 01:27:41 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 01:27:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 01:27:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 01:27:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 01:27:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 01:27:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 01:27:42 INFO - Return code: 0 01:27:42 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/FO6Gl0R-S5eBjr032mt9RA/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0298', '--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:42 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/FO6Gl0R-S5eBjr032mt9RA/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0298 --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:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xgLCviu1vt/Render', 01:27:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:27:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:27:42 INFO - 'HOME': '/Users/cltbld', 01:27:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:27:42 INFO - 'LOGNAME': 'cltbld', 01:27:42 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:27:42 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 01:27:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:27:42 INFO - 'MOZ_NO_REMOTE': '1', 01:27:42 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:27:42 INFO - 'NO_EM_RESTART': '1', 01:27:42 INFO - 'PAGER': '/bin/cat', 01:27:42 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:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:27:42 INFO - 'PWD': '/builds/slave/test', 01:27:42 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:27:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:27:42 INFO - 'SHELL': '/bin/bash', 01:27:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TwZ7EedStd/Listeners', 01:27:42 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 01:27:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:27:42 INFO - 'USER': 'cltbld', 01:27:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:27:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:27:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:27:42 INFO - 'XPC_FLAGS': '0x0', 01:27:42 INFO - 'XPC_SERVICE_NAME': '0', 01:27:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:27:42 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/FO6Gl0R-S5eBjr032mt9RA/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0298', '--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:42 INFO - mozversion application_buildid: 20160627053125 01:27:42 INFO - mozversion application_changeset: 8664ad5c52883cb66cb9b46879eb062fb6600756 01:27:42 INFO - mozversion application_display_name: Firefox 01:27:42 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:27:42 INFO - mozversion application_name: Firefox 01:27:42 INFO - mozversion application_remotingname: firefox 01:27:42 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 01:27:42 INFO - mozversion application_vendor: Mozilla 01:27:42 INFO - mozversion application_version: 48.0 01:27:42 INFO - mozversion platform_buildid: 20160627053125 01:27:42 INFO - mozversion platform_changeset: 8664ad5c52883cb66cb9b46879eb062fb6600756 01:27:42 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 01:27:42 INFO - mozversion platform_version: 48.0 01:27:42 INFO - using testdate: 1467102462 01:27:42 INFO - actual date: 1467102462 01:27:42 INFO - starting webserver on 'localhost:56321' 01:27:42 INFO - SUITE-START | Running 2 tests 01:27:42 INFO - TEST-START | dromaeo_css 01:27:42 INFO - operating with platform_type : mac_ 01:27:42 INFO - Initialising browser for dromaeo_css test... 01:27:42 INFO - TEST-INFO | started process 2536 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/tmpcfAdSV/profile http://localhost:56321/getInfo.html) 01:27:44 INFO - PROCESS | 2536 | __metrics Screen width/height:1600/1200 01:27:44 INFO - PROCESS | 2536 | colorDepth:24 01:27:44 INFO - PROCESS | 2536 | Browser inner width/height: 1024/689 01:27:44 INFO - PROCESS | 2536 | __metrics 01:27:44 INFO - PROCESS | 2536 | 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:44 ERROR - PROCESS | 2536 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 01:27:45 INFO - TEST-INFO | 2536: exit 0 01:27:45 INFO - Browser initialized. 01:27:45 INFO - Running cycle 1/1 for dromaeo_css test... 01:27:45 INFO - TEST-INFO | started process 2539 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/tmpcfAdSV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:27:56 INFO - PROCESS | 2539 | RSS: Main: 161464320 01:27:56 INFO - PROCESS | 2539 | 01:28:48 INFO - PROCESS | 2539 | 1467102528343 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:58 INFO - PROCESS | 2539 | Cycle 1(1): loaded http://localhost:56321/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56321/tests/dromaeo/cssquery-ext.html) 01:30:59 INFO - PROCESS | 2539 | RSS: Main: 305123328 01:30:59 INFO - PROCESS | 2539 | 01:34:02 INFO - PROCESS | 2539 | Cycle 1(1): loaded http://localhost:56321/tests/dromaeo/cssquery-ext.html (next: http://localhost:56321/tests/dromaeo/cssquery-jquery.html) 01:34:03 INFO - PROCESS | 2539 | RSS: Main: 311435264 01:34:03 INFO - PROCESS | 2539 | 01:37:05 INFO - PROCESS | 2539 | Cycle 1(1): loaded http://localhost:56321/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56321/tests/dromaeo/cssquery-mootools.html) 01:37:05 INFO - PROCESS | 2539 | RSS: Main: 310202368 01:37:05 INFO - PROCESS | 2539 | 01:40:07 INFO - PROCESS | 2539 | Cycle 1(1): loaded http://localhost:56321/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56321/tests/dromaeo/cssquery-prototype.html) 01:40:08 INFO - PROCESS | 2539 | RSS: Main: 311140352 01:40:08 INFO - PROCESS | 2539 | 01:43:11 INFO - PROCESS | 2539 | Cycle 1(1): loaded http://localhost:56321/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56321/tests/dromaeo/cssquery-yui.html) 01:43:12 INFO - PROCESS | 2539 | RSS: Main: 274944000 01:43:12 INFO - PROCESS | 2539 | 01:46:14 INFO - PROCESS | 2539 | Cycle 1(1): loaded http://localhost:56321/tests/dromaeo/cssquery-yui.html (next: http://localhost:56321/tests/dromaeo/cssquery-dojo.html) 01:46:15 INFO - PROCESS | 2539 | RSS: Main: 317894656 01:46:15 INFO - PROCESS | 2539 | 01:46:15 INFO - PROCESS | 2539 | __start_tp_report 01:46:15 INFO - PROCESS | 2539 | _x_x_mozilla_page_load 01:46:15 INFO - PROCESS | 2539 | _x_x_mozilla_page_load_details 01:46:15 INFO - PROCESS | 2539 | |i|pagename|runs| 01:46:15 INFO - PROCESS | 2539 | |0;dojo.html;3136.2316232523026;3585.1754096557775;3619.2037751694634;3628.9637103628957;3685.8525658973645;6001.069834175699;6020.608660437079;6027.668478233695;6050;6062.878742425154;6009.489193418553;6051.364606716286;6065.302490213627;6068.787592434266;6075.058365953273;5362.1420572708375;5415.566754659628;5464.6994415307145;5504.036793561111;5529.087700529408;5462.890742185154;5529.253550770655;5531.446855314456;5539.833804985857;5551.611387202897;5426.810061647843;5482.259894914176;5511.641743286694;5520.227168196456;5544.306961629796;5464.371597266306;5508.173773933902;5537.501624853742;5540.335159780811;5586.720663966786;5359.839204823861;5367.033933891937;5411.972940135274;5443.673379597197;5458.699771512565;5784.768609255625;5846.7076646167525;5851.38560451155;5889.116632505115;5891.79378721747;6326.462250708695;6335.461485773715;6369.108324834527;6376.043593460971;6383.39357761012;6225.7509699611965;6311.842203944892;6400.071989561534;6459.5478316517865;6473.417392434703;6141.662208578527;6146.7233974471255;6150.354212807681;6158.168647232566;6165.229346331708;6599.8020059398295;6600.207975042929;6610.041543976096;6613.173353330833;6617.669116544153;6198.969005154945;6204.13142160098;6270.467010304084;6277.717502712298;6331.461825744771;6423.775167869102;6457.418832305115;6461.870762584722;6465.15952926126;6465.870682586416;6281.9948808190475;6298.338674439209;6321.557490975588;6335.968320158462;6347.143135676717;6193.473554747807;6237.906431403532;6241.500679945593;6257.9674353731125;6262.12330273762;5071.137906555894;5077.796888124508;5084.694918304914;5116.769745361393;5182.559482444034;4577.954220457819;4609.124266389375;4636.930446043312;4661.230896901973;4661.906761864744;6119;6134.141220229171;6138.662373569497;6141.662208578571;6144.047672610753;6240.875182496325;6325.335839736854;6407.711652975628;6425.518086143558;6427.260865000491;6085.604435711664;6086.939130608637;6106.664133472702;6120.326764055951;6132.110843927606;5465.152901300305;5631.943680563142;5649.6327738696855;5658.405867383865;5666.914996275059;6546.279909209983;6567.113439685581;6651.7006734698025;6673.566218195801;6688.398044176048;6372.267189273293;6377.7767778127545;6385.7126429309865;6394.552381333262;6407.871842563216;6472.967635161794;6646.667666616747;6654.700538475877;6661.2006559213205;6673;6231.1276421300145;6257.7184026718;6328.683565821782;6355.078513615592;6360.618362898241;6399.136116624196;6435.452986496013;6437.002264648987;6438.066480360415;6438.323975982455;4867.756612169448;6170;6213.254409470893;6250.249970003629;6279.748810047547;5628.915566266591;6248.687565621792;6258.874822503615;6262.060690896402;6266.530010249158;6223.502119830312;6246.156768836149;6252.531060170414;6266.530010249158;6271;5776.393478684678;6141.762184092961;6177.6911154443;6200.720967556561;6298.401651843067;6238.282597501164;6250.656213908097;6274.215723034621;6276.780312689034;6286.52851036165 01:46:15 INFO - PROCESS | 2539 | |1;ext.html;546.8414159893629;550.4220568403176;571.3458090486396;576.7375843990984;586.4604563801305;34621.826890865545;41185.17629647409;41945.58054419451;42125.15749685004;42128.157436851245;46365;46906.53093469073;47209;47259.0548189036;47380;9835.213182945365;10060.346077504939;10085.596576136983;10102.898971010269;10105.595776168944;9888.159506441962;10006.399616023025;10006.89993100067;10016.949915250414;10020.649277275297;10025.548850301717;10052.44711540865;10062.345947513424;10071.194304455626;10074.445905475195;9956.701298961043;9968.601255949754;10012.64955726546;10027.34822236556;10035.799284014352;9746.707598772047;9874.703758887174;9881.505924703735;9883.406995580217;9884;42670.146597067884;43183.352249715965;43200.135997280195;43242.351364729555;43267.350989735176;3379.024861809965;3487.6337984511515;3554.431290993429;3597.388443964532;3916.060145565044;28724.425511489862;29130;29235;29854.402911941856;31032.379352412827;2769.418422131355;2790.525610646195;2830.830150190995;2889.4221155768932;2896.2614533294086;501587;503572;506406;507768;508447.20434784977;37402.251954960746;37945.62054379421;38049.80975095162;65722.67138664276;66303.33696663068;30965.69034309628;37167.25665486729;37358.43962340567;37360.065998350256;37363.813180933925;17025;17257.913710431367;17260.654786904444;17284.04937728437;17295.65408691819;14507.129572225489;14597.489087881873;14600.123992560268;14632.487862924747;14636.487722929647;8585.871211931826;8655.653773848975;8683.782905427288;8737.558302880081;8775.166359195866;3566.375884220251;3608.1160115771804;3610.079429745451;3613.638636136365;3639.8180090995343;964.5755867418313;979.8873129590046;1033.245730616654;1048.6591857646295;1135.840982262484;31499.527507087412;31502.36995260082;31632.36735265282;31662.525062124085;32428.513572295964;1120.2158489057692;1148.190525679394;1191.2911817468591;1259.168948494008;1272.2239433945285;14010.626148269568;15871.841281587036;16071.196440177944;16088.517344479684;16258.430954916521;664681;668112;672091;673273.6336318385;676153;63681;64772.05683829493;65149;65285;65329;1560.5708430181792;1564.092826160824;1576.4876415164888;1578;1588.7299159142974;30047.24881877883;30651.08046758601;30656.773729050587;30985.380292394475;30995.845020774752;49792.00415991732;55854.72072639611;56000.439995601155;56031;56083;561248;562182;564120;564352;564869;27839.721602783713;27875;44767.65697029094;46468;46575.76712116418;45844.54155458403;47485.81285467891;47873;49355.50644493653;49379;32139.761484891314;38656.42015369828;38729.225415492096;39603.80198099106;39835.80082099571;35440.64559354477;38801.835944921695;38943.80528097455;39159.80420097881;39843.00392490211;34032.31935361328;34461.13847153841;35198.47202292019;39597.40603891001;39758.80120599378;28828.279293017844;31959.68040319567;32627.510587340734;32707;33128.83435582807;24907.62638560459;25092.12177573776;25265.62101568515;25300.228983970945;25324.493510129327 01:46:15 INFO - PROCESS | 2539 | |2;jquery.html;406.666533442577;420.4576096835083;420.4618088846277;423.8749568877181;429.35382249714195;18805.529861753465;20817.959102044893;21281.321579787607;21498;23230.767692323072;21490.892545537252;21639.59010247438;21917.561648767056;22445.98993045313;22454.989525471352;6044.818655440333;6175.228096487939;6257;6265.028920517327;6266.373362663743;4507.706999045068;4673.76631168442;4771.021940502206;6182.690865456731;6245.437910588046;4733.692309999857;6693;6851.862962740742;6852.177738671367;6858.451323894076;6541.869162616721;6831.35102165296;6836.65816709165;6840.794776156698;6861.039454476378;4097.918041639151;6054.939450605526;6059.545534084918;6068.696565171724;6077.665728384937;26003.829827657803;26626.467470650674;26898.193054208405;26960;27012.054578089672;2302.735185453661;2385.33210701004;2670.5994100884827;2694.8248363856287;2790.9812918284883;15283;15454.381824726997;15535.223238838125;15647.843521564753;16055;1795.4523870219577;1821.244183663778;1864.9067546622614;1868.2060124447191;1881.3820114701346;80636.59681701612;99919.50040249825;102995;103144.48427757889;122350.16474752886;9341.953290233505;11507;11603.941980290214;11635.956784727767;11867.821982670266;14761.483348082766;14773.778393324108;14853.48012819546;14855;14859.331330090172;9227.44635321883;9290.210332121711;9324.720258392259;9427.104425079606;9736.902630973742;9485.383450075724;9515.524223788922;9518.714438566854;9602.183814375856;9632.807343853083;7007;7059.047028651066;7090.290970902868;7091.219965803757;7113.57318560888;1917.6931690929384;1942.9805701943083;1943.5724140689026;1962.4407043992394;1984.3650031989625;931.5435436636;968.2399316536485;994.4132961552718;1037.1858091398217;1143.1997601678859;17924.731129033076;21010.894945525175;21385.46536336573;21988.450288742588;22242.88878555597;1032.5714828346302;1057.8519007338978;1065.5205157679075;1077;1107.6898468428853;10070.848937265946;11469.369184694755;11551.480183391599;11950.701232469259;11959.342236176919;645255;646053;646894;656803;678709;63353.049704254474;73787.31063446806;96784.03215967891;115690;119673.40163299128;1056.287006270764;1082.7726177502814;1084.728817795551;1099.631623406152;1100.8568886044764;15455.613609659398;19449.4165175045;19662.606747865248;19698.40904772859;19909.900450497655;50819.74590127026;51119.488805111476;51725.22412163745;52171;54327;74224.51550969058;74460.25539744533;74872.62563687147;74988;75091.62454187694;7966.203379661987;8257.876131858147;8487.91512084896;8495.660173593235;8770.079141690287;11986.220895641756;12923.28921909304;13143.934280328536;13386.129901556367;13436;11111.499982500645;11487.425628718698;11549.94225028903;11600.477978490819;11787.587434439753;10921.617743378943;11145.888541114486;11206.888000555433;11515.481803318704;11643.825342619699;9784.755381115529;11772.059589540699;11832.70418239551;11856.644300670994;11880.76238475243;8445.282151017233;8576.699815506427;8734.737957861274;8748;8813.911860881566;8803.987541432818;11093.556257749922;11545.307281563133;11905.047596192457;11958.521659133885 01:46:15 INFO - PROCESS | 2539 | |3;mootools.html;1476.889233307501;1617.4824056301984;1639.0411609208613;1925.4608709561328;2032.64428724973;8370.53962032088;8955.05971872953;9085.40944838585;9103.135202155785;9114.225290850278;8844.292456603473;9068.863967040483;9091.863622045674;9095.90904090959;9098;2532.0631366394414;2548.6941567011986;2553.9106131285407;2554.846709197454;2558.8336758110754;2473.332200305916;2550.0947163756878;2555.399481121944;2557.7570130837635;2566.5123626510995;2510.9121180758675;2546.4397832476825;2561.6285638582394;2564.4101856572997;2566.3968967292762;2513.0576033987254;2549.5155920375164;2556.8721563921918;2562.461883004571;2568.75596818302;2286.6570014497793;2382.5466465456057;2551.553478141336;2553.2850801775535;2558.5010922870047;8853.513056781874;9105.998340182574;9169.724908252763;9175.632974681004;9191.724248272563;5583.246261754652;5690.9146362804595;5692.971535142297;5729.742161602696;5742.6841523716175;3635.3456377851976;3699.611540788232;3730.0674831292176;3779.5464544254864;3783.1109689223013;1041.8958104189596;1052.1425038593522;1065.344812940044;1071.3786004117594;1083.8428427877914;12337.383130843422;12661.240325580495;12661.873381266161;12669.493220271179;12696.682582935407;4991.600671946236;4994.400671919393;5131.281620573122;5158.297761738617;5159.7678104485385;10776.730581735519;10869.076128529005;10875.78248435027;10888.074513666426;10888.346699198073;5025.472325405853;5374.140137577969;5718.685472299064;5836.066229403275;5842.883142337129;4636.095961287584;4741.715497070187;5035.446100928895;5199.740012999335;5240.528352448298;4169.520505141887;4214.894627634272;4273.87178384649;4319.308910574293;4324.070324880103;1363.5704753002972;1394.400407824645;1410.2102822419479;1411.8941079419085;1413.4770135049891;758.2190343945745;868.1795703060548;920.8388532006873;972.4457059476172;980.6861804222581;6097.420745029215;6160.568760186744;6171.382861713792;6184.165137706442;6340.651264180515;481.458359345736;488.0263873572175;492.0675320268143;493.40791050739256;497.6541303793829;4044.2315959967514;4093.63157315843;4132.483439570054;4140.130572579732;4143.357779544175;10947.069499092508;11393.658190254306;11480.77038459226;11481.770364592661;11488.88511114895;11185.217034807649;11190.888091119148;11268.549258029587;11332.6033588824;11389.94305028486;8134.349252059821;8564.443311184878;8610.741677749678;8728;8817.470951742916;5980.730867111078;5993.22088128558;6158.353372895961;6209.410106039919;6223.37766223374;9838.262130340396;9933.255005874444;10145.34055286404;10149.898501014895;10160.745981350525;9509.381890177116;9935.801283974135;10264.281500294908;10277.897221027693;10380.06579407857;6905.620190889549;7209.855802884018;7576.469647124869;7659.004329437137;7797.259260370256;7183.856322873408;8294.129116442928;8444.197801208875;8504.787380315542;8511.659533618586;7414.703411863465;7493;7614.772698935012;7667.616619168908;7746.883796742941;7459.92540074614;7466.552006879605;7470.738524151629;7569.848603028019;7620.771376858703;6063.878722425615;6064.241969753781;7143;7156.892646610201;7197.8920316196345;4820.517948205152;4943.431505376928;5023.572996295356;5066.341375621146;5087.542121209109;4513.593776560126;5020.347354843993;5131.868845579344;5142.665726727751;5204.193350030751 01:46:15 INFO - PROCESS | 2539 | |4;prototype.html;337.88849679605727;339.0422057687033;349.6660689041965;349.7639093611812;350.6020666543474;6620.822005437387;6627.701753421095;6659.833504162399;6692.899606505906;6718.328167183291;6323.873522529547;6410.775622853201;6537.084808126866;6602.27374988752;6617.669116544178;4898.730569818658;4936.481669424713;4960.6527543072;4986.87532811679;4990.75046247688;4070.8805078603473;4973.303737476756;4977.1538838397555;4981.178105612587;4985.152524070899;4637.1189474;4743.786529606176;4799.760011999403;4863.392075990501;4876.853939324256;4884.827641366047;4897.902041959176;4903.19097348937;4910.877228069309;4913.606911447076;4193.60160784728;4194.706370554062;4195.538490766014;4203.07532342884;4209.6421804146685;5029.396472423333;6321.810345689591;6341.778037768702;6347.809565713036;6362.77730279438;4236.6187043166265;4303.763293018883;5302.594812374724;5751.884962300773;5759.740811663485;6681.732730690766;6896.793096207071;6974.860502789967;7018.719251229944;7024.332688394646;3904.726669133162;4463.375127482155;4559.384483094773;4597.080583883237;4618.792154353062;7301.415886729049;7406.000189974338;7407.8888816667795;7424.109106907152;7456.105267367951;4639.930401043987;4648.233041548175;4650.255959046604;4651.395318608559;4663.300504924221;3897.395903634941;4279;4388.780560971939;4715.7877895494785;4726.385569875894;4615.7076018714815;5421.213923981;5609.102543593087;5657.20799088128;5668.348139963874;7021.438284937193;7075.858482830315;7077.363037326562;7087.405333799957;7113.608751518716;1555.7510798272222;1616.1192150278127;1642.3430627748996;1645.0951976412925;1651.2156725555506;995.0546980368679;997.8802543694803;1001.9348742331725;1006.8288390973552;1036.5594622285498;6535.215774107138;6620.106285651472;6622.437092847162;6636.037774522763;6702.430293425016;7246.96376518114;7248.347648711536;7257.673404696801;7279.963600181965;7311.537692461529;6641;6652.933470665328;6661.2006559213205;6678.599284042876;6679.298673639295;3760.9435858462143;3806.1626442182674;3844.8077596120643;3858;3915.1778126593163;7729.420293478011;7742.303192712683;7791.727289544839;7822.8435431291055;7838.412119091091;7593.6582853771715;7642.235776422313;7649;7653.196414376522;7665.846683066307;7500.287472690085;7793.687682734815;7795.181505941794;7812.414068944852;7823.335016523659;6084.847878802888;7138.143422789299;7333.74331898381;7349.118105827335;7354.521956072838;7200.639968001684;7241.5293005954445;7263.309985551364;7263.491555591269;7282.053333066668;6175.814725558241;7745.806354841174;7754.147043825287;7777.961110194413;7791.61041947889;4620.884477888079;4685.67200295976;4689.273162659793;4693.436787585511;4697.295405689174;7036.190838053484;7360.815979600338;7381.11426628808;7401;7402.814929626587;7052.294770522906;7056.7530136444975;7064.788056358318;7104.254053324326;7113.502054856111;7075.433965282662;7079.185893622093;7082.964585177041;7089.723849707102;7098.29017098286;5594.328680558359;5921.792737254175;6921.2386637470845;6933.0293758874805;6992.1958974718555;6013.488853447506;6526.118973938457;6773.593584384953;7099.396551293198;7100.502964792621;6812.420944219797;6860.108185935796;6906.551074180162;6922.8961565577565;6935.133108361455 01:46:15 INFO - PROCESS | 2539 | |5;yui.html;771.390601424874;794.2573693596488;795.9840803183937;804.6781287485005;808.1474044882647;15512.612184695374;15622.609434764108;15626.453074142408;15700.371985120595;15718.135502547355;16207.83792162078;16658.50024499264;16664.916675416636;16675.66648667026;16700.415485458012;800.6877317846045;816.1552792859392;960.3277705606068;984.4634674102631;1000.1898462245568;991.1624677147835;994.666786626481;998.1615443027855;999.2605471950741;1001.7245257554158;1050.338286879265;1051.742323130834;1052.4527245832162;1054.151408116466;1054.2725519391595;831.9500829950192;995.5619527407954;1034.684421251518;1041.182671602788;1043.5147656339839;780.9609519524001;783.6747749683874;784.2353705137502;784.2823816208163;785.9253370929753;15939.601509962227;15964;15990.281652119167;15993.680126397407;16038.75941860873;12717.236965782082;12845.036622253276;12990.155639883378;13169.209847409185;13341.132826366254;9164.770880727969;10192.54133563984;10237.385756854612;10376.740581485448;10699.518521666543;3512.6662967018347;3554.253606742587;3564.6078931317534;3589.533360663097;3759.172981943968;86409.56795216046;86478.56760716156;87193;88710;89043.10956890478;8016.278534931884;9379.390339627902;9531.237500999903;9616.855747163798;9824.968378320316;22368.55262894733;22432.66351004736;22438.878056097128;22442.775572244394;22483.21308754186;7481.551106933602;7484.775456736306;7548.886766698504;7598.240175982468;7682.577458239851;3867.400552914303;3963.36586146221;4325.437693099877;4348.326009468537;4384.7369157850635;3863.729538932304;3915.9021024474614;3920.843166273317;3926.2736393767245;3931.253061918226;685.8456847209338;703.8768215562257;704.5314865614309;709.8048036789821;734.7685479074074;577.9971100144472;608.2427377914524;609.7168038942514;610.6824451285307;618.2519151826341;7269.636518174176;8436;8460.703875364332;8542.18849209324;8645.913540864636;602.764921680545;604.338249616669;614.846288427893;618.1099217127322;620.2959640807716;3939.074317535377;4002.7598344099447;4024.4164596133396;4044.939325910114;4069.959300407017;96038.0396196043;96287;97077;97387.51306243423;97439.51280243554;78822;80347.19652803396;81021;81979.59010204911;82805;73867.26132738603;74188.62905685438;74820;76238.61880690561;77196.61401693067;8605.870911936201;8867.024627291123;8904.421212621159;8926.642934282556;9000.279977601907;50782.49217507778;50845.237321440945;52248;52824.73587632191;54008;69727.65136174287;72076.19809504594;72285.6385718068;72323;72368;11088.833667495155;11323.433828308716;11453.198276120926;11537.711557211136;11552.364619945984;9342;12033.63899083029;12087.818682719942;12113.818292725793;12988;11303.095752339628;11411.942940285246;11446.02708769764;11634.53461861516;12090.637280881587;11390.430478476208;11536.25014374063;11693.590724324607;12203.962663173375;12269.263844169378;11144.60993865211;11305.434728263719;11384.772304553697;11558.422078895854;12253.509859605516;9767.609295628095;10047.246456515937;10056.849147262943;10344.948275258575;10407.375557466576;9761.316707830383;9905.306628536222;9911.207103431852;9942.105210531086;9947.651832185838 01:46:15 INFO - PROCESS | 2539 | __end_tp_report 01:46:15 INFO - PROCESS | 2539 | __start_cc_report 01:46:15 INFO - PROCESS | 2539 | _x_x_mozilla_cycle_collect,1286 01:46:15 INFO - PROCESS | 2539 | __end_cc_report 01:46:15 INFO - PROCESS | 2539 | __startTimestamp1467103575072__endTimestamp 01:46:15 INFO - PROCESS | 2539 | 01:46:15 INFO - PROCESS | 2539 | ------- Summary: start ------- 01:46:15 INFO - PROCESS | 2539 | Number of tests: 6 01:46:15 INFO - PROCESS | 2539 | 01:46:15 INFO - PROCESS | 2539 | [#0] dojo.html Cycles:165 Average:5960.45 Median:6198.97 stddev:638.21 (10.3%) stddev-sans-first:600.48 01:46:15 INFO - PROCESS | 2539 | Values: 3136.2 3585.2 3619.2 3629.0 3685.9 6001.1 6020.6 6027.7 6050.0 6062.9 6009.5 6051.4 6065.3 6068.8 6075.1 5362.1 5415.6 5464.7 5504.0 5529.1 5462.9 5529.3 5531.4 5539.8 5551.6 5426.8 5482.3 5511.6 5520.2 5544.3 5464.4 5508.2 5537.5 5540.3 5586.7 5359.8 5367.0 5412.0 5443.7 5458.7 5784.8 5846.7 5851.4 5889.1 5891.8 6326.5 6335.5 6369.1 6376.0 6383.4 6225.8 6311.8 6400.1 6459.5 6473.4 6141.7 6146.7 6150.4 6158.2 6165.2 6599.8 6600.2 6610.0 6613.2 6617.7 6199.0 6204.1 6270.5 6277.7 6331.5 6423.8 6457.4 6461.9 6465.2 6465.9 6282.0 6298.3 6321.6 6336.0 6347.1 6193.5 6237.9 6241.5 6258.0 6262.1 5071.1 5077.8 5084.7 5116.8 5182.6 4578.0 4609.1 4636.9 4661.2 4661.9 6119.0 6134.1 6138.7 6141.7 6144.0 6240.9 6325.3 6407.7 6425.5 6427.3 6085.6 6086.9 6106.7 6120.3 6132.1 5465.2 5631.9 5649.6 5658.4 5666.9 6546.3 6567.1 6651.7 6673.6 6688.4 6372.3 6377.8 6385.7 6394.6 6407.9 6473.0 6646.7 6654.7 6661.2 6673.0 6231.1 6257.7 6328.7 6355.1 6360.6 6399.1 6435.5 6437.0 6438.1 6438.3 4867.8 6170.0 6213.3 6250.2 6279.7 5628.9 6248.7 6258.9 6262.1 6266.5 6223.5 6246.2 6252.5 6266.5 6271.0 5776.4 6141.8 6177.7 6200.7 6298.4 6238.3 6250.7 6274.2 6276.8 6286.5 01:46:15 INFO - PROCESS | 2539 | 01:46:15 INFO - PROCESS | 2539 | [#1] ext.html Cycles:180 Average:70669.64 Median:3626.73 stddev:156253.26 (4308.4%) stddev-sans-first:156602.89 01:46:15 INFO - PROCESS | 2539 | Values: 546.8 550.4 571.3 576.7 586.5 34621.8 41185.2 41945.6 42125.2 42128.2 46365.0 46906.5 47209.0 47259.1 47380.0 9835.2 10060.3 10085.6 10102.9 10105.6 9888.2 10006.4 10006.9 10016.9 10020.6 10025.5 10052.4 10062.3 10071.2 10074.4 9956.7 9968.6 10012.6 10027.3 10035.8 9746.7 9874.7 9881.5 9883.4 9884.0 42670.1 43183.4 43200.1 43242.4 43267.4 3379.0 3487.6 3554.4 3597.4 3916.1 28724.4 29130.0 29235.0 29854.4 31032.4 2769.4 2790.5 2830.8 2889.4 2896.3 501587.0 503572.0 506406.0 507768.0 508447.2 37402.3 37945.6 38049.8 65722.7 66303.3 30965.7 37167.3 37358.4 37360.1 37363.8 17025.0 17257.9 17260.7 17284.0 17295.7 14507.1 14597.5 14600.1 14632.5 14636.5 8585.9 8655.7 8683.8 8737.6 8775.2 3566.4 3608.1 3610.1 3613.6 3639.8 964.6 979.9 1033.2 1048.7 1135.8 31499.5 31502.4 31632.4 31662.5 32428.5 1120.2 1148.2 1191.3 1259.2 1272.2 14010.6 15871.8 16071.2 16088.5 16258.4 664681.0 668112.0 672091.0 673273.6 676153.0 63681.0 64772.1 65149.0 65285.0 65329.0 1560.6 1564.1 1576.5 1578.0 1588.7 30047.2 30651.1 30656.8 30985.4 30995.8 49792.0 55854.7 56000.4 56031.0 56083.0 561248.0 562182.0 564120.0 564352.0 564869.0 27839.7 27875.0 44767.7 46468.0 46575.8 45844.5 47485.8 47873.0 49355.5 49379.0 32139.8 38656.4 38729.2 39603.8 39835.8 35440.6 38801.8 38943.8 39159.8 39843.0 34032.3 34461.1 35198.5 39597.4 39758.8 28828.3 31959.7 32627.5 32707.0 33128.8 24907.6 25092.1 25265.6 25300.2 25324.5 01:46:15 INFO - PROCESS | 2539 | 01:46:15 INFO - PROCESS | 2539 | [#2] jquery.html Cycles:180 Average:35593.38 Median:14194.58 stddev:107771.57 (759.2%) stddev-sans-first:108041.51 01:46:15 INFO - PROCESS | 2539 | Values: 406.7 420.5 420.5 423.9 429.4 18805.5 20818.0 21281.3 21498.0 23230.8 21490.9 21639.6 21917.6 22446.0 22455.0 6044.8 6175.2 6257.0 6265.0 6266.4 4507.7 4673.8 4771.0 6182.7 6245.4 4733.7 6693.0 6851.9 6852.2 6858.5 6541.9 6831.4 6836.7 6840.8 6861.0 4097.9 6054.9 6059.5 6068.7 6077.7 26003.8 26626.5 26898.2 26960.0 27012.1 2302.7 2385.3 2670.6 2694.8 2791.0 15283.0 15454.4 15535.2 15647.8 16055.0 1795.5 1821.2 1864.9 1868.2 1881.4 80636.6 99919.5 102995.0 103144.5 122350.2 9342.0 11507.0 11603.9 11636.0 11867.8 14761.5 14773.8 14853.5 14855.0 14859.3 9227.4 9290.2 9324.7 9427.1 9736.9 9485.4 9515.5 9518.7 9602.2 9632.8 7007.0 7059.0 7090.3 7091.2 7113.6 1917.7 1943.0 1943.6 1962.4 1984.4 931.5 968.2 994.4 1037.2 1143.2 17924.7 21010.9 21385.5 21988.5 22242.9 1032.6 1057.9 1065.5 1077.0 1107.7 10070.8 11469.4 11551.5 11950.7 11959.3 645255.0 646053.0 646894.0 656803.0 678709.0 63353.0 73787.3 96784.0 115690.0 119673.4 1056.3 1082.8 1084.7 1099.6 1100.9 15455.6 19449.4 19662.6 19698.4 19909.9 50819.7 51119.5 51725.2 52171.0 54327.0 74224.5 74460.3 74872.6 74988.0 75091.6 7966.2 8257.9 8487.9 8495.7 8770.1 11986.2 12923.3 13143.9 13386.1 13436.0 11111.5 11487.4 11549.9 11600.5 11787.6 10921.6 11145.9 11206.9 11515.5 11643.8 9784.8 11772.1 11832.7 11856.6 11880.8 8445.3 8576.7 8734.7 8748.0 8813.9 8804.0 11093.6 11545.3 11905.0 11958.5 01:46:15 INFO - PROCESS | 2539 | 01:46:15 INFO - PROCESS | 2539 | [#3] mootools.html Cycles:180 Average:5923.42 Median:2744.63 stddev:3343.98 (121.8%) stddev-sans-first:3336.67 01:46:15 INFO - PROCESS | 2539 | Values: 1476.9 1617.5 1639.0 1925.5 2032.6 8370.5 8955.1 9085.4 9103.1 9114.2 8844.3 9068.9 9091.9 9095.9 9098.0 2532.1 2548.7 2553.9 2554.8 2558.8 2473.3 2550.1 2555.4 2557.8 2566.5 2510.9 2546.4 2561.6 2564.4 2566.4 2513.1 2549.5 2556.9 2562.5 2568.8 2286.7 2382.5 2551.6 2553.3 2558.5 8853.5 9106.0 9169.7 9175.6 9191.7 5583.2 5690.9 5693.0 5729.7 5742.7 3635.3 3699.6 3730.1 3779.5 3783.1 1041.9 1052.1 1065.3 1071.4 1083.8 12337.4 12661.2 12661.9 12669.5 12696.7 4991.6 4994.4 5131.3 5158.3 5159.8 10776.7 10869.1 10875.8 10888.1 10888.3 5025.5 5374.1 5718.7 5836.1 5842.9 4636.1 4741.7 5035.4 5199.7 5240.5 4169.5 4214.9 4273.9 4319.3 4324.1 1363.6 1394.4 1410.2 1411.9 1413.5 758.2 868.2 920.8 972.4 980.7 6097.4 6160.6 6171.4 6184.2 6340.7 481.5 488.0 492.1 493.4 497.7 4044.2 4093.6 4132.5 4140.1 4143.4 10947.1 11393.7 11480.8 11481.8 11488.9 11185.2 11190.9 11268.5 11332.6 11389.9 8134.3 8564.4 8610.7 8728.0 8817.5 5980.7 5993.2 6158.4 6209.4 6223.4 9838.3 9933.3 10145.3 10149.9 10160.7 9509.4 9935.8 10264.3 10277.9 10380.1 6905.6 7209.9 7576.5 7659.0 7797.3 7183.9 8294.1 8444.2 8504.8 8511.7 7414.7 7493.0 7614.8 7667.6 7746.9 7459.9 7466.6 7470.7 7569.8 7620.8 6063.9 6064.2 7143.0 7156.9 7197.9 4820.5 4943.4 5023.6 5066.3 5087.5 4513.6 5020.3 5131.9 5142.7 5204.2 01:46:15 INFO - PROCESS | 2539 | 01:46:15 INFO - PROCESS | 2539 | [#4] prototype.html Cycles:180 Average:5742.47 Median:6620.46 stddev:1871.96 (28.3%) stddev-sans-first:1832.73 01:46:15 INFO - PROCESS | 2539 | Values: 337.9 339.0 349.7 349.8 350.6 6620.8 6627.7 6659.8 6692.9 6718.3 6323.9 6410.8 6537.1 6602.3 6617.7 4898.7 4936.5 4960.7 4986.9 4990.8 4070.9 4973.3 4977.2 4981.2 4985.2 4637.1 4743.8 4799.8 4863.4 4876.9 4884.8 4897.9 4903.2 4910.9 4913.6 4193.6 4194.7 4195.5 4203.1 4209.6 5029.4 6321.8 6341.8 6347.8 6362.8 4236.6 4303.8 5302.6 5751.9 5759.7 6681.7 6896.8 6974.9 7018.7 7024.3 3904.7 4463.4 4559.4 4597.1 4618.8 7301.4 7406.0 7407.9 7424.1 7456.1 4639.9 4648.2 4650.3 4651.4 4663.3 3897.4 4279.0 4388.8 4715.8 4726.4 4615.7 5421.2 5609.1 5657.2 5668.3 7021.4 7075.9 7077.4 7087.4 7113.6 1555.8 1616.1 1642.3 1645.1 1651.2 995.1 997.9 1001.9 1006.8 1036.6 6535.2 6620.1 6622.4 6636.0 6702.4 7247.0 7248.3 7257.7 7280.0 7311.5 6641.0 6652.9 6661.2 6678.6 6679.3 3760.9 3806.2 3844.8 3858.0 3915.2 7729.4 7742.3 7791.7 7822.8 7838.4 7593.7 7642.2 7649.0 7653.2 7665.8 7500.3 7793.7 7795.2 7812.4 7823.3 6084.8 7138.1 7333.7 7349.1 7354.5 7200.6 7241.5 7263.3 7263.5 7282.1 6175.8 7745.8 7754.1 7778.0 7791.6 4620.9 4685.7 4689.3 4693.4 4697.3 7036.2 7360.8 7381.1 7401.0 7402.8 7052.3 7056.8 7064.8 7104.3 7113.5 7075.4 7079.2 7083.0 7089.7 7098.3 5594.3 5921.8 6921.2 6933.0 6992.2 6013.5 6526.1 6773.6 7099.4 7100.5 6812.4 6860.1 6906.6 6922.9 6935.1 01:46:15 INFO - PROCESS | 2539 | 01:46:15 INFO - PROCESS | 2539 | [#5] yui.html Cycles:180 Average:19264.43 Median:53416.37 stddev:27244.12 (51.0%) stddev-sans-first:27285.16 01:46:15 INFO - PROCESS | 2539 | Values: 771.4 794.3 796.0 804.7 808.1 15512.6 15622.6 15626.5 15700.4 15718.1 16207.8 16658.5 16664.9 16675.7 16700.4 800.7 816.2 960.3 984.5 1000.2 991.2 994.7 998.2 999.3 1001.7 1050.3 1051.7 1052.5 1054.2 1054.3 832.0 995.6 1034.7 1041.2 1043.5 781.0 783.7 784.2 784.3 785.9 15939.6 15964.0 15990.3 15993.7 16038.8 12717.2 12845.0 12990.2 13169.2 13341.1 9164.8 10192.5 10237.4 10376.7 10699.5 3512.7 3554.3 3564.6 3589.5 3759.2 86409.6 86478.6 87193.0 88710.0 89043.1 8016.3 9379.4 9531.2 9616.9 9825.0 22368.6 22432.7 22438.9 22442.8 22483.2 7481.6 7484.8 7548.9 7598.2 7682.6 3867.4 3963.4 4325.4 4348.3 4384.7 3863.7 3915.9 3920.8 3926.3 3931.3 685.8 703.9 704.5 709.8 734.8 578.0 608.2 609.7 610.7 618.3 7269.6 8436.0 8460.7 8542.2 8645.9 602.8 604.3 614.8 618.1 620.3 3939.1 4002.8 4024.4 4044.9 4070.0 96038.0 96287.0 97077.0 97387.5 97439.5 78822.0 80347.2 81021.0 81979.6 82805.0 73867.3 74188.6 74820.0 76238.6 77196.6 8605.9 8867.0 8904.4 8926.6 9000.3 50782.5 50845.2 52248.0 52824.7 54008.0 69727.7 72076.2 72285.6 72323.0 72368.0 11088.8 11323.4 11453.2 11537.7 11552.4 9342.0 12033.6 12087.8 12113.8 12988.0 11303.1 11411.9 11446.0 11634.5 12090.6 11390.4 11536.3 11693.6 12204.0 12269.3 11144.6 11305.4 11384.8 11558.4 12253.5 9767.6 10047.2 10056.8 10344.9 10407.4 9761.3 9905.3 9911.2 9942.1 9947.7 01:46:15 INFO - PROCESS | 2539 | -------- Summary: end -------- 01:46:15 INFO - PROCESS | 2539 | 01:46:15 INFO - TEST-INFO | 2539: exit 0 01:46:15 INFO - TEST-OK | dromaeo_css | took 1113287ms 01:46:15 INFO - TEST-START | kraken 01:46:15 INFO - operating with platform_type : mac_ 01:46:15 INFO - Initialising browser for kraken test... 01:46:15 INFO - TEST-INFO | started process 2542 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/tmp86PCPS/profile http://localhost:56321/getInfo.html) 01:46:17 INFO - PROCESS | 2542 | __metrics Screen width/height:1600/1200 01:46:17 INFO - PROCESS | 2542 | colorDepth:24 01:46:17 INFO - PROCESS | 2542 | Browser inner width/height: 1024/689 01:46:17 INFO - PROCESS | 2542 | __metrics 01:46:17 INFO - PROCESS | 2542 | 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:17 ERROR - PROCESS | 2542 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 01:46:18 INFO - TEST-INFO | 2542: exit 0 01:46:18 INFO - Browser initialized. 01:46:18 INFO - Running cycle 1/1 for kraken test... 01:46:18 INFO - TEST-INFO | started process 2545 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/tmp86PCPS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:46:30 INFO - PROCESS | 2545 | RSS: Main: 161538048 01:46:30 INFO - PROCESS | 2545 | 01:46:33 INFO - PROCESS | 2545 | Cycle 1(1): loaded http://localhost:56321/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56321/tests/kraken/driver.html?testName=audio-beat-detection) 01:46:33 INFO - PROCESS | 2545 | RSS: Main: 224088064 01:46:33 INFO - PROCESS | 2545 | 01:46:36 INFO - PROCESS | 2545 | Cycle 1(1): loaded http://localhost:56321/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56321/tests/kraken/driver.html?testName=audio-dft) 01:46:37 INFO - PROCESS | 2545 | RSS: Main: 239931392 01:46:37 INFO - PROCESS | 2545 | 01:46:40 INFO - PROCESS | 2545 | Cycle 1(1): loaded http://localhost:56321/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56321/tests/kraken/driver.html?testName=audio-fft) 01:46:40 INFO - PROCESS | 2545 | RSS: Main: 325578752 01:46:40 INFO - PROCESS | 2545 | 01:46:42 INFO - PROCESS | 2545 | Cycle 1(1): loaded http://localhost:56321/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56321/tests/kraken/driver.html?testName=audio-oscillator) 01:46:43 INFO - PROCESS | 2545 | RSS: Main: 231665664 01:46:43 INFO - PROCESS | 2545 | 01:46:45 INFO - PROCESS | 2545 | Cycle 1(1): loaded http://localhost:56321/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56321/tests/kraken/driver.html?testName=imaging-gaussian-blur) 01:46:46 INFO - PROCESS | 2545 | RSS: Main: 232308736 01:46:46 INFO - PROCESS | 2545 | 01:46:49 INFO - PROCESS | 2545 | Cycle 1(1): loaded http://localhost:56321/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56321/tests/kraken/driver.html?testName=imaging-darkroom) 01:46:49 INFO - PROCESS | 2545 | RSS: Main: 269209600 01:46:49 INFO - PROCESS | 2545 | 01:46:52 INFO - PROCESS | 2545 | Cycle 1(1): loaded http://localhost:56321/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56321/tests/kraken/driver.html?testName=imaging-desaturate) 01:46:53 INFO - PROCESS | 2545 | RSS: Main: 269778944 01:46:53 INFO - PROCESS | 2545 | 01:46:55 INFO - PROCESS | 2545 | Cycle 1(1): loaded http://localhost:56321/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56321/tests/kraken/driver.html?testName=json-parse-financial) 01:46:56 INFO - PROCESS | 2545 | RSS: Main: 269824000 01:46:56 INFO - PROCESS | 2545 | 01:46:58 INFO - PROCESS | 2545 | Cycle 1(1): loaded http://localhost:56321/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56321/tests/kraken/driver.html?testName=json-stringify-tinderbox) 01:46:58 INFO - PROCESS | 2545 | RSS: Main: 260808704 01:46:58 INFO - PROCESS | 2545 | 01:47:00 INFO - PROCESS | 2545 | Cycle 1(1): loaded http://localhost:56321/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56321/tests/kraken/driver.html?testName=stanford-crypto-aes) 01:47:01 INFO - PROCESS | 2545 | RSS: Main: 268394496 01:47:01 INFO - PROCESS | 2545 | 01:47:03 INFO - PROCESS | 2545 | Cycle 1(1): loaded http://localhost:56321/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56321/tests/kraken/driver.html?testName=stanford-crypto-ccm) 01:47:03 INFO - PROCESS | 2545 | RSS: Main: 271134720 01:47:03 INFO - PROCESS | 2545 | 01:47:06 INFO - PROCESS | 2545 | Cycle 1(1): loaded http://localhost:56321/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56321/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 01:47:07 INFO - PROCESS | 2545 | RSS: Main: 278183936 01:47:07 INFO - PROCESS | 2545 | 01:47:10 INFO - PROCESS | 2545 | Cycle 1(1): loaded http://localhost:56321/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56321/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 01:47:10 INFO - PROCESS | 2545 | RSS: Main: 275337216 01:47:10 INFO - PROCESS | 2545 | 01:47:12 INFO - PROCESS | 2545 | Cycle 1(1): loaded http://localhost:56321/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56321/tests/kraken/driver.html?testName=ai-astar) 01:47:12 INFO - PROCESS | 2545 | RSS: Main: 336150528 01:47:12 INFO - PROCESS | 2545 | 01:47:12 INFO - PROCESS | 2545 | __start_tp_report 01:47:12 INFO - PROCESS | 2545 | _x_x_mozilla_page_load 01:47:12 INFO - PROCESS | 2545 | _x_x_mozilla_page_load_details 01:47:12 INFO - PROCESS | 2545 | |i|pagename|runs| 01:47:12 INFO - PROCESS | 2545 | |0;ai-astar;114;113;120;111;112;111;112;113;112;132 01:47:12 INFO - PROCESS | 2545 | |1;audio-beat-detection;166;136;140;163;117;139;120;155;154;118 01:47:12 INFO - PROCESS | 2545 | |2;audio-dft;180;185;182;185;185;185;185;182;183;184 01:47:12 INFO - PROCESS | 2545 | |3;audio-fft;69;79;76;158;56;54;56;64;135;56 01:47:12 INFO - PROCESS | 2545 | |4;audio-oscillator;91;91;145;79;88;87;125;79;82;127 01:47:12 INFO - PROCESS | 2545 | |5;imaging-gaussian-blur;87;84;88;84;133;130;82;114;135;88 01:47:12 INFO - PROCESS | 2545 | |6;imaging-darkroom;92;93;93;94;93;92;94;92;92;92 01:47:12 INFO - PROCESS | 2545 | |7;imaging-desaturate;86;88;89;86;87;89;89;87;87;85 01:47:12 INFO - PROCESS | 2545 | |8;json-parse-financial;59;63;64;63;62;63;63;78;59;55 01:47:12 INFO - PROCESS | 2545 | |9;json-stringify-tinderbox;62;61;71;78;73;69;64;66;66;66 01:47:12 INFO - PROCESS | 2545 | |10;stanford-crypto-aes;80;81;79;80;79;79;69;67;82;104 01:47:12 INFO - PROCESS | 2545 | |11;stanford-crypto-ccm;126;126;149;142;138;129;125;124;146;138 01:47:12 INFO - PROCESS | 2545 | |12;stanford-crypto-pbkdf2;165;166;166;162;170;165;161;164;160;214 01:47:12 INFO - PROCESS | 2545 | |13;stanford-crypto-sha256-iterative;67;68;65;66;66;96;70;68;66;65 01:47:12 INFO - PROCESS | 2545 | __end_tp_report 01:47:12 INFO - PROCESS | 2545 | __start_cc_report 01:47:12 INFO - PROCESS | 2545 | _x_x_mozilla_cycle_collect,1898 01:47:12 INFO - PROCESS | 2545 | __end_cc_report 01:47:12 INFO - PROCESS | 2545 | __startTimestamp1467103632606__endTimestamp 01:47:12 INFO - PROCESS | 2545 | 01:47:12 INFO - PROCESS | 2545 | ------- Summary: start ------- 01:47:12 INFO - PROCESS | 2545 | Number of tests: 14 01:47:12 INFO - PROCESS | 2545 | 01:47:12 INFO - PROCESS | 2545 | [#0] ai-astar Cycles:10 Average:115.00 Median:113.00 stddev:6.51 (5.8%) stddev-sans-first:6.90 01:47:12 INFO - PROCESS | 2545 | Values: 114.0 113.0 120.0 111.0 112.0 111.0 112.0 113.0 112.0 132.0 01:47:12 INFO - PROCESS | 2545 | 01:47:12 INFO - PROCESS | 2545 | [#1] audio-beat-detection Cycles:10 Average:140.80 Median:147.00 stddev:18.41 (12.5%) stddev-sans-first:17.12 01:47:12 INFO - PROCESS | 2545 | Values: 166.0 136.0 140.0 163.0 117.0 139.0 120.0 155.0 154.0 118.0 01:47:12 INFO - PROCESS | 2545 | 01:47:12 INFO - PROCESS | 2545 | [#2] audio-dft Cycles:10 Average:183.60 Median:185.00 stddev:1.78 (1.0%) stddev-sans-first:1.32 01:47:12 INFO - PROCESS | 2545 | Values: 180.0 185.0 182.0 185.0 185.0 185.0 185.0 182.0 183.0 184.0 01:47:12 INFO - PROCESS | 2545 | 01:47:12 INFO - PROCESS | 2545 | [#3] audio-fft Cycles:10 Average:80.30 Median:60.00 stddev:36.37 (60.6%) stddev-sans-first:38.35 01:47:12 INFO - PROCESS | 2545 | Values: 69.0 79.0 76.0 158.0 56.0 54.0 56.0 64.0 135.0 56.0 01:47:12 INFO - PROCESS | 2545 | 01:47:12 INFO - PROCESS | 2545 | [#4] audio-oscillator Cycles:10 Average:99.40 Median:84.50 stddev:23.70 (28.1%) stddev-sans-first:24.94 01:47:12 INFO - PROCESS | 2545 | Values: 91.0 91.0 145.0 79.0 88.0 87.0 125.0 79.0 82.0 127.0 01:47:12 INFO - PROCESS | 2545 | 01:47:12 INFO - PROCESS | 2545 | [#5] imaging-gaussian-blur Cycles:10 Average:102.50 Median:84.00 stddev:22.71 (27.0%) stddev-sans-first:23.38 01:47:12 INFO - PROCESS | 2545 | Values: 87.0 84.0 88.0 84.0 133.0 130.0 82.0 114.0 135.0 88.0 01:47:12 INFO - PROCESS | 2545 | 01:47:12 INFO - PROCESS | 2545 | [#6] imaging-darkroom Cycles:10 Average:92.70 Median:93.00 stddev:0.82 (0.9%) stddev-sans-first:0.83 01:47:12 INFO - PROCESS | 2545 | Values: 92.0 93.0 93.0 94.0 93.0 92.0 94.0 92.0 92.0 92.0 01:47:12 INFO - PROCESS | 2545 | 01:47:12 INFO - PROCESS | 2545 | [#7] imaging-desaturate Cycles:10 Average:87.30 Median:87.50 stddev:1.42 (1.6%) stddev-sans-first:1.42 01:47:12 INFO - PROCESS | 2545 | Values: 86.0 88.0 89.0 86.0 87.0 89.0 89.0 87.0 87.0 85.0 01:47:12 INFO - PROCESS | 2545 | 01:47:12 INFO - PROCESS | 2545 | [#8] json-parse-financial Cycles:10 Average:62.90 Median:63.00 stddev:5.99 (9.5%) stddev-sans-first:6.18 01:47:12 INFO - PROCESS | 2545 | Values: 59.0 63.0 64.0 63.0 62.0 63.0 63.0 78.0 59.0 55.0 01:47:12 INFO - PROCESS | 2545 | 01:47:12 INFO - PROCESS | 2545 | [#9] json-stringify-tinderbox Cycles:10 Average:67.60 Median:67.50 stddev:5.23 (7.8%) stddev-sans-first:5.14 01:47:12 INFO - PROCESS | 2545 | Values: 62.0 61.0 71.0 78.0 73.0 69.0 64.0 66.0 66.0 66.0 01:47:12 INFO - PROCESS | 2545 | 01:47:12 INFO - PROCESS | 2545 | [#10] stanford-crypto-aes Cycles:10 Average:80.00 Median:79.50 stddev:9.85 (12.4%) stddev-sans-first:10.45 01:47:12 INFO - PROCESS | 2545 | Values: 80.0 81.0 79.0 80.0 79.0 79.0 69.0 67.0 82.0 104.0 01:47:12 INFO - PROCESS | 2545 | 01:47:12 INFO - PROCESS | 2545 | [#11] stanford-crypto-ccm Cycles:10 Average:134.30 Median:138.00 stddev:9.42 (6.8%) stddev-sans-first:9.50 01:47:12 INFO - PROCESS | 2545 | Values: 126.0 126.0 149.0 142.0 138.0 129.0 125.0 124.0 146.0 138.0 01:47:12 INFO - PROCESS | 2545 | 01:47:12 INFO - PROCESS | 2545 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.30 Median:165.50 stddev:15.97 (9.6%) stddev-sans-first:16.86 01:47:12 INFO - PROCESS | 2545 | Values: 165.0 166.0 166.0 162.0 170.0 165.0 161.0 164.0 160.0 214.0 01:47:12 INFO - PROCESS | 2545 | 01:47:12 INFO - PROCESS | 2545 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.70 Median:67.50 stddev:9.37 (13.9%) stddev-sans-first:9.89 01:47:12 INFO - PROCESS | 2545 | Values: 67.0 68.0 65.0 66.0 66.0 96.0 70.0 68.0 66.0 65.0 01:47:12 INFO - PROCESS | 2545 | -------- Summary: end -------- 01:47:12 INFO - PROCESS | 2545 | 01:47:13 INFO - TEST-INFO | 2545: exit 0 01:47:13 INFO - TEST-OK | kraken | took 57753ms 01:47:13 INFO - SUITE-END | took 1171s 01:47:13 INFO - Completed test suite (00:19:31) 01:47:13 INFO - javascript benchmark 01:47:13 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3136.2316232523026, 3585.1754096557775, 3619.2037751694634, 3628.9637103628957, 3685.8525658973645, 6001.069834175699, 6020.608660437079, 6027.668478233695, 6050.0, 6062.878742425154, 6009.489193418553, 6051.364606716286, 6065.302490213627, 6068.787592434266, 6075.058365953273, 5362.1420572708375, 5415.566754659628, 5464.6994415307145, 5504.036793561111, 5529.087700529408, 5462.890742185154, 5529.253550770655, 5531.446855314456, 5539.833804985857, 5551.611387202897, 5426.810061647843, 5482.259894914176, 5511.641743286694, 5520.227168196456, 5544.306961629796, 5464.371597266306, 5508.173773933902, 5537.501624853742, 5540.335159780811, 5586.720663966786, 5359.839204823861, 5367.033933891937, 5411.972940135274, 5443.673379597197, 5458.699771512565, 5784.768609255625, 5846.7076646167525, 5851.38560451155, 5889.116632505115, 5891.79378721747, 6326.462250708695, 6335.461485773715, 6369.108324834527, 6376.043593460971, 6383.39357761012, 6225.7509699611965, 6311.842203944892, 6400.071989561534, 6459.5478316517865, 6473.417392434703, 6141.662208578527, 6146.7233974471255, 6150.354212807681, 6158.168647232566, 6165.229346331708, 6599.8020059398295, 6600.207975042929, 6610.041543976096, 6613.173353330833, 6617.669116544153, 6198.969005154945, 6204.13142160098, 6270.467010304084, 6277.717502712298, 6331.461825744771, 6423.775167869102, 6457.418832305115, 6461.870762584722, 6465.15952926126, 6465.870682586416, 6281.9948808190475, 6298.338674439209, 6321.557490975588, 6335.968320158462, 6347.143135676717, 6193.473554747807, 6237.906431403532, 6241.500679945593, 6257.9674353731125, 6262.12330273762, 5071.137906555894, 5077.796888124508, 5084.694918304914, 5116.769745361393, 5182.559482444034, 4577.954220457819, 4609.124266389375, 4636.930446043312, 4661.230896901973, 4661.906761864744, 6119.0, 6134.141220229171, 6138.662373569497, 6141.662208578571, 6144.047672610753, 6240.875182496325, 6325.335839736854, 6407.711652975628, 6425.518086143558, 6427.260865000491, 6085.604435711664, 6086.939130608637, 6106.664133472702, 6120.326764055951, 6132.110843927606, 5465.152901300305, 5631.943680563142, 5649.6327738696855, 5658.405867383865, 5666.914996275059, 6546.279909209983, 6567.113439685581, 6651.7006734698025, 6673.566218195801, 6688.398044176048, 6372.267189273293, 6377.7767778127545, 6385.7126429309865, 6394.552381333262, 6407.871842563216, 6472.967635161794, 6646.667666616747, 6654.700538475877, 6661.2006559213205, 6673.0, 6231.1276421300145, 6257.7184026718, 6328.683565821782, 6355.078513615592, 6360.618362898241, 6399.136116624196, 6435.452986496013, 6437.002264648987, 6438.066480360415, 6438.323975982455, 4867.756612169448, 6170.0, 6213.254409470893, 6250.249970003629, 6279.748810047547, 5628.915566266591, 6248.687565621792, 6258.874822503615, 6262.060690896402, 6266.530010249158, 6223.502119830312, 6246.156768836149, 6252.531060170414, 6266.530010249158, 6271.0, 5776.393478684678, 6141.762184092961, 6177.6911154443, 6200.720967556561, 6298.401651843067, 6238.282597501164, 6250.656213908097, 6274.215723034621, 6276.780312689034, 6286.52851036165], "value": 5921.151423398306, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [546.8414159893629, 550.4220568403176, 571.3458090486396, 576.7375843990984, 586.4604563801305, 34621.826890865545, 41185.17629647409, 41945.58054419451, 42125.15749685004, 42128.157436851245, 46365.0, 46906.53093469073, 47209.0, 47259.0548189036, 47380.0, 9835.213182945365, 10060.346077504939, 10085.596576136983, 10102.898971010269, 10105.595776168944, 9888.159506441962, 10006.399616023025, 10006.89993100067, 10016.949915250414, 10020.649277275297, 10025.548850301717, 10052.44711540865, 10062.345947513424, 10071.194304455626, 10074.445905475195, 9956.701298961043, 9968.601255949754, 10012.64955726546, 10027.34822236556, 10035.799284014352, 9746.707598772047, 9874.703758887174, 9881.505924703735, 9883.406995580217, 9884.0, 42670.146597067884, 43183.352249715965, 43200.135997280195, 43242.351364729555, 43267.350989735176, 3379.024861809965, 3487.6337984511515, 3554.431290993429, 3597.388443964532, 3916.060145565044, 28724.425511489862, 29130.0, 29235.0, 29854.402911941856, 31032.379352412827, 2769.418422131355, 2790.525610646195, 2830.830150190995, 2889.4221155768932, 2896.2614533294086, 501587.0, 503572.0, 506406.0, 507768.0, 508447.20434784977, 37402.251954960746, 37945.62054379421, 38049.80975095162, 65722.67138664276, 66303.33696663068, 30965.69034309628, 37167.25665486729, 37358.43962340567, 37360.065998350256, 37363.813180933925, 17025.0, 17257.913710431367, 17260.654786904444, 17284.04937728437, 17295.65408691819, 14507.129572225489, 14597.489087881873, 14600.123992560268, 14632.487862924747, 14636.487722929647, 8585.871211931826, 8655.653773848975, 8683.782905427288, 8737.558302880081, 8775.166359195866, 3566.375884220251, 3608.1160115771804, 3610.079429745451, 3613.638636136365, 3639.8180090995343, 964.5755867418313, 979.8873129590046, 1033.245730616654, 1048.6591857646295, 1135.840982262484, 31499.527507087412, 31502.36995260082, 31632.36735265282, 31662.525062124085, 32428.513572295964, 1120.2158489057692, 1148.190525679394, 1191.2911817468591, 1259.168948494008, 1272.2239433945285, 14010.626148269568, 15871.841281587036, 16071.196440177944, 16088.517344479684, 16258.430954916521, 664681.0, 668112.0, 672091.0, 673273.6336318385, 676153.0, 63681.0, 64772.05683829493, 65149.0, 65285.0, 65329.0, 1560.5708430181792, 1564.092826160824, 1576.4876415164888, 1578.0, 1588.7299159142974, 30047.24881877883, 30651.08046758601, 30656.773729050587, 30985.380292394475, 30995.845020774752, 49792.00415991732, 55854.72072639611, 56000.439995601155, 56031.0, 56083.0, 561248.0, 562182.0, 564120.0, 564352.0, 564869.0, 27839.721602783713, 27875.0, 44767.65697029094, 46468.0, 46575.76712116418, 45844.54155458403, 47485.81285467891, 47873.0, 49355.50644493653, 49379.0, 32139.761484891314, 38656.42015369828, 38729.225415492096, 39603.80198099106, 39835.80082099571, 35440.64559354477, 38801.835944921695, 38943.80528097455, 39159.80420097881, 39843.00392490211, 34032.31935361328, 34461.13847153841, 35198.47202292019, 39597.40603891001, 39758.80120599378, 28828.279293017844, 31959.68040319567, 32627.510587340734, 32707.0, 33128.83435582807, 24907.62638560459, 25092.12177573776, 25265.62101568515, 25300.228983970945, 25324.493510129327], "value": 19562.21976711992, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [406.666533442577, 420.4576096835083, 420.4618088846277, 423.8749568877181, 429.35382249714195, 18805.529861753465, 20817.959102044893, 21281.321579787607, 21498.0, 23230.767692323072, 21490.892545537252, 21639.59010247438, 21917.561648767056, 22445.98993045313, 22454.989525471352, 6044.818655440333, 6175.228096487939, 6257.0, 6265.028920517327, 6266.373362663743, 4507.706999045068, 4673.76631168442, 4771.021940502206, 6182.690865456731, 6245.437910588046, 4733.692309999857, 6693.0, 6851.862962740742, 6852.177738671367, 6858.451323894076, 6541.869162616721, 6831.35102165296, 6836.65816709165, 6840.794776156698, 6861.039454476378, 4097.918041639151, 6054.939450605526, 6059.545534084918, 6068.696565171724, 6077.665728384937, 26003.829827657803, 26626.467470650674, 26898.193054208405, 26960.0, 27012.054578089672, 2302.735185453661, 2385.33210701004, 2670.5994100884827, 2694.8248363856287, 2790.9812918284883, 15283.0, 15454.381824726997, 15535.223238838125, 15647.843521564753, 16055.0, 1795.4523870219577, 1821.244183663778, 1864.9067546622614, 1868.2060124447191, 1881.3820114701346, 80636.59681701612, 99919.50040249825, 102995.0, 103144.48427757889, 122350.16474752886, 9341.953290233505, 11507.0, 11603.941980290214, 11635.956784727767, 11867.821982670266, 14761.483348082766, 14773.778393324108, 14853.48012819546, 14855.0, 14859.331330090172, 9227.44635321883, 9290.210332121711, 9324.720258392259, 9427.104425079606, 9736.902630973742, 9485.383450075724, 9515.524223788922, 9518.714438566854, 9602.183814375856, 9632.807343853083, 7007.0, 7059.047028651066, 7090.290970902868, 7091.219965803757, 7113.57318560888, 1917.6931690929384, 1942.9805701943083, 1943.5724140689026, 1962.4407043992394, 1984.3650031989625, 931.5435436636, 968.2399316536485, 994.4132961552718, 1037.1858091398217, 1143.1997601678859, 17924.731129033076, 21010.894945525175, 21385.46536336573, 21988.450288742588, 22242.88878555597, 1032.5714828346302, 1057.8519007338978, 1065.5205157679075, 1077.0, 1107.6898468428853, 10070.848937265946, 11469.369184694755, 11551.480183391599, 11950.701232469259, 11959.342236176919, 645255.0, 646053.0, 646894.0, 656803.0, 678709.0, 63353.049704254474, 73787.31063446806, 96784.03215967891, 115690.0, 119673.40163299128, 1056.287006270764, 1082.7726177502814, 1084.728817795551, 1099.631623406152, 1100.8568886044764, 15455.613609659398, 19449.4165175045, 19662.606747865248, 19698.40904772859, 19909.900450497655, 50819.74590127026, 51119.488805111476, 51725.22412163745, 52171.0, 54327.0, 74224.51550969058, 74460.25539744533, 74872.62563687147, 74988.0, 75091.62454187694, 7966.203379661987, 8257.876131858147, 8487.91512084896, 8495.660173593235, 8770.079141690287, 11986.220895641756, 12923.28921909304, 13143.934280328536, 13386.129901556367, 13436.0, 11111.499982500645, 11487.425628718698, 11549.94225028903, 11600.477978490819, 11787.587434439753, 10921.617743378943, 11145.888541114486, 11206.888000555433, 11515.481803318704, 11643.825342619699, 9784.755381115529, 11772.059589540699, 11832.70418239551, 11856.644300670994, 11880.76238475243, 8445.282151017233, 8576.699815506427, 8734.737957861274, 8748.0, 8813.911860881566, 8803.987541432818, 11093.556257749922, 11545.307281563133, 11905.047596192457, 11958.521659133885], "value": 10067.916480695842, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1476.889233307501, 1617.4824056301984, 1639.0411609208613, 1925.4608709561328, 2032.64428724973, 8370.53962032088, 8955.05971872953, 9085.40944838585, 9103.135202155785, 9114.225290850278, 8844.292456603473, 9068.863967040483, 9091.863622045674, 9095.90904090959, 9098.0, 2532.0631366394414, 2548.6941567011986, 2553.9106131285407, 2554.846709197454, 2558.8336758110754, 2473.332200305916, 2550.0947163756878, 2555.399481121944, 2557.7570130837635, 2566.5123626510995, 2510.9121180758675, 2546.4397832476825, 2561.6285638582394, 2564.4101856572997, 2566.3968967292762, 2513.0576033987254, 2549.5155920375164, 2556.8721563921918, 2562.461883004571, 2568.75596818302, 2286.6570014497793, 2382.5466465456057, 2551.553478141336, 2553.2850801775535, 2558.5010922870047, 8853.513056781874, 9105.998340182574, 9169.724908252763, 9175.632974681004, 9191.724248272563, 5583.246261754652, 5690.9146362804595, 5692.971535142297, 5729.742161602696, 5742.6841523716175, 3635.3456377851976, 3699.611540788232, 3730.0674831292176, 3779.5464544254864, 3783.1109689223013, 1041.8958104189596, 1052.1425038593522, 1065.344812940044, 1071.3786004117594, 1083.8428427877914, 12337.383130843422, 12661.240325580495, 12661.873381266161, 12669.493220271179, 12696.682582935407, 4991.600671946236, 4994.400671919393, 5131.281620573122, 5158.297761738617, 5159.7678104485385, 10776.730581735519, 10869.076128529005, 10875.78248435027, 10888.074513666426, 10888.346699198073, 5025.472325405853, 5374.140137577969, 5718.685472299064, 5836.066229403275, 5842.883142337129, 4636.095961287584, 4741.715497070187, 5035.446100928895, 5199.740012999335, 5240.528352448298, 4169.520505141887, 4214.894627634272, 4273.87178384649, 4319.308910574293, 4324.070324880103, 1363.5704753002972, 1394.400407824645, 1410.2102822419479, 1411.8941079419085, 1413.4770135049891, 758.2190343945745, 868.1795703060548, 920.8388532006873, 972.4457059476172, 980.6861804222581, 6097.420745029215, 6160.568760186744, 6171.382861713792, 6184.165137706442, 6340.651264180515, 481.458359345736, 488.0263873572175, 492.0675320268143, 493.40791050739256, 497.6541303793829, 4044.2315959967514, 4093.63157315843, 4132.483439570054, 4140.130572579732, 4143.357779544175, 10947.069499092508, 11393.658190254306, 11480.77038459226, 11481.770364592661, 11488.88511114895, 11185.217034807649, 11190.888091119148, 11268.549258029587, 11332.6033588824, 11389.94305028486, 8134.349252059821, 8564.443311184878, 8610.741677749678, 8728.0, 8817.470951742916, 5980.730867111078, 5993.22088128558, 6158.353372895961, 6209.410106039919, 6223.37766223374, 9838.262130340396, 9933.255005874444, 10145.34055286404, 10149.898501014895, 10160.745981350525, 9509.381890177116, 9935.801283974135, 10264.281500294908, 10277.897221027693, 10380.06579407857, 6905.620190889549, 7209.855802884018, 7576.469647124869, 7659.004329437137, 7797.259260370256, 7183.856322873408, 8294.129116442928, 8444.197801208875, 8504.787380315542, 8511.659533618586, 7414.703411863465, 7493.0, 7614.772698935012, 7667.616619168908, 7746.883796742941, 7459.92540074614, 7466.552006879605, 7470.738524151629, 7569.848603028019, 7620.771376858703, 6063.878722425615, 6064.241969753781, 7143.0, 7156.892646610201, 7197.8920316196345, 4820.517948205152, 4943.431505376928, 5023.572996295356, 5066.341375621146, 5087.542121209109, 4513.593776560126, 5020.347354843993, 5131.868845579344, 5142.665726727751, 5204.193350030751], "value": 4696.464912844164, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [337.88849679605727, 339.0422057687033, 349.6660689041965, 349.7639093611812, 350.6020666543474, 6620.822005437387, 6627.701753421095, 6659.833504162399, 6692.899606505906, 6718.328167183291, 6323.873522529547, 6410.775622853201, 6537.084808126866, 6602.27374988752, 6617.669116544178, 4898.730569818658, 4936.481669424713, 4960.6527543072, 4986.87532811679, 4990.75046247688, 4070.8805078603473, 4973.303737476756, 4977.1538838397555, 4981.178105612587, 4985.152524070899, 4637.1189474, 4743.786529606176, 4799.760011999403, 4863.392075990501, 4876.853939324256, 4884.827641366047, 4897.902041959176, 4903.19097348937, 4910.877228069309, 4913.606911447076, 4193.60160784728, 4194.706370554062, 4195.538490766014, 4203.07532342884, 4209.6421804146685, 5029.396472423333, 6321.810345689591, 6341.778037768702, 6347.809565713036, 6362.77730279438, 4236.6187043166265, 4303.763293018883, 5302.594812374724, 5751.884962300773, 5759.740811663485, 6681.732730690766, 6896.793096207071, 6974.860502789967, 7018.719251229944, 7024.332688394646, 3904.726669133162, 4463.375127482155, 4559.384483094773, 4597.080583883237, 4618.792154353062, 7301.415886729049, 7406.000189974338, 7407.8888816667795, 7424.109106907152, 7456.105267367951, 4639.930401043987, 4648.233041548175, 4650.255959046604, 4651.395318608559, 4663.300504924221, 3897.395903634941, 4279.0, 4388.780560971939, 4715.7877895494785, 4726.385569875894, 4615.7076018714815, 5421.213923981, 5609.102543593087, 5657.20799088128, 5668.348139963874, 7021.438284937193, 7075.858482830315, 7077.363037326562, 7087.405333799957, 7113.608751518716, 1555.7510798272222, 1616.1192150278127, 1642.3430627748996, 1645.0951976412925, 1651.2156725555506, 995.0546980368679, 997.8802543694803, 1001.9348742331725, 1006.8288390973552, 1036.5594622285498, 6535.215774107138, 6620.106285651472, 6622.437092847162, 6636.037774522763, 6702.430293425016, 7246.96376518114, 7248.347648711536, 7257.673404696801, 7279.963600181965, 7311.537692461529, 6641.0, 6652.933470665328, 6661.2006559213205, 6678.599284042876, 6679.298673639295, 3760.9435858462143, 3806.1626442182674, 3844.8077596120643, 3858.0, 3915.1778126593163, 7729.420293478011, 7742.303192712683, 7791.727289544839, 7822.8435431291055, 7838.412119091091, 7593.6582853771715, 7642.235776422313, 7649.0, 7653.196414376522, 7665.846683066307, 7500.287472690085, 7793.687682734815, 7795.181505941794, 7812.414068944852, 7823.335016523659, 6084.847878802888, 7138.143422789299, 7333.74331898381, 7349.118105827335, 7354.521956072838, 7200.639968001684, 7241.5293005954445, 7263.309985551364, 7263.491555591269, 7282.053333066668, 6175.814725558241, 7745.806354841174, 7754.147043825287, 7777.961110194413, 7791.61041947889, 4620.884477888079, 4685.67200295976, 4689.273162659793, 4693.436787585511, 4697.295405689174, 7036.190838053484, 7360.815979600338, 7381.11426628808, 7401.0, 7402.814929626587, 7052.294770522906, 7056.7530136444975, 7064.788056358318, 7104.254053324326, 7113.502054856111, 7075.433965282662, 7079.185893622093, 7082.964585177041, 7089.723849707102, 7098.29017098286, 5594.328680558359, 5921.792737254175, 6921.2386637470845, 6933.0293758874805, 6992.1958974718555, 6013.488853447506, 6526.118973938457, 6773.593584384953, 7099.396551293198, 7100.502964792621, 6812.420944219797, 6860.108185935796, 6906.551074180162, 6922.8961565577565, 6935.133108361455], "value": 5122.255180984205, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [771.390601424874, 794.2573693596488, 795.9840803183937, 804.6781287485005, 808.1474044882647, 15512.612184695374, 15622.609434764108, 15626.453074142408, 15700.371985120595, 15718.135502547355, 16207.83792162078, 16658.50024499264, 16664.916675416636, 16675.66648667026, 16700.415485458012, 800.6877317846045, 816.1552792859392, 960.3277705606068, 984.4634674102631, 1000.1898462245568, 991.1624677147835, 994.666786626481, 998.1615443027855, 999.2605471950741, 1001.7245257554158, 1050.338286879265, 1051.742323130834, 1052.4527245832162, 1054.151408116466, 1054.2725519391595, 831.9500829950192, 995.5619527407954, 1034.684421251518, 1041.182671602788, 1043.5147656339839, 780.9609519524001, 783.6747749683874, 784.2353705137502, 784.2823816208163, 785.9253370929753, 15939.601509962227, 15964.0, 15990.281652119167, 15993.680126397407, 16038.75941860873, 12717.236965782082, 12845.036622253276, 12990.155639883378, 13169.209847409185, 13341.132826366254, 9164.770880727969, 10192.54133563984, 10237.385756854612, 10376.740581485448, 10699.518521666543, 3512.6662967018347, 3554.253606742587, 3564.6078931317534, 3589.533360663097, 3759.172981943968, 86409.56795216046, 86478.56760716156, 87193.0, 88710.0, 89043.10956890478, 8016.278534931884, 9379.390339627902, 9531.237500999903, 9616.855747163798, 9824.968378320316, 22368.55262894733, 22432.66351004736, 22438.878056097128, 22442.775572244394, 22483.21308754186, 7481.551106933602, 7484.775456736306, 7548.886766698504, 7598.240175982468, 7682.577458239851, 3867.400552914303, 3963.36586146221, 4325.437693099877, 4348.326009468537, 4384.7369157850635, 3863.729538932304, 3915.9021024474614, 3920.843166273317, 3926.2736393767245, 3931.253061918226, 685.8456847209338, 703.8768215562257, 704.5314865614309, 709.8048036789821, 734.7685479074074, 577.9971100144472, 608.2427377914524, 609.7168038942514, 610.6824451285307, 618.2519151826341, 7269.636518174176, 8436.0, 8460.703875364332, 8542.18849209324, 8645.913540864636, 602.764921680545, 604.338249616669, 614.846288427893, 618.1099217127322, 620.2959640807716, 3939.074317535377, 4002.7598344099447, 4024.4164596133396, 4044.939325910114, 4069.959300407017, 96038.0396196043, 96287.0, 97077.0, 97387.51306243423, 97439.51280243554, 78822.0, 80347.19652803396, 81021.0, 81979.59010204911, 82805.0, 73867.26132738603, 74188.62905685438, 74820.0, 76238.61880690561, 77196.61401693067, 8605.870911936201, 8867.024627291123, 8904.421212621159, 8926.642934282556, 9000.279977601907, 50782.49217507778, 50845.237321440945, 52248.0, 52824.73587632191, 54008.0, 69727.65136174287, 72076.19809504594, 72285.6385718068, 72323.0, 72368.0, 11088.833667495155, 11323.433828308716, 11453.198276120926, 11537.711557211136, 11552.364619945984, 9342.0, 12033.63899083029, 12087.818682719942, 12113.818292725793, 12988.0, 11303.095752339628, 11411.942940285246, 11446.02708769764, 11634.53461861516, 12090.637280881587, 11390.430478476208, 11536.25014374063, 11693.590724324607, 12203.962663173375, 12269.263844169378, 11144.60993865211, 11305.434728263719, 11384.772304553697, 11558.422078895854, 12253.509859605516, 9767.609295628095, 10047.246456515937, 10056.849147262943, 10344.948275258575, 10407.375557466576, 9761.316707830383, 9905.306628536222, 9911.207103431852, 9942.105210531086, 9947.651832185838], "value": 7251.774138530622, "unit": "score"}], "name": "dromaeo_css", "value": 7669.129755438038, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 120.0, 111.0, 112.0, 111.0, 112.0, 113.0, 112.0, 132.0], "value": 115.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 136.0, 140.0, 163.0, 117.0, 139.0, 120.0, 155.0, 154.0, 118.0], "value": 140.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 185.0, 182.0, 185.0, 185.0, 185.0, 185.0, 182.0, 183.0, 184.0], "value": 183.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 79.0, 76.0, 158.0, 56.0, 54.0, 56.0, 64.0, 135.0, 56.0], "value": 80.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 91.0, 145.0, 79.0, 88.0, 87.0, 125.0, 79.0, 82.0, 127.0], "value": 99.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 88.0, 84.0, 133.0, 130.0, 82.0, 114.0, 135.0, 88.0], "value": 102.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 93.0, 94.0, 93.0, 92.0, 94.0, 92.0, 92.0, 92.0], "value": 92.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 88.0, 89.0, 86.0, 87.0, 89.0, 89.0, 87.0, 87.0, 85.0], "value": 87.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 63.0, 64.0, 63.0, 62.0, 63.0, 63.0, 78.0, 59.0, 55.0], "value": 62.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 61.0, 71.0, 78.0, 73.0, 69.0, 64.0, 66.0, 66.0, 66.0], "value": 67.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 81.0, 79.0, 80.0, 79.0, 79.0, 69.0, 67.0, 82.0, 104.0], "value": 80.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 126.0, 149.0, 142.0, 138.0, 129.0, 125.0, 124.0, 146.0, 138.0], "value": 134.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 166.0, 166.0, 162.0, 170.0, 165.0, 161.0, 164.0, 160.0, 214.0], "value": 169.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 65.0, 66.0, 66.0, 96.0, 70.0, 68.0, 66.0, 65.0], "value": 69.7, "unit": "score"}], "name": "kraken", "value": 1485.3999999999999, "alertThreshold": 2.0}]} 01:47:13 INFO - Return code: 0 01:47:13 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 01:47:13 ERROR - # TBPL SUCCESS # 01:47:13 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:47:13 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:47:13 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:47:13 INFO - 2016-06-28 01:47:13.910 screenresolution[2549:33286] starting screenresolution argv=screenresolution get 01:47:13 INFO - 2016-06-28 01:47:13.913 screenresolution[2549:33286] Display 0: 1600x1200x32@60 01:47:13 INFO - 2016-06-28 01:47:13.919 screenresolution[2550:33289] starting screenresolution argv=screenresolution list 01:47:13 INFO - Available Modes on Display 0 01:47:13 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:47:13 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:47:13 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:47:13 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:47:14 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:47:14 INFO - Intel Iris: 01:47:14 INFO - Chipset Model: Intel Iris 01:47:14 INFO - Type: GPU 01:47:14 INFO - Bus: Built-In 01:47:14 INFO - VRAM (Dynamic, Max): 1536 MB 01:47:14 INFO - Vendor: Intel (0x8086) 01:47:14 INFO - Device ID: 0x0a2e 01:47:14 INFO - Revision ID: 0x0009 01:47:14 INFO - Displays: 01:47:14 INFO - Display: 01:47:14 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:47:14 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:47:14 INFO - Display Serial Number: 1600x1200 60 01:47:14 INFO - Main Display: Yes 01:47:14 INFO - Mirror: Off 01:47:14 INFO - Online: Yes 01:47:14 INFO - Rotation: Supported 01:47:14 INFO - Return code: 0 01:47:14 INFO - Running post-action listener: _resource_record_post_action 01:47:14 INFO - Running post-run listener: _resource_record_post_run 01:47:15 INFO - Total resource usage - Wall time: 1195s; CPU: 53.0%; Read bytes: 806912; Write bytes: 491244032; Read time: 6; Write time: 1519 01:47:15 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 193192448; Write bytes: 185769984; Read time: 17650; Write time: 391 01:47:15 INFO - run-tests - Wall time: 1173s; CPU: 52.0%; Read bytes: 806912; Write bytes: 295733760; Read time: 6; Write time: 1093 01:47:15 INFO - Running post-run listener: _upload_blobber_files 01:47:15 INFO - Blob upload gear active. 01:47:15 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 01:47:15 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:15 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:15 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:15 INFO - (blobuploader) - INFO - Open directory for files ... 01:47:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 01:47:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:47:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:47:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 01:47:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:47:16 INFO - (blobuploader) - INFO - Done attempting. 01:47:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 01:47:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:47:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:47:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 01:47:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:47:17 INFO - (blobuploader) - INFO - Done attempting. 01:47:17 INFO - (blobuploader) - INFO - Iteration through files over. 01:47:17 INFO - Return code: 0 01:47:17 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 01:47:17 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 01:47:17 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8186ec99cfd4082263a52ac806b1b22fbbc9eada3cf370288e4e04ec9e5ab99c153008b7bce2c1680e1ed84aaa8929de863d4b5e2d9a05b9f008af9ef684eeb3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2435202aac41b2e97f8474609b0eedafadf5ca9900274332e0e3f6da961cf34647c3d61bd7425fafb0309b91b8b28564be79554d1319659007caae24e4a199de"} 01:47:17 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 01:47:17 INFO - Writing to file /builds/slave/test/properties/blobber_files 01:47:17 INFO - Contents: 01:47:17 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8186ec99cfd4082263a52ac806b1b22fbbc9eada3cf370288e4e04ec9e5ab99c153008b7bce2c1680e1ed84aaa8929de863d4b5e2d9a05b9f008af9ef684eeb3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2435202aac41b2e97f8474609b0eedafadf5ca9900274332e0e3f6da961cf34647c3d61bd7425fafb0309b91b8b28564be79554d1319659007caae24e4a199de"} 01:47:17 INFO - Running post-run listener: copy_logs_to_upload_dir 01:47:17 INFO - Copying logs to upload dir... 01:47:17 INFO - mkdir: /builds/slave/test/build/upload/logs 01:47:17 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1250.523513 ========= master_lag: 237.24 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 24 mins, 47 secs) (at 2016-06-28 01:51:14.933809) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-06-28 01:51:14.937793) ========= 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.xgLCviu1vt/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.TwZ7EedStd/Listeners TMPDIR=/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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/8186ec99cfd4082263a52ac806b1b22fbbc9eada3cf370288e4e04ec9e5ab99c153008b7bce2c1680e1ed84aaa8929de863d4b5e2d9a05b9f008af9ef684eeb3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2435202aac41b2e97f8474609b0eedafadf5ca9900274332e0e3f6da961cf34647c3d61bd7425fafb0309b91b8b28564be79554d1319659007caae24e4a199de"} build_url:https://queue.taskcluster.net/v1/task/FO6Gl0R-S5eBjr032mt9RA/artifacts/public/build/firefox-48.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011546 build_url: 'https://queue.taskcluster.net/v1/task/FO6Gl0R-S5eBjr032mt9RA/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/8186ec99cfd4082263a52ac806b1b22fbbc9eada3cf370288e4e04ec9e5ab99c153008b7bce2c1680e1ed84aaa8929de863d4b5e2d9a05b9f008af9ef684eeb3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2435202aac41b2e97f8474609b0eedafadf5ca9900274332e0e3f6da961cf34647c3d61bd7425fafb0309b91b8b28564be79554d1319659007caae24e4a199de"}' ========= master_lag: 1.46 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-06-28 01:51:16.411040) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 29 secs) (at 2016-06-28 01:51:16.411396) ========= 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.xgLCviu1vt/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.TwZ7EedStd/Listeners TMPDIR=/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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.004952 ========= master_lag: 29.30 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 29 secs) (at 2016-06-28 01:51:45.713479) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-28 01:51:45.713866) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-28 01:51:45.843130) ========= ========= Total master_lag: 268.07 =========