builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0110 starttime: 1467870297.73 results: success (0) buildid: 20160706204229 builduid: 8d63b2827b9f4cf9b8f9f0da9fefc1a1 revision: e4b27dae353c933a01584dfeff27d547ef60f04e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-06 22:44:57.727682) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-06 22:44:57.728237) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-06 22:44:57.728565) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-06 22:44:57.752491) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 22:44:57.752932) ========= 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.botEVMndKY/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.4s2eA6qdfR/Listeners TMPDIR=/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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-07-06 22:44:57-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 159M=0s 2016-07-06 22:44:57 (159 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.166748 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 22:44:57.941985) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 22:44:57.942312) ========= 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.botEVMndKY/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.4s2eA6qdfR/Listeners TMPDIR=/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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.020702 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 22:44:57.990711) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 22:44:57.991077) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev e4b27dae353c933a01584dfeff27d547ef60f04e --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 e4b27dae353c933a01584dfeff27d547ef60f04e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.botEVMndKY/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.4s2eA6qdfR/Listeners TMPDIR=/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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-07-06 22:44:58,065 truncating revision to first 12 chars 2016-07-06 22:44:58,066 Setting DEBUG logging. 2016-07-06 22:44:58,066 attempt 1/10 2016-07-06 22:44:58,066 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/e4b27dae353c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-06 22:44:58,355 unpacking tar archive at: mozilla-beta-e4b27dae353c/testing/mozharness/ program finished with exit code 0 elapsedTime=0.572634 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 22:44:58.585388) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-06 22:44:58.585701) ========= script_repo_revision: e4b27dae353c933a01584dfeff27d547ef60f04e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-06 22:44:58.586099) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-06 22:44:58.586375) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-06 22:44:58.604812) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2016-07-06 22:44:58.605147) ========= /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.botEVMndKY/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.4s2eA6qdfR/Listeners TMPDIR=/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 22:44:58 INFO - MultiFileLogger online at 20160706 22:44:58 in /builds/slave/test 22:44:58 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 22:44:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:44:58 INFO - {'append_to_log': False, 22:44:58 INFO - 'base_work_dir': '/builds/slave/test', 22:44:58 INFO - 'blob_upload_branch': 'Mozilla-Beta', 22:44:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:44:58 INFO - 'branch': 'Mozilla-Beta', 22:44:58 INFO - 'buildbot_json_path': 'buildprops.json', 22:44:58 INFO - 'config_files': ('talos/mac_config.py',), 22:44:58 INFO - 'default_actions': ('clobber', 22:44:58 INFO - 'read-buildbot-config', 22:44:58 INFO - 'download-and-extract', 22:44:58 INFO - 'populate-webroot', 22:44:58 INFO - 'create-virtualenv', 22:44:58 INFO - 'install', 22:44:58 INFO - 'run-tests'), 22:44:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:44:58 INFO - 'download_minidump_stackwalk': True, 22:44:58 INFO - 'download_symbols': 'ondemand', 22:44:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:44:58 INFO - 'tooltool.py': '/tools/tooltool.py', 22:44:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:44:58 INFO - '/tools/misc-python/virtualenv.py')}, 22:44:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:44:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:44:58 INFO - 'installer_path': 'installer.exe', 22:44:58 INFO - 'log_level': 'info', 22:44:58 INFO - 'log_name': 'talos', 22:44:58 INFO - 'log_to_console': True, 22:44:58 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 22:44:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 22:44:58 INFO - 'opt_config_files': (), 22:44:58 INFO - 'pip_index': False, 22:44:58 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:44:58 INFO - 'cmd': ('bash', 22:44:58 INFO - '-c', 22:44:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:44:58 INFO - 'enabled': True, 22:44:58 INFO - 'halt_on_failure': False, 22:44:58 INFO - 'name': 'check_screen_resolution'},), 22:44:58 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:44:58 INFO - 'cmd': ('bash', 22:44:58 INFO - '-c', 22:44:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:44:58 INFO - 'enabled': True, 22:44:58 INFO - 'halt_on_failure': False, 22:44:58 INFO - 'name': 'check_screen_resolution'},), 22:44:58 INFO - 'run_cmd_checks_enabled': True, 22:44:58 INFO - 'sps_profile': False, 22:44:58 INFO - 'sps_profile_interval': 0, 22:44:58 INFO - 'suite': 'dromaeojs', 22:44:58 INFO - 'system_bits': '32', 22:44:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:44:58 INFO - 'title': 't-yosemite-r7-0110', 22:44:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:44:58 INFO - 'use_talos_json': True, 22:44:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:44:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:44:58 INFO - 'work_dir': 'build'} 22:44:58 INFO - ##### 22:44:58 INFO - ##### Running clobber step. 22:44:58 INFO - ##### 22:44:58 INFO - Running pre-action listener: _resource_record_pre_action 22:44:58 INFO - Running main action method: clobber 22:44:58 INFO - rmtree: /builds/slave/test/build 22:44:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:45:00 INFO - Running post-action listener: _resource_record_post_action 22:45:00 INFO - ##### 22:45:00 INFO - ##### Running read-buildbot-config step. 22:45:00 INFO - ##### 22:45:00 INFO - Running pre-action listener: _resource_record_pre_action 22:45:00 INFO - Running main action method: read_buildbot_config 22:45:00 INFO - Using buildbot properties: 22:45:00 INFO - { 22:45:00 INFO - "project": "", 22:45:00 INFO - "product": "firefox", 22:45:00 INFO - "script_repo_revision": "production", 22:45:00 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 22:45:00 INFO - "repository": "", 22:45:00 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 22:45:00 INFO - "buildid": "20160706204229", 22:45:00 INFO - "pgo_build": "False", 22:45:00 INFO - "basedir": "/builds/slave/test", 22:45:00 INFO - "buildnumber": 37, 22:45:00 INFO - "slavename": "t-yosemite-r7-0110", 22:45:00 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 22:45:00 INFO - "platform": "yosemite_r7", 22:45:00 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 22:45:00 INFO - "branch": "mozilla-beta", 22:45:00 INFO - "repo_path": "releases/mozilla-beta", 22:45:00 INFO - "revision": "e4b27dae353c933a01584dfeff27d547ef60f04e", 22:45:00 INFO - "stage_platform": "macosx64", 22:45:00 INFO - "builduid": "8d63b2827b9f4cf9b8f9f0da9fefc1a1", 22:45:00 INFO - "slavebuilddir": "test" 22:45:00 INFO - } 22:45:00 INFO - Found installer url https://queue.taskcluster.net/v1/task/DLbm233hT9i2W3-QNNHisw/artifacts/public/build/firefox-48.0.en-US.mac.dmg. 22:45:00 INFO - Running post-action listener: _resource_record_post_action 22:45:00 INFO - ##### 22:45:00 INFO - ##### Running download-and-extract step. 22:45:00 INFO - ##### 22:45:00 INFO - Running pre-action listener: _resource_record_pre_action 22:45:00 INFO - Running main action method: download_and_extract 22:45:00 INFO - mkdir: /builds/slave/test/build/tests 22:45: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')]} 22:45:00 INFO - https://queue.taskcluster.net/v1/task/DLbm233hT9i2W3-QNNHisw/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json matches https://queue.taskcluster.net 22:45:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DLbm233hT9i2W3-QNNHisw/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json 22:45:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DLbm233hT9i2W3-QNNHisw/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json 22:45:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DLbm233hT9i2W3-QNNHisw/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 22:45:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DLbm233hT9i2W3-QNNHisw/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 22:45:00 INFO - Downloaded 1165 bytes. 22:45:00 INFO - Reading from file /builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json 22:45:00 INFO - Using the following test package requirements: 22:45:00 INFO - {u'common': [u'firefox-48.0.en-US.mac.common.tests.zip'], 22:45:00 INFO - u'cppunittest': [u'firefox-48.0.en-US.mac.common.tests.zip', 22:45:00 INFO - u'firefox-48.0.en-US.mac.cppunittest.tests.zip'], 22:45:00 INFO - u'gtest': [u'firefox-48.0.en-US.mac.common.tests.zip', 22:45:00 INFO - u'firefox-48.0.en-US.mac.gtest.tests.zip'], 22:45:00 INFO - u'jittest': [u'firefox-48.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 22:45:00 INFO - u'mochitest': [u'firefox-48.0.en-US.mac.common.tests.zip', 22:45:00 INFO - u'firefox-48.0.en-US.mac.mochitest.tests.zip'], 22:45:00 INFO - u'mozbase': [u'firefox-48.0.en-US.mac.common.tests.zip'], 22:45:00 INFO - u'reftest': [u'firefox-48.0.en-US.mac.common.tests.zip', 22:45:00 INFO - u'firefox-48.0.en-US.mac.reftest.tests.zip'], 22:45:00 INFO - u'talos': [u'firefox-48.0.en-US.mac.common.tests.zip', 22:45:00 INFO - u'firefox-48.0.en-US.mac.talos.tests.zip'], 22:45:00 INFO - u'web-platform': [u'firefox-48.0.en-US.mac.common.tests.zip', 22:45:00 INFO - u'firefox-48.0.en-US.mac.web-platform.tests.zip'], 22:45:00 INFO - u'xpcshell': [u'firefox-48.0.en-US.mac.common.tests.zip', 22:45:00 INFO - u'firefox-48.0.en-US.mac.xpcshell.tests.zip']} 22:45:00 INFO - Downloading packages: [u'firefox-48.0.en-US.mac.common.tests.zip'] for test suite category: common 22:45: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')]} 22:45:00 INFO - https://queue.taskcluster.net/v1/task/DLbm233hT9i2W3-QNNHisw/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 22:45:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DLbm233hT9i2W3-QNNHisw/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 22:45:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DLbm233hT9i2W3-QNNHisw/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 22:45:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DLbm233hT9i2W3-QNNHisw/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 22:45:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DLbm233hT9i2W3-QNNHisw/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 22:45:01 INFO - Downloaded 18351436 bytes. 22:45:01 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'] 22:45:01 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 22:45:05 INFO - Return code: 0 22:45:05 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 22:45: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')]} 22:45:05 INFO - https://queue.taskcluster.net/v1/task/DLbm233hT9i2W3-QNNHisw/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 22:45:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DLbm233hT9i2W3-QNNHisw/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 22:45:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DLbm233hT9i2W3-QNNHisw/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 22:45:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DLbm233hT9i2W3-QNNHisw/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 22:45:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DLbm233hT9i2W3-QNNHisw/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 22:45:06 INFO - Downloaded 18351436 bytes. 22:45:06 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'] 22:45:06 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 22:45:09 INFO - Return code: 0 22:45:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:45:09 INFO - https://queue.taskcluster.net/v1/task/DLbm233hT9i2W3-QNNHisw/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 22:45:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DLbm233hT9i2W3-QNNHisw/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip 22:45:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DLbm233hT9i2W3-QNNHisw/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip 22:45:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DLbm233hT9i2W3-QNNHisw/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 22:45:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DLbm233hT9i2W3-QNNHisw/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 22:45:10 INFO - Downloaded 10840145 bytes. 22:45:10 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'] 22:45:10 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 22:45:11 INFO - Return code: 0 22:45:11 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')]} 22:45:11 INFO - https://queue.taskcluster.net/v1/task/DLbm233hT9i2W3-QNNHisw/artifacts/public/build/firefox-48.0.en-US.mac.dmg matches https://queue.taskcluster.net 22:45:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DLbm233hT9i2W3-QNNHisw/artifacts/public/build/firefox-48.0.en-US.mac.dmg 22:45:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DLbm233hT9i2W3-QNNHisw/artifacts/public/build/firefox-48.0.en-US.mac.dmg 22:45:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DLbm233hT9i2W3-QNNHisw/artifacts/public/build/firefox-48.0.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0.en-US.mac.dmg 22:45:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DLbm233hT9i2W3-QNNHisw/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 22:45:13 INFO - Downloaded 86187840 bytes. 22:45:13 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DLbm233hT9i2W3-QNNHisw/artifacts/public/build/firefox-48.0.en-US.mac.dmg 22:45:13 INFO - mkdir: /builds/slave/test/properties 22:45:13 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:45:13 INFO - Writing to file /builds/slave/test/properties/build_url 22:45:13 INFO - Contents: 22:45:13 INFO - build_url:https://queue.taskcluster.net/v1/task/DLbm233hT9i2W3-QNNHisw/artifacts/public/build/firefox-48.0.en-US.mac.dmg 22:45:14 INFO - Running post-action listener: _resource_record_post_action 22:45:14 INFO - Running post-action listener: set_extra_try_arguments 22:45:14 INFO - ##### 22:45:14 INFO - ##### Running populate-webroot step. 22:45:14 INFO - ##### 22:45:14 INFO - Running pre-action listener: _resource_record_pre_action 22:45:14 INFO - Running main action method: populate_webroot 22:45:14 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:45:14 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:45:14 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:45:14 INFO - '--tppagecycles', 22:45:14 INFO - '7'], 22:45:14 INFO - 'tests': ['tsvgm']}}, 22:45:14 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:45:14 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:45:14 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:45:14 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:45:14 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:45:14 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:45:14 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:45:14 INFO - 'talos_options': ['--disable-e10s'], 22:45:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:45:14 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:45:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:45:14 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:45:14 INFO - 'talos_options': ['--disable-e10s'], 22:45:14 INFO - 'tests': ['damp', 'tps']}, 22:45:14 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:45:14 INFO - 'tests': ['damp', 'tps']}, 22:45:14 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:45:14 INFO - 'tests': ['dromaeo_dom']}, 22:45:14 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:45:14 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:45:14 INFO - 'tests': ['a11yr', 22:45:14 INFO - 'ts_paint', 22:45:14 INFO - 'tpaint', 22:45:14 INFO - 'sessionrestore', 22:45:14 INFO - 'sessionrestore_no_auto_restore', 22:45:14 INFO - 'tabpaint']}, 22:45:14 INFO - 'other-e10s': {'tests': ['a11yr', 22:45:14 INFO - 'ts_paint', 22:45:14 INFO - 'tpaint', 22:45:14 INFO - 'sessionrestore', 22:45:14 INFO - 'sessionrestore_no_auto_restore', 22:45:14 INFO - 'tabpaint']}, 22:45:14 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:45:14 INFO - 'ts_paint', 22:45:14 INFO - 'tpaint', 22:45:14 INFO - 'sessionrestore', 22:45:14 INFO - 'sessionrestore_no_auto_restore', 22:45:14 INFO - 'tabpaint']}, 22:45:14 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:45:14 INFO - 'ts_paint', 22:45:14 INFO - 'tpaint', 22:45:14 INFO - 'sessionrestore', 22:45:14 INFO - 'sessionrestore_no_auto_restore', 22:45:14 INFO - 'tabpaint']}, 22:45:14 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:45:14 INFO - 'tests': ['a11yr', 22:45:14 INFO - 'ts_paint', 22:45:14 INFO - 'tpaint', 22:45:14 INFO - 'sessionrestore', 22:45:14 INFO - 'sessionrestore_no_auto_restore', 22:45:14 INFO - 'tabpaint']}, 22:45:14 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:45:14 INFO - 'tests': ['a11yr', 22:45:14 INFO - 'ts_paint', 22:45:14 INFO - 'tpaint', 22:45:14 INFO - 'sessionrestore', 22:45:14 INFO - 'sessionrestore_no_auto_restore', 22:45:14 INFO - 'tabpaint']}, 22:45:14 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:45:14 INFO - 'tests': ['tsvgx', 22:45:14 INFO - 'tsvgr_opacity', 22:45:14 INFO - 'tart', 22:45:14 INFO - 'tscrollx', 22:45:14 INFO - 'cart']}, 22:45:14 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:45:14 INFO - 'tsvgr_opacity', 22:45:14 INFO - 'tart', 22:45:14 INFO - 'tscrollx', 22:45:14 INFO - 'cart']}, 22:45:14 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:45:14 INFO - 'talos_options': ['--disable-e10s'], 22:45:14 INFO - 'tests': ['tp5o']}, 22:45:14 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:45:14 INFO - 'tests': ['tp5o']}, 22:45:14 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:45:14 INFO - 'talos_options': ['--disable-e10s', 22:45:14 INFO - '--xperf_path', 22:45:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:45:14 INFO - 'tests': ['tp5n']}, 22:45:14 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:45:14 INFO - 'talos_options': ['--xperf_path', 22:45:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:45:14 INFO - 'tests': ['tp5n']}}, 22:45:14 INFO - 'talos.zip': {'path': '', 22:45:14 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:45:14 INFO - Running post-action listener: _resource_record_post_action 22:45:14 INFO - ##### 22:45:14 INFO - ##### Running create-virtualenv step. 22:45:14 INFO - ##### 22:45:14 INFO - Running pre-action listener: _resource_record_pre_action 22:45:14 INFO - Running main action method: create_virtualenv 22:45:14 INFO - Creating virtualenv /builds/slave/test/build/venv 22:45:14 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 22:45:14 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:45:14 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:45:14 INFO - Using real prefix '/tools/python27' 22:45:14 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:45:15 INFO - Installing distribute.............................................................................................................................................................................................done. 22:45:18 INFO - Installing pip.................done. 22:45:18 INFO - Return code: 0 22:45:18 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:45:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:45:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:45:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:45:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:45:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:45:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:45:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ae36a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ae46420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8114b16d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10acd5e00>, '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 0x10ab3edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10acd0b20>, '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.botEVMndKY/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.4s2eA6qdfR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 22:45:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 22:45:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 22:45:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.botEVMndKY/Render', 22:45:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:45:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:45:18 INFO - 'HOME': '/Users/cltbld', 22:45:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:45:18 INFO - 'LOGNAME': 'cltbld', 22:45:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:45:18 INFO - 'MOZ_NO_REMOTE': '1', 22:45:18 INFO - 'NO_EM_RESTART': '1', 22:45:18 INFO - 'PAGER': '/bin/cat', 22:45:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:45:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:45:18 INFO - 'PWD': '/builds/slave/test', 22:45:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:45:18 INFO - 'SHELL': '/bin/bash', 22:45:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4s2eA6qdfR/Listeners', 22:45:18 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 22:45:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:45:18 INFO - 'USER': 'cltbld', 22:45:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:45:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:45:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:45:18 INFO - 'XPC_FLAGS': '0x0', 22:45:18 INFO - 'XPC_SERVICE_NAME': '0', 22:45:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:45:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:45:18 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 22:45:18 INFO - Cleaning up... 22:45:18 INFO - Return code: 0 22:45:18 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 22:45:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:45:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:45:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:45:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:45:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:45:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:45:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10ae36a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ae46420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8114b16d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10acd5e00>, '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 0x10ab3edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10acd0b20>, '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.botEVMndKY/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.4s2eA6qdfR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 22:45:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 22:45:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 22:45:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.botEVMndKY/Render', 22:45:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:45:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:45:18 INFO - 'HOME': '/Users/cltbld', 22:45:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:45:18 INFO - 'LOGNAME': 'cltbld', 22:45:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:45:18 INFO - 'MOZ_NO_REMOTE': '1', 22:45:18 INFO - 'NO_EM_RESTART': '1', 22:45:18 INFO - 'PAGER': '/bin/cat', 22:45:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:45:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:45:18 INFO - 'PWD': '/builds/slave/test', 22:45:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:45:18 INFO - 'SHELL': '/bin/bash', 22:45:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4s2eA6qdfR/Listeners', 22:45:18 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 22:45:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:45:18 INFO - 'USER': 'cltbld', 22:45:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:45:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:45:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:45:18 INFO - 'XPC_FLAGS': '0x0', 22:45:18 INFO - 'XPC_SERVICE_NAME': '0', 22:45:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:45:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:45:18 INFO - Downloading/unpacking psutil>=0.7.1 22:45:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:45:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:45:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:45:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:45:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:45:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:45:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:45:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:45:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:45:22 INFO - Installing collected packages: psutil 22:45:22 INFO - Running setup.py install for psutil 22:45:22 INFO - building 'psutil._psutil_osx' extension 22:45:22 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 22:45:22 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 22:45:22 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 22:45:22 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 22:45:22 INFO - building 'psutil._psutil_posix' extension 22:45:22 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 22:45:22 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 22:45:22 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 22:45:22 INFO - ^ 22:45:23 INFO - 1 warning generated. 22:45:23 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 22:45:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:45:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:45:23 INFO - Successfully installed psutil 22:45:23 INFO - Cleaning up... 22:45:23 INFO - Return code: 0 22:45:23 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 22:45:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:45:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:45:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:45:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:45:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:45:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:45:23 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 0x10ae36a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ae46420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8114b16d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10acd5e00>, '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 0x10ab3edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10acd0b20>, '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.botEVMndKY/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.4s2eA6qdfR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 22:45:23 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 22:45:23 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 22:45:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.botEVMndKY/Render', 22:45:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:45:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:45:23 INFO - 'HOME': '/Users/cltbld', 22:45:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:45:23 INFO - 'LOGNAME': 'cltbld', 22:45:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:45:23 INFO - 'MOZ_NO_REMOTE': '1', 22:45:23 INFO - 'NO_EM_RESTART': '1', 22:45:23 INFO - 'PAGER': '/bin/cat', 22:45:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:45:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:45:23 INFO - 'PWD': '/builds/slave/test', 22:45:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:45:23 INFO - 'SHELL': '/bin/bash', 22:45:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4s2eA6qdfR/Listeners', 22:45:23 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 22:45:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:45:23 INFO - 'USER': 'cltbld', 22:45:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:45:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:45:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:45:23 INFO - 'XPC_FLAGS': '0x0', 22:45:23 INFO - 'XPC_SERVICE_NAME': '0', 22:45:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:45:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:45:23 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 22:45:23 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 22:45:23 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 22:45:23 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 22:45:23 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 22:45:23 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 22:45:23 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 22:45:25 INFO - Downloading mozsystemmonitor-0.0.tar.gz 22:45:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:45:26 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 22:45:26 INFO - Installing collected packages: mozsystemmonitor 22:45:26 INFO - Running setup.py install for mozsystemmonitor 22:45:26 INFO - Successfully installed mozsystemmonitor 22:45:26 INFO - Cleaning up... 22:45:26 INFO - Return code: 0 22:45:26 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:45:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:45:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:45:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:45:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:45:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:45:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:45:26 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 0x10ae36a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ae46420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8114b16d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10acd5e00>, '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 0x10ab3edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10acd0b20>, '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.botEVMndKY/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.4s2eA6qdfR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 22:45:26 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 22:45:26 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 22:45:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.botEVMndKY/Render', 22:45:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:45:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:45:26 INFO - 'HOME': '/Users/cltbld', 22:45:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:45:26 INFO - 'LOGNAME': 'cltbld', 22:45:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:45:26 INFO - 'MOZ_NO_REMOTE': '1', 22:45:26 INFO - 'NO_EM_RESTART': '1', 22:45:26 INFO - 'PAGER': '/bin/cat', 22:45:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:45:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:45:26 INFO - 'PWD': '/builds/slave/test', 22:45:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:45:26 INFO - 'SHELL': '/bin/bash', 22:45:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4s2eA6qdfR/Listeners', 22:45:26 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 22:45:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:45:26 INFO - 'USER': 'cltbld', 22:45:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:45:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:45:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:45:26 INFO - 'XPC_FLAGS': '0x0', 22:45:26 INFO - 'XPC_SERVICE_NAME': '0', 22:45:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:45:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:45:26 INFO - Downloading/unpacking blobuploader==1.2.4 22:45:26 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 22:45:26 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 22:45:26 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 22:45:26 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 22:45:26 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 22:45:26 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 22:45:28 INFO - Downloading blobuploader-1.2.4.tar.gz 22:45:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:45:29 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:45:29 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 22:45:29 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 22:45:29 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 22:45:29 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 22:45:29 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 22:45:29 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 22:45:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:45:29 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:45:29 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 22:45:29 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 22:45:29 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 22:45:29 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 22:45:29 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 22:45:29 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 22:45:30 INFO - Downloading docopt-0.6.1.tar.gz 22:45:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:45:30 INFO - Installing collected packages: blobuploader, requests, docopt 22:45:30 INFO - Running setup.py install for blobuploader 22:45:30 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:45:30 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:45:30 INFO - Running setup.py install for requests 22:45:30 INFO - Running setup.py install for docopt 22:45:30 INFO - Successfully installed blobuploader requests docopt 22:45:30 INFO - Cleaning up... 22:45:31 INFO - Return code: 0 22:45:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:45:31 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')]} 22:45:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:45:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:45:31 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')]} 22:45:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:45:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:45:31 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 0x10ae36a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ae46420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8114b16d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10acd5e00>, '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 0x10ab3edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10acd0b20>, '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.botEVMndKY/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.4s2eA6qdfR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 22:45:31 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 22:45:31 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 22:45:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.botEVMndKY/Render', 22:45:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:45:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:45:31 INFO - 'HOME': '/Users/cltbld', 22:45:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:45:31 INFO - 'LOGNAME': 'cltbld', 22:45:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:45:31 INFO - 'MOZ_NO_REMOTE': '1', 22:45:31 INFO - 'NO_EM_RESTART': '1', 22:45:31 INFO - 'PAGER': '/bin/cat', 22:45:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:45:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:45:31 INFO - 'PWD': '/builds/slave/test', 22:45:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:45:31 INFO - 'SHELL': '/bin/bash', 22:45:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4s2eA6qdfR/Listeners', 22:45:31 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 22:45:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:45:31 INFO - 'USER': 'cltbld', 22:45:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:45:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:45:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:45:31 INFO - 'XPC_FLAGS': '0x0', 22:45:31 INFO - 'XPC_SERVICE_NAME': '0', 22:45:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:45:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:45:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:45:31 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-lG6ek8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:45:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:45:31 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-nBWpNW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:45:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:45:31 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-GUDErc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:45:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:45:31 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-3bF7X9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:45:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:45:31 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-H5tQOA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:45:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:45:31 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-LEeU36-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:45:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:45:31 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-qC8wE1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:45:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:45:32 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-dtS1Au-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:45:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:45:32 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-0QSMj2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:45:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:45:32 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-nxpSFR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:45:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:45:32 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-fwlNWp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:45:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:45:32 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-qg49j0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:45:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:45:32 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-QOCNS4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:45:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:45:32 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-QyFf3r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:45:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:45:32 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-cZ2fkm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:45:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:45:32 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-hxbtm5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:45:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:45:32 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-X1J0EO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:45:33 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:45:33 INFO - Running setup.py install for manifestparser 22:45:33 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:45:33 INFO - Running setup.py install for mozcrash 22:45:33 INFO - Running setup.py install for mozdebug 22:45:33 INFO - Running setup.py install for mozdevice 22:45:33 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:45:33 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:45:33 INFO - Running setup.py install for mozfile 22:45:33 INFO - Running setup.py install for mozhttpd 22:45:33 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:45:33 INFO - Running setup.py install for mozinfo 22:45:34 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:45:34 INFO - Running setup.py install for mozInstall 22:45:34 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:45:34 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:45:34 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:45:34 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:45:34 INFO - Running setup.py install for mozleak 22:45:34 INFO - Running setup.py install for mozlog 22:45:34 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:45:34 INFO - Running setup.py install for moznetwork 22:45:34 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:45:34 INFO - Running setup.py install for mozprocess 22:45:34 INFO - Running setup.py install for mozprofile 22:45:34 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:45:34 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:45:34 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:45:35 INFO - Running setup.py install for mozrunner 22:45:35 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:45:35 INFO - Running setup.py install for mozscreenshot 22:45:35 INFO - Running setup.py install for moztest 22:45:35 INFO - Running setup.py install for mozversion 22:45:35 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:45:35 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:45:35 INFO - Cleaning up... 22:45:35 INFO - Return code: 0 22:45:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:45:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:45:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:45:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:45:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:45:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:45:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:45:35 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 0x10ae36a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ae46420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8114b16d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10acd5e00>, '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 0x10ab3edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10acd0b20>, '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.botEVMndKY/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.4s2eA6qdfR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 22:45:35 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 22:45:35 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 22:45:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.botEVMndKY/Render', 22:45:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:45:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:45:35 INFO - 'HOME': '/Users/cltbld', 22:45:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:45:35 INFO - 'LOGNAME': 'cltbld', 22:45:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:45:35 INFO - 'MOZ_NO_REMOTE': '1', 22:45:35 INFO - 'NO_EM_RESTART': '1', 22:45:35 INFO - 'PAGER': '/bin/cat', 22:45:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:45:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:45:35 INFO - 'PWD': '/builds/slave/test', 22:45:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:45:35 INFO - 'SHELL': '/bin/bash', 22:45:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4s2eA6qdfR/Listeners', 22:45:35 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 22:45:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:45:35 INFO - 'USER': 'cltbld', 22:45:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:45:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:45:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:45:35 INFO - 'XPC_FLAGS': '0x0', 22:45:35 INFO - 'XPC_SERVICE_NAME': '0', 22:45:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:45:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:45:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:45:35 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-xdWkPV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:45:36 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)) 22:45:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:45:36 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-ittpIO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:45:36 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)) 22:45:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:45:36 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-38XYbj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:45:36 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)) 22:45:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:45:36 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-tcNERH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:45:36 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)) 22:45:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:45:36 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-KK__aQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:45:36 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)) 22:45:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:45:36 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-YXjj6m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:45:36 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)) 22:45:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:45:36 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-6njNhz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:45:36 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)) 22:45:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:45:36 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-rOkWzq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:45:36 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)) 22:45:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:45:36 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-u_Bc5I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:45:36 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)) 22:45:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:45:36 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-_d826Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:45:36 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)) 22:45:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:45:36 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-xg74mj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:45:37 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)) 22:45:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:45:37 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-pIW4pA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:45:37 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)) 22:45:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:45:37 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-ZtG2lD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:45:37 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)) 22:45:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:45:37 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-UqAbcx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:45:37 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)) 22:45:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:45:37 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-6PyoRc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:45:37 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)) 22:45:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:45:37 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-W2xZSU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:45:37 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)) 22:45:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:45:37 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-YnkuK1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:45:37 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)) 22:45:37 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)) 22:45:37 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)) 22:45:37 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)) 22:45:37 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)) 22:45:37 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)) 22:45:37 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:45:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:45:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:45:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:45:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:45:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:45:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:45:40 INFO - Downloading blessings-1.6.tar.gz 22:45:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:45:40 INFO - Installing collected packages: blessings 22:45:40 INFO - Running setup.py install for blessings 22:45:40 INFO - Successfully installed blessings 22:45:40 INFO - Cleaning up... 22:45:40 INFO - Return code: 0 22:45:40 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:45:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:45:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:45:40 INFO - Reading from file tmpfile_stdout 22:45:40 INFO - Current package versions: 22:45:40 INFO - blessings == 1.6 22:45:40 INFO - blobuploader == 1.2.4 22:45:40 INFO - docopt == 0.6.1 22:45:40 INFO - manifestparser == 1.1 22:45:40 INFO - mozInstall == 1.12 22:45:40 INFO - mozcrash == 0.17 22:45:40 INFO - mozdebug == 0.1 22:45:40 INFO - mozdevice == 0.48 22:45:40 INFO - mozfile == 1.2 22:45:40 INFO - mozhttpd == 0.7 22:45:40 INFO - mozinfo == 0.9 22:45:40 INFO - mozleak == 0.1 22:45:40 INFO - mozlog == 3.1 22:45:40 INFO - moznetwork == 0.27 22:45:40 INFO - mozprocess == 0.22 22:45:40 INFO - mozprofile == 0.28 22:45:40 INFO - mozrunner == 6.11 22:45:40 INFO - mozscreenshot == 0.1 22:45:40 INFO - mozsystemmonitor == 0.0 22:45:40 INFO - moztest == 0.7 22:45:40 INFO - mozversion == 1.4 22:45:40 INFO - psutil == 3.1.1 22:45:40 INFO - requests == 1.2.3 22:45:40 INFO - wsgiref == 0.1.2 22:45:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:45: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')]} 22:45:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:45:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:45: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')]} 22:45:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:45:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:45:40 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 0x10ae36a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ae46420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8114b16d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10acd5e00>, '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 0x10ab3edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10acd0b20>, '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.botEVMndKY/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.4s2eA6qdfR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 22:45:40 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 22:45:40 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 22:45:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.botEVMndKY/Render', 22:45:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:45:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:45:40 INFO - 'HOME': '/Users/cltbld', 22:45:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:45:40 INFO - 'LOGNAME': 'cltbld', 22:45:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:45:40 INFO - 'MOZ_NO_REMOTE': '1', 22:45:40 INFO - 'NO_EM_RESTART': '1', 22:45:40 INFO - 'PAGER': '/bin/cat', 22:45:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:45:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:45:40 INFO - 'PWD': '/builds/slave/test', 22:45:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:45:40 INFO - 'SHELL': '/bin/bash', 22:45:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4s2eA6qdfR/Listeners', 22:45:40 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 22:45:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:45:40 INFO - 'USER': 'cltbld', 22:45:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:45:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:45:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:45:40 INFO - 'XPC_FLAGS': '0x0', 22:45:40 INFO - 'XPC_SERVICE_NAME': '0', 22:45:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:45:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:45:41 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)) 22:45:41 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)) 22:45:41 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)) 22:45:41 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)) 22:45:41 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)) 22:45:41 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)) 22:45:41 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)) 22:45:41 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)) 22:45:41 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)) 22:45:41 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)) 22:45:41 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)) 22:45:41 INFO - Cleaning up... 22:45:41 INFO - Return code: 0 22:45:41 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:45: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')]} 22:45:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:45:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:45: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')]} 22:45:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:45:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:45:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ae36a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ae46420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8114b16d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10acd5e00>, '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 0x10ab3edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10acd0b20>, '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.botEVMndKY/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.4s2eA6qdfR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 22:45:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 22:45:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 22:45:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.botEVMndKY/Render', 22:45:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:45:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:45:41 INFO - 'HOME': '/Users/cltbld', 22:45:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:45:41 INFO - 'LOGNAME': 'cltbld', 22:45:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:45:41 INFO - 'MOZ_NO_REMOTE': '1', 22:45:41 INFO - 'NO_EM_RESTART': '1', 22:45:41 INFO - 'PAGER': '/bin/cat', 22:45:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:45:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:45:41 INFO - 'PWD': '/builds/slave/test', 22:45:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:45:41 INFO - 'SHELL': '/bin/bash', 22:45:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4s2eA6qdfR/Listeners', 22:45:41 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 22:45:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:45:41 INFO - 'USER': 'cltbld', 22:45:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:45:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:45:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:45:41 INFO - 'XPC_FLAGS': '0x0', 22:45:41 INFO - 'XPC_SERVICE_NAME': '0', 22:45:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:45:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:45:41 INFO - Downloading/unpacking jsonschema 22:45:41 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 22:45:41 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 22:45:41 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 22:45:41 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 22:45:41 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 22:45:41 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 22:45:43 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:45:43 INFO - Downloading/unpacking functools32 (from jsonschema) 22:45:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:45:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:45:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:45:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:45:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:45:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:45:44 INFO - Downloading functools32-3.2.3-2.tar.gz 22:45:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:45:44 INFO - warning: no files found matching '*.txt' 22:45:44 INFO - no previously-included directories found matching 'build' 22:45:44 INFO - no previously-included directories found matching 'dist' 22:45:44 INFO - no previously-included directories found matching '.git*' 22:45:44 INFO - Installing collected packages: jsonschema, functools32 22:45:44 INFO - Running setup.py install for functools32 22:45:44 INFO - warning: no files found matching '*.txt' 22:45:44 INFO - no previously-included directories found matching 'build' 22:45:44 INFO - no previously-included directories found matching 'dist' 22:45:44 INFO - no previously-included directories found matching '.git*' 22:45:44 INFO - Successfully installed jsonschema functools32 22:45:44 INFO - Cleaning up... 22:45:44 INFO - Return code: 0 22:45:44 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 22:45:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:45:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:45:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:45:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:45:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:45:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:45:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ae36a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ae46420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff8114b16d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10acd5e00>, '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 0x10ab3edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10acd0b20>, '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.botEVMndKY/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.4s2eA6qdfR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 22:45:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 22:45:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 22:45:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.botEVMndKY/Render', 22:45:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:45:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:45:44 INFO - 'HOME': '/Users/cltbld', 22:45:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:45:44 INFO - 'LOGNAME': 'cltbld', 22:45:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:45:44 INFO - 'MOZ_NO_REMOTE': '1', 22:45:44 INFO - 'NO_EM_RESTART': '1', 22:45:44 INFO - 'PAGER': '/bin/cat', 22:45:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:45:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:45:44 INFO - 'PWD': '/builds/slave/test', 22:45:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:45:44 INFO - 'SHELL': '/bin/bash', 22:45:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4s2eA6qdfR/Listeners', 22:45:44 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 22:45:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:45:44 INFO - 'USER': 'cltbld', 22:45:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:45:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:45:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:45:44 INFO - 'XPC_FLAGS': '0x0', 22:45:44 INFO - 'XPC_SERVICE_NAME': '0', 22:45:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:45:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:45:44 INFO - Downloading/unpacking flake8 22:45:44 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 22:45:44 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 22:45:44 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 22:45:44 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 22:45:44 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 22:45:44 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 22:45:47 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 22:45:47 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 22:45:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:45:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:45:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:45:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:45:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:45:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:45:47 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 22:45:47 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 22:45:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:45:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:45:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:45:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:45:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:45:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:45:47 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 22:45:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:45:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:45:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:45:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:45:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:45:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:45:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 22:45:48 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:45:48 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:45:48 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:45:48 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:45:48 INFO - no previously-included directories found matching 'docs/_build' 22:45:48 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 22:45:48 INFO - Running setup.py install for pep8 22:45:48 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:45:48 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:45:48 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:45:48 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:45:48 INFO - no previously-included directories found matching 'docs/_build' 22:45:48 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 22:45:48 INFO - Successfully installed flake8 mccabe pyflakes pep8 22:45:48 INFO - Cleaning up... 22:45:48 INFO - Return code: 0 22:45:48 INFO - Running post-action listener: _resource_record_post_action 22:45:48 INFO - Running post-action listener: _start_resource_monitoring 22:45:48 INFO - Starting resource monitoring. 22:45:48 INFO - ##### 22:45:48 INFO - ##### Running install step. 22:45:48 INFO - ##### 22:45:48 INFO - Running pre-action listener: _resource_record_pre_action 22:45:48 INFO - Running main action method: install 22:45:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:45:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:45:49 INFO - Reading from file tmpfile_stdout 22:45:49 INFO - Detecting whether we're running mozinstall >=1.0... 22:45:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:45:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:45:49 INFO - Reading from file tmpfile_stdout 22:45:49 INFO - Output received: 22:45:49 INFO - Usage: mozinstall [options] installer 22:45:49 INFO - Options: 22:45:49 INFO - -h, --help show this help message and exit 22:45:49 INFO - -d DEST, --destination=DEST 22:45:49 INFO - Directory to install application into. [default: 22:45:49 INFO - "/builds/slave/test"] 22:45:49 INFO - --app=APP Application being installed. [default: firefox] 22:45:49 INFO - mkdir: /builds/slave/test/build/application 22:45:49 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'] 22:45:49 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 22:46:11 INFO - Reading from file tmpfile_stdout 22:46:11 INFO - Output received: 22:46:11 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 22:46:11 INFO - Running post-action listener: _resource_record_post_action 22:46:11 INFO - ##### 22:46:11 INFO - ##### Running run-tests step. 22:46:11 INFO - ##### 22:46:11 INFO - Running pre-action listener: _resource_record_pre_action 22:46:11 INFO - Running main action method: run_tests 22:46:11 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:46:11 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:46:11 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:46:11 INFO - 2016-07-06 22:46:11.017 screenresolution[2369:34060] starting screenresolution argv=screenresolution get 22:46:11 INFO - 2016-07-06 22:46:11.019 screenresolution[2369:34060] Display 0: 1600x1200x32@60 22:46:11 INFO - 2016-07-06 22:46:11.025 screenresolution[2370:34062] starting screenresolution argv=screenresolution list 22:46:11 INFO - Available Modes on Display 0 22:46:11 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:46:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:46:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:46:11 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:46:11 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:46:11 INFO - Intel Iris: 22:46:11 INFO - Chipset Model: Intel Iris 22:46:11 INFO - Type: GPU 22:46:11 INFO - Bus: Built-In 22:46:11 INFO - VRAM (Dynamic, Max): 1536 MB 22:46:11 INFO - Vendor: Intel (0x8086) 22:46:11 INFO - Device ID: 0x0a2e 22:46:11 INFO - Revision ID: 0x0009 22:46:11 INFO - Displays: 22:46:11 INFO - Display: 22:46:11 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:46:11 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:46:11 INFO - Display Serial Number: 1600x1200 60 22:46:11 INFO - Main Display: Yes 22:46:11 INFO - Mirror: Off 22:46:11 INFO - Online: Yes 22:46:11 INFO - Rotation: Supported 22:46:11 INFO - Return code: 0 22:46:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:46:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:46:11 INFO - Python 2.7.3 22:46:11 INFO - Return code: 0 22:46:11 INFO - grabbing minidump binary from tooltool 22:46:11 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')]} 22:46:11 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 0x10acd5e00>, '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 0x10ab3edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10acd0b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:46:11 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 22:46:11 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 22:46:11 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:46:11 INFO - Return code: 0 22:46:11 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 22:46:11 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:46:11 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:46:11 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 22:46:11 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:46:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 22:46:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 22:46:12 INFO - Return code: 0 22:46:12 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/DLbm233hT9i2W3-QNNHisw/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0110', '--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 22:46:12 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/DLbm233hT9i2W3-QNNHisw/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0110 --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 22:46:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.botEVMndKY/Render', 22:46:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:46:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:46:12 INFO - 'HOME': '/Users/cltbld', 22:46:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:46:12 INFO - 'LOGNAME': 'cltbld', 22:46:12 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:46:12 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 22:46:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:46:12 INFO - 'MOZ_NO_REMOTE': '1', 22:46:12 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:46:12 INFO - 'NO_EM_RESTART': '1', 22:46:12 INFO - 'PAGER': '/bin/cat', 22:46:12 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:46:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:46:12 INFO - 'PWD': '/builds/slave/test', 22:46:12 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:46:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:46:12 INFO - 'SHELL': '/bin/bash', 22:46:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4s2eA6qdfR/Listeners', 22:46:12 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 22:46:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:46:12 INFO - 'USER': 'cltbld', 22:46:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:46:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:46:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:46:12 INFO - 'XPC_FLAGS': '0x0', 22:46:12 INFO - 'XPC_SERVICE_NAME': '0', 22:46:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:46:12 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/DLbm233hT9i2W3-QNNHisw/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0110', '--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 22:46:12 INFO - mozversion application_buildid: 20160706204229 22:46:12 INFO - mozversion application_changeset: e4b27dae353c933a01584dfeff27d547ef60f04e 22:46:12 INFO - mozversion application_display_name: Firefox 22:46:12 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:46:12 INFO - mozversion application_name: Firefox 22:46:12 INFO - mozversion application_remotingname: firefox 22:46:12 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 22:46:12 INFO - mozversion application_vendor: Mozilla 22:46:12 INFO - mozversion application_version: 48.0 22:46:12 INFO - mozversion platform_buildid: 20160706204229 22:46:12 INFO - mozversion platform_changeset: e4b27dae353c933a01584dfeff27d547ef60f04e 22:46:12 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 22:46:12 INFO - mozversion platform_version: 48.0 22:46:12 INFO - using testdate: 1467870372 22:46:12 INFO - actual date: 1467870372 22:46:12 INFO - starting webserver on 'localhost:54609' 22:46:12 INFO - SUITE-START | Running 2 tests 22:46:12 INFO - TEST-START | dromaeo_css 22:46:12 INFO - operating with platform_type : mac_ 22:46:12 INFO - Initialising browser for dromaeo_css test... 22:46:12 INFO - TEST-INFO | started process 2388 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/tmpioZQ0o/profile http://localhost:54609/getInfo.html) 22:46:14 INFO - PROCESS | 2388 | __metrics Screen width/height:1600/1200 22:46:14 INFO - PROCESS | 2388 | colorDepth:24 22:46:14 INFO - PROCESS | 2388 | Browser inner width/height: 1024/689 22:46:14 INFO - PROCESS | 2388 | __metrics 22:46:14 INFO - PROCESS | 2388 | 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" 22:46:14 ERROR - PROCESS | 2388 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 22:46:15 INFO - TEST-INFO | 2388: exit 0 22:46:15 INFO - Browser initialized. 22:46:15 INFO - Running cycle 1/1 for dromaeo_css test... 22:46:15 INFO - TEST-INFO | started process 2391 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/tmpioZQ0o/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:46:27 INFO - PROCESS | 2391 | RSS: Main: 161820672 22:46:27 INFO - PROCESS | 2391 | 22:47:18 INFO - PROCESS | 2391 | 1467870438504 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] 22:49:28 INFO - PROCESS | 2391 | Cycle 1(1): loaded http://localhost:54609/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54609/tests/dromaeo/cssquery-ext.html) 22:49:29 INFO - PROCESS | 2391 | RSS: Main: 301789184 22:49:29 INFO - PROCESS | 2391 | 22:52:32 INFO - PROCESS | 2391 | Cycle 1(1): loaded http://localhost:54609/tests/dromaeo/cssquery-ext.html (next: http://localhost:54609/tests/dromaeo/cssquery-jquery.html) 22:52:33 INFO - PROCESS | 2391 | RSS: Main: 305659904 22:52:33 INFO - PROCESS | 2391 | 22:55:35 INFO - PROCESS | 2391 | Cycle 1(1): loaded http://localhost:54609/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54609/tests/dromaeo/cssquery-mootools.html) 22:55:35 INFO - PROCESS | 2391 | RSS: Main: 311623680 22:55:35 INFO - PROCESS | 2391 | 22:58:37 INFO - PROCESS | 2391 | Cycle 1(1): loaded http://localhost:54609/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54609/tests/dromaeo/cssquery-prototype.html) 22:58:38 INFO - PROCESS | 2391 | RSS: Main: 309960704 22:58:38 INFO - PROCESS | 2391 | 23:01:41 INFO - PROCESS | 2391 | Cycle 1(1): loaded http://localhost:54609/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54609/tests/dromaeo/cssquery-yui.html) 23:01:42 INFO - PROCESS | 2391 | RSS: Main: 277860352 23:01:42 INFO - PROCESS | 2391 | 23:04:44 INFO - PROCESS | 2391 | Cycle 1(1): loaded http://localhost:54609/tests/dromaeo/cssquery-yui.html (next: http://localhost:54609/tests/dromaeo/cssquery-dojo.html) 23:04:44 INFO - PROCESS | 2391 | RSS: Main: 323686400 23:04:44 INFO - PROCESS | 2391 | 23:04:44 INFO - PROCESS | 2391 | __start_tp_report 23:04:44 INFO - PROCESS | 2391 | _x_x_mozilla_page_load 23:04:44 INFO - PROCESS | 2391 | _x_x_mozilla_page_load_details 23:04:44 INFO - PROCESS | 2391 | |i|pagename|runs| 23:04:44 INFO - PROCESS | 2391 | |0;dojo.html;3478.3738926993137;3573.1067233191693;3578.6242444543304;3587.2466781975786;3659.323025240331;5903.616264942776;5906.911396329047;5915.497182739462;5921.5558833087525;5948.256467941507;5892.3518412974645;5945.613535120214;5950.940490595092;5954.732037058332;5972.372900845414;5213.556847667934;5221.425643179256;5267.078261304276;5306.893862122755;5350.892982140354;5244.239585260135;5274.182501712241;5288.788448462057;5292.814751483699;5300.072487314724;5220.086484865153;5258.684478931276;5290.1800220965615;5293.444188360225;5317.308749862532;5288.365396152467;5314.548263397616;5316.069687804657;5325.387580428262;5333.706646134461;5097.745112744385;5229.1633338666015;5244.370675518962;5245.554127899133;5253.553447956928;5669.121286200645;5679.488846003838;5713.200151978726;5736.4550367715;5868.2371291732225;6171.950768369388;6181.938180618181;6207.906881396782;6214.192155019864;6215.098810672473;6137.407629318945;6203.782867599657;6226.128342032119;6270.435660790505;6271.62370257786;5787.131930210502;5934.961381758175;5955.493783028491;5972.372900845392;6014.96992515039;5405.972970135203;6325.462335701425;6355.142055822497;6368.64972426521;6392.744290228339;6122.418370254819;6151.200343955257;6151.84620384494;6170.259568851767;6181.598196117238;6218.19163508741;6220.315765265817;6227.688615569204;6233.158523599347;6244.65654389013;6228.22147231596;6238.875222495615;6241.21984751906;6249.218847644044;6253.249610046823;6140.412224875431;6162.815115546541;6236.844078898064;6238.812835614939;6275.435210831047;4868.075065737498;4933.925991110136;4934.136526107916;4941.530554597308;4976;4462.620677242406;4465.642748580106;4508.278675411918;4510.7068040577915;4543.591076803037;6003.8198854034445;6007.8197654070445;6018.879622407528;6023.548233882477;6031.788887388921;6146.815595532141;6176.320604733476;6235.033569796688;6244.812655620339;6245.500359971192;6000.519958403404;6028.065649824284;6038.758449661965;6078.422549857756;6081.963454387738;5431.293931788921;5439.864003399946;5499.972500137554;5504.339479262514;5514.062609356419;6368.363163683594;6391.456726178234;6439.356064393523;6455.838604034937;6490.350964903472;6190.659513726789;6223.315435302023;6244.781432649836;6250.562460627712;6256.374362563707;6346.873062538631;6501.479881609365;6507.609543427409;6512.837179070561;6516.348365163446;6136.140940268277;6264.310925798068;6279.120923070683;6279.529035322278;6284.371562843679;6292.622442653455;6306.621602703853;6311.495080393649;6311.968440157953;6320.5891617044745;6099.237595300588;6149.0776383542825;6168.938310617016;6212.099245609452;6218.471429928326;6151.96924015395;6181.690915454299;6199.783007594712;6201.689915504297;6218.22272215973;6082.878342433215;6113.327533971171;6121.969390153021;6127.816165515042;6133.570650054453;5848.590598658053;6064.151018857452;6110.16388879229;6153.5692501526255;6177.907331389943;6135.385782596045;6166.013437849922;6169.290531588925;6169.84575385619;6183.165272688129 23:04:44 INFO - PROCESS | 2391 | |1;ext.html;553.9418361072087;566.5439321346316;571.6655756382517;611.9204503414555;612.9019356902896;32622.184445388877;39785.20429591407;40756.38865417017;40814.591854081526;40979.38530922031;43938;44306;44358.33462498057;44478.777606112;44482;9145.817083658323;9772.315937884334;9978.750531236741;9989.250806189528;9993.300468967174;12065.517379304818;12375.62873113808;12378.504859805596;12384.318862462562;12406.62780116598;12008.519659213665;12096.758064838697;12105.697357566043;12105.878941210607;12121.030317574618;12050;12141.696457588543;12158.696032599168;12160;12174.208676436003;11862.991645710124;11988.400579970967;12007.399630018552;12021.459034343476;12024.639260822101;35395.64604353949;42562;43082.784586077185;43111;43127;3269.9346013079844;3336.115929278743;3342.8829990950444;3355.0438125134465;3417.2311229973307;29224.70775292241;29287.2678183045;30252.697473025208;30983.38033239345;31178.90873819428;2759.8344099354103;2776.7917406194615;2799.9160025199276;2823.378856651533;2863.7995340326183;456165;491761;493063;494742;495783;38937.805310973265;39172.804135979706;39588.60411395907;69618.65190674116;70259.2974070263;33192.50211246833;34575.61697532121;38600.227995439935;38931;38976.41535376971;15978.600534986486;16903;16919.746203806953;16925.91537042307;16937.491875243762;12761.808572871414;12901.354932253538;13815.930920345534;13817.585472435843;14194.574162775134;8477.576121193915;8483;8495.532745698922;8530.87203691945;8595.52724600153;3465.462853257749;3503.2468019375956;3514.1741690702665;3520.2255503789124;3541.203229273393;797.6211299632628;1038.772558511712;1055.5039131608132;1099.2689861242345;1240.49759847262;26684.59973100405;31926.88255911032;32254.516182257285;32666.346673066404;32834.50748238778;1060.7295139739317;1128.2891778179694;1139.9373034483017;1153.3944679043402;1204.4700331853956;14078.366473508717;14325.424203337625;15118.395264189308;15235.695286094216;15460.690786184214;632827;633352;633803;635587;639088;52873.7356313216;53268.200976985376;63398.6830065856;63945.68027159834;64093.35906640967;1424.0672359604475;1573.7403328450678;1588.3646541383307;1595.234287541963;1599.9840001600069;29472;29731.256718581342;29824.403511929206;30177.54733678953;30536.389272214874;52234.21648675349;52397.47602523926;52972.47027529676;53027.73486132544;53077;416182;418720;419442;420469;420488;24353.39116522101;25220.99116035338;28603.42793144167;28999;29099.563506547842;43287.56712432835;44259;44484.33273500836;44814;45145.54854451413;34948.82525587457;35533.82233088818;36690.63309366872;37335.626643733216;37378;38863.805680972546;39587.010324742114;40053.99865003398;41001.38497922474;43222.351664724425;32307.192320191243;38161.618383816916;38382.6161738379;38851.417228741026;38975;33732.49401258934;33887.4597590766;34127.658723412445;34370.14074648054;34988.47517287294;22634.981425835547;25490.872545637154;25676.972921082946;25877.353066173495;26080.8695956519 23:04:44 INFO - PROCESS | 2391 | |2;jquery.html;413.57195302861544;414.10139996208227;415.76093746096;416.96872734544917;426.8847411198976;18961.715574266396;20350;21026.89486552569;21050.368488945354;21064.684029739547;20465.079071441785;20576;21886.23398181064;21921.79430131342;21934.122635094576;5745.310562732478;5869.178315035877;5986.73059712314;5995.790147344844;6024.457798798129;5940.495057920082;6026.608270462428;6062.575619706622;6065.4844338231305;6088.756449742005;5889.293284805808;5953.166556682068;6032.27612686476;6040.365761595035;6067.726952287136;6015.037593984964;6063.029915213545;6071;6079.787207447763;6087.2999605045325;5490.972545137249;5796.275465566804;5803.419658034205;5805.854853628651;5817.534597232211;13791.793123103062;14160.36278367476;16093.91953040239;26421.73578264212;26454.07410740634;2383.5828729972186;2437.5003124359464;2439.634054891763;2535.657686212356;2679.981606989351;8534.208432776464;10195.898041019569;14736.484223052248;14872.553823385315;15881.444149454714;1743.2155530011419;1806.223323970692;1843.2442698493553;1851.25949620153;1870.7193920911836;83413.74879376814;100134.49932750362;114702.42648786787;117225.8277417232;127223;9656.806863862825;11660.242084264495;11772.529098836121;12021.579244726392;12153.513859445524;13989.3704783285;14477.565673029825;14975.1014939104;15486.767698484531;15510.534683959499;9125.269978401711;9167;9263.95368023169;9270.953645231866;9325.953370233105;8320.167983201753;8634.438761480484;9307.674231401868;9351.625934962525;9414.058594140668;4791.784369703371;4830.7101573905675;4847.248676455155;4850.175470170079;4875.196177414308;1671.2312336325426;2035.5827055453597;2038.225474319749;2041.9886411884281;2049.6208201482477;862.5169904853302;984.0749695286551;984.6898227058454;984.8818141823027;1038.8078205532001;19594.70607940882;20989.16043358279;22001.899905004684;22175.334739957827;22859.428514286945;961.956711947964;965.6571916969515;982.1259079419323;1013.9594416223416;1027.1372047480152;9424.293178011676;10828.837567436494;11089.223754337281;11264.38045907483;11299.039581635652;659040;666326;668005;676624.6168769124;677345;129300.35349823191;129488;130363;131848;136594.31702841423;1073.736934451049;1078.0620859851979;1101.5758932810766;1101.8292164714321;1103.382106020631;18373.908130459262;18409.447716568524;18505.72241416353;18611;19125.426237212905;39776.60223397729;46280.53719462762;48682.75658621684;51384;55110.724446379114;70723;74491;75329.6233518829;77865.61067194628;78081.60959195168;8189.385796065438;8386.371022173484;8529.488230705929;8588.65645374178;8663.870041949502;8669.783255418413;11689.649310520697;12485;13274;13351.599452016455;11247.7750444989;11383.203175777948;11552.94223528877;11736.354500502213;11776.293422394683;10256.794864102825;11148.05241554477;11193.440327983732;11296.887031129583;11447.942760286145;11287.20989530725;11374.146938979444;11486.885131148916;11783.941080294886;11953.342566158944;8307.044689860744;8330.583470826314;8414.6213420395;8540.060593334852;8823.470591764515;8140.552269624991;11322.547098115983;11373.488193031168;11473.139514536277;11622.418879056182 23:04:44 INFO - PROCESS | 2391 | |3;mootools.html;1440.8703216710496;1490.6646004648949;1634.6975809475248;1887.2828325236408;2042.7446569178853;8441.282490988266;9019;9176.495292758895;9185.862212066808;9211.953940230298;8974.685885993991;9041.63833446663;9159.633614655422;9159.771005724844;9159.862602060974;2550.3624093976505;2576.343032526703;2581.6127580862926;2584.2634849068018;2586.9741302587017;2512.5979843225086;2574.382148284408;2582.5609646360163;2584.5218634552666;2586.2112055822968;2547.5796493578537;2572.0354866924904;2586.4697736963876;2590.611408288753;2591.6371707960807;2546.885390157438;2565.5638541447906;2577.1237779155085;2589.572720501115;2589.6115582662565;2528.987355063213;2562.6924769027837;2571.3700143464785;2589.4562141950214;2592.727763584834;9005.684801031997;9340.486273254968;9382.061793820632;9402.106799854002;9418.717438476857;5672.06410942194;5771.595988280822;5826.854328641775;5831.212786273841;5835.299764028301;3664.59689434162;3743.3262012837677;3800.771953682788;3811.1615444602144;3820.0449887528116;1052.3422860712055;1063.5533076108054;1065.137238836537;1098.9945050274778;1103.4979084516526;12526.373681315898;12581.559645412459;12619.810702839464;12739.299338536377;12745.426455809511;4467.687261891701;4486.775661216926;4904.386951631046;4928.556429921325;4977.950220497821;9875.703728888146;9888.50557472138;9903.45530995802;9909.65316213929;9910.851337229948;5588.972055139699;5633.042382794934;5634.408387119293;5671.319441667027;5691.089425691869;4706.835260765925;4747.786349614206;5030.37120359955;5223.582113430916;5258.50044245796;4033.757974521538;4060.7766572838777;4130.91738165235;4156.542780294165;4164.479440069991;1267.8161666558296;1278.7122897348022;1279.36031984008;1290.6450726050223;1291.4834066373526;834.4325858416214;885.5173930208052;919.8988111307751;948.1277224952922;966.7438128895708;6117.571769976148;6168.814935551941;6228.719707613168;6284.780032698834;6293.811185664437;964.1419136968104;973.2797729680127;990.8513722941473;1020.9132223761063;1024.9487525623838;4042.130941847517;4055.6755459563165;4118.876433706993;4124.979375103166;4189.392538081961;11310.377929213808;11669.416529173508;11683.649490515298;11692.240004399688;11863.822042669366;11519.25124866881;11543.422828858524;11660.00889924349;11661.591844285409;11667.824982625267;8808.383413161017;8932.151445612657;8953.373263871597;8954.059823718546;8963;6068.029115341524;6097.634141951497;6168.105624684305;6190.752369905334;6268.467180289544;9548.236141108555;9984.051515106054;10144.644937427154;10164.542595583365;10182.338148020355;9951;10204.897951020395;10290.691279261635;10325.070743632808;10385.376877387382;7206.387457065998;7225.963870180615;7381.077365329334;7390.408767298711;7668.156502784577;8572.27135693473;8608.91391086081;8673.392862499564;8679.479231246145;8679.60941757635;7479.70081196746;7575.469717119749;7648.579328137006;7668.501547399401;7716.961415192888;7371.8157046074275;7466.066741657293;7469.178390377163;7593.126790419173;7607.733729319447;7173.0675012248075;7277.38142257914;7297.1973082961895;7298.270172982871;7334.266573342623;4987.650864439454;5003.749812509433;5050.520200580939;5179.585633149414;5181.222816577393;5067.822626208213;5157.742112894415;5179.22311653255;5191.29917461153;5330.920036199383 23:04:44 INFO - PROCESS | 2391 | |4;prototype.html;332.6328788678531;337.5849466469008;343.309947006517;345.1922501346848;345.4162465433419;6099.268087829456;6179.486025923655;6755.763548275805;6770.458363330934;6806.863862722743;6436.195475565554;6627.9337206627915;6692;6702.899456508157;6777;5054.721990290533;5082.3138876251605;5096.184610462326;5101.642884998052;5120.718360490171;5084.567811736007;5091.134507133777;5099.643024988252;5106.974465127669;5107.157319042353;4818.614510839142;4921.335619691332;5023.522765337287;5032.622553308498;5054.418741844698;5030.673006254582;5032.6477146599755;5045.545900868903;5076.187809950427;5084.44071152173;4373.825046998117;4376.540463251345;4382.978085109554;4383.320585309282;4384.978075109636;5578;5849.61977471463;5969.731362088716;6273.996160614325;6560.179977502812;6009.2188015557695;6017.247844019497;6032.668203248863;6032.728527216286;6034.275886893601;7026.824329391806;7260.27397260275;7285.817854553626;7292.8906066409045;7311.341979221842;4821.276808478721;4868.391451068616;4871.244957031666;4871.853844384674;4889.144399730033;6593.340665933416;7729.845403091963;7731.922680773233;7740.419468539826;7741.61291935401;4945.55490005894;4945.901081978413;4962;4965.851024469272;4968.30443737877;5031.320771695774;5048.671836330626;5054.519820617035;5057.393112826484;5063.49365063498;5739.713014349266;5998.220231369981;6001.969990150109;6032.095185722089;6035.275766907999;7263.745768898063;7329.450291228072;7335.083114610674;7337.70649174027;7340.96329518349;1661.3271624991655;1737.3658614605579;1771.6623948918484;1772.6720556697053;1773.5388798912375;1056.2500624556496;1067.7864427114453;1070.7537266428765;1075.5643964194514;1113.582406597526;6874.862502749917;6904.344087311697;6945;6960.269171736894;6964;7485.85028299431;7489.625518724042;7529.397648188132;7533.849323013618;7537.359324457482;6926.549774264657;6941.160592443007;6947.548409353376;6954.130733658293;6954.200266969262;3540.725338878002;3624.7825130492256;3851.730378873452;3998.2403343364667;4003.899902502461;6310.2743184534365;7897.565633890191;7949.562774047483;7952.721654742172;7972.043354797462;6158.797425338798;7725.961370193113;7740.187280335484;7746.457747957589;7757.224277572198;7929.563873986871;7930.444868859125;7932.841343173104;7948;7952.443328966917;7098.929010709827;7446.813829654302;7447.739329123455;7471.962640186982;7502.774916752506;7320.304571065741;7355.043844300207;7356.080489938758;7358.852822943618;7376.926230737624;7894.289513943772;7897.289243968071;7897.842043159219;7905.56519391439;7914.564698941614;4972.303877457087;4973.52751488608;4975.850724478271;4984.775685094107;4986.551210390937;7196.488737365003;7425.183229844821;7754;7778;7792.10390805042;7295.9056141579185;7346.742863999734;7353.8161545961775;7359.080114985627;7362.815929601803;5927.585069045125;7091.574505529685;7311.561306321638;7341.302576255247;7348.154962179417;6049.667268300285;6910;7217.458690598121;7224.711011559479;7233.059702238886;5703.486686198262;7249.818754531179;7254.709811607688;7263.237360077116;7300.525465844703;5926.259217597801;6956.37392634645;6994.685239164148;7048.118985126859;7132.85734285301 23:04:44 INFO - PROCESS | 2391 | |5;yui.html;767.7005967672605;770.710983381232;779.4543819326469;781.1368437876145;792.7344339646219;15434.69130617387;15627.531174064768;15737.52787416378;15751.054936703806;15763.21183940804;14491.855081449183;14574;14603.634909127277;14610.561683149495;14660.193689347081;659.8119535932249;811.7077851973285;948.6205517792904;973.547300505265;977.2816979520082;959.2853324273426;967.5404183013083;969.4958621516807;972.265939215891;974.1476208317722;1035.9637412690558;1036.4144258493964;1037.2635428845529;1038.7455073507;1039.4438975148298;842.1999100854182;856.3662889461816;857.5583574459158;857.9957100214459;1034.5861655337887;753.4763339479064;763.5151678684076;764.9005629268215;767.6353731977322;768.7539987204096;14738.189399583018;14801.629959250997;14809.185494797783;14836.554903352917;14841.332140053724;12826.486940522367;13028.348582570929;13118.278494682789;13171.802422963567;13224.206547607078;10183.388996660224;10230.795384092276;10379.688609341732;10415.583376664925;10813.18901082422;3591.2458383739445;3604.116991337113;3621.728370372233;3676.099355657854;3860.6718428933573;81517.59241203815;81809.18190818075;83996.99999999939;84151;84455;9666.275029372684;9855.211583073336;10035.297529172887;10157;10170.491475426199;23063.192788252665;23098.769012309996;23099.88450057739;23125;23156.65265021026;7524.172341042481;7662.693492260359;7764.883526747103;7767.456278060482;7767.766966990999;4225.830966761357;4272.4873015237745;4309.719868208556;4364.1708075466195;4394.450693663292;3728.0493474164455;3791.1848952474797;3792.1846802937503;3795.411711184771;3806.600306967784;655.9311272316434;664.2361284522838;677.2144312597363;687.6871023684212;697.3688811625487;531.553495064148;583.2592607388593;590.1796502861026;621.7077973352517;629.2574761781142;7688.192739762245;8165.918340816635;8264.958675206586;8380.371472139492;8429.93458679752;497.9714112827173;554.4178612456905;578.9276340457443;581.9272590926134;591.7721677154327;3738.887833365003;3918.9804050979565;3948.9012774680864;3950.881473555798;4000.7799571023875;69669.909902703;87006;87385.68921466183;87585;89250;58785.706071470224;76779;78224;78764.6061769699;79715.60142199251;68439.65780171067;69092.654536727;69109.65445172742;69172;70315;7775.6112194388925;7792.6103694816165;7803.219678032151;7819.990054699155;7845.686172553034;38142.66500672463;46022.76988615035;47460.67775255699;48533.75733121453;48686.02627947492;63379.683101584196;67025;67690.66154669195;68122.31877681358;68941.65529172322;9774.560144793359;10030.749231268985;10035;10214.233932455245;10581.047705706524;10647.89352106469;10672.413017283814;10724.195685323475;10908.181886358714;11248.325100493996;10403.94798026005;10628.36229826213;10634.30876992993;10640.733981650521;10739.892601074202;9849.60601575929;10253.28227024131;10515.316504427188;10639.840402393815;10815.513301901276;8780.780480487783;9839.458829764146;10326;10551.314164579278;10836.67489975302;8956.373053886166;8978.685745998859;9121.635134594542;9139.817203656023;9193.816123677356;8789.780255493664;8911.2425443838;9015;9045.502497362708;9062.184403403726 23:04:44 INFO - PROCESS | 2391 | __end_tp_report 23:04:44 INFO - PROCESS | 2391 | __start_cc_report 23:04:44 INFO - PROCESS | 2391 | _x_x_mozilla_cycle_collect,1299 23:04:44 INFO - PROCESS | 2391 | __end_cc_report 23:04:44 INFO - PROCESS | 2391 | __startTimestamp1467871484925__endTimestamp 23:04:44 INFO - PROCESS | 2391 | 23:04:44 INFO - PROCESS | 2391 | ------- Summary: start ------- 23:04:44 INFO - PROCESS | 2391 | Number of tests: 6 23:04:44 INFO - PROCESS | 2391 | 23:04:44 INFO - PROCESS | 2391 | [#0] dojo.html Cycles:165 Average:5832.61 Median:6121.97 stddev:621.16 (10.1%) stddev-sans-first:594.97 23:04:44 INFO - PROCESS | 2391 | Values: 3478.4 3573.1 3578.6 3587.2 3659.3 5903.6 5906.9 5915.5 5921.6 5948.3 5892.4 5945.6 5950.9 5954.7 5972.4 5213.6 5221.4 5267.1 5306.9 5350.9 5244.2 5274.2 5288.8 5292.8 5300.1 5220.1 5258.7 5290.2 5293.4 5317.3 5288.4 5314.5 5316.1 5325.4 5333.7 5097.7 5229.2 5244.4 5245.6 5253.6 5669.1 5679.5 5713.2 5736.5 5868.2 6172.0 6181.9 6207.9 6214.2 6215.1 6137.4 6203.8 6226.1 6270.4 6271.6 5787.1 5935.0 5955.5 5972.4 6015.0 5406.0 6325.5 6355.1 6368.6 6392.7 6122.4 6151.2 6151.8 6170.3 6181.6 6218.2 6220.3 6227.7 6233.2 6244.7 6228.2 6238.9 6241.2 6249.2 6253.2 6140.4 6162.8 6236.8 6238.8 6275.4 4868.1 4933.9 4934.1 4941.5 4976.0 4462.6 4465.6 4508.3 4510.7 4543.6 6003.8 6007.8 6018.9 6023.5 6031.8 6146.8 6176.3 6235.0 6244.8 6245.5 6000.5 6028.1 6038.8 6078.4 6082.0 5431.3 5439.9 5500.0 5504.3 5514.1 6368.4 6391.5 6439.4 6455.8 6490.4 6190.7 6223.3 6244.8 6250.6 6256.4 6346.9 6501.5 6507.6 6512.8 6516.3 6136.1 6264.3 6279.1 6279.5 6284.4 6292.6 6306.6 6311.5 6312.0 6320.6 6099.2 6149.1 6168.9 6212.1 6218.5 6152.0 6181.7 6199.8 6201.7 6218.2 6082.9 6113.3 6122.0 6127.8 6133.6 5848.6 6064.2 6110.2 6153.6 6177.9 6135.4 6166.0 6169.3 6169.8 6183.2 23:04:44 INFO - PROCESS | 2391 | 23:04:44 INFO - PROCESS | 2391 | [#1] ext.html Cycles:180 Average:64673.65 Median:34472.88 stddev:139219.94 (403.9%) stddev-sans-first:139527.25 23:04:44 INFO - PROCESS | 2391 | Values: 553.9 566.5 571.7 611.9 612.9 32622.2 39785.2 40756.4 40814.6 40979.4 43938.0 44306.0 44358.3 44478.8 44482.0 9145.8 9772.3 9978.8 9989.3 9993.3 12065.5 12375.6 12378.5 12384.3 12406.6 12008.5 12096.8 12105.7 12105.9 12121.0 12050.0 12141.7 12158.7 12160.0 12174.2 11863.0 11988.4 12007.4 12021.5 12024.6 35395.6 42562.0 43082.8 43111.0 43127.0 3269.9 3336.1 3342.9 3355.0 3417.2 29224.7 29287.3 30252.7 30983.4 31178.9 2759.8 2776.8 2799.9 2823.4 2863.8 456165.0 491761.0 493063.0 494742.0 495783.0 38937.8 39172.8 39588.6 69618.7 70259.3 33192.5 34575.6 38600.2 38931.0 38976.4 15978.6 16903.0 16919.7 16925.9 16937.5 12761.8 12901.4 13815.9 13817.6 14194.6 8477.6 8483.0 8495.5 8530.9 8595.5 3465.5 3503.2 3514.2 3520.2 3541.2 797.6 1038.8 1055.5 1099.3 1240.5 26684.6 31926.9 32254.5 32666.3 32834.5 1060.7 1128.3 1139.9 1153.4 1204.5 14078.4 14325.4 15118.4 15235.7 15460.7 632827.0 633352.0 633803.0 635587.0 639088.0 52873.7 53268.2 63398.7 63945.7 64093.4 1424.1 1573.7 1588.4 1595.2 1600.0 29472.0 29731.3 29824.4 30177.5 30536.4 52234.2 52397.5 52972.5 53027.7 53077.0 416182.0 418720.0 419442.0 420469.0 420488.0 24353.4 25221.0 28603.4 28999.0 29099.6 43287.6 44259.0 44484.3 44814.0 45145.5 34948.8 35533.8 36690.6 37335.6 37378.0 38863.8 39587.0 40054.0 41001.4 43222.4 32307.2 38161.6 38382.6 38851.4 38975.0 33732.5 33887.5 34127.7 34370.1 34988.5 22635.0 25490.9 25677.0 25877.4 26080.9 23:04:44 INFO - PROCESS | 2391 | 23:04:44 INFO - PROCESS | 2391 | [#2] jquery.html Cycles:180 Average:36689.27 Median:2410.54 stddev:110994.25 (4604.5%) stddev-sans-first:111272.20 23:04:44 INFO - PROCESS | 2391 | Values: 413.6 414.1 415.8 417.0 426.9 18961.7 20350.0 21026.9 21050.4 21064.7 20465.1 20576.0 21886.2 21921.8 21934.1 5745.3 5869.2 5986.7 5995.8 6024.5 5940.5 6026.6 6062.6 6065.5 6088.8 5889.3 5953.2 6032.3 6040.4 6067.7 6015.0 6063.0 6071.0 6079.8 6087.3 5491.0 5796.3 5803.4 5805.9 5817.5 13791.8 14160.4 16093.9 26421.7 26454.1 2383.6 2437.5 2439.6 2535.7 2680.0 8534.2 10195.9 14736.5 14872.6 15881.4 1743.2 1806.2 1843.2 1851.3 1870.7 83413.7 100134.5 114702.4 117225.8 127223.0 9656.8 11660.2 11772.5 12021.6 12153.5 13989.4 14477.6 14975.1 15486.8 15510.5 9125.3 9167.0 9264.0 9271.0 9326.0 8320.2 8634.4 9307.7 9351.6 9414.1 4791.8 4830.7 4847.2 4850.2 4875.2 1671.2 2035.6 2038.2 2042.0 2049.6 862.5 984.1 984.7 984.9 1038.8 19594.7 20989.2 22001.9 22175.3 22859.4 962.0 965.7 982.1 1014.0 1027.1 9424.3 10828.8 11089.2 11264.4 11299.0 659040.0 666326.0 668005.0 676624.6 677345.0 129300.4 129488.0 130363.0 131848.0 136594.3 1073.7 1078.1 1101.6 1101.8 1103.4 18373.9 18409.4 18505.7 18611.0 19125.4 39776.6 46280.5 48682.8 51384.0 55110.7 70723.0 74491.0 75329.6 77865.6 78081.6 8189.4 8386.4 8529.5 8588.7 8663.9 8669.8 11689.6 12485.0 13274.0 13351.6 11247.8 11383.2 11552.9 11736.4 11776.3 10256.8 11148.1 11193.4 11296.9 11447.9 11287.2 11374.1 11486.9 11783.9 11953.3 8307.0 8330.6 8414.6 8540.1 8823.5 8140.6 11322.5 11373.5 11473.1 11622.4 23:04:44 INFO - PROCESS | 2391 | 23:04:44 INFO - PROCESS | 2391 | [#3] mootools.html Cycles:180 Average:5983.71 Median:5112.78 stddev:3349.82 (65.5%) stddev-sans-first:3341.82 23:04:44 INFO - PROCESS | 2391 | Values: 1440.9 1490.7 1634.7 1887.3 2042.7 8441.3 9019.0 9176.5 9185.9 9212.0 8974.7 9041.6 9159.6 9159.8 9159.9 2550.4 2576.3 2581.6 2584.3 2587.0 2512.6 2574.4 2582.6 2584.5 2586.2 2547.6 2572.0 2586.5 2590.6 2591.6 2546.9 2565.6 2577.1 2589.6 2589.6 2529.0 2562.7 2571.4 2589.5 2592.7 9005.7 9340.5 9382.1 9402.1 9418.7 5672.1 5771.6 5826.9 5831.2 5835.3 3664.6 3743.3 3800.8 3811.2 3820.0 1052.3 1063.6 1065.1 1099.0 1103.5 12526.4 12581.6 12619.8 12739.3 12745.4 4467.7 4486.8 4904.4 4928.6 4978.0 9875.7 9888.5 9903.5 9909.7 9910.9 5589.0 5633.0 5634.4 5671.3 5691.1 4706.8 4747.8 5030.4 5223.6 5258.5 4033.8 4060.8 4130.9 4156.5 4164.5 1267.8 1278.7 1279.4 1290.6 1291.5 834.4 885.5 919.9 948.1 966.7 6117.6 6168.8 6228.7 6284.8 6293.8 964.1 973.3 990.9 1020.9 1024.9 4042.1 4055.7 4118.9 4125.0 4189.4 11310.4 11669.4 11683.6 11692.2 11863.8 11519.3 11543.4 11660.0 11661.6 11667.8 8808.4 8932.2 8953.4 8954.1 8963.0 6068.0 6097.6 6168.1 6190.8 6268.5 9548.2 9984.1 10144.6 10164.5 10182.3 9951.0 10204.9 10290.7 10325.1 10385.4 7206.4 7226.0 7381.1 7390.4 7668.2 8572.3 8608.9 8673.4 8679.5 8679.6 7479.7 7575.5 7648.6 7668.5 7717.0 7371.8 7466.1 7469.2 7593.1 7607.7 7173.1 7277.4 7297.2 7298.3 7334.3 4987.7 5003.7 5050.5 5179.6 5181.2 5067.8 5157.7 5179.2 5191.3 5330.9 23:04:44 INFO - PROCESS | 2391 | 23:04:44 INFO - PROCESS | 2391 | [#4] prototype.html Cycles:180 Average:5953.50 Median:6659.97 stddev:1876.36 (28.2%) stddev-sans-first:1833.58 23:04:44 INFO - PROCESS | 2391 | Values: 332.6 337.6 343.3 345.2 345.4 6099.3 6179.5 6755.8 6770.5 6806.9 6436.2 6627.9 6692.0 6702.9 6777.0 5054.7 5082.3 5096.2 5101.6 5120.7 5084.6 5091.1 5099.6 5107.0 5107.2 4818.6 4921.3 5023.5 5032.6 5054.4 5030.7 5032.6 5045.5 5076.2 5084.4 4373.8 4376.5 4383.0 4383.3 4385.0 5578.0 5849.6 5969.7 6274.0 6560.2 6009.2 6017.2 6032.7 6032.7 6034.3 7026.8 7260.3 7285.8 7292.9 7311.3 4821.3 4868.4 4871.2 4871.9 4889.1 6593.3 7729.8 7731.9 7740.4 7741.6 4945.6 4945.9 4962.0 4965.9 4968.3 5031.3 5048.7 5054.5 5057.4 5063.5 5739.7 5998.2 6002.0 6032.1 6035.3 7263.7 7329.5 7335.1 7337.7 7341.0 1661.3 1737.4 1771.7 1772.7 1773.5 1056.3 1067.8 1070.8 1075.6 1113.6 6874.9 6904.3 6945.0 6960.3 6964.0 7485.9 7489.6 7529.4 7533.8 7537.4 6926.5 6941.2 6947.5 6954.1 6954.2 3540.7 3624.8 3851.7 3998.2 4003.9 6310.3 7897.6 7949.6 7952.7 7972.0 6158.8 7726.0 7740.2 7746.5 7757.2 7929.6 7930.4 7932.8 7948.0 7952.4 7098.9 7446.8 7447.7 7472.0 7502.8 7320.3 7355.0 7356.1 7358.9 7376.9 7894.3 7897.3 7897.8 7905.6 7914.6 4972.3 4973.5 4975.9 4984.8 4986.6 7196.5 7425.2 7754.0 7778.0 7792.1 7295.9 7346.7 7353.8 7359.1 7362.8 5927.6 7091.6 7311.6 7341.3 7348.2 6049.7 6910.0 7217.5 7224.7 7233.1 5703.5 7249.8 7254.7 7263.2 7300.5 5926.3 6956.4 6994.7 7048.1 7132.9 23:04:44 INFO - PROCESS | 2391 | 23:04:44 INFO - PROCESS | 2391 | [#5] yui.html Cycles:180 Average:17835.47 Median:29684.48 stddev:24882.79 (83.8%) stddev-sans-first:24919.59 23:04:44 INFO - PROCESS | 2391 | Values: 767.7 770.7 779.5 781.1 792.7 15434.7 15627.5 15737.5 15751.1 15763.2 14491.9 14574.0 14603.6 14610.6 14660.2 659.8 811.7 948.6 973.5 977.3 959.3 967.5 969.5 972.3 974.1 1036.0 1036.4 1037.3 1038.7 1039.4 842.2 856.4 857.6 858.0 1034.6 753.5 763.5 764.9 767.6 768.8 14738.2 14801.6 14809.2 14836.6 14841.3 12826.5 13028.3 13118.3 13171.8 13224.2 10183.4 10230.8 10379.7 10415.6 10813.2 3591.2 3604.1 3621.7 3676.1 3860.7 81517.6 81809.2 83997.0 84151.0 84455.0 9666.3 9855.2 10035.3 10157.0 10170.5 23063.2 23098.8 23099.9 23125.0 23156.7 7524.2 7662.7 7764.9 7767.5 7767.8 4225.8 4272.5 4309.7 4364.2 4394.5 3728.0 3791.2 3792.2 3795.4 3806.6 655.9 664.2 677.2 687.7 697.4 531.6 583.3 590.2 621.7 629.3 7688.2 8165.9 8265.0 8380.4 8429.9 498.0 554.4 578.9 581.9 591.8 3738.9 3919.0 3948.9 3950.9 4000.8 69669.9 87006.0 87385.7 87585.0 89250.0 58785.7 76779.0 78224.0 78764.6 79715.6 68439.7 69092.7 69109.7 69172.0 70315.0 7775.6 7792.6 7803.2 7820.0 7845.7 38142.7 46022.8 47460.7 48533.8 48686.0 63379.7 67025.0 67690.7 68122.3 68941.7 9774.6 10030.7 10035.0 10214.2 10581.0 10647.9 10672.4 10724.2 10908.2 11248.3 10403.9 10628.4 10634.3 10640.7 10739.9 9849.6 10253.3 10515.3 10639.8 10815.5 8780.8 9839.5 10326.0 10551.3 10836.7 8956.4 8978.7 9121.6 9139.8 9193.8 8789.8 8911.2 9015.0 9045.5 9062.2 23:04:44 INFO - PROCESS | 2391 | -------- Summary: end -------- 23:04:44 INFO - PROCESS | 2391 | 23:04:45 INFO - TEST-INFO | 2391: exit 0 23:04:45 INFO - TEST-OK | dromaeo_css | took 1112840ms 23:04:45 INFO - TEST-START | kraken 23:04:45 INFO - operating with platform_type : mac_ 23:04:45 INFO - Initialising browser for kraken test... 23:04:45 INFO - TEST-INFO | started process 2395 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/tmpW75L2G/profile http://localhost:54609/getInfo.html) 23:04:47 INFO - PROCESS | 2395 | __metrics Screen width/height:1600/1200 23:04:47 INFO - PROCESS | 2395 | colorDepth:24 23:04:47 INFO - PROCESS | 2395 | Browser inner width/height: 1024/689 23:04:47 INFO - PROCESS | 2395 | __metrics 23:04:47 INFO - PROCESS | 2395 | 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" 23:04:47 ERROR - PROCESS | 2395 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 23:04:48 INFO - TEST-INFO | 2395: exit 0 23:04:48 INFO - Browser initialized. 23:04:48 INFO - Running cycle 1/1 for kraken test... 23:04:48 INFO - TEST-INFO | started process 2398 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/tmpW75L2G/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:05:00 INFO - PROCESS | 2398 | RSS: Main: 161247232 23:05:00 INFO - PROCESS | 2398 | 23:05:02 INFO - PROCESS | 2398 | Cycle 1(1): loaded http://localhost:54609/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54609/tests/kraken/driver.html?testName=audio-beat-detection) 23:05:03 INFO - PROCESS | 2398 | RSS: Main: 223854592 23:05:03 INFO - PROCESS | 2398 | 23:05:06 INFO - PROCESS | 2398 | Cycle 1(1): loaded http://localhost:54609/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54609/tests/kraken/driver.html?testName=audio-dft) 23:05:07 INFO - PROCESS | 2398 | RSS: Main: 240074752 23:05:07 INFO - PROCESS | 2398 | 23:05:10 INFO - PROCESS | 2398 | Cycle 1(1): loaded http://localhost:54609/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54609/tests/kraken/driver.html?testName=audio-fft) 23:05:10 INFO - PROCESS | 2398 | RSS: Main: 324206592 23:05:10 INFO - PROCESS | 2398 | 23:05:12 INFO - PROCESS | 2398 | Cycle 1(1): loaded http://localhost:54609/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54609/tests/kraken/driver.html?testName=audio-oscillator) 23:05:13 INFO - PROCESS | 2398 | RSS: Main: 230215680 23:05:13 INFO - PROCESS | 2398 | 23:05:15 INFO - PROCESS | 2398 | Cycle 1(1): loaded http://localhost:54609/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54609/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:05:16 INFO - PROCESS | 2398 | RSS: Main: 231628800 23:05:16 INFO - PROCESS | 2398 | 23:05:19 INFO - PROCESS | 2398 | Cycle 1(1): loaded http://localhost:54609/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54609/tests/kraken/driver.html?testName=imaging-darkroom) 23:05:19 INFO - PROCESS | 2398 | RSS: Main: 269582336 23:05:19 INFO - PROCESS | 2398 | 23:05:22 INFO - PROCESS | 2398 | Cycle 1(1): loaded http://localhost:54609/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54609/tests/kraken/driver.html?testName=imaging-desaturate) 23:05:23 INFO - PROCESS | 2398 | RSS: Main: 270278656 23:05:23 INFO - PROCESS | 2398 | 23:05:25 INFO - PROCESS | 2398 | Cycle 1(1): loaded http://localhost:54609/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54609/tests/kraken/driver.html?testName=json-parse-financial) 23:05:26 INFO - PROCESS | 2398 | RSS: Main: 268701696 23:05:26 INFO - PROCESS | 2398 | 23:05:28 INFO - PROCESS | 2398 | Cycle 1(1): loaded http://localhost:54609/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54609/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:05:28 INFO - PROCESS | 2398 | RSS: Main: 260816896 23:05:28 INFO - PROCESS | 2398 | 23:05:30 INFO - PROCESS | 2398 | Cycle 1(1): loaded http://localhost:54609/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54609/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:05:31 INFO - PROCESS | 2398 | RSS: Main: 267751424 23:05:31 INFO - PROCESS | 2398 | 23:05:33 INFO - PROCESS | 2398 | Cycle 1(1): loaded http://localhost:54609/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54609/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:05:33 INFO - PROCESS | 2398 | RSS: Main: 271081472 23:05:33 INFO - PROCESS | 2398 | 23:05:36 INFO - PROCESS | 2398 | Cycle 1(1): loaded http://localhost:54609/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54609/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:05:37 INFO - PROCESS | 2398 | RSS: Main: 278761472 23:05:37 INFO - PROCESS | 2398 | 23:05:40 INFO - PROCESS | 2398 | Cycle 1(1): loaded http://localhost:54609/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54609/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:05:40 INFO - PROCESS | 2398 | RSS: Main: 275476480 23:05:40 INFO - PROCESS | 2398 | 23:05:42 INFO - PROCESS | 2398 | Cycle 1(1): loaded http://localhost:54609/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54609/tests/kraken/driver.html?testName=ai-astar) 23:05:42 INFO - PROCESS | 2398 | RSS: Main: 336453632 23:05:42 INFO - PROCESS | 2398 | 23:05:42 INFO - PROCESS | 2398 | __start_tp_report 23:05:42 INFO - PROCESS | 2398 | _x_x_mozilla_page_load 23:05:42 INFO - PROCESS | 2398 | _x_x_mozilla_page_load_details 23:05:42 INFO - PROCESS | 2398 | |i|pagename|runs| 23:05:42 INFO - PROCESS | 2398 | |0;ai-astar;112;114;120;110;112;111;111;111;112;135 23:05:42 INFO - PROCESS | 2398 | |1;audio-beat-detection;160;120;146;169;144;169;145;186;154;104 23:05:42 INFO - PROCESS | 2398 | |2;audio-dft;187;185;180;186;184;186;185;186;185;186 23:05:42 INFO - PROCESS | 2398 | |3;audio-fft;70;77;74;156;56;55;58;63;134;56 23:05:42 INFO - PROCESS | 2398 | |4;audio-oscillator;87;89;145;80;78;81;126;79;77;127 23:05:42 INFO - PROCESS | 2398 | |5;imaging-gaussian-blur;117;130;87;86;87;84;87;85;87;83 23:05:42 INFO - PROCESS | 2398 | |6;imaging-darkroom;93;93;93;92;93;93;93;93;92;93 23:05:42 INFO - PROCESS | 2398 | |7;imaging-desaturate;82;84;86;84;84;83;82;82;83;98 23:05:42 INFO - PROCESS | 2398 | |8;json-parse-financial;58;62;63;62;62;62;63;80;58;56 23:05:42 INFO - PROCESS | 2398 | |9;json-stringify-tinderbox;62;65;53;76;104;97;98;93;67;67 23:05:42 INFO - PROCESS | 2398 | |10;stanford-crypto-aes;80;80;79;80;79;78;77;78;78;82 23:05:42 INFO - PROCESS | 2398 | |11;stanford-crypto-ccm;127;128;128;125;127;140;114;135;165;161 23:05:42 INFO - PROCESS | 2398 | |12;stanford-crypto-pbkdf2;165;163;162;163;164;162;162;163;156;158 23:05:42 INFO - PROCESS | 2398 | |13;stanford-crypto-sha256-iterative;65;67;68;67;68;67;66;68;66;66 23:05:42 INFO - PROCESS | 2398 | __end_tp_report 23:05:42 INFO - PROCESS | 2398 | __start_cc_report 23:05:42 INFO - PROCESS | 2398 | _x_x_mozilla_cycle_collect,1902 23:05:42 INFO - PROCESS | 2398 | __end_cc_report 23:05:42 INFO - PROCESS | 2398 | __startTimestamp1467871542638__endTimestamp 23:05:42 INFO - PROCESS | 2398 | 23:05:42 INFO - PROCESS | 2398 | ------- Summary: start ------- 23:05:42 INFO - PROCESS | 2398 | Number of tests: 14 23:05:42 INFO - PROCESS | 2398 | 23:05:42 INFO - PROCESS | 2398 | [#0] ai-astar Cycles:10 Average:114.80 Median:112.00 stddev:7.64 (6.8%) stddev-sans-first:8.04 23:05:42 INFO - PROCESS | 2398 | Values: 112.0 114.0 120.0 110.0 112.0 111.0 111.0 111.0 112.0 135.0 23:05:42 INFO - PROCESS | 2398 | 23:05:42 INFO - PROCESS | 2398 | [#1] audio-beat-detection Cycles:10 Average:149.70 Median:157.00 stddev:24.10 (15.3%) stddev-sans-first:25.27 23:05:42 INFO - PROCESS | 2398 | Values: 160.0 120.0 146.0 169.0 144.0 169.0 145.0 186.0 154.0 104.0 23:05:42 INFO - PROCESS | 2398 | 23:05:42 INFO - PROCESS | 2398 | [#2] audio-dft Cycles:10 Average:185.00 Median:186.00 stddev:1.94 (1.0%) stddev-sans-first:1.92 23:05:42 INFO - PROCESS | 2398 | Values: 187.0 185.0 180.0 186.0 184.0 186.0 185.0 186.0 185.0 186.0 23:05:42 INFO - PROCESS | 2398 | 23:05:42 INFO - PROCESS | 2398 | [#3] audio-fft Cycles:10 Average:79.90 Median:60.50 stddev:35.57 (58.8%) stddev-sans-first:37.55 23:05:42 INFO - PROCESS | 2398 | Values: 70.0 77.0 74.0 156.0 56.0 55.0 58.0 63.0 134.0 56.0 23:05:42 INFO - PROCESS | 2398 | 23:05:42 INFO - PROCESS | 2398 | [#4] audio-oscillator Cycles:10 Average:96.90 Median:79.50 stddev:25.47 (32.0%) stddev-sans-first:26.76 23:05:42 INFO - PROCESS | 2398 | Values: 87.0 89.0 145.0 80.0 78.0 81.0 126.0 79.0 77.0 127.0 23:05:42 INFO - PROCESS | 2398 | 23:05:42 INFO - PROCESS | 2398 | [#5] imaging-gaussian-blur Cycles:10 Average:93.30 Median:86.50 stddev:16.27 (18.8%) stddev-sans-first:14.82 23:05:42 INFO - PROCESS | 2398 | Values: 117.0 130.0 87.0 86.0 87.0 84.0 87.0 85.0 87.0 83.0 23:05:42 INFO - PROCESS | 2398 | 23:05:42 INFO - PROCESS | 2398 | [#6] imaging-darkroom Cycles:10 Average:92.80 Median:93.00 stddev:0.42 (0.5%) stddev-sans-first:0.44 23:05:42 INFO - PROCESS | 2398 | Values: 93.0 93.0 93.0 92.0 93.0 93.0 93.0 93.0 92.0 93.0 23:05:42 INFO - PROCESS | 2398 | 23:05:42 INFO - PROCESS | 2398 | [#7] imaging-desaturate Cycles:10 Average:84.80 Median:84.00 stddev:4.80 (5.7%) stddev-sans-first:4.99 23:05:42 INFO - PROCESS | 2398 | Values: 82.0 84.0 86.0 84.0 84.0 83.0 82.0 82.0 83.0 98.0 23:05:42 INFO - PROCESS | 2398 | 23:05:42 INFO - PROCESS | 2398 | [#8] json-parse-financial Cycles:10 Average:62.60 Median:62.00 stddev:6.59 (10.6%) stddev-sans-first:6.77 23:05:42 INFO - PROCESS | 2398 | Values: 58.0 62.0 63.0 62.0 62.0 62.0 63.0 80.0 58.0 56.0 23:05:42 INFO - PROCESS | 2398 | 23:05:42 INFO - PROCESS | 2398 | [#9] json-stringify-tinderbox Cycles:10 Average:78.20 Median:71.50 stddev:18.13 (25.4%) stddev-sans-first:18.26 23:05:42 INFO - PROCESS | 2398 | Values: 62.0 65.0 53.0 76.0 104.0 97.0 98.0 93.0 67.0 67.0 23:05:42 INFO - PROCESS | 2398 | 23:05:42 INFO - PROCESS | 2398 | [#10] stanford-crypto-aes Cycles:10 Average:79.10 Median:79.50 stddev:1.45 (1.8%) stddev-sans-first:1.50 23:05:42 INFO - PROCESS | 2398 | Values: 80.0 80.0 79.0 80.0 79.0 78.0 77.0 78.0 78.0 82.0 23:05:42 INFO - PROCESS | 2398 | 23:05:42 INFO - PROCESS | 2398 | [#11] stanford-crypto-ccm Cycles:10 Average:135.00 Median:131.50 stddev:16.22 (12.3%) stddev-sans-first:16.94 23:05:42 INFO - PROCESS | 2398 | Values: 127.0 128.0 128.0 125.0 127.0 140.0 114.0 135.0 165.0 161.0 23:05:42 INFO - PROCESS | 2398 | 23:05:42 INFO - PROCESS | 2398 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:161.80 Median:163.00 stddev:2.74 (1.7%) stddev-sans-first:2.65 23:05:42 INFO - PROCESS | 2398 | Values: 165.0 163.0 162.0 163.0 164.0 162.0 162.0 163.0 156.0 158.0 23:05:42 INFO - PROCESS | 2398 | 23:05:42 INFO - PROCESS | 2398 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.80 Median:67.00 stddev:1.03 (1.5%) stddev-sans-first:0.87 23:05:42 INFO - PROCESS | 2398 | Values: 65.0 67.0 68.0 67.0 68.0 67.0 66.0 68.0 66.0 66.0 23:05:42 INFO - PROCESS | 2398 | -------- Summary: end -------- 23:05:42 INFO - PROCESS | 2398 | 23:05:43 INFO - TEST-INFO | 2398: exit 0 23:05:43 INFO - TEST-OK | kraken | took 57892ms 23:05:43 INFO - SUITE-END | took 1170s 23:05:43 INFO - Completed test suite (00:19:31) 23:05:43 INFO - javascript benchmark 23:05:43 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3478.3738926993137, 3573.1067233191693, 3578.6242444543304, 3587.2466781975786, 3659.323025240331, 5903.616264942776, 5906.911396329047, 5915.497182739462, 5921.5558833087525, 5948.256467941507, 5892.3518412974645, 5945.613535120214, 5950.940490595092, 5954.732037058332, 5972.372900845414, 5213.556847667934, 5221.425643179256, 5267.078261304276, 5306.893862122755, 5350.892982140354, 5244.239585260135, 5274.182501712241, 5288.788448462057, 5292.814751483699, 5300.072487314724, 5220.086484865153, 5258.684478931276, 5290.1800220965615, 5293.444188360225, 5317.308749862532, 5288.365396152467, 5314.548263397616, 5316.069687804657, 5325.387580428262, 5333.706646134461, 5097.745112744385, 5229.1633338666015, 5244.370675518962, 5245.554127899133, 5253.553447956928, 5669.121286200645, 5679.488846003838, 5713.200151978726, 5736.4550367715, 5868.2371291732225, 6171.950768369388, 6181.938180618181, 6207.906881396782, 6214.192155019864, 6215.098810672473, 6137.407629318945, 6203.782867599657, 6226.128342032119, 6270.435660790505, 6271.62370257786, 5787.131930210502, 5934.961381758175, 5955.493783028491, 5972.372900845392, 6014.96992515039, 5405.972970135203, 6325.462335701425, 6355.142055822497, 6368.64972426521, 6392.744290228339, 6122.418370254819, 6151.200343955257, 6151.84620384494, 6170.259568851767, 6181.598196117238, 6218.19163508741, 6220.315765265817, 6227.688615569204, 6233.158523599347, 6244.65654389013, 6228.22147231596, 6238.875222495615, 6241.21984751906, 6249.218847644044, 6253.249610046823, 6140.412224875431, 6162.815115546541, 6236.844078898064, 6238.812835614939, 6275.435210831047, 4868.075065737498, 4933.925991110136, 4934.136526107916, 4941.530554597308, 4976.0, 4462.620677242406, 4465.642748580106, 4508.278675411918, 4510.7068040577915, 4543.591076803037, 6003.8198854034445, 6007.8197654070445, 6018.879622407528, 6023.548233882477, 6031.788887388921, 6146.815595532141, 6176.320604733476, 6235.033569796688, 6244.812655620339, 6245.500359971192, 6000.519958403404, 6028.065649824284, 6038.758449661965, 6078.422549857756, 6081.963454387738, 5431.293931788921, 5439.864003399946, 5499.972500137554, 5504.339479262514, 5514.062609356419, 6368.363163683594, 6391.456726178234, 6439.356064393523, 6455.838604034937, 6490.350964903472, 6190.659513726789, 6223.315435302023, 6244.781432649836, 6250.562460627712, 6256.374362563707, 6346.873062538631, 6501.479881609365, 6507.609543427409, 6512.837179070561, 6516.348365163446, 6136.140940268277, 6264.310925798068, 6279.120923070683, 6279.529035322278, 6284.371562843679, 6292.622442653455, 6306.621602703853, 6311.495080393649, 6311.968440157953, 6320.5891617044745, 6099.237595300588, 6149.0776383542825, 6168.938310617016, 6212.099245609452, 6218.471429928326, 6151.96924015395, 6181.690915454299, 6199.783007594712, 6201.689915504297, 6218.22272215973, 6082.878342433215, 6113.327533971171, 6121.969390153021, 6127.816165515042, 6133.570650054453, 5848.590598658053, 6064.151018857452, 6110.16388879229, 6153.5692501526255, 6177.907331389943, 6135.385782596045, 6166.013437849922, 6169.290531588925, 6169.84575385619, 6183.165272688129], "value": 5794.516938462043, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [553.9418361072087, 566.5439321346316, 571.6655756382517, 611.9204503414555, 612.9019356902896, 32622.184445388877, 39785.20429591407, 40756.38865417017, 40814.591854081526, 40979.38530922031, 43938.0, 44306.0, 44358.33462498057, 44478.777606112, 44482.0, 9145.817083658323, 9772.315937884334, 9978.750531236741, 9989.250806189528, 9993.300468967174, 12065.517379304818, 12375.62873113808, 12378.504859805596, 12384.318862462562, 12406.62780116598, 12008.519659213665, 12096.758064838697, 12105.697357566043, 12105.878941210607, 12121.030317574618, 12050.0, 12141.696457588543, 12158.696032599168, 12160.0, 12174.208676436003, 11862.991645710124, 11988.400579970967, 12007.399630018552, 12021.459034343476, 12024.639260822101, 35395.64604353949, 42562.0, 43082.784586077185, 43111.0, 43127.0, 3269.9346013079844, 3336.115929278743, 3342.8829990950444, 3355.0438125134465, 3417.2311229973307, 29224.70775292241, 29287.2678183045, 30252.697473025208, 30983.38033239345, 31178.90873819428, 2759.8344099354103, 2776.7917406194615, 2799.9160025199276, 2823.378856651533, 2863.7995340326183, 456165.0, 491761.0, 493063.0, 494742.0, 495783.0, 38937.805310973265, 39172.804135979706, 39588.60411395907, 69618.65190674116, 70259.2974070263, 33192.50211246833, 34575.61697532121, 38600.227995439935, 38931.0, 38976.41535376971, 15978.600534986486, 16903.0, 16919.746203806953, 16925.91537042307, 16937.491875243762, 12761.808572871414, 12901.354932253538, 13815.930920345534, 13817.585472435843, 14194.574162775134, 8477.576121193915, 8483.0, 8495.532745698922, 8530.87203691945, 8595.52724600153, 3465.462853257749, 3503.2468019375956, 3514.1741690702665, 3520.2255503789124, 3541.203229273393, 797.6211299632628, 1038.772558511712, 1055.5039131608132, 1099.2689861242345, 1240.49759847262, 26684.59973100405, 31926.88255911032, 32254.516182257285, 32666.346673066404, 32834.50748238778, 1060.7295139739317, 1128.2891778179694, 1139.9373034483017, 1153.3944679043402, 1204.4700331853956, 14078.366473508717, 14325.424203337625, 15118.395264189308, 15235.695286094216, 15460.690786184214, 632827.0, 633352.0, 633803.0, 635587.0, 639088.0, 52873.7356313216, 53268.200976985376, 63398.6830065856, 63945.68027159834, 64093.35906640967, 1424.0672359604475, 1573.7403328450678, 1588.3646541383307, 1595.234287541963, 1599.9840001600069, 29472.0, 29731.256718581342, 29824.403511929206, 30177.54733678953, 30536.389272214874, 52234.21648675349, 52397.47602523926, 52972.47027529676, 53027.73486132544, 53077.0, 416182.0, 418720.0, 419442.0, 420469.0, 420488.0, 24353.39116522101, 25220.99116035338, 28603.42793144167, 28999.0, 29099.563506547842, 43287.56712432835, 44259.0, 44484.33273500836, 44814.0, 45145.54854451413, 34948.82525587457, 35533.82233088818, 36690.63309366872, 37335.626643733216, 37378.0, 38863.805680972546, 39587.010324742114, 40053.99865003398, 41001.38497922474, 43222.351664724425, 32307.192320191243, 38161.618383816916, 38382.6161738379, 38851.417228741026, 38975.0, 33732.49401258934, 33887.4597590766, 34127.658723412445, 34370.14074648054, 34988.47517287294, 22634.981425835547, 25490.872545637154, 25676.972921082946, 25877.353066173495, 26080.8695956519], "value": 19307.952313553196, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [413.57195302861544, 414.10139996208227, 415.76093746096, 416.96872734544917, 426.8847411198976, 18961.715574266396, 20350.0, 21026.89486552569, 21050.368488945354, 21064.684029739547, 20465.079071441785, 20576.0, 21886.23398181064, 21921.79430131342, 21934.122635094576, 5745.310562732478, 5869.178315035877, 5986.73059712314, 5995.790147344844, 6024.457798798129, 5940.495057920082, 6026.608270462428, 6062.575619706622, 6065.4844338231305, 6088.756449742005, 5889.293284805808, 5953.166556682068, 6032.27612686476, 6040.365761595035, 6067.726952287136, 6015.037593984964, 6063.029915213545, 6071.0, 6079.787207447763, 6087.2999605045325, 5490.972545137249, 5796.275465566804, 5803.419658034205, 5805.854853628651, 5817.534597232211, 13791.793123103062, 14160.36278367476, 16093.91953040239, 26421.73578264212, 26454.07410740634, 2383.5828729972186, 2437.5003124359464, 2439.634054891763, 2535.657686212356, 2679.981606989351, 8534.208432776464, 10195.898041019569, 14736.484223052248, 14872.553823385315, 15881.444149454714, 1743.2155530011419, 1806.223323970692, 1843.2442698493553, 1851.25949620153, 1870.7193920911836, 83413.74879376814, 100134.49932750362, 114702.42648786787, 117225.8277417232, 127223.0, 9656.806863862825, 11660.242084264495, 11772.529098836121, 12021.579244726392, 12153.513859445524, 13989.3704783285, 14477.565673029825, 14975.1014939104, 15486.767698484531, 15510.534683959499, 9125.269978401711, 9167.0, 9263.95368023169, 9270.953645231866, 9325.953370233105, 8320.167983201753, 8634.438761480484, 9307.674231401868, 9351.625934962525, 9414.058594140668, 4791.784369703371, 4830.7101573905675, 4847.248676455155, 4850.175470170079, 4875.196177414308, 1671.2312336325426, 2035.5827055453597, 2038.225474319749, 2041.9886411884281, 2049.6208201482477, 862.5169904853302, 984.0749695286551, 984.6898227058454, 984.8818141823027, 1038.8078205532001, 19594.70607940882, 20989.16043358279, 22001.899905004684, 22175.334739957827, 22859.428514286945, 961.956711947964, 965.6571916969515, 982.1259079419323, 1013.9594416223416, 1027.1372047480152, 9424.293178011676, 10828.837567436494, 11089.223754337281, 11264.38045907483, 11299.039581635652, 659040.0, 666326.0, 668005.0, 676624.6168769124, 677345.0, 129300.35349823191, 129488.0, 130363.0, 131848.0, 136594.31702841423, 1073.736934451049, 1078.0620859851979, 1101.5758932810766, 1101.8292164714321, 1103.382106020631, 18373.908130459262, 18409.447716568524, 18505.72241416353, 18611.0, 19125.426237212905, 39776.60223397729, 46280.53719462762, 48682.75658621684, 51384.0, 55110.724446379114, 70723.0, 74491.0, 75329.6233518829, 77865.61067194628, 78081.60959195168, 8189.385796065438, 8386.371022173484, 8529.488230705929, 8588.65645374178, 8663.870041949502, 8669.783255418413, 11689.649310520697, 12485.0, 13274.0, 13351.599452016455, 11247.7750444989, 11383.203175777948, 11552.94223528877, 11736.354500502213, 11776.293422394683, 10256.794864102825, 11148.05241554477, 11193.440327983732, 11296.887031129583, 11447.942760286145, 11287.20989530725, 11374.146938979444, 11486.885131148916, 11783.941080294886, 11953.342566158944, 8307.044689860744, 8330.583470826314, 8414.6213420395, 8540.060593334852, 8823.470591764515, 8140.552269624991, 11322.547098115983, 11373.488193031168, 11473.139514536277, 11622.418879056182], "value": 9780.462826529629, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1440.8703216710496, 1490.6646004648949, 1634.6975809475248, 1887.2828325236408, 2042.7446569178853, 8441.282490988266, 9019.0, 9176.495292758895, 9185.862212066808, 9211.953940230298, 8974.685885993991, 9041.63833446663, 9159.633614655422, 9159.771005724844, 9159.862602060974, 2550.3624093976505, 2576.343032526703, 2581.6127580862926, 2584.2634849068018, 2586.9741302587017, 2512.5979843225086, 2574.382148284408, 2582.5609646360163, 2584.5218634552666, 2586.2112055822968, 2547.5796493578537, 2572.0354866924904, 2586.4697736963876, 2590.611408288753, 2591.6371707960807, 2546.885390157438, 2565.5638541447906, 2577.1237779155085, 2589.572720501115, 2589.6115582662565, 2528.987355063213, 2562.6924769027837, 2571.3700143464785, 2589.4562141950214, 2592.727763584834, 9005.684801031997, 9340.486273254968, 9382.061793820632, 9402.106799854002, 9418.717438476857, 5672.06410942194, 5771.595988280822, 5826.854328641775, 5831.212786273841, 5835.299764028301, 3664.59689434162, 3743.3262012837677, 3800.771953682788, 3811.1615444602144, 3820.0449887528116, 1052.3422860712055, 1063.5533076108054, 1065.137238836537, 1098.9945050274778, 1103.4979084516526, 12526.373681315898, 12581.559645412459, 12619.810702839464, 12739.299338536377, 12745.426455809511, 4467.687261891701, 4486.775661216926, 4904.386951631046, 4928.556429921325, 4977.950220497821, 9875.703728888146, 9888.50557472138, 9903.45530995802, 9909.65316213929, 9910.851337229948, 5588.972055139699, 5633.042382794934, 5634.408387119293, 5671.319441667027, 5691.089425691869, 4706.835260765925, 4747.786349614206, 5030.37120359955, 5223.582113430916, 5258.50044245796, 4033.757974521538, 4060.7766572838777, 4130.91738165235, 4156.542780294165, 4164.479440069991, 1267.8161666558296, 1278.7122897348022, 1279.36031984008, 1290.6450726050223, 1291.4834066373526, 834.4325858416214, 885.5173930208052, 919.8988111307751, 948.1277224952922, 966.7438128895708, 6117.571769976148, 6168.814935551941, 6228.719707613168, 6284.780032698834, 6293.811185664437, 964.1419136968104, 973.2797729680127, 990.8513722941473, 1020.9132223761063, 1024.9487525623838, 4042.130941847517, 4055.6755459563165, 4118.876433706993, 4124.979375103166, 4189.392538081961, 11310.377929213808, 11669.416529173508, 11683.649490515298, 11692.240004399688, 11863.822042669366, 11519.25124866881, 11543.422828858524, 11660.00889924349, 11661.591844285409, 11667.824982625267, 8808.383413161017, 8932.151445612657, 8953.373263871597, 8954.059823718546, 8963.0, 6068.029115341524, 6097.634141951497, 6168.105624684305, 6190.752369905334, 6268.467180289544, 9548.236141108555, 9984.051515106054, 10144.644937427154, 10164.542595583365, 10182.338148020355, 9951.0, 10204.897951020395, 10290.691279261635, 10325.070743632808, 10385.376877387382, 7206.387457065998, 7225.963870180615, 7381.077365329334, 7390.408767298711, 7668.156502784577, 8572.27135693473, 8608.91391086081, 8673.392862499564, 8679.479231246145, 8679.60941757635, 7479.70081196746, 7575.469717119749, 7648.579328137006, 7668.501547399401, 7716.961415192888, 7371.8157046074275, 7466.066741657293, 7469.178390377163, 7593.126790419173, 7607.733729319447, 7173.0675012248075, 7277.38142257914, 7297.1973082961895, 7298.270172982871, 7334.266573342623, 4987.650864439454, 5003.749812509433, 5050.520200580939, 5179.585633149414, 5181.222816577393, 5067.822626208213, 5157.742112894415, 5179.22311653255, 5191.29917461153, 5330.920036199383], "value": 4814.533862023398, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [332.6328788678531, 337.5849466469008, 343.309947006517, 345.1922501346848, 345.4162465433419, 6099.268087829456, 6179.486025923655, 6755.763548275805, 6770.458363330934, 6806.863862722743, 6436.195475565554, 6627.9337206627915, 6692.0, 6702.899456508157, 6777.0, 5054.721990290533, 5082.3138876251605, 5096.184610462326, 5101.642884998052, 5120.718360490171, 5084.567811736007, 5091.134507133777, 5099.643024988252, 5106.974465127669, 5107.157319042353, 4818.614510839142, 4921.335619691332, 5023.522765337287, 5032.622553308498, 5054.418741844698, 5030.673006254582, 5032.6477146599755, 5045.545900868903, 5076.187809950427, 5084.44071152173, 4373.825046998117, 4376.540463251345, 4382.978085109554, 4383.320585309282, 4384.978075109636, 5578.0, 5849.61977471463, 5969.731362088716, 6273.996160614325, 6560.179977502812, 6009.2188015557695, 6017.247844019497, 6032.668203248863, 6032.728527216286, 6034.275886893601, 7026.824329391806, 7260.27397260275, 7285.817854553626, 7292.8906066409045, 7311.341979221842, 4821.276808478721, 4868.391451068616, 4871.244957031666, 4871.853844384674, 4889.144399730033, 6593.340665933416, 7729.845403091963, 7731.922680773233, 7740.419468539826, 7741.61291935401, 4945.55490005894, 4945.901081978413, 4962.0, 4965.851024469272, 4968.30443737877, 5031.320771695774, 5048.671836330626, 5054.519820617035, 5057.393112826484, 5063.49365063498, 5739.713014349266, 5998.220231369981, 6001.969990150109, 6032.095185722089, 6035.275766907999, 7263.745768898063, 7329.450291228072, 7335.083114610674, 7337.70649174027, 7340.96329518349, 1661.3271624991655, 1737.3658614605579, 1771.6623948918484, 1772.6720556697053, 1773.5388798912375, 1056.2500624556496, 1067.7864427114453, 1070.7537266428765, 1075.5643964194514, 1113.582406597526, 6874.862502749917, 6904.344087311697, 6945.0, 6960.269171736894, 6964.0, 7485.85028299431, 7489.625518724042, 7529.397648188132, 7533.849323013618, 7537.359324457482, 6926.549774264657, 6941.160592443007, 6947.548409353376, 6954.130733658293, 6954.200266969262, 3540.725338878002, 3624.7825130492256, 3851.730378873452, 3998.2403343364667, 4003.899902502461, 6310.2743184534365, 7897.565633890191, 7949.562774047483, 7952.721654742172, 7972.043354797462, 6158.797425338798, 7725.961370193113, 7740.187280335484, 7746.457747957589, 7757.224277572198, 7929.563873986871, 7930.444868859125, 7932.841343173104, 7948.0, 7952.443328966917, 7098.929010709827, 7446.813829654302, 7447.739329123455, 7471.962640186982, 7502.774916752506, 7320.304571065741, 7355.043844300207, 7356.080489938758, 7358.852822943618, 7376.926230737624, 7894.289513943772, 7897.289243968071, 7897.842043159219, 7905.56519391439, 7914.564698941614, 4972.303877457087, 4973.52751488608, 4975.850724478271, 4984.775685094107, 4986.551210390937, 7196.488737365003, 7425.183229844821, 7754.0, 7778.0, 7792.10390805042, 7295.9056141579185, 7346.742863999734, 7353.8161545961775, 7359.080114985627, 7362.815929601803, 5927.585069045125, 7091.574505529685, 7311.561306321638, 7341.302576255247, 7348.154962179417, 6049.667268300285, 6910.0, 7217.458690598121, 7224.711011559479, 7233.059702238886, 5703.486686198262, 7249.818754531179, 7254.709811607688, 7263.237360077116, 7300.525465844703, 5926.259217597801, 6956.37392634645, 6994.685239164148, 7048.118985126859, 7132.85734285301], "value": 5326.059085767429, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [767.7005967672605, 770.710983381232, 779.4543819326469, 781.1368437876145, 792.7344339646219, 15434.69130617387, 15627.531174064768, 15737.52787416378, 15751.054936703806, 15763.21183940804, 14491.855081449183, 14574.0, 14603.634909127277, 14610.561683149495, 14660.193689347081, 659.8119535932249, 811.7077851973285, 948.6205517792904, 973.547300505265, 977.2816979520082, 959.2853324273426, 967.5404183013083, 969.4958621516807, 972.265939215891, 974.1476208317722, 1035.9637412690558, 1036.4144258493964, 1037.2635428845529, 1038.7455073507, 1039.4438975148298, 842.1999100854182, 856.3662889461816, 857.5583574459158, 857.9957100214459, 1034.5861655337887, 753.4763339479064, 763.5151678684076, 764.9005629268215, 767.6353731977322, 768.7539987204096, 14738.189399583018, 14801.629959250997, 14809.185494797783, 14836.554903352917, 14841.332140053724, 12826.486940522367, 13028.348582570929, 13118.278494682789, 13171.802422963567, 13224.206547607078, 10183.388996660224, 10230.795384092276, 10379.688609341732, 10415.583376664925, 10813.18901082422, 3591.2458383739445, 3604.116991337113, 3621.728370372233, 3676.099355657854, 3860.6718428933573, 81517.59241203815, 81809.18190818075, 83996.99999999939, 84151.0, 84455.0, 9666.275029372684, 9855.211583073336, 10035.297529172887, 10157.0, 10170.491475426199, 23063.192788252665, 23098.769012309996, 23099.88450057739, 23125.0, 23156.65265021026, 7524.172341042481, 7662.693492260359, 7764.883526747103, 7767.456278060482, 7767.766966990999, 4225.830966761357, 4272.4873015237745, 4309.719868208556, 4364.1708075466195, 4394.450693663292, 3728.0493474164455, 3791.1848952474797, 3792.1846802937503, 3795.411711184771, 3806.600306967784, 655.9311272316434, 664.2361284522838, 677.2144312597363, 687.6871023684212, 697.3688811625487, 531.553495064148, 583.2592607388593, 590.1796502861026, 621.7077973352517, 629.2574761781142, 7688.192739762245, 8165.918340816635, 8264.958675206586, 8380.371472139492, 8429.93458679752, 497.9714112827173, 554.4178612456905, 578.9276340457443, 581.9272590926134, 591.7721677154327, 3738.887833365003, 3918.9804050979565, 3948.9012774680864, 3950.881473555798, 4000.7799571023875, 69669.909902703, 87006.0, 87385.68921466183, 87585.0, 89250.0, 58785.706071470224, 76779.0, 78224.0, 78764.6061769699, 79715.60142199251, 68439.65780171067, 69092.654536727, 69109.65445172742, 69172.0, 70315.0, 7775.6112194388925, 7792.6103694816165, 7803.219678032151, 7819.990054699155, 7845.686172553034, 38142.66500672463, 46022.76988615035, 47460.67775255699, 48533.75733121453, 48686.02627947492, 63379.683101584196, 67025.0, 67690.66154669195, 68122.31877681358, 68941.65529172322, 9774.560144793359, 10030.749231268985, 10035.0, 10214.233932455245, 10581.047705706524, 10647.89352106469, 10672.413017283814, 10724.195685323475, 10908.181886358714, 11248.325100493996, 10403.94798026005, 10628.36229826213, 10634.30876992993, 10640.733981650521, 10739.892601074202, 9849.60601575929, 10253.28227024131, 10515.316504427188, 10639.840402393815, 10815.513301901276, 8780.780480487783, 9839.458829764146, 10326.0, 10551.314164579278, 10836.67489975302, 8956.373053886166, 8978.685745998859, 9121.635134594542, 9139.817203656023, 9193.816123677356, 8789.780255493664, 8911.2425443838, 9015.0, 9045.502497362708, 9062.184403403726], "value": 6879.016033591489, "unit": "score"}], "name": "dromaeo_css", "value": 7602.193043127103, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 114.0, 120.0, 110.0, 112.0, 111.0, 111.0, 111.0, 112.0, 135.0], "value": 114.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 120.0, 146.0, 169.0, 144.0, 169.0, 145.0, 186.0, 154.0, 104.0], "value": 149.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 185.0, 180.0, 186.0, 184.0, 186.0, 185.0, 186.0, 185.0, 186.0], "value": 185.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 77.0, 74.0, 156.0, 56.0, 55.0, 58.0, 63.0, 134.0, 56.0], "value": 79.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 89.0, 145.0, 80.0, 78.0, 81.0, 126.0, 79.0, 77.0, 127.0], "value": 96.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 130.0, 87.0, 86.0, 87.0, 84.0, 87.0, 85.0, 87.0, 83.0], "value": 93.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 93.0, 92.0, 93.0, 93.0, 93.0, 93.0, 92.0, 93.0], "value": 92.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 84.0, 86.0, 84.0, 84.0, 83.0, 82.0, 82.0, 83.0, 98.0], "value": 84.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 62.0, 63.0, 62.0, 62.0, 62.0, 63.0, 80.0, 58.0, 56.0], "value": 62.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 65.0, 53.0, 76.0, 104.0, 97.0, 98.0, 93.0, 67.0, 67.0], "value": 78.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 80.0, 79.0, 80.0, 79.0, 78.0, 77.0, 78.0, 78.0, 82.0], "value": 79.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 128.0, 128.0, 125.0, 127.0, 140.0, 114.0, 135.0, 165.0, 161.0], "value": 135.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 163.0, 162.0, 163.0, 164.0, 162.0, 162.0, 163.0, 156.0, 158.0], "value": 161.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 67.0, 68.0, 67.0, 68.0, 67.0, 66.0, 68.0, 66.0, 66.0], "value": 66.8, "unit": "score"}], "name": "kraken", "value": 1480.6999999999996, "alertThreshold": 2.0}]} 23:05:43 INFO - Return code: 0 23:05:43 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 23:05:43 ERROR - # TBPL SUCCESS # 23:05:43 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:05:43 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:05:43 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:05:43 INFO - 2016-07-06 23:05:43.961 screenresolution[2402:36198] starting screenresolution argv=screenresolution get 23:05:43 INFO - 2016-07-06 23:05:43.963 screenresolution[2402:36198] Display 0: 1600x1200x32@60 23:05:43 INFO - 2016-07-06 23:05:43.968 screenresolution[2403:36202] starting screenresolution argv=screenresolution list 23:05:43 INFO - Available Modes on Display 0 23:05:43 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:05:43 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:05:43 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:05:43 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:05:44 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:05:44 INFO - Intel Iris: 23:05:44 INFO - Chipset Model: Intel Iris 23:05:44 INFO - Type: GPU 23:05:44 INFO - Bus: Built-In 23:05:44 INFO - VRAM (Dynamic, Max): 1536 MB 23:05:44 INFO - Vendor: Intel (0x8086) 23:05:44 INFO - Device ID: 0x0a2e 23:05:44 INFO - Revision ID: 0x0009 23:05:44 INFO - Displays: 23:05:44 INFO - Display: 23:05:44 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:05:44 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:05:44 INFO - Display Serial Number: 1600x1200 60 23:05:44 INFO - Main Display: Yes 23:05:44 INFO - Mirror: Off 23:05:44 INFO - Online: Yes 23:05:44 INFO - Rotation: Supported 23:05:44 INFO - Return code: 0 23:05:44 INFO - Running post-action listener: _resource_record_post_action 23:05:44 INFO - Running post-run listener: _resource_record_post_run 23:05:45 INFO - Total resource usage - Wall time: 1194s; CPU: 52.0%; Read bytes: 892928; Write bytes: 495683584; Read time: 10; Write time: 1773 23:05:45 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 194593280; Write bytes: 195626496; Read time: 17648; Write time: 370 23:05:45 INFO - run-tests - Wall time: 1173s; CPU: 52.0%; Read bytes: 892928; Write bytes: 299057664; Read time: 10; Write time: 1398 23:05:45 INFO - Running post-run listener: _upload_blobber_files 23:05:45 INFO - Blob upload gear active. 23:05:45 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:05:45 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 23:05:45 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'] 23:05:45 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 23:05:45 INFO - (blobuploader) - INFO - Open directory for files ... 23:05:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 23:05:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:05:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:05:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:05:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:05:46 INFO - (blobuploader) - INFO - Done attempting. 23:05:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 23:05:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:05:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:05:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:05:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:05:47 INFO - (blobuploader) - INFO - Done attempting. 23:05:47 INFO - (blobuploader) - INFO - Iteration through files over. 23:05:47 INFO - Return code: 0 23:05:47 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:05:47 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:05:47 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/271245bc84311e6df95b9fc3b76391bc08a58e003d47599768cb4f816ee407614874270a84e8b489e03e22a14be558a17d8203a68d91d5a61109f281c531faa1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e52bf1d1f01bab8371de81eea7581e570e2c6d997ffbfe84254c4b25f0cd07bb2a1419c400f97d1c57e5a19d769dc22728caeec92fede3cd76bad3bfba398289"} 23:05:47 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:05:47 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:05:47 INFO - Contents: 23:05:47 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/271245bc84311e6df95b9fc3b76391bc08a58e003d47599768cb4f816ee407614874270a84e8b489e03e22a14be558a17d8203a68d91d5a61109f281c531faa1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e52bf1d1f01bab8371de81eea7581e570e2c6d997ffbfe84254c4b25f0cd07bb2a1419c400f97d1c57e5a19d769dc22728caeec92fede3cd76bad3bfba398289"} 23:05:47 INFO - Running post-run listener: copy_logs_to_upload_dir 23:05:47 INFO - Copying logs to upload dir... 23:05:47 INFO - mkdir: /builds/slave/test/build/upload/logs 23:05:47 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1248.715935 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2016-07-06 23:05:47.360195) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-06 23:05:47.364521) ========= 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.botEVMndKY/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.4s2eA6qdfR/Listeners TMPDIR=/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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/271245bc84311e6df95b9fc3b76391bc08a58e003d47599768cb4f816ee407614874270a84e8b489e03e22a14be558a17d8203a68d91d5a61109f281c531faa1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e52bf1d1f01bab8371de81eea7581e570e2c6d997ffbfe84254c4b25f0cd07bb2a1419c400f97d1c57e5a19d769dc22728caeec92fede3cd76bad3bfba398289"} build_url:https://queue.taskcluster.net/v1/task/DLbm233hT9i2W3-QNNHisw/artifacts/public/build/firefox-48.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011291 build_url: 'https://queue.taskcluster.net/v1/task/DLbm233hT9i2W3-QNNHisw/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/271245bc84311e6df95b9fc3b76391bc08a58e003d47599768cb4f816ee407614874270a84e8b489e03e22a14be558a17d8203a68d91d5a61109f281c531faa1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e52bf1d1f01bab8371de81eea7581e570e2c6d997ffbfe84254c4b25f0cd07bb2a1419c400f97d1c57e5a19d769dc22728caeec92fede3cd76bad3bfba398289"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-06 23:05:47.397459) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 23:05:47.397809) ========= 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.botEVMndKY/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.4s2eA6qdfR/Listeners TMPDIR=/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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.005073 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 23:05:47.446679) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-06 23:05:47.447011) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-06 23:05:47.447356) ========= ========= Total master_lag: 0.18 =========