builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0030 starttime: 1464289388.18 results: success (0) buildid: 20160526100338 builduid: b705ecbd4de940048064908aff0a8618 revision: 388772a2f7620727b3125c722c142abee8a74aa0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-26 12:03:08.178447) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-26 12:03:08.179356) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-26 12:03:08.179963) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-26 12:03:08.203157) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 12:03:08.203796) ========= 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.8YFxuWTJvw/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.WbJmsCoFWt/Listeners TMPDIR=/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-26 12:03:08-- 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% 16.7M=0.001s 2016-05-26 12:03:08 (16.7 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.133283 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 12:03:08.362315) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 12:03:08.363087) ========= 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.8YFxuWTJvw/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.WbJmsCoFWt/Listeners TMPDIR=/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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.027576 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 12:03:08.415081) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-26 12:03:08.415913) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 388772a2f7620727b3125c722c142abee8a74aa0 --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 388772a2f7620727b3125c722c142abee8a74aa0 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.8YFxuWTJvw/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.WbJmsCoFWt/Listeners TMPDIR=/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-26 12:03:08,490 truncating revision to first 12 chars 2016-05-26 12:03:08,490 Setting DEBUG logging. 2016-05-26 12:03:08,490 attempt 1/10 2016-05-26 12:03:08,490 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/388772a2f762?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-26 12:03:10,474 unpacking tar archive at: mozilla-beta-388772a2f762/testing/mozharness/ program finished with exit code 0 elapsedTime=2.268181 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-26 12:03:10.709265) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-26 12:03:10.710019) ========= script_repo_revision: 388772a2f7620727b3125c722c142abee8a74aa0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-26 12:03:10.710929) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-26 12:03:10.711616) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-26 12:03:10.729466) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2016-05-26 12:03:10.730167) ========= /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.8YFxuWTJvw/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.WbJmsCoFWt/Listeners TMPDIR=/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 12:03:10 INFO - MultiFileLogger online at 20160526 12:03:10 in /builds/slave/test 12:03:10 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 12:03:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:03:10 INFO - {'append_to_log': False, 12:03:10 INFO - 'base_work_dir': '/builds/slave/test', 12:03:10 INFO - 'blob_upload_branch': 'Mozilla-Beta', 12:03:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:03:10 INFO - 'branch': 'Mozilla-Beta', 12:03:10 INFO - 'buildbot_json_path': 'buildprops.json', 12:03:10 INFO - 'config_files': ('talos/mac_config.py',), 12:03:10 INFO - 'default_actions': ('clobber', 12:03:10 INFO - 'read-buildbot-config', 12:03:10 INFO - 'download-and-extract', 12:03:10 INFO - 'populate-webroot', 12:03:10 INFO - 'create-virtualenv', 12:03:10 INFO - 'install', 12:03:10 INFO - 'run-tests'), 12:03:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:03:10 INFO - 'download_minidump_stackwalk': True, 12:03:10 INFO - 'download_symbols': 'ondemand', 12:03:10 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:03:10 INFO - 'tooltool.py': '/tools/tooltool.py', 12:03:10 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:03:10 INFO - '/tools/misc-python/virtualenv.py')}, 12:03:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:03:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:03:10 INFO - 'installer_path': 'installer.exe', 12:03:10 INFO - 'log_level': 'info', 12:03:10 INFO - 'log_name': 'talos', 12:03:10 INFO - 'log_to_console': True, 12:03:10 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:03:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:03:10 INFO - 'opt_config_files': (), 12:03:10 INFO - 'pip_index': False, 12:03:10 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:03:10 INFO - 'cmd': ('bash', 12:03:10 INFO - '-c', 12:03:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:03:10 INFO - 'enabled': True, 12:03:10 INFO - 'halt_on_failure': False, 12:03:10 INFO - 'name': 'check_screen_resolution'},), 12:03:10 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:03:10 INFO - 'cmd': ('bash', 12:03:10 INFO - '-c', 12:03:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:03:10 INFO - 'enabled': True, 12:03:10 INFO - 'halt_on_failure': False, 12:03:10 INFO - 'name': 'check_screen_resolution'},), 12:03:10 INFO - 'run_cmd_checks_enabled': True, 12:03:10 INFO - 'sps_profile': False, 12:03:10 INFO - 'sps_profile_interval': 0, 12:03:10 INFO - 'suite': 'dromaeojs', 12:03:10 INFO - 'system_bits': '32', 12:03:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:03:10 INFO - 'title': 't-yosemite-r7-0030', 12:03:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:03:10 INFO - 'use_talos_json': True, 12:03:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:03:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:03:10 INFO - 'work_dir': 'build'} 12:03:10 INFO - ##### 12:03:10 INFO - ##### Running clobber step. 12:03:10 INFO - ##### 12:03:10 INFO - Running pre-action listener: _resource_record_pre_action 12:03:10 INFO - Running main action method: clobber 12:03:10 INFO - rmtree: /builds/slave/test/build 12:03:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:03:12 INFO - Running post-action listener: _resource_record_post_action 12:03:12 INFO - ##### 12:03:12 INFO - ##### Running read-buildbot-config step. 12:03:12 INFO - ##### 12:03:12 INFO - Running pre-action listener: _resource_record_pre_action 12:03:12 INFO - Running main action method: read_buildbot_config 12:03:12 INFO - Using buildbot properties: 12:03:12 INFO - { 12:03:12 INFO - "project": "", 12:03:12 INFO - "product": "firefox", 12:03:12 INFO - "script_repo_revision": "production", 12:03:12 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 12:03:12 INFO - "repository": "", 12:03:12 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 12:03:12 INFO - "buildid": "20160526100338", 12:03:12 INFO - "pgo_build": "False", 12:03:12 INFO - "basedir": "/builds/slave/test", 12:03:12 INFO - "buildnumber": 4, 12:03:12 INFO - "slavename": "t-yosemite-r7-0030", 12:03:12 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 12:03:12 INFO - "platform": "yosemite_r7", 12:03:12 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 12:03:12 INFO - "branch": "mozilla-beta", 12:03:12 INFO - "repo_path": "releases/mozilla-beta", 12:03:12 INFO - "revision": "388772a2f7620727b3125c722c142abee8a74aa0", 12:03:12 INFO - "stage_platform": "macosx64", 12:03:12 INFO - "builduid": "b705ecbd4de940048064908aff0a8618", 12:03:12 INFO - "slavebuilddir": "test" 12:03:12 INFO - } 12:03:12 INFO - Found installer url https://queue.taskcluster.net/v1/task/R_7YRjrlSkitOrHzsewm-w/artifacts/public/build/firefox-47.0.en-US.mac.dmg. 12:03:12 INFO - Running post-action listener: _resource_record_post_action 12:03:12 INFO - ##### 12:03:12 INFO - ##### Running download-and-extract step. 12:03:12 INFO - ##### 12:03:12 INFO - Running pre-action listener: _resource_record_pre_action 12:03:12 INFO - Running main action method: download_and_extract 12:03:12 INFO - mkdir: /builds/slave/test/build/tests 12:03:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:03:12 INFO - https://queue.taskcluster.net/v1/task/R_7YRjrlSkitOrHzsewm-w/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json matches https://queue.taskcluster.net 12:03:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_7YRjrlSkitOrHzsewm-w/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json 12:03:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_7YRjrlSkitOrHzsewm-w/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json 12:03:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_7YRjrlSkitOrHzsewm-w/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-47.0.en-US.mac.test_packages.json 12:03:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_7YRjrlSkitOrHzsewm-w/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-47.0.en-US.mac.test_packages.json'}, attempt #1 12:03:14 INFO - Downloaded 1241 bytes. 12:03:14 INFO - Reading from file /builds/slave/test/build/firefox-47.0.en-US.mac.test_packages.json 12:03:14 INFO - Using the following test package requirements: 12:03:14 INFO - {u'common': [u'firefox-47.0.en-US.mac.common.tests.zip'], 12:03:14 INFO - u'cppunittest': [u'firefox-47.0.en-US.mac.common.tests.zip', 12:03:14 INFO - u'firefox-47.0.en-US.mac.cppunittest.tests.zip'], 12:03:14 INFO - u'gtest': [u'firefox-47.0.en-US.mac.common.tests.zip', 12:03:14 INFO - u'firefox-47.0.en-US.mac.gtest.tests.zip'], 12:03:14 INFO - u'jittest': [u'firefox-47.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 12:03:14 INFO - u'mochitest': [u'firefox-47.0.en-US.mac.common.tests.zip', 12:03:14 INFO - u'firefox-47.0.en-US.mac.mochitest.tests.zip'], 12:03:14 INFO - u'mozbase': [u'firefox-47.0.en-US.mac.common.tests.zip'], 12:03:14 INFO - u'reftest': [u'firefox-47.0.en-US.mac.common.tests.zip', 12:03:14 INFO - u'firefox-47.0.en-US.mac.reftest.tests.zip'], 12:03:14 INFO - u'talos': [u'firefox-47.0.en-US.mac.common.tests.zip', 12:03:14 INFO - u'firefox-47.0.en-US.mac.talos.tests.zip'], 12:03:14 INFO - u'web-platform': [u'firefox-47.0.en-US.mac.common.tests.zip', 12:03:14 INFO - u'firefox-47.0.en-US.mac.web-platform.tests.zip'], 12:03:14 INFO - u'webapprt': [u'firefox-47.0.en-US.mac.common.tests.zip'], 12:03:14 INFO - u'xpcshell': [u'firefox-47.0.en-US.mac.common.tests.zip', 12:03:14 INFO - u'firefox-47.0.en-US.mac.xpcshell.tests.zip']} 12:03:14 INFO - Downloading packages: [u'firefox-47.0.en-US.mac.common.tests.zip'] for test suite category: common 12:03:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:03:14 INFO - https://queue.taskcluster.net/v1/task/R_7YRjrlSkitOrHzsewm-w/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 12:03:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_7YRjrlSkitOrHzsewm-w/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 12:03:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_7YRjrlSkitOrHzsewm-w/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 12:03:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_7YRjrlSkitOrHzsewm-w/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip 12:03:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_7YRjrlSkitOrHzsewm-w/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip'}, attempt #1 12:03:15 INFO - Downloaded 17816934 bytes. 12:03:15 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 12:03:15 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 12:03:18 INFO - Return code: 0 12:03:18 INFO - Downloading packages: [u'firefox-47.0.en-US.mac.common.tests.zip', u'firefox-47.0.en-US.mac.talos.tests.zip'] for test suite category: talos 12:03:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:03:18 INFO - https://queue.taskcluster.net/v1/task/R_7YRjrlSkitOrHzsewm-w/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 12:03:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_7YRjrlSkitOrHzsewm-w/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 12:03:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_7YRjrlSkitOrHzsewm-w/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 12:03:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_7YRjrlSkitOrHzsewm-w/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip 12:03:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_7YRjrlSkitOrHzsewm-w/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip'}, attempt #1 12:03:19 INFO - Downloaded 17816934 bytes. 12:03:19 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 12:03:19 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 12:03:23 INFO - Return code: 0 12:03: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')]} 12:03:23 INFO - https://queue.taskcluster.net/v1/task/R_7YRjrlSkitOrHzsewm-w/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 12:03:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_7YRjrlSkitOrHzsewm-w/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip 12:03:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_7YRjrlSkitOrHzsewm-w/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip 12:03:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_7YRjrlSkitOrHzsewm-w/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip 12:03:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_7YRjrlSkitOrHzsewm-w/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip'}, attempt #1 12:03:24 INFO - Downloaded 10845194 bytes. 12:03:24 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 12:03:24 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 12:03:24 INFO - Return code: 0 12:03:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:03:24 INFO - https://queue.taskcluster.net/v1/task/R_7YRjrlSkitOrHzsewm-w/artifacts/public/build/firefox-47.0.en-US.mac.dmg matches https://queue.taskcluster.net 12:03:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_7YRjrlSkitOrHzsewm-w/artifacts/public/build/firefox-47.0.en-US.mac.dmg 12:03:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_7YRjrlSkitOrHzsewm-w/artifacts/public/build/firefox-47.0.en-US.mac.dmg 12:03:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_7YRjrlSkitOrHzsewm-w/artifacts/public/build/firefox-47.0.en-US.mac.dmg to /builds/slave/test/build/firefox-47.0.en-US.mac.dmg 12:03:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/R_7YRjrlSkitOrHzsewm-w/artifacts/public/build/firefox-47.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-47.0.en-US.mac.dmg'}, attempt #1 12:03:27 INFO - Downloaded 88467164 bytes. 12:03:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/R_7YRjrlSkitOrHzsewm-w/artifacts/public/build/firefox-47.0.en-US.mac.dmg 12:03:27 INFO - mkdir: /builds/slave/test/properties 12:03:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:03:27 INFO - Writing to file /builds/slave/test/properties/build_url 12:03:27 INFO - Contents: 12:03:27 INFO - build_url:https://queue.taskcluster.net/v1/task/R_7YRjrlSkitOrHzsewm-w/artifacts/public/build/firefox-47.0.en-US.mac.dmg 12:03:28 INFO - Running post-action listener: _resource_record_post_action 12:03:28 INFO - Running post-action listener: set_extra_try_arguments 12:03:28 INFO - ##### 12:03:28 INFO - ##### Running populate-webroot step. 12:03:28 INFO - ##### 12:03:28 INFO - Running pre-action listener: _resource_record_pre_action 12:03:28 INFO - Running main action method: populate_webroot 12:03:28 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:03:28 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:03:28 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:03:28 INFO - '--tppagecycles', 12:03:28 INFO - '7'], 12:03:28 INFO - 'tests': ['tsvgm']}}, 12:03:28 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 12:03:28 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 12:03:28 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:03:28 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 12:03:28 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 12:03:28 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:03:28 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:03:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:03:28 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:03:28 INFO - 'talos_options': ['--e10s'], 12:03:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:03:28 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:03:28 INFO - 'tests': ['damp', 'tps']}, 12:03:28 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:03:28 INFO - 'talos_options': ['--e10s'], 12:03:28 INFO - 'tests': ['damp', 'tps']}, 12:03:28 INFO - 'g3': {'tests': ['dromaeo_dom']}, 12:03:28 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:03:28 INFO - 'other': {'tests': ['a11yr', 12:03:28 INFO - 'ts_paint', 12:03:28 INFO - 'tpaint', 12:03:28 INFO - 'sessionrestore', 12:03:28 INFO - 'sessionrestore_no_auto_restore', 12:03:28 INFO - 'tabpaint']}, 12:03:28 INFO - 'other-e10s': {'talos_options': ['--e10s'], 12:03:28 INFO - 'tests': ['a11yr', 12:03:28 INFO - 'ts_paint', 12:03:28 INFO - 'tpaint', 12:03:28 INFO - 'sessionrestore', 12:03:28 INFO - 'sessionrestore_no_auto_restore', 12:03:28 INFO - 'tabpaint']}, 12:03:28 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 12:03:28 INFO - 'tests': ['a11yr', 12:03:28 INFO - 'ts_paint', 12:03:28 INFO - 'tpaint', 12:03:28 INFO - 'sessionrestore', 12:03:28 INFO - 'sessionrestore_no_auto_restore', 12:03:28 INFO - 'tabpaint']}, 12:03:28 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 12:03:28 INFO - 'tests': ['a11yr', 12:03:28 INFO - 'ts_paint', 12:03:28 INFO - 'tpaint', 12:03:28 INFO - 'sessionrestore', 12:03:28 INFO - 'sessionrestore_no_auto_restore', 12:03:28 INFO - 'tabpaint']}, 12:03:28 INFO - 'other_l64': {'tests': ['a11yr', 12:03:28 INFO - 'ts_paint', 12:03:28 INFO - 'tpaint', 12:03:28 INFO - 'sessionrestore', 12:03:28 INFO - 'sessionrestore_no_auto_restore', 12:03:28 INFO - 'tabpaint']}, 12:03:28 INFO - 'other_nol64': {'tests': ['a11yr', 12:03:28 INFO - 'ts_paint', 12:03:28 INFO - 'tpaint', 12:03:28 INFO - 'sessionrestore', 12:03:28 INFO - 'sessionrestore_no_auto_restore', 12:03:28 INFO - 'tabpaint']}, 12:03:28 INFO - 'svgr': {'tests': ['tsvgx', 12:03:28 INFO - 'tsvgr_opacity', 12:03:28 INFO - 'tart', 12:03:28 INFO - 'tscrollx', 12:03:28 INFO - 'cart']}, 12:03:28 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 12:03:28 INFO - 'tests': ['tsvgx', 12:03:28 INFO - 'tsvgr_opacity', 12:03:28 INFO - 'tart', 12:03:28 INFO - 'tscrollx', 12:03:28 INFO - 'cart']}, 12:03:28 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:03:28 INFO - 'tests': ['tp5o']}, 12:03:28 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:03:28 INFO - 'talos_options': ['--e10s'], 12:03:28 INFO - 'tests': ['tp5o']}, 12:03:28 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:03:28 INFO - 'talos_options': ['--xperf_path', 12:03:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:03:28 INFO - 'tests': ['tp5n']}, 12:03:28 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:03:28 INFO - 'talos_options': ['--e10s', 12:03:28 INFO - '--xperf_path', 12:03:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:03:28 INFO - 'tests': ['tp5n']}}, 12:03:28 INFO - 'talos.zip': {'path': '', 12:03:28 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:03:28 INFO - Running post-action listener: _resource_record_post_action 12:03:28 INFO - ##### 12:03:28 INFO - ##### Running create-virtualenv step. 12:03:28 INFO - ##### 12:03:28 INFO - Running pre-action listener: _resource_record_pre_action 12:03:28 INFO - Running main action method: create_virtualenv 12:03:28 INFO - Creating virtualenv /builds/slave/test/build/venv 12:03:28 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 12:03:28 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:03:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:03:28 INFO - Using real prefix '/tools/python27' 12:03:28 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:03:29 INFO - Installing distribute.............................................................................................................................................................................................done. 12:03:32 INFO - Installing pip.................done. 12:03:32 INFO - Return code: 0 12:03:32 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:03:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:03:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:03:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:03:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:03:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:03:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:03:32 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 0x1063bda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1063c44c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff19ad702e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106261d30>, '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 0x1060c5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106258b20>, '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.8YFxuWTJvw/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.WbJmsCoFWt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:03:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 12:03:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 12:03:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8YFxuWTJvw/Render', 12:03:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:03:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:03:32 INFO - 'HOME': '/Users/cltbld', 12:03:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:03:32 INFO - 'LOGNAME': 'cltbld', 12:03:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:03:32 INFO - 'MOZ_NO_REMOTE': '1', 12:03:32 INFO - 'NO_EM_RESTART': '1', 12:03:32 INFO - 'PAGER': '/bin/cat', 12:03:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:03:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:03:32 INFO - 'PWD': '/builds/slave/test', 12:03:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:03:32 INFO - 'SHELL': '/bin/bash', 12:03:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WbJmsCoFWt/Listeners', 12:03:32 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 12:03:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:03:32 INFO - 'USER': 'cltbld', 12:03:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:03:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:03:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:03:32 INFO - 'XPC_FLAGS': '0x0', 12:03:32 INFO - 'XPC_SERVICE_NAME': '0', 12:03:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:03:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:03:32 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 12:03:32 INFO - Cleaning up... 12:03:32 INFO - Return code: 0 12:03:32 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 12:03:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:03:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:03:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:03:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:03:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:03:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:03:32 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 0x1063bda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1063c44c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff19ad702e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106261d30>, '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 0x1060c5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106258b20>, '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.8YFxuWTJvw/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.WbJmsCoFWt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:03:32 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 12:03:32 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 12:03:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8YFxuWTJvw/Render', 12:03:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:03:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:03:32 INFO - 'HOME': '/Users/cltbld', 12:03:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:03:32 INFO - 'LOGNAME': 'cltbld', 12:03:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:03:32 INFO - 'MOZ_NO_REMOTE': '1', 12:03:32 INFO - 'NO_EM_RESTART': '1', 12:03:32 INFO - 'PAGER': '/bin/cat', 12:03:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:03:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:03:32 INFO - 'PWD': '/builds/slave/test', 12:03:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:03:32 INFO - 'SHELL': '/bin/bash', 12:03:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WbJmsCoFWt/Listeners', 12:03:32 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 12:03:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:03:32 INFO - 'USER': 'cltbld', 12:03:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:03:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:03:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:03:32 INFO - 'XPC_FLAGS': '0x0', 12:03:32 INFO - 'XPC_SERVICE_NAME': '0', 12:03:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:03:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:03:33 INFO - Downloading/unpacking psutil>=0.7.1 12:03:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:03:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:03:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:03:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:03:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:03:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:03:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:03:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:03:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:03:35 INFO - Installing collected packages: psutil 12:03:35 INFO - Running setup.py install for psutil 12:03:36 INFO - building 'psutil._psutil_osx' extension 12:03:36 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 12:03:36 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 12:03:36 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 12:03:36 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 12:03:36 INFO - building 'psutil._psutil_posix' extension 12:03:36 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 12:03:36 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:03:36 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:03:36 INFO - ^ 12:03:36 INFO - 1 warning generated. 12:03:36 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 12:03:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:03:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:03:37 INFO - Successfully installed psutil 12:03:37 INFO - Cleaning up... 12:03:37 INFO - Return code: 0 12:03:37 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 12:03:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:03:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:03:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:03:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:03:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:03:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:03:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1063bda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1063c44c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff19ad702e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106261d30>, '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 0x1060c5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106258b20>, '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.8YFxuWTJvw/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.WbJmsCoFWt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:03:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 12:03:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 12:03:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8YFxuWTJvw/Render', 12:03:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:03:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:03:37 INFO - 'HOME': '/Users/cltbld', 12:03:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:03:37 INFO - 'LOGNAME': 'cltbld', 12:03:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:03:37 INFO - 'MOZ_NO_REMOTE': '1', 12:03:37 INFO - 'NO_EM_RESTART': '1', 12:03:37 INFO - 'PAGER': '/bin/cat', 12:03:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:03:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:03:37 INFO - 'PWD': '/builds/slave/test', 12:03:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:03:37 INFO - 'SHELL': '/bin/bash', 12:03:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WbJmsCoFWt/Listeners', 12:03:37 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 12:03:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:03:37 INFO - 'USER': 'cltbld', 12:03:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:03:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:03:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:03:37 INFO - 'XPC_FLAGS': '0x0', 12:03:37 INFO - 'XPC_SERVICE_NAME': '0', 12:03:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:03:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:03:37 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 12:03: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 12:03: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 12:03: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 12:03: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 12:03: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 12:03: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 12:03:39 INFO - Downloading mozsystemmonitor-0.0.tar.gz 12:03:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:03:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 12:03:39 INFO - Installing collected packages: mozsystemmonitor 12:03:39 INFO - Running setup.py install for mozsystemmonitor 12:03:40 INFO - Successfully installed mozsystemmonitor 12:03:40 INFO - Cleaning up... 12:03:40 INFO - Return code: 0 12:03:40 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:03: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')]} 12:03:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:03:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:03: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')]} 12:03:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:03:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:03:40 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 0x1063bda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1063c44c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff19ad702e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106261d30>, '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 0x1060c5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106258b20>, '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.8YFxuWTJvw/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.WbJmsCoFWt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:03:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:03:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 12:03:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8YFxuWTJvw/Render', 12:03:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:03:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:03:40 INFO - 'HOME': '/Users/cltbld', 12:03:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:03:40 INFO - 'LOGNAME': 'cltbld', 12:03:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:03:40 INFO - 'MOZ_NO_REMOTE': '1', 12:03:40 INFO - 'NO_EM_RESTART': '1', 12:03:40 INFO - 'PAGER': '/bin/cat', 12:03:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:03:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:03:40 INFO - 'PWD': '/builds/slave/test', 12:03:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:03:40 INFO - 'SHELL': '/bin/bash', 12:03:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WbJmsCoFWt/Listeners', 12:03:40 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 12:03:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:03:40 INFO - 'USER': 'cltbld', 12:03:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:03:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:03:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:03:40 INFO - 'XPC_FLAGS': '0x0', 12:03:40 INFO - 'XPC_SERVICE_NAME': '0', 12:03:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:03:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:03:40 INFO - Downloading/unpacking blobuploader==1.2.4 12:03:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:03:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:03:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:03:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:03:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:03:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:03:42 INFO - Downloading blobuploader-1.2.4.tar.gz 12:03:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:03:42 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:03:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:03:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:03:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:03:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:03:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:03:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:03:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:03:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:03: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 12:03: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 12:03: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 12:03: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 12:03: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 12:03: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 12:03:44 INFO - Downloading docopt-0.6.1.tar.gz 12:03:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:03:44 INFO - Installing collected packages: blobuploader, requests, docopt 12:03:44 INFO - Running setup.py install for blobuploader 12:03:44 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:03:44 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:03:44 INFO - Running setup.py install for requests 12:03:44 INFO - Running setup.py install for docopt 12:03:44 INFO - Successfully installed blobuploader requests docopt 12:03:44 INFO - Cleaning up... 12:03:45 INFO - Return code: 0 12:03:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:03:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:03:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:03:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:03:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:03:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:03:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:03:45 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 0x1063bda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1063c44c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff19ad702e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106261d30>, '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 0x1060c5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106258b20>, '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.8YFxuWTJvw/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.WbJmsCoFWt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:03:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 12:03:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:03:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8YFxuWTJvw/Render', 12:03:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:03:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:03:45 INFO - 'HOME': '/Users/cltbld', 12:03:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:03:45 INFO - 'LOGNAME': 'cltbld', 12:03:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:03:45 INFO - 'MOZ_NO_REMOTE': '1', 12:03:45 INFO - 'NO_EM_RESTART': '1', 12:03:45 INFO - 'PAGER': '/bin/cat', 12:03:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:03:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:03:45 INFO - 'PWD': '/builds/slave/test', 12:03:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:03:45 INFO - 'SHELL': '/bin/bash', 12:03:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WbJmsCoFWt/Listeners', 12:03:45 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 12:03:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:03:45 INFO - 'USER': 'cltbld', 12:03:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:03:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:03:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:03:45 INFO - 'XPC_FLAGS': '0x0', 12:03:45 INFO - 'XPC_SERVICE_NAME': '0', 12:03:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:03:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:03:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:03:45 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-kwJNVg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:03:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:03:45 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-efyw32-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:03:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:03:45 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-_zoGMv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:03:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:03:45 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-DhPQmI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:03:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:03:45 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-2BJc6C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:03:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:03:45 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-efCMfJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:03:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:03:45 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-Pjz0Eu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:03:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:03:45 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-BQgcM1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:03:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:03:46 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-t0S3Pm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:03:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:03:46 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-wGatsB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:03:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:03:46 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-Rco8Og-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:03:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:03:46 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-xn2iVc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:03:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:03:46 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-jUbRwf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:03:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:03:46 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-LbD9gb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:03:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:03:46 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-C5Rm9n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:03:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:03:46 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-zCICir-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:03:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:03:46 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-1knKAF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:03:47 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:03:47 INFO - Running setup.py install for manifestparser 12:03:47 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:03:47 INFO - Running setup.py install for mozcrash 12:03:47 INFO - Running setup.py install for mozdebug 12:03:47 INFO - Running setup.py install for mozdevice 12:03:47 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:03:47 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:03:47 INFO - Running setup.py install for mozfile 12:03:47 INFO - Running setup.py install for mozhttpd 12:03:47 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:03:47 INFO - Running setup.py install for mozinfo 12:03:48 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:03:48 INFO - Running setup.py install for mozInstall 12:03:48 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:03:48 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:03:48 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:03:48 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:03:48 INFO - Running setup.py install for mozleak 12:03:48 INFO - Running setup.py install for mozlog 12:03:48 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:03:48 INFO - Running setup.py install for moznetwork 12:03:48 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:03:48 INFO - Running setup.py install for mozprocess 12:03:48 INFO - Running setup.py install for mozprofile 12:03:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:03:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:03:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:03:48 INFO - Running setup.py install for mozrunner 12:03:49 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:03:49 INFO - Running setup.py install for mozscreenshot 12:03:49 INFO - Running setup.py install for moztest 12:03:49 INFO - Running setup.py install for mozversion 12:03:49 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:03:49 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:03:49 INFO - Cleaning up... 12:03:49 INFO - Return code: 0 12:03:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:03:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:03:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:03:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:03:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:03:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:03:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:03:49 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 0x1063bda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1063c44c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff19ad702e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106261d30>, '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 0x1060c5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106258b20>, '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.8YFxuWTJvw/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.WbJmsCoFWt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:03:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 12:03:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:03:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8YFxuWTJvw/Render', 12:03:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:03:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:03:49 INFO - 'HOME': '/Users/cltbld', 12:03:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:03:49 INFO - 'LOGNAME': 'cltbld', 12:03:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:03:49 INFO - 'MOZ_NO_REMOTE': '1', 12:03:49 INFO - 'NO_EM_RESTART': '1', 12:03:49 INFO - 'PAGER': '/bin/cat', 12:03:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:03:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:03:49 INFO - 'PWD': '/builds/slave/test', 12:03:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:03:49 INFO - 'SHELL': '/bin/bash', 12:03:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WbJmsCoFWt/Listeners', 12:03:49 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 12:03:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:03:49 INFO - 'USER': 'cltbld', 12:03:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:03:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:03:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:03:49 INFO - 'XPC_FLAGS': '0x0', 12:03:49 INFO - 'XPC_SERVICE_NAME': '0', 12:03:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:03:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:03:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:03:49 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-dOMXsO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:03:49 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 12:03:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:03:49 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-Yaum33-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:03:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:03:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:03:50 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-jNmtCf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:03:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 12:03:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:03:50 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-jB3swv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:03:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:03:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:03:50 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-Mm9dEJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:03:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 12:03:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:03:50 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-YJ0dW3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:03:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 12:03:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:03:50 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-tNQ5Lo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:03:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 12:03:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:03:50 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-NljdQJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:03:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 12:03:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:03:50 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-wPPiVR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:03:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 12:03:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:03:50 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-t04qw2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:03:50 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)) 12:03:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:03:50 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-BazBPI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:03:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 12:03:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:03:50 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-1ChKbM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:03:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 12:03:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:03:51 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-u66Bh9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:03:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 12:03:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:03:51 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-x9XQx9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:03:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 12:03:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:03:51 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-IJPSqq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:03:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 12:03:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:03:51 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-3H_Mos-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:03:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 12:03:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:03:51 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-TrYv93-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:03:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 12:03:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:03:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:03:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 12:03:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:03:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:03:51 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:03:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:03:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:03:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:03:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:03:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:03:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:03:53 INFO - Downloading blessings-1.6.tar.gz 12:03:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:03:54 INFO - Installing collected packages: blessings 12:03:54 INFO - Running setup.py install for blessings 12:03:54 INFO - Successfully installed blessings 12:03:54 INFO - Cleaning up... 12:03:54 INFO - Return code: 0 12:03:54 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:03:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:03:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:03:54 INFO - Reading from file tmpfile_stdout 12:03:54 INFO - Current package versions: 12:03:54 INFO - blessings == 1.6 12:03:54 INFO - blobuploader == 1.2.4 12:03:54 INFO - docopt == 0.6.1 12:03:54 INFO - manifestparser == 1.1 12:03:54 INFO - mozInstall == 1.12 12:03:54 INFO - mozcrash == 0.17 12:03:54 INFO - mozdebug == 0.1 12:03:54 INFO - mozdevice == 0.48 12:03:54 INFO - mozfile == 1.2 12:03:54 INFO - mozhttpd == 0.7 12:03:54 INFO - mozinfo == 0.9 12:03:54 INFO - mozleak == 0.1 12:03:54 INFO - mozlog == 3.1 12:03:54 INFO - moznetwork == 0.27 12:03:54 INFO - mozprocess == 0.22 12:03:54 INFO - mozprofile == 0.28 12:03:54 INFO - mozrunner == 6.11 12:03:54 INFO - mozscreenshot == 0.1 12:03:54 INFO - mozsystemmonitor == 0.0 12:03:54 INFO - moztest == 0.7 12:03:54 INFO - mozversion == 1.4 12:03:54 INFO - psutil == 3.1.1 12:03:54 INFO - requests == 1.2.3 12:03:54 INFO - wsgiref == 0.1.2 12:03:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:03:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:03:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:03:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:03:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:03:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:03:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:03:54 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 0x1063bda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1063c44c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff19ad702e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106261d30>, '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 0x1060c5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106258b20>, '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.8YFxuWTJvw/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.WbJmsCoFWt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:03:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 12:03:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:03:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8YFxuWTJvw/Render', 12:03:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:03:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:03:54 INFO - 'HOME': '/Users/cltbld', 12:03:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:03:54 INFO - 'LOGNAME': 'cltbld', 12:03:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:03:54 INFO - 'MOZ_NO_REMOTE': '1', 12:03:54 INFO - 'NO_EM_RESTART': '1', 12:03:54 INFO - 'PAGER': '/bin/cat', 12:03:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:03:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:03:54 INFO - 'PWD': '/builds/slave/test', 12:03:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:03:54 INFO - 'SHELL': '/bin/bash', 12:03:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WbJmsCoFWt/Listeners', 12:03:54 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 12:03:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:03:54 INFO - 'USER': 'cltbld', 12:03:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:03:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:03:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:03:54 INFO - 'XPC_FLAGS': '0x0', 12:03:54 INFO - 'XPC_SERVICE_NAME': '0', 12:03:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:03:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:03:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 12:03:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 12:03:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 12:03:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 12:03:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 12:03:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 12:03:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 12:03:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 12:03:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 12:03:54 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 12:03:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 12:03:54 INFO - Cleaning up... 12:03:54 INFO - Return code: 0 12:03:54 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:03:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:03:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:03:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:03:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:03:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:03:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:03:54 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 0x1063bda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1063c44c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff19ad702e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106261d30>, '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 0x1060c5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106258b20>, '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.8YFxuWTJvw/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.WbJmsCoFWt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:03:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 12:03:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 12:03:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8YFxuWTJvw/Render', 12:03:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:03:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:03:54 INFO - 'HOME': '/Users/cltbld', 12:03:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:03:54 INFO - 'LOGNAME': 'cltbld', 12:03:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:03:54 INFO - 'MOZ_NO_REMOTE': '1', 12:03:54 INFO - 'NO_EM_RESTART': '1', 12:03:54 INFO - 'PAGER': '/bin/cat', 12:03:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:03:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:03:54 INFO - 'PWD': '/builds/slave/test', 12:03:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:03:54 INFO - 'SHELL': '/bin/bash', 12:03:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WbJmsCoFWt/Listeners', 12:03:54 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 12:03:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:03:54 INFO - 'USER': 'cltbld', 12:03:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:03:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:03:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:03:54 INFO - 'XPC_FLAGS': '0x0', 12:03:54 INFO - 'XPC_SERVICE_NAME': '0', 12:03:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:03:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:03:55 INFO - Downloading/unpacking jsonschema 12:03:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:03:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:03:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:03:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:03:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:03:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:03:57 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:03:57 INFO - Downloading/unpacking functools32 (from jsonschema) 12:03:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:03:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:03:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:03:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:03:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:03:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:03:57 INFO - Downloading functools32-3.2.3-2.tar.gz 12:03:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:03:57 INFO - warning: no files found matching '*.txt' 12:03:57 INFO - no previously-included directories found matching 'build' 12:03:57 INFO - no previously-included directories found matching 'dist' 12:03:57 INFO - no previously-included directories found matching '.git*' 12:03:58 INFO - Installing collected packages: jsonschema, functools32 12:03:58 INFO - Running setup.py install for functools32 12:03:58 INFO - warning: no files found matching '*.txt' 12:03:58 INFO - no previously-included directories found matching 'build' 12:03:58 INFO - no previously-included directories found matching 'dist' 12:03:58 INFO - no previously-included directories found matching '.git*' 12:03:58 INFO - Successfully installed jsonschema functools32 12:03:58 INFO - Cleaning up... 12:03:58 INFO - Return code: 0 12:03:58 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 12:03:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:03:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:03:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:03:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:03:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:03:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:03:58 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 0x1063bda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1063c44c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff19ad702e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106261d30>, '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 0x1060c5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106258b20>, '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.8YFxuWTJvw/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.WbJmsCoFWt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:03:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 12:03:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 12:03:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8YFxuWTJvw/Render', 12:03:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:03:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:03:58 INFO - 'HOME': '/Users/cltbld', 12:03:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:03:58 INFO - 'LOGNAME': 'cltbld', 12:03:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:03:58 INFO - 'MOZ_NO_REMOTE': '1', 12:03:58 INFO - 'NO_EM_RESTART': '1', 12:03:58 INFO - 'PAGER': '/bin/cat', 12:03:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:03:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:03:58 INFO - 'PWD': '/builds/slave/test', 12:03:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:03:58 INFO - 'SHELL': '/bin/bash', 12:03:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WbJmsCoFWt/Listeners', 12:03:58 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 12:03:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:03:58 INFO - 'USER': 'cltbld', 12:03:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:03:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:03:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:03:58 INFO - 'XPC_FLAGS': '0x0', 12:03:58 INFO - 'XPC_SERVICE_NAME': '0', 12:03:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:03:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:03:58 INFO - Downloading/unpacking flake8 12:03:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:03:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:03:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:03:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:03:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:03:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:04:01 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 12:04:01 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 12:04:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:04:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:04:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:04:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:04:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:04:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:04:01 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 12:04:01 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 12:04:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:04:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:04:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:04:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:04:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:04:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:04:01 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 12:04:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:04:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:04:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:04:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:04:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:04:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:04:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 12:04:02 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 12:04:02 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 12:04:02 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 12:04:02 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 12:04:02 INFO - no previously-included directories found matching 'docs/_build' 12:04:02 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 12:04:02 INFO - Running setup.py install for pep8 12:04:02 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 12:04:02 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 12:04:02 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 12:04:02 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 12:04:02 INFO - no previously-included directories found matching 'docs/_build' 12:04:02 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 12:04:02 INFO - Successfully installed flake8 mccabe pyflakes pep8 12:04:02 INFO - Cleaning up... 12:04:02 INFO - Return code: 0 12:04:02 INFO - Running post-action listener: _resource_record_post_action 12:04:02 INFO - Running post-action listener: _start_resource_monitoring 12:04:02 INFO - Starting resource monitoring. 12:04:02 INFO - ##### 12:04:02 INFO - ##### Running install step. 12:04:02 INFO - ##### 12:04:02 INFO - Running pre-action listener: _resource_record_pre_action 12:04:02 INFO - Running main action method: install 12:04:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:04:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:04:03 INFO - Reading from file tmpfile_stdout 12:04:03 INFO - Detecting whether we're running mozinstall >=1.0... 12:04:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:04:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:04:03 INFO - Reading from file tmpfile_stdout 12:04:03 INFO - Output received: 12:04:03 INFO - Usage: mozinstall [options] installer 12:04:03 INFO - Options: 12:04:03 INFO - -h, --help show this help message and exit 12:04:03 INFO - -d DEST, --destination=DEST 12:04:03 INFO - Directory to install application into. [default: 12:04:03 INFO - "/builds/slave/test"] 12:04:03 INFO - --app=APP Application being installed. [default: firefox] 12:04:03 INFO - mkdir: /builds/slave/test/build/application 12:04:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-47.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 12:04:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-47.0.en-US.mac.dmg --destination /builds/slave/test/build/application 12:04:25 INFO - Reading from file tmpfile_stdout 12:04:25 INFO - Output received: 12:04:25 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 12:04:25 INFO - Running post-action listener: _resource_record_post_action 12:04:25 INFO - ##### 12:04:25 INFO - ##### Running run-tests step. 12:04:25 INFO - ##### 12:04:25 INFO - Running pre-action listener: _resource_record_pre_action 12:04:25 INFO - Running main action method: run_tests 12:04:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:04:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:04:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:04:25 INFO - 2016-05-26 12:04:25.550 screenresolution[1638:7320] starting screenresolution argv=screenresolution get 12:04:25 INFO - 2016-05-26 12:04:25.553 screenresolution[1638:7320] Display 0: 1600x1200x32@60 12:04:25 INFO - 2016-05-26 12:04:25.558 screenresolution[1639:7322] starting screenresolution argv=screenresolution list 12:04:25 INFO - Available Modes on Display 0 12:04:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:04:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:04:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:04:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:04:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:04:25 INFO - Intel Iris: 12:04:25 INFO - Chipset Model: Intel Iris 12:04:25 INFO - Type: GPU 12:04:25 INFO - Bus: Built-In 12:04:25 INFO - VRAM (Dynamic, Max): 1536 MB 12:04:25 INFO - Vendor: Intel (0x8086) 12:04:25 INFO - Device ID: 0x0a2e 12:04:25 INFO - Revision ID: 0x0009 12:04:25 INFO - Displays: 12:04:25 INFO - Display: 12:04:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:04:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:04:25 INFO - Display Serial Number: 1600x1200 60 12:04:25 INFO - Main Display: Yes 12:04:25 INFO - Mirror: Off 12:04:25 INFO - Online: Yes 12:04:25 INFO - Rotation: Supported 12:04:25 INFO - Return code: 0 12:04:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:04:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:04:25 INFO - Python 2.7.3 12:04:25 INFO - Return code: 0 12:04:25 INFO - grabbing minidump binary from tooltool 12:04:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:04:25 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 0x106261d30>, '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 0x1060c5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106258b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:04:25 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 12:04:25 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 12:04:25 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:04:25 INFO - Return code: 0 12:04:25 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:04:25 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:04:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:04:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:04:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:04:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 12:04:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 12:04:27 INFO - Return code: 0 12:04:27 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/R_7YRjrlSkitOrHzsewm-w/artifacts/public/build/firefox-47.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0030', '--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 12:04:27 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/R_7YRjrlSkitOrHzsewm-w/artifacts/public/build/firefox-47.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0030 --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 12:04:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8YFxuWTJvw/Render', 12:04:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:04:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:04:27 INFO - 'HOME': '/Users/cltbld', 12:04:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:04:27 INFO - 'LOGNAME': 'cltbld', 12:04:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:04:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:04:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:04:27 INFO - 'MOZ_NO_REMOTE': '1', 12:04:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:04:27 INFO - 'NO_EM_RESTART': '1', 12:04:27 INFO - 'PAGER': '/bin/cat', 12:04:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:04:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:04:27 INFO - 'PWD': '/builds/slave/test', 12:04:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:04:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:04:27 INFO - 'SHELL': '/bin/bash', 12:04:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WbJmsCoFWt/Listeners', 12:04:27 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 12:04:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:04:27 INFO - 'USER': 'cltbld', 12:04:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:04:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:04:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:04:27 INFO - 'XPC_FLAGS': '0x0', 12:04:27 INFO - 'XPC_SERVICE_NAME': '0', 12:04:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:04:27 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/R_7YRjrlSkitOrHzsewm-w/artifacts/public/build/firefox-47.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0030', '--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 12:04:27 INFO - mozversion application_buildid: 20160526100338 12:04:27 INFO - mozversion application_changeset: 388772a2f7620727b3125c722c142abee8a74aa0 12:04:27 INFO - mozversion application_display_name: Firefox 12:04:27 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:04:27 INFO - mozversion application_name: Firefox 12:04:27 INFO - mozversion application_remotingname: firefox 12:04:27 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 12:04:27 INFO - mozversion application_vendor: Mozilla 12:04:27 INFO - mozversion application_version: 47.0 12:04:27 INFO - mozversion platform_buildid: 20160526100338 12:04:27 INFO - mozversion platform_changeset: 388772a2f7620727b3125c722c142abee8a74aa0 12:04:27 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 12:04:27 INFO - mozversion platform_version: 47.0 12:04:27 INFO - using testdate: 1464289467 12:04:27 INFO - actual date: 1464289467 12:04:27 INFO - starting webserver on 'localhost:49270' 12:04:27 INFO - SUITE-START | Running 2 tests 12:04:27 INFO - TEST-START | dromaeo_css 12:04:27 INFO - operating with platform_type : mac_ 12:04:27 INFO - Initialising browser for dromaeo_css test... 12:04:27 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/tmpzzGdcd/profile http://localhost:49270/getInfo.html) 12:04:29 INFO - PROCESS | 1657 | __metrics Screen width/height:1600/1200 12:04:29 INFO - PROCESS | 1657 | colorDepth:24 12:04:29 INFO - PROCESS | 1657 | Browser inner width/height: 1024/689 12:04:29 INFO - PROCESS | 1657 | __metrics 12:04:29 INFO - PROCESS | 1657 | 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" 12:04:29 INFO - PROCESS | 1657 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:04:30 INFO - TEST-INFO | 1657: exit 0 12:04:30 INFO - Browser initialized. 12:04:30 INFO - Running cycle 1/1 for dromaeo_css test... 12:04:30 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/tmpzzGdcd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:04:42 INFO - PROCESS | 1660 | RSS: Main: 157179904 12:04:42 INFO - PROCESS | 1660 | 12:05:32 INFO - PROCESS | 1660 | 1464289532917 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 12:07:42 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49270/tests/dromaeo/cssquery-ext.html) 12:07:44 INFO - PROCESS | 1660 | RSS: Main: 296960000 12:07:44 INFO - PROCESS | 1660 | 12:10:46 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-ext.html (next: http://localhost:49270/tests/dromaeo/cssquery-jquery.html) 12:10:46 INFO - PROCESS | 1660 | RSS: Main: 292638720 12:10:46 INFO - PROCESS | 1660 | 12:13:48 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49270/tests/dromaeo/cssquery-mootools.html) 12:13:49 INFO - PROCESS | 1660 | RSS: Main: 297263104 12:13:49 INFO - PROCESS | 1660 | 12:16:51 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49270/tests/dromaeo/cssquery-prototype.html) 12:16:51 INFO - PROCESS | 1660 | RSS: Main: 256847872 12:16:51 INFO - PROCESS | 1660 | 12:19:54 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49270/tests/dromaeo/cssquery-yui.html) 12:19:55 INFO - PROCESS | 1660 | RSS: Main: 257392640 12:19:55 INFO - PROCESS | 1660 | 12:22:58 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-yui.html (next: http://localhost:49270/tests/dromaeo/cssquery-dojo.html) 12:22:58 INFO - PROCESS | 1660 | RSS: Main: 303149056 12:22:58 INFO - PROCESS | 1660 | 12:22:58 INFO - PROCESS | 1660 | __start_tp_report 12:22:58 INFO - PROCESS | 1660 | _x_x_mozilla_page_load 12:22:58 INFO - PROCESS | 1660 | _x_x_mozilla_page_load_details 12:22:58 INFO - PROCESS | 1660 | |i|pagename|runs| 12:22:58 INFO - PROCESS | 1660 | |0;dojo.html;3587.0673624857536;3609.3322735293955;3624.2026754114104;3644.4533320001997;3691.53855768029;4643.791029403681;5624.818788054508;5799.623024503404;5819.709014549277;5845.50313223376;5774.913376299359;5820.38885916979;5833.270841144857;5842.824715258538;5852.356240813507;5246.921296180561;5257.76340064698;5264.078786212417;5320.76056577453;5368.194770784394;5395.082835917903;5411.269478620376;5411.269478620376;5419.024575576394;5422.4306447823;4283.421738065368;5348.812791552296;5360.678359298435;5368.167933970221;5408.4591540845995;5404.189371594253;5422.349318081816;5435.701036442994;5452.482014208644;5489.204065410533;5167.457416971239;5353.437889021636;5357.785688572452;5360.678359298416;5362.142057270857;4799.856004319876;5739.024365857814;5764.164196191528;5776.682282474462;5814.796482123148;6235.158253635746;6275.09011193379;6289.622622642611;6295.150154729099;6297.27581328144;6189.2572891252885;6234.189555357774;6251.937480625226;6266.592671476339;6279.372062793684;6061.121137435135;6069.423404776539;6075.119107729354;6079.361667024988;6082;6208.099825525273;6305.8108256752375;6397.904031439532;6398.136251606066;6420.935790642127;6277.058441233851;6279.183706118175;6298.149749783812;6313.463355614732;6333.20834895638;6262.530310226751;6268.655223962634;6286.937130628727;6291.6854157291955;6301.275353334334;6180.4437600615265;6204.162438070893;6211.81364559064;6289.685515724195;6300.621962682254;6246.562740608205;6258.3428739982555;6261;6267.310595834455;6293.590916590407;5039.899202015939;5042.09242336383;5053.570446512014;5058.4435712071645;5060.987802439527;4453.487848897354;4552.521985191574;4565.863024109282;4601.079784043205;4602.884927876763;5932.644041357532;5954.880902382015;5999.610025348338;6001.279846418458;6005.099235114681;6212.9068063979075;6267.1539342189135;6268.843278917972;6271.090691849748;6272.5922815016875;6018;6041.426064523863;6064.151018857364;6065.211522502046;6086.786962456293;5524.972375138179;5553.666779993213;5564.805231816867;5570.498655120978;5581.358143813432;6321.051842223703;6426.260979987362;6483.578567393104;6522.315156908551;6537.280899101095;6293.716782744879;6301.6534090625455;6301.968490157703;6310.810675679737;6331.841703957253;6538.607683539003;6538.738450461929;6554.180727409074;6556.770513032021;6564.573302735307;6141.424638644238;6196.256449226122;6197.81406557804;6226.844328891814;6310.873782524415;6127.417895300117;6286.119943207863;6302.716377763103;6303;6304.3380445054445;6201.658908760061;6212.627242365473;6220.098085777628;6222.937770622236;6230.190075290183;6106.664133472702;6175.228096487939;6189.907151392823;6194.566380353332;6198.194234749454;6136.539759517965;6138.785142520169;6155.230596175478;6172.6913654318005;6176.382361763787;6131.84670383244;6168.321484636596;6202.534809889186;6220.782272620437;6233.127362169391;6111.786087486917;6129.970128114858;6183.350748171377;6188.226471691039;6190.288116866438 12:22:58 INFO - PROCESS | 1660 | |1;ext.html;486.1929197532093;594.3551246897116;599.3347384403312;616.5191150902295;629.6127881352968;31502;41312.58687413129;41338.58661413385;41448.792756036215;41473.75578732635;44839.327410088874;45310.54689453104;45495;45514.089718205614;45642.543574564246;9617.567209475574;9866.963968783282;9880.25898057645;9886.851697224547;9895;9658.951705241463;9820.263480238975;9828.115469607734;9838.311318207729;9848.704538863845;9821.165200957927;9884.406935583853;9884.406935583853;9892.208623310155;9912.15746661531;9760.560774765152;9846.556904939294;9863.605455781795;9967.700968970943;9976.800463990752;9671.661491847823;9806.950965245129;9826.950865245628;9834.114929656365;9856.90143098567;43217.13565728699;43611;43664.3450348245;43669.34495982563;43685.56314436878;3263.526788615661;3282.3107147499054;3430.7255419566623;3753.699704023699;3843.077661361281;29268;29429.96995105161;29829.254268643457;30345.544816827765;30700.38599228003;2752.0642981386427;2759.379139693573;2807.4806160860308;2891.34944637457;2892.6673432555103;431280;433885;433919.8304008491;434433;435341;48479.75760121177;49037.26444103341;49213.753931230116;74394;74614.38849640236;47456.05087898292;47923.281150782765;48010.51989480061;48028.5197148031;48144.796380090775;17563.56091097733;17699;17700.822991769917;17705.557361066076;17721.822781772018;11397.943010285062;13473.865261347457;13514.86485135136;13521.391537380841;13523.39144738489;15280.31238594266;15335.846641533664;15625.687486250212;15720.21398930049;15767.211639417983;6366.10342294255;6426.4537514311805;6470.547061705635;6487.22153341602;6516.152900122954;964.9372790768718;1042.3589492462233;1103.7240689827543;1110.2062025651699;1137.5961533655598;30733;30852.691473084982;31001.379972400424;31486.685133148832;31687.890923818017;1177.60550215677;1182.1193211057816;1228.6314105768413;1230.4462991653613;1280.295837289487;15467.071975681494;15806;15831.841681583268;16011.679766404839;16145.677086458205;547789;554749;557090;558747;559988.2000589971;63279.367206328265;63890.36109638937;64016.67991660012;64067.67966160233;64123.35876641174;1298.5455090718174;1405.156905856478;1501.7522108851917;1516.7194069097252;1534.1102160746516;29004.41991160207;29694.703052969195;29718.55422168626;29922.850385747934;30455.847720761256;48267.5173248263;48853;49276.507234927194;49350.50649493459;49383;358693;369105;369987;370871.2912870837;371274;30632.387352253278;35002.824985874904;35008.949731508095;35991.64008359883;36001.459978099825;49346.753266234875;49736.005279894925;50290.49709503005;51578.74210628923;51702.22446663379;37028.62971370252;37376.439353410264;39845;40370.59629403668;41351.172976539696;32042.679573203968;33265;37921;38580.80709596434;39253.41119883147;38253.61746382501;38498.6150138495;38836.80581597187;38911.6108838908;39553.01117471971;33177.83411082929;33515.25168145513;35047.64952350444;35229.471557927165;35276.64723352836;25535.74464255408;25839;25899.482010360065;25920.96316147333;26314.34214144585 12:22:58 INFO - PROCESS | 1660 | |2;jquery.html;375.83463276158494;378.9886303410897;379.88793305974724;384.93840985442336;385.6509858577988;20504.282350117748;21078.6838197427;21676;21969.890150549265;22040.559188816234;12186.390680465985;12725.936370318135;12791.36043197841;12795.488180472794;12799.680007999805;4729.432468103823;6132.5093992480715;6134.4478996890275;6138.631682099066;6139.846503837395;6015.398460153971;6020.367861374559;6129.908051379233;6130.295016073164;6131.264248290211;5818.476337129658;5975.372585878486;5999.490043346321;6022.187004754368;6028.065649824284;5842.269716285465;5980.401959804029;5996.910046349309;5997.88004239915;6000.489958353545;5065.848024559269;5187.3775146982225;5214.661047031968;5214.8174813881715;5226.869328266824;12910.483580656857;12916.41876115577;12931.224126552437;18256.9045857249;26361.604575931375;2100.1809294375053;2106.6208082545136;2174.967375489369;2175.3147758456034;2204.7244094488187;10257.281990260684;10282.691519254435;10548.789024219474;13760.380782864795;14471.493497727633;1678.8740844436593;1730.2732852202105;1760.9383671571434;1790.650823089498;1812.8578995233052;85549.71675424873;109665;109776.90223097826;113578.29632555436;113601;11212.719682008015;11555.01782348493;11717.531298748125;11725.237859539102;11806.40967951599;14521.419143234152;14532.636684082983;14609.78085328721;14612.26938653063;14641.121532708072;9054;9195.862062069074;9201.585928633227;9274;9290;9550.95224523873;9565.378250413702;9625.51872406377;9697.272704547187;9708.660196893075;4517.706349087299;4595.839145629887;4599.9080018399445;4635.513271106553;4644.814207431732;1701.4810482802864;1723.8189990051114;1838.5219842840959;1912.6079153773444;1931.5364312565164;859.8280343931239;946.2004606107828;949.8765160529088;996.7209181429212;1016.8830584482879;18168.45568126724;20551.588968220552;20847.37457876266;21621.35135945924;21797.237096701538;998.2962011781822;1006.1246715357686;1013.8327176016022;1020.2042406922612;1032.808930347888;11161.162912781412;11766.94116529429;11775.705607359885;11781.587644432575;11915.70210744721;657568;677855;681780.5910970513;698284;702665;111677;127332;127753;130525;132721;1048.2086025051037;1054.7099547624464;1069.764651776608;1070.3952266969138;1070.4808168038496;15321.923390382977;18670.346537871108;19231.846089234692;19469.318573849847;19833.603327933648;45953.540464594924;49429.50570494249;52649.210261845335;53497;53566.46433535615;73086;73290.26709732834;75098.49803004018;76248.0937976555;77706.2229377699;8069.273765361146;8190.467619604707;8354.749357519284;8355.498670079814;8569.014563325049;12957.22256664603;13418.261995590336;13512.121712088683;13765.311734413439;13830.23933683657;11532.88467115318;11665.125115616533;11819.621987306435;11957.94021029924;12189.75620487603;11666.591669291534;11749.941250294036;11779.175457717878;11986.340751258764;12104.818427723767;10230.590776368861;11631.6510504685;11967.461464233957;12055.879441205476;12227;8223.506589604644;8354.206350396702;8383.077861435358;8395.664173432995;8829.16122968317;8785.868211976698;11260.436978150896;12016.939915300718;12142.635720928387;12184.57353992606 12:22:58 INFO - PROCESS | 1660 | |3;mootools.html;1480.5928369698324;1614.6205641674208;1639.483562677756;1968.5373937124773;2048.4059622709415;8300.045494768103;8975.281977441806;9125.817483650322;9136.49749263791;9147.268218542516;8861.73414797557;9140.588673509692;9155.404898681598;9165.037671044527;9170.403923744952;2546.808989325799;2575.1502004338618;2578.1105518596055;2579.1746641074865;2581.1482210870463;2510.987445062772;2580.7677309042183;2586.314626623946;2586.469773696397;2587.469568738404;2542.3898264416594;2558.475512519929;2583.1217386088724;2584.25056733548;2586.612008198776;2541.8601976891264;2562.2185233503774;2569.922902312924;2580.6258092576472;2584.6252293417538;2531.6835395575554;2567.9871600642064;2568.0883286433423;2583.3799888026924;2593.8573378464175;9135.634574617008;9201.585928633227;9213;9227.984921658612;9228.861567076432;5587.916181257285;5736.053551164053;5770.2498675171955;5784.68184249866;5786.900488907094;3101.6122984626923;3598.4062629666337;3674.063113905937;3707.3512135376473;3766.8681596144;1054.6783231114507;1075.9946200269028;1082.410086502858;1082.9512671929704;1109.1736656191108;12334.074944379117;12517.874821251762;12557;12574.308413037279;12584.119111662187;4296.742195468282;5028.673136246132;5028.85123228573;5060.139776237975;5078.187490001582;10544.841827372595;10547.261691681508;10549.20880933933;10553.419561924014;10562.049415552483;5737.598368114275;5753.712314384264;5761.049426844524;5764.99112655292;5770.538356931519;4640.79339371759;4808.08646357191;4986.601071914238;5182.689038657617;5226.189940559219;4001.139754952699;4065.89835254121;4076.5515793262716;4081.6326530612387;4126.979365103215;1328.2362641480993;1337.1480499508252;1342.4227582139579;1353.0461024977565;1357.3552562532718;1381.3507651404025;1401.5444980381212;1407.9929600351932;1428.5428662827796;1429.4425174182077;8445.831083378422;8510.872336914952;8593.613287402082;8602.31181505478;8639.308855291561;528.9308393323283;540.2868213957616;543.3995435044219;549.8103154411722;551.0769461152554;6290.24517057956;6308.8422789429715;6349.523785715997;6359.8092057238355;6361.236651601837;10438.63464778729;10911.563537458413;10933.835992460119;10936.890631093745;10960.671179864617;10351.585936562604;10730.78538429227;10807.243492955575;10829.891701082888;10830.458477076272;8503.829923401498;8512.404131710844;8572.914270857336;8594.484330940164;8611.61247743853;8022.237887400921;8027.799305017421;8110.861423220974;8307.916920830714;8369.539675317916;9407.905920940977;9711.514424278617;9825.754356141153;9911.45486998222;9950.850737239092;9887;10060.49697515136;10169.745756356151;10236.283460157718;10301.072903438726;8311.376646751638;8359;8401.033881103507;8492.660293588187;8502.78743031429;7201.819954501179;8529.658813647384;8529.701460448854;8595.52724600153;8609.741707748777;7518.774436766687;7660.502067365604;7720.420968427503;7782.455228134203;7819.218078192363;7322.121345438582;7510.098788145458;7534.35957943581;7673.117591477052;7702;7002.964985175042;8007.239312265326;8054.872317875619;8141.79645508867;8167.509949403055;7184.856302874018;7234.421246300178;7342.853142937004;7382.966384706253;7396.593187374746;7482.625868706652;7510.549367037996;7541.35898448638;7554.244575542401;7559.244075592397 12:22:58 INFO - PROCESS | 1660 | |4;prototype.html;333.76969890775365;340.3193612774451;342.9434143366345;343.4059077795414;349.3223147094636;6613.9669301653485;6722.126123603937;6734.562253453528;6736.629485378302;6742.5617334873195;6611.504137189718;6649.135612370385;6656.933430665692;6660.933390666092;6663.267040625527;4769.5707386335225;4859.635527335447;4908.018396320732;4969.180085285923;4996.9500304996845;3916.118873253524;3969.3847453644585;4704.176769065417;4970.2296144097545;4976.377952755905;4267.935980960273;4927.753612319388;4936.728479933602;4948.604111671076;4960.280759289901;4702.835400760974;4765.642576806753;4874.220124780054;4879.731614761186;4888.755562221874;4185.958140418617;4198.286291330481;4200.348945913388;4204.054087830214;4216.683748718859;6274.152989346471;6409.935900641027;6440.581362211441;6446.677666116722;6469.288378278386;4698.859034228979;5802.18769372284;5805.477507024388;5812.447817457335;5835.5915085944;6782.118324617817;6977.79066628002;6987.650617469157;6989.475789315821;6992.23085460604;4736.242201247818;4758.476567577598;4762.52374762521;4776.020915712305;4787.425508938914;5900.350961394243;7345.742898998509;7406.740764073232;7425.368843648296;7429.07136607924;4818.397700287464;4819.855404337875;4821.252705830602;4823.541763532458;4829.517048295143;4875.561709295766;4877.707337559757;4878.463369029404;4885.096257192431;4894.437139728977;5758.510526605285;5783.277090363705;5783.942160578425;5788.797392091256;5793.333766616809;6916.23921368649;7138.214796372395;7156.856862862714;7160.570365777966;7170.103737032871;1624.7319192333368;1653.2312474699322;1653.2477722636172;1655.3130450862757;1657.2459530913407;995.6067353395368;1041.286718597763;1044.2324891204958;1046.9319494233002;1068.033429746081;5643.927653745775;6146.877062458726;6735.259121496631;6748.223954245226;6753.054572359873;7318.890216646648;7320.048393708784;7337.669804858794;7337.972683824269;7343.3758130558435;6678.766243181465;6709;6733.326667333227;6740.62926539045;6749.527533072736;3837.0791010157363;3873.186630807562;3877.1664092219744;3878.9806050969564;3879.96120038796;7409.259074092548;7689.461737678421;7707.614619269014;7791.376689864797;7795.298423141831;7516.173220945691;7619.390448764086;7644.732434364771;7649.541027538366;7652.540847549165;6224.408681175371;7743.4966727162555;7765.650545725456;7771.455998080193;7780.299773020455;7110.680019399036;7350.595717235603;7366.300201480851;7367.263273672805;7370.462178154408;7246.746363877239;7277;7290.270972902879;7314.414846812136;7328.816779580553;7706.306432421109;7730.806729831799;7732.884006740016;7741.92258077412;7742.606330860499;4161.396597493407;4811.882771580494;4864.100141473838;4911.943932054625;4921.13880070979;7121.287871212838;7241.167265764505;7280.490365674352;7281.963590182015;7287.672054757655;7051.823704407431;7090.255523170199;7098.112735908012;7109.146902371748;7125.216226215216;7046.718131274692;7050.5769653820935;7069.717211311696;7076.2923707628825;7089.184743754328;6034.883546543903;6099.902017636868;6424.196975378078;6925.792226233221;6926.0649812276715;6648.800535983928;6899.89650155238;7078.115235595551;7100.538464999759;7139.2503787101605;5377.8924421510565;6803.4557235422035;6859.965700171467;6925.6190909500465;7028.226895041644 12:22:58 INFO - PROCESS | 1660 | |5;yui.html;578.7598146769091;672.549391907422;700.7967689370083;729.5731996781884;754.7207533212712;15582.220888955564;15656.608584785386;15660.295286712097;15779.842201577982;15782.053076815384;14530.927345363258;14608.707825843478;14683.11901285921;14698.706025879475;14707.852921470781;828.8010877389444;841.7600983719641;842.2503971465401;984.7439665686919;1010.5099026971913;828.6354004238146;838.8280402517499;968.5302628225305;999.635133176389;1000.2398177385166;1044.1542350695922;1050.9894901051007;1052.6631477927067;1052.768390953989;1055.076807793181;1036.6475398364555;1041.1098510773256;1042.4631314872847;1043.0143514378915;1043.243648354944;617.789951416516;759.3204082346324;770.8265640230903;771.1055175995818;772.088935056633;15179.16514591708;15283.465078722302;15285.61785955349;15291.388344466208;15302.464413745465;12569;13329.533466328729;13399;13515.391807368693;13648.36219653648;11340.546378144863;11446.484908179069;11498.885011149949;11514.94242528782;11587.826182607268;3561.626029266916;3580.158662714286;3635.2184280379847;3642.5264715586804;3811.5616704079107;87643.56178219132;89440;89687.55156224243;89734;90496.54751726265;9893.604255829687;9935.65225217114;9993.950030249947;9998.450085245382;10117.140043096271;22039.89800509968;22040;22045.228417005328;22069.11723531073;22088.337349879206;7674;7767.961160194162;7801.531908085533;7869.868319132501;7909.129995700583;4283.892902677458;4290.957090429056;4313.956860431355;4319.438472998553;4544.159330523864;3733.4586484959527;3742.4947632069866;3785.337565925952;3787.886363409102;3791.1659434924272;653.9934600653933;655.8786624474487;665.9567128136656;674.8022260487674;689.3106893106893;502.36450889624655;611.8011646214998;625.8904691678938;634.4448607468465;666.8732940741244;7938.404619653549;8378.874316885252;8429.241368276884;8547.401681882333;8838.425502342456;576.1012820000813;601.4917394801386;612.1495791471682;617.102116420607;643.1831573901119;3891.922161556753;3920.8235629396745;3990.501187351581;4026.174634199982;4175.603317684815;81968;83916.58041709752;84255.578722106;84372.57813710893;84682;80599;80845.59577202077;81785.59107204426;82234.58882705549;84976.15023849806;55103.44896550983;65913.34086659168;69273.65363173252;70950.64524677343;71628.2837171632;6413.717256548803;6832.658367081725;7012.195121951284;7850.764477065697;7862.01724784402;49443.75278123586;52051.21923171231;52477.55370189596;53329.733351334544;54668;51357.48642513527;67952;68724;68910.65544672245;73295.90056149269;11078.390688512209;11323.15076369259;11384;11486.885131148581;11737.530498779954;11910.880891190976;12085.335306558223;12267.447964841782;12318;12691.301978391277;10513.78972420532;11406.315621062762;11449.313041217554;11897;12263.75472490563;9067.818643626959;10342;11640.35978021217;11696.35670038156;11831.112666550216;11311.038561722016;11514.481848317013;11591.420428978687;11677.76644467123;11697.415129243675;8288.958555207186;8372.204640559137;8931.330150238628;9868.703938881845;9987.800243995225;8609.053004169411;8982.685606003759;9745.891472095727;9814.950925245328;9843.85234221473 12:22:58 INFO - PROCESS | 1660 | __end_tp_report 12:22:58 INFO - PROCESS | 1660 | __start_cc_report 12:22:58 INFO - PROCESS | 1660 | _x_x_mozilla_cycle_collect,1132 12:22:58 INFO - PROCESS | 1660 | __end_cc_report 12:22:58 INFO - PROCESS | 1660 | __startTimestamp1464290578420__endTimestamp 12:22:58 INFO - PROCESS | 1660 | 12:22:58 INFO - PROCESS | 1660 | ------- Summary: start ------- 12:22:58 INFO - PROCESS | 1660 | Number of tests: 6 12:22:58 INFO - PROCESS | 1660 | 12:22:58 INFO - PROCESS | 1660 | [#0] dojo.html Cycles:165 Average:5859.98 Median:6141.42 stddev:629.87 (10.3%) stddev-sans-first:606.04 12:22:58 INFO - PROCESS | 1660 | Values: 3587.1 3609.3 3624.2 3644.5 3691.5 4643.8 5624.8 5799.6 5819.7 5845.5 5774.9 5820.4 5833.3 5842.8 5852.4 5246.9 5257.8 5264.1 5320.8 5368.2 5395.1 5411.3 5411.3 5419.0 5422.4 4283.4 5348.8 5360.7 5368.2 5408.5 5404.2 5422.3 5435.7 5452.5 5489.2 5167.5 5353.4 5357.8 5360.7 5362.1 4799.9 5739.0 5764.2 5776.7 5814.8 6235.2 6275.1 6289.6 6295.2 6297.3 6189.3 6234.2 6251.9 6266.6 6279.4 6061.1 6069.4 6075.1 6079.4 6082.0 6208.1 6305.8 6397.9 6398.1 6420.9 6277.1 6279.2 6298.1 6313.5 6333.2 6262.5 6268.7 6286.9 6291.7 6301.3 6180.4 6204.2 6211.8 6289.7 6300.6 6246.6 6258.3 6261.0 6267.3 6293.6 5039.9 5042.1 5053.6 5058.4 5061.0 4453.5 4552.5 4565.9 4601.1 4602.9 5932.6 5954.9 5999.6 6001.3 6005.1 6212.9 6267.2 6268.8 6271.1 6272.6 6018.0 6041.4 6064.2 6065.2 6086.8 5525.0 5553.7 5564.8 5570.5 5581.4 6321.1 6426.3 6483.6 6522.3 6537.3 6293.7 6301.7 6302.0 6310.8 6331.8 6538.6 6538.7 6554.2 6556.8 6564.6 6141.4 6196.3 6197.8 6226.8 6310.9 6127.4 6286.1 6302.7 6303.0 6304.3 6201.7 6212.6 6220.1 6222.9 6230.2 6106.7 6175.2 6189.9 6194.6 6198.2 6136.5 6138.8 6155.2 6172.7 6176.4 6131.8 6168.3 6202.5 6220.8 6233.1 6111.8 6130.0 6183.4 6188.2 6190.3 12:22:58 INFO - PROCESS | 1660 | 12:22:58 INFO - PROCESS | 1660 | [#1] ext.html Cycles:180 Average:60684.99 Median:39403.21 stddev:121905.92 (309.4%) stddev-sans-first:122164.11 12:22:58 INFO - PROCESS | 1660 | Values: 486.2 594.4 599.3 616.5 629.6 31502.0 41312.6 41338.6 41448.8 41473.8 44839.3 45310.5 45495.0 45514.1 45642.5 9617.6 9867.0 9880.3 9886.9 9895.0 9659.0 9820.3 9828.1 9838.3 9848.7 9821.2 9884.4 9884.4 9892.2 9912.2 9760.6 9846.6 9863.6 9967.7 9976.8 9671.7 9807.0 9827.0 9834.1 9856.9 43217.1 43611.0 43664.3 43669.3 43685.6 3263.5 3282.3 3430.7 3753.7 3843.1 29268.0 29430.0 29829.3 30345.5 30700.4 2752.1 2759.4 2807.5 2891.3 2892.7 431280.0 433885.0 433919.8 434433.0 435341.0 48479.8 49037.3 49213.8 74394.0 74614.4 47456.1 47923.3 48010.5 48028.5 48144.8 17563.6 17699.0 17700.8 17705.6 17721.8 11397.9 13473.9 13514.9 13521.4 13523.4 15280.3 15335.8 15625.7 15720.2 15767.2 6366.1 6426.5 6470.5 6487.2 6516.2 964.9 1042.4 1103.7 1110.2 1137.6 30733.0 30852.7 31001.4 31486.7 31687.9 1177.6 1182.1 1228.6 1230.4 1280.3 15467.1 15806.0 15831.8 16011.7 16145.7 547789.0 554749.0 557090.0 558747.0 559988.2 63279.4 63890.4 64016.7 64067.7 64123.4 1298.5 1405.2 1501.8 1516.7 1534.1 29004.4 29694.7 29718.6 29922.9 30455.8 48267.5 48853.0 49276.5 49350.5 49383.0 358693.0 369105.0 369987.0 370871.3 371274.0 30632.4 35002.8 35008.9 35991.6 36001.5 49346.8 49736.0 50290.5 51578.7 51702.2 37028.6 37376.4 39845.0 40370.6 41351.2 32042.7 33265.0 37921.0 38580.8 39253.4 38253.6 38498.6 38836.8 38911.6 39553.0 33177.8 33515.3 35047.6 35229.5 35276.6 25535.7 25839.0 25899.5 25921.0 26314.3 12:22:58 INFO - PROCESS | 1660 | 12:22:58 INFO - PROCESS | 1660 | [#2] jquery.html Cycles:180 Average:36673.70 Median:11473.78 stddev:113155.75 (986.2%) stddev-sans-first:113440.36 12:22:58 INFO - PROCESS | 1660 | Values: 375.8 379.0 379.9 384.9 385.7 20504.3 21078.7 21676.0 21969.9 22040.6 12186.4 12725.9 12791.4 12795.5 12799.7 4729.4 6132.5 6134.4 6138.6 6139.8 6015.4 6020.4 6129.9 6130.3 6131.3 5818.5 5975.4 5999.5 6022.2 6028.1 5842.3 5980.4 5996.9 5997.9 6000.5 5065.8 5187.4 5214.7 5214.8 5226.9 12910.5 12916.4 12931.2 18256.9 26361.6 2100.2 2106.6 2175.0 2175.3 2204.7 10257.3 10282.7 10548.8 13760.4 14471.5 1678.9 1730.3 1760.9 1790.7 1812.9 85549.7 109665.0 109776.9 113578.3 113601.0 11212.7 11555.0 11717.5 11725.2 11806.4 14521.4 14532.6 14609.8 14612.3 14641.1 9054.0 9195.9 9201.6 9274.0 9290.0 9551.0 9565.4 9625.5 9697.3 9708.7 4517.7 4595.8 4599.9 4635.5 4644.8 1701.5 1723.8 1838.5 1912.6 1931.5 859.8 946.2 949.9 996.7 1016.9 18168.5 20551.6 20847.4 21621.4 21797.2 998.3 1006.1 1013.8 1020.2 1032.8 11161.2 11766.9 11775.7 11781.6 11915.7 657568.0 677855.0 681780.6 698284.0 702665.0 111677.0 127332.0 127753.0 130525.0 132721.0 1048.2 1054.7 1069.8 1070.4 1070.5 15321.9 18670.3 19231.8 19469.3 19833.6 45953.5 49429.5 52649.2 53497.0 53566.5 73086.0 73290.3 75098.5 76248.1 77706.2 8069.3 8190.5 8354.7 8355.5 8569.0 12957.2 13418.3 13512.1 13765.3 13830.2 11532.9 11665.1 11819.6 11957.9 12189.8 11666.6 11749.9 11779.2 11986.3 12104.8 10230.6 11631.7 11967.5 12055.9 12227.0 8223.5 8354.2 8383.1 8395.7 8829.2 8785.9 11260.4 12016.9 12142.6 12184.6 12:22:58 INFO - PROCESS | 1660 | 12:22:58 INFO - PROCESS | 1660 | [#3] mootools.html Cycles:180 Average:6277.88 Median:541.84 stddev:3318.30 (612.4%) stddev-sans-first:3308.01 12:22:58 INFO - PROCESS | 1660 | Values: 1480.6 1614.6 1639.5 1968.5 2048.4 8300.0 8975.3 9125.8 9136.5 9147.3 8861.7 9140.6 9155.4 9165.0 9170.4 2546.8 2575.2 2578.1 2579.2 2581.1 2511.0 2580.8 2586.3 2586.5 2587.5 2542.4 2558.5 2583.1 2584.3 2586.6 2541.9 2562.2 2569.9 2580.6 2584.6 2531.7 2568.0 2568.1 2583.4 2593.9 9135.6 9201.6 9213.0 9228.0 9228.9 5587.9 5736.1 5770.2 5784.7 5786.9 3101.6 3598.4 3674.1 3707.4 3766.9 1054.7 1076.0 1082.4 1083.0 1109.2 12334.1 12517.9 12557.0 12574.3 12584.1 4296.7 5028.7 5028.9 5060.1 5078.2 10544.8 10547.3 10549.2 10553.4 10562.0 5737.6 5753.7 5761.0 5765.0 5770.5 4640.8 4808.1 4986.6 5182.7 5226.2 4001.1 4065.9 4076.6 4081.6 4127.0 1328.2 1337.1 1342.4 1353.0 1357.4 1381.4 1401.5 1408.0 1428.5 1429.4 8445.8 8510.9 8593.6 8602.3 8639.3 528.9 540.3 543.4 549.8 551.1 6290.2 6308.8 6349.5 6359.8 6361.2 10438.6 10911.6 10933.8 10936.9 10960.7 10351.6 10730.8 10807.2 10829.9 10830.5 8503.8 8512.4 8572.9 8594.5 8611.6 8022.2 8027.8 8110.9 8307.9 8369.5 9407.9 9711.5 9825.8 9911.5 9950.9 9887.0 10060.5 10169.7 10236.3 10301.1 8311.4 8359.0 8401.0 8492.7 8502.8 7201.8 8529.7 8529.7 8595.5 8609.7 7518.8 7660.5 7720.4 7782.5 7819.2 7322.1 7510.1 7534.4 7673.1 7702.0 7003.0 8007.2 8054.9 8141.8 8167.5 7184.9 7234.4 7342.9 7383.0 7396.6 7482.6 7510.5 7541.4 7554.2 7559.2 12:22:58 INFO - PROCESS | 1660 | 12:22:58 INFO - PROCESS | 1660 | [#4] prototype.html Cycles:180 Average:5795.60 Median:6653.03 stddev:1849.47 (27.8%) stddev-sans-first:1808.66 12:22:58 INFO - PROCESS | 1660 | Values: 333.8 340.3 342.9 343.4 349.3 6614.0 6722.1 6734.6 6736.6 6742.6 6611.5 6649.1 6656.9 6660.9 6663.3 4769.6 4859.6 4908.0 4969.2 4997.0 3916.1 3969.4 4704.2 4970.2 4976.4 4267.9 4927.8 4936.7 4948.6 4960.3 4702.8 4765.6 4874.2 4879.7 4888.8 4186.0 4198.3 4200.3 4204.1 4216.7 6274.2 6409.9 6440.6 6446.7 6469.3 4698.9 5802.2 5805.5 5812.4 5835.6 6782.1 6977.8 6987.7 6989.5 6992.2 4736.2 4758.5 4762.5 4776.0 4787.4 5900.4 7345.7 7406.7 7425.4 7429.1 4818.4 4819.9 4821.3 4823.5 4829.5 4875.6 4877.7 4878.5 4885.1 4894.4 5758.5 5783.3 5783.9 5788.8 5793.3 6916.2 7138.2 7156.9 7160.6 7170.1 1624.7 1653.2 1653.2 1655.3 1657.2 995.6 1041.3 1044.2 1046.9 1068.0 5643.9 6146.9 6735.3 6748.2 6753.1 7318.9 7320.0 7337.7 7338.0 7343.4 6678.8 6709.0 6733.3 6740.6 6749.5 3837.1 3873.2 3877.2 3879.0 3880.0 7409.3 7689.5 7707.6 7791.4 7795.3 7516.2 7619.4 7644.7 7649.5 7652.5 6224.4 7743.5 7765.7 7771.5 7780.3 7110.7 7350.6 7366.3 7367.3 7370.5 7246.7 7277.0 7290.3 7314.4 7328.8 7706.3 7730.8 7732.9 7741.9 7742.6 4161.4 4811.9 4864.1 4911.9 4921.1 7121.3 7241.2 7280.5 7282.0 7287.7 7051.8 7090.3 7098.1 7109.1 7125.2 7046.7 7050.6 7069.7 7076.3 7089.2 6034.9 6099.9 6424.2 6925.8 6926.1 6648.8 6899.9 7078.1 7100.5 7139.3 5377.9 6803.5 6860.0 6925.6 7028.2 12:22:58 INFO - PROCESS | 1660 | 12:22:58 INFO - PROCESS | 1660 | [#5] yui.html Cycles:180 Average:18480.94 Median:52264.39 stddev:25775.86 (49.3%) stddev-sans-first:25813.12 12:22:58 INFO - PROCESS | 1660 | Values: 578.8 672.5 700.8 729.6 754.7 15582.2 15656.6 15660.3 15779.8 15782.1 14530.9 14608.7 14683.1 14698.7 14707.9 828.8 841.8 842.3 984.7 1010.5 828.6 838.8 968.5 999.6 1000.2 1044.2 1051.0 1052.7 1052.8 1055.1 1036.6 1041.1 1042.5 1043.0 1043.2 617.8 759.3 770.8 771.1 772.1 15179.2 15283.5 15285.6 15291.4 15302.5 12569.0 13329.5 13399.0 13515.4 13648.4 11340.5 11446.5 11498.9 11514.9 11587.8 3561.6 3580.2 3635.2 3642.5 3811.6 87643.6 89440.0 89687.6 89734.0 90496.5 9893.6 9935.7 9994.0 9998.5 10117.1 22039.9 22040.0 22045.2 22069.1 22088.3 7674.0 7768.0 7801.5 7869.9 7909.1 4283.9 4291.0 4314.0 4319.4 4544.2 3733.5 3742.5 3785.3 3787.9 3791.2 654.0 655.9 666.0 674.8 689.3 502.4 611.8 625.9 634.4 666.9 7938.4 8378.9 8429.2 8547.4 8838.4 576.1 601.5 612.1 617.1 643.2 3891.9 3920.8 3990.5 4026.2 4175.6 81968.0 83916.6 84255.6 84372.6 84682.0 80599.0 80845.6 81785.6 82234.6 84976.2 55103.4 65913.3 69273.7 70950.6 71628.3 6413.7 6832.7 7012.2 7850.8 7862.0 49443.8 52051.2 52477.6 53329.7 54668.0 51357.5 67952.0 68724.0 68910.7 73295.9 11078.4 11323.2 11384.0 11486.9 11737.5 11910.9 12085.3 12267.4 12318.0 12691.3 10513.8 11406.3 11449.3 11897.0 12263.8 9067.8 10342.0 11640.4 11696.4 11831.1 11311.0 11514.5 11591.4 11677.8 11697.4 8289.0 8372.2 8931.3 9868.7 9987.8 8609.1 8982.7 9745.9 9815.0 9843.9 12:22:58 INFO - PROCESS | 1660 | -------- Summary: end -------- 12:22:58 INFO - PROCESS | 1660 | 12:22:58 INFO - TEST-INFO | 1660: exit 0 12:22:58 INFO - TEST-OK | dromaeo_css | took 1111710ms 12:22:58 INFO - TEST-START | kraken 12:22:58 INFO - operating with platform_type : mac_ 12:22:58 INFO - Initialising browser for kraken test... 12:22:59 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/tmpHeu5IY/profile http://localhost:49270/getInfo.html) 12:23:01 INFO - PROCESS | 1670 | __metrics Screen width/height:1600/1200 12:23:01 INFO - PROCESS | 1670 | colorDepth:24 12:23:01 INFO - PROCESS | 1670 | Browser inner width/height: 1024/689 12:23:01 INFO - PROCESS | 1670 | __metrics 12:23:01 INFO - PROCESS | 1670 | 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" 12:23:01 INFO - PROCESS | 1670 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:23:01 INFO - TEST-INFO | 1670: exit 0 12:23:01 INFO - Browser initialized. 12:23:01 INFO - Running cycle 1/1 for kraken test... 12:23:01 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/tmpHeu5IY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:23:13 INFO - PROCESS | 1673 | RSS: Main: 156884992 12:23:13 INFO - PROCESS | 1673 | 12:23:16 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection) 12:23:16 INFO - PROCESS | 1673 | RSS: Main: 218562560 12:23:16 INFO - PROCESS | 1673 | 12:23:19 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-dft) 12:23:19 INFO - PROCESS | 1673 | RSS: Main: 228667392 12:23:19 INFO - PROCESS | 1673 | 12:23:23 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-fft) 12:23:23 INFO - PROCESS | 1673 | RSS: Main: 218304512 12:23:23 INFO - PROCESS | 1673 | 12:23:25 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator) 12:23:26 INFO - PROCESS | 1673 | RSS: Main: 219353088 12:23:26 INFO - PROCESS | 1673 | 12:23:28 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:23:29 INFO - PROCESS | 1673 | RSS: Main: 220311552 12:23:29 INFO - PROCESS | 1673 | 12:23:31 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom) 12:23:32 INFO - PROCESS | 1673 | RSS: Main: 220282880 12:23:32 INFO - PROCESS | 1673 | 12:23:35 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate) 12:23:35 INFO - PROCESS | 1673 | RSS: Main: 220221440 12:23:35 INFO - PROCESS | 1673 | 12:23:38 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial) 12:23:38 INFO - PROCESS | 1673 | RSS: Main: 220557312 12:23:38 INFO - PROCESS | 1673 | 12:23:40 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:23:41 INFO - PROCESS | 1673 | RSS: Main: 255426560 12:23:41 INFO - PROCESS | 1673 | 12:23:42 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:23:43 INFO - PROCESS | 1673 | RSS: Main: 256471040 12:23:43 INFO - PROCESS | 1673 | 12:23:45 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:23:46 INFO - PROCESS | 1673 | RSS: Main: 261390336 12:23:46 INFO - PROCESS | 1673 | 12:23:48 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:23:49 INFO - PROCESS | 1673 | RSS: Main: 262684672 12:23:49 INFO - PROCESS | 1673 | 12:23:51 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:23:52 INFO - PROCESS | 1673 | RSS: Main: 262856704 12:23:52 INFO - PROCESS | 1673 | 12:23:54 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49270/tests/kraken/driver.html?testName=ai-astar) 12:23:54 INFO - PROCESS | 1673 | RSS: Main: 326041600 12:23:54 INFO - PROCESS | 1673 | 12:23:54 INFO - PROCESS | 1673 | __start_tp_report 12:23:54 INFO - PROCESS | 1673 | _x_x_mozilla_page_load 12:23:54 INFO - PROCESS | 1673 | _x_x_mozilla_page_load_details 12:23:54 INFO - PROCESS | 1673 | |i|pagename|runs| 12:23:54 INFO - PROCESS | 1673 | |0;ai-astar;113;112;111;113;113;113;128;112;110;111 12:23:54 INFO - PROCESS | 1673 | |1;audio-beat-detection;154;120;144;153;116;135;121;179;178;145 12:23:54 INFO - PROCESS | 1673 | |2;audio-dft;185;184;181;184;191;253;226;183;182;182 12:23:54 INFO - PROCESS | 1673 | |3;audio-fft;72;96;106;153;55;57;56;56;111;56 12:23:54 INFO - PROCESS | 1673 | |4;audio-oscillator;87;89;132;77;80;83;118;75;79;120 12:23:54 INFO - PROCESS | 1673 | |5;imaging-gaussian-blur;87;84;86;84;86;83;116;130;75;104 12:23:54 INFO - PROCESS | 1673 | |6;imaging-darkroom;92;92;92;93;93;92;92;92;91;92 12:23:54 INFO - PROCESS | 1673 | |7;imaging-desaturate;85;82;87;83;85;84;84;84;83;84 12:23:54 INFO - PROCESS | 1673 | |8;json-parse-financial;55;57;59;57;57;58;58;56;66;51 12:23:54 INFO - PROCESS | 1673 | |9;json-stringify-tinderbox;62;60;68;67;77;72;67;65;65;66 12:23:54 INFO - PROCESS | 1673 | |10;stanford-crypto-aes;82;81;80;81;80;80;81;68;80;100 12:23:54 INFO - PROCESS | 1673 | |11;stanford-crypto-ccm;130;117;130;125;128;126;124;114;123;106 12:23:54 INFO - PROCESS | 1673 | |12;stanford-crypto-pbkdf2;135;123;162;171;137;122;122;134;137;130 12:23:54 INFO - PROCESS | 1673 | |13;stanford-crypto-sha256-iterative;65;66;64;65;65;66;64;64;66;67 12:23:54 INFO - PROCESS | 1673 | __end_tp_report 12:23:54 INFO - PROCESS | 1673 | __start_cc_report 12:23:54 INFO - PROCESS | 1673 | _x_x_mozilla_cycle_collect,1205 12:23:54 INFO - PROCESS | 1673 | __end_cc_report 12:23:54 INFO - PROCESS | 1673 | __startTimestamp1464290634286__endTimestamp 12:23:54 INFO - PROCESS | 1673 | 12:23:54 INFO - PROCESS | 1673 | ------- Summary: start ------- 12:23:54 INFO - PROCESS | 1673 | Number of tests: 14 12:23:54 INFO - PROCESS | 1673 | 12:23:54 INFO - PROCESS | 1673 | [#0] ai-astar Cycles:10 Average:113.60 Median:113.00 stddev:5.17 (4.6%) stddev-sans-first:5.48 12:23:54 INFO - PROCESS | 1673 | Values: 113.0 112.0 111.0 113.0 113.0 113.0 128.0 112.0 110.0 111.0 12:23:54 INFO - PROCESS | 1673 | 12:23:54 INFO - PROCESS | 1673 | [#1] audio-beat-detection Cycles:10 Average:144.50 Median:149.00 stddev:22.44 (15.1%) stddev-sans-first:23.53 12:23:54 INFO - PROCESS | 1673 | Values: 154.0 120.0 144.0 153.0 116.0 135.0 121.0 179.0 178.0 145.0 12:23:54 INFO - PROCESS | 1673 | 12:23:54 INFO - PROCESS | 1673 | [#2] audio-dft Cycles:10 Average:195.10 Median:184.50 stddev:24.41 (13.2%) stddev-sans-first:25.61 12:23:54 INFO - PROCESS | 1673 | Values: 185.0 184.0 181.0 184.0 191.0 253.0 226.0 183.0 182.0 182.0 12:23:54 INFO - PROCESS | 1673 | 12:23:54 INFO - PROCESS | 1673 | [#3] audio-fft Cycles:10 Average:81.80 Median:56.00 stddev:33.56 (59.9%) stddev-sans-first:35.41 12:23:54 INFO - PROCESS | 1673 | Values: 72.0 96.0 106.0 153.0 55.0 57.0 56.0 56.0 111.0 56.0 12:23:54 INFO - PROCESS | 1673 | 12:23:54 INFO - PROCESS | 1673 | [#4] audio-oscillator Cycles:10 Average:94.00 Median:79.50 stddev:20.98 (26.4%) stddev-sans-first:22.10 12:23:54 INFO - PROCESS | 1673 | Values: 87.0 89.0 132.0 77.0 80.0 83.0 118.0 75.0 79.0 120.0 12:23:54 INFO - PROCESS | 1673 | 12:23:54 INFO - PROCESS | 1673 | [#5] imaging-gaussian-blur Cycles:10 Average:93.50 Median:84.00 stddev:17.44 (20.8%) stddev-sans-first:18.34 12:23:54 INFO - PROCESS | 1673 | Values: 87.0 84.0 86.0 84.0 86.0 83.0 116.0 130.0 75.0 104.0 12:23:54 INFO - PROCESS | 1673 | 12:23:54 INFO - PROCESS | 1673 | [#6] imaging-darkroom Cycles:10 Average:92.10 Median:92.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 12:23:54 INFO - PROCESS | 1673 | Values: 92.0 92.0 92.0 93.0 93.0 92.0 92.0 92.0 91.0 92.0 12:23:54 INFO - PROCESS | 1673 | 12:23:54 INFO - PROCESS | 1673 | [#7] imaging-desaturate Cycles:10 Average:84.10 Median:84.00 stddev:1.37 (1.6%) stddev-sans-first:1.41 12:23:54 INFO - PROCESS | 1673 | Values: 85.0 82.0 87.0 83.0 85.0 84.0 84.0 84.0 83.0 84.0 12:23:54 INFO - PROCESS | 1673 | 12:23:54 INFO - PROCESS | 1673 | [#8] json-parse-financial Cycles:10 Average:57.40 Median:57.50 stddev:3.75 (6.5%) stddev-sans-first:3.87 12:23:54 INFO - PROCESS | 1673 | Values: 55.0 57.0 59.0 57.0 57.0 58.0 58.0 56.0 66.0 51.0 12:23:54 INFO - PROCESS | 1673 | 12:23:54 INFO - PROCESS | 1673 | [#9] json-stringify-tinderbox Cycles:10 Average:66.90 Median:67.00 stddev:4.82 (7.2%) stddev-sans-first:4.77 12:23:54 INFO - PROCESS | 1673 | Values: 62.0 60.0 68.0 67.0 77.0 72.0 67.0 65.0 65.0 66.0 12:23:54 INFO - PROCESS | 1673 | 12:23:54 INFO - PROCESS | 1673 | [#10] stanford-crypto-aes Cycles:10 Average:81.30 Median:80.50 stddev:7.70 (9.6%) stddev-sans-first:8.17 12:23:54 INFO - PROCESS | 1673 | Values: 82.0 81.0 80.0 81.0 80.0 80.0 81.0 68.0 80.0 100.0 12:23:54 INFO - PROCESS | 1673 | 12:23:54 INFO - PROCESS | 1673 | [#11] stanford-crypto-ccm Cycles:10 Average:122.30 Median:125.50 stddev:7.73 (6.2%) stddev-sans-first:7.68 12:23:54 INFO - PROCESS | 1673 | Values: 130.0 117.0 130.0 125.0 128.0 126.0 124.0 114.0 123.0 106.0 12:23:54 INFO - PROCESS | 1673 | 12:23:54 INFO - PROCESS | 1673 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:137.30 Median:136.00 stddev:16.63 (12.2%) stddev-sans-first:17.61 12:23:54 INFO - PROCESS | 1673 | Values: 135.0 123.0 162.0 171.0 137.0 122.0 122.0 134.0 137.0 130.0 12:23:54 INFO - PROCESS | 1673 | 12:23:54 INFO - PROCESS | 1673 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.20 Median:65.50 stddev:1.03 (1.6%) stddev-sans-first:1.09 12:23:54 INFO - PROCESS | 1673 | Values: 65.0 66.0 64.0 65.0 65.0 66.0 64.0 64.0 66.0 67.0 12:23:54 INFO - PROCESS | 1673 | -------- Summary: end -------- 12:23:54 INFO - PROCESS | 1673 | 12:23:54 INFO - TEST-INFO | 1673: exit 0 12:23:55 INFO - TEST-OK | kraken | took 56054ms 12:23:55 INFO - SUITE-END | took 1167s 12:23:55 INFO - Completed test suite (00:19:28) 12:23:55 INFO - javascript benchmark 12:23:55 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3587.0673624857536, 3609.3322735293955, 3624.2026754114104, 3644.4533320001997, 3691.53855768029, 4643.791029403681, 5624.818788054508, 5799.623024503404, 5819.709014549277, 5845.50313223376, 5774.913376299359, 5820.38885916979, 5833.270841144857, 5842.824715258538, 5852.356240813507, 5246.921296180561, 5257.76340064698, 5264.078786212417, 5320.76056577453, 5368.194770784394, 5395.082835917903, 5411.269478620376, 5411.269478620376, 5419.024575576394, 5422.4306447823, 4283.421738065368, 5348.812791552296, 5360.678359298435, 5368.167933970221, 5408.4591540845995, 5404.189371594253, 5422.349318081816, 5435.701036442994, 5452.482014208644, 5489.204065410533, 5167.457416971239, 5353.437889021636, 5357.785688572452, 5360.678359298416, 5362.142057270857, 4799.856004319876, 5739.024365857814, 5764.164196191528, 5776.682282474462, 5814.796482123148, 6235.158253635746, 6275.09011193379, 6289.622622642611, 6295.150154729099, 6297.27581328144, 6189.2572891252885, 6234.189555357774, 6251.937480625226, 6266.592671476339, 6279.372062793684, 6061.121137435135, 6069.423404776539, 6075.119107729354, 6079.361667024988, 6082.0, 6208.099825525273, 6305.8108256752375, 6397.904031439532, 6398.136251606066, 6420.935790642127, 6277.058441233851, 6279.183706118175, 6298.149749783812, 6313.463355614732, 6333.20834895638, 6262.530310226751, 6268.655223962634, 6286.937130628727, 6291.6854157291955, 6301.275353334334, 6180.4437600615265, 6204.162438070893, 6211.81364559064, 6289.685515724195, 6300.621962682254, 6246.562740608205, 6258.3428739982555, 6261.0, 6267.310595834455, 6293.590916590407, 5039.899202015939, 5042.09242336383, 5053.570446512014, 5058.4435712071645, 5060.987802439527, 4453.487848897354, 4552.521985191574, 4565.863024109282, 4601.079784043205, 4602.884927876763, 5932.644041357532, 5954.880902382015, 5999.610025348338, 6001.279846418458, 6005.099235114681, 6212.9068063979075, 6267.1539342189135, 6268.843278917972, 6271.090691849748, 6272.5922815016875, 6018.0, 6041.426064523863, 6064.151018857364, 6065.211522502046, 6086.786962456293, 5524.972375138179, 5553.666779993213, 5564.805231816867, 5570.498655120978, 5581.358143813432, 6321.051842223703, 6426.260979987362, 6483.578567393104, 6522.315156908551, 6537.280899101095, 6293.716782744879, 6301.6534090625455, 6301.968490157703, 6310.810675679737, 6331.841703957253, 6538.607683539003, 6538.738450461929, 6554.180727409074, 6556.770513032021, 6564.573302735307, 6141.424638644238, 6196.256449226122, 6197.81406557804, 6226.844328891814, 6310.873782524415, 6127.417895300117, 6286.119943207863, 6302.716377763103, 6303.0, 6304.3380445054445, 6201.658908760061, 6212.627242365473, 6220.098085777628, 6222.937770622236, 6230.190075290183, 6106.664133472702, 6175.228096487939, 6189.907151392823, 6194.566380353332, 6198.194234749454, 6136.539759517965, 6138.785142520169, 6155.230596175478, 6172.6913654318005, 6176.382361763787, 6131.84670383244, 6168.321484636596, 6202.534809889186, 6220.782272620437, 6233.127362169391, 6111.786087486917, 6129.970128114858, 6183.350748171377, 6188.226471691039, 6190.288116866438], "unit": "score", "name": "dojo.html", "value": 5822.5709268635}, {"lowerIsBetter": false, "replicates": [486.1929197532093, 594.3551246897116, 599.3347384403312, 616.5191150902295, 629.6127881352968, 31502.0, 41312.58687413129, 41338.58661413385, 41448.792756036215, 41473.75578732635, 44839.327410088874, 45310.54689453104, 45495.0, 45514.089718205614, 45642.543574564246, 9617.567209475574, 9866.963968783282, 9880.25898057645, 9886.851697224547, 9895.0, 9658.951705241463, 9820.263480238975, 9828.115469607734, 9838.311318207729, 9848.704538863845, 9821.165200957927, 9884.406935583853, 9884.406935583853, 9892.208623310155, 9912.15746661531, 9760.560774765152, 9846.556904939294, 9863.605455781795, 9967.700968970943, 9976.800463990752, 9671.661491847823, 9806.950965245129, 9826.950865245628, 9834.114929656365, 9856.90143098567, 43217.13565728699, 43611.0, 43664.3450348245, 43669.34495982563, 43685.56314436878, 3263.526788615661, 3282.3107147499054, 3430.7255419566623, 3753.699704023699, 3843.077661361281, 29268.0, 29429.96995105161, 29829.254268643457, 30345.544816827765, 30700.38599228003, 2752.0642981386427, 2759.379139693573, 2807.4806160860308, 2891.34944637457, 2892.6673432555103, 431280.0, 433885.0, 433919.8304008491, 434433.0, 435341.0, 48479.75760121177, 49037.26444103341, 49213.753931230116, 74394.0, 74614.38849640236, 47456.05087898292, 47923.281150782765, 48010.51989480061, 48028.5197148031, 48144.796380090775, 17563.56091097733, 17699.0, 17700.822991769917, 17705.557361066076, 17721.822781772018, 11397.943010285062, 13473.865261347457, 13514.86485135136, 13521.391537380841, 13523.39144738489, 15280.31238594266, 15335.846641533664, 15625.687486250212, 15720.21398930049, 15767.211639417983, 6366.10342294255, 6426.4537514311805, 6470.547061705635, 6487.22153341602, 6516.152900122954, 964.9372790768718, 1042.3589492462233, 1103.7240689827543, 1110.2062025651699, 1137.5961533655598, 30733.0, 30852.691473084982, 31001.379972400424, 31486.685133148832, 31687.890923818017, 1177.60550215677, 1182.1193211057816, 1228.6314105768413, 1230.4462991653613, 1280.295837289487, 15467.071975681494, 15806.0, 15831.841681583268, 16011.679766404839, 16145.677086458205, 547789.0, 554749.0, 557090.0, 558747.0, 559988.2000589971, 63279.367206328265, 63890.36109638937, 64016.67991660012, 64067.67966160233, 64123.35876641174, 1298.5455090718174, 1405.156905856478, 1501.7522108851917, 1516.7194069097252, 1534.1102160746516, 29004.41991160207, 29694.703052969195, 29718.55422168626, 29922.850385747934, 30455.847720761256, 48267.5173248263, 48853.0, 49276.507234927194, 49350.50649493459, 49383.0, 358693.0, 369105.0, 369987.0, 370871.2912870837, 371274.0, 30632.387352253278, 35002.824985874904, 35008.949731508095, 35991.64008359883, 36001.459978099825, 49346.753266234875, 49736.005279894925, 50290.49709503005, 51578.74210628923, 51702.22446663379, 37028.62971370252, 37376.439353410264, 39845.0, 40370.59629403668, 41351.172976539696, 32042.679573203968, 33265.0, 37921.0, 38580.80709596434, 39253.41119883147, 38253.61746382501, 38498.6150138495, 38836.80581597187, 38911.6108838908, 39553.01117471971, 33177.83411082929, 33515.25168145513, 35047.64952350444, 35229.471557927165, 35276.64723352836, 25535.74464255408, 25839.0, 25899.482010360065, 25920.96316147333, 26314.34214144585], "unit": "score", "name": "ext.html", "value": 19873.205920979108}, {"lowerIsBetter": false, "replicates": [375.83463276158494, 378.9886303410897, 379.88793305974724, 384.93840985442336, 385.6509858577988, 20504.282350117748, 21078.6838197427, 21676.0, 21969.890150549265, 22040.559188816234, 12186.390680465985, 12725.936370318135, 12791.36043197841, 12795.488180472794, 12799.680007999805, 4729.432468103823, 6132.5093992480715, 6134.4478996890275, 6138.631682099066, 6139.846503837395, 6015.398460153971, 6020.367861374559, 6129.908051379233, 6130.295016073164, 6131.264248290211, 5818.476337129658, 5975.372585878486, 5999.490043346321, 6022.187004754368, 6028.065649824284, 5842.269716285465, 5980.401959804029, 5996.910046349309, 5997.88004239915, 6000.489958353545, 5065.848024559269, 5187.3775146982225, 5214.661047031968, 5214.8174813881715, 5226.869328266824, 12910.483580656857, 12916.41876115577, 12931.224126552437, 18256.9045857249, 26361.604575931375, 2100.1809294375053, 2106.6208082545136, 2174.967375489369, 2175.3147758456034, 2204.7244094488187, 10257.281990260684, 10282.691519254435, 10548.789024219474, 13760.380782864795, 14471.493497727633, 1678.8740844436593, 1730.2732852202105, 1760.9383671571434, 1790.650823089498, 1812.8578995233052, 85549.71675424873, 109665.0, 109776.90223097826, 113578.29632555436, 113601.0, 11212.719682008015, 11555.01782348493, 11717.531298748125, 11725.237859539102, 11806.40967951599, 14521.419143234152, 14532.636684082983, 14609.78085328721, 14612.26938653063, 14641.121532708072, 9054.0, 9195.862062069074, 9201.585928633227, 9274.0, 9290.0, 9550.95224523873, 9565.378250413702, 9625.51872406377, 9697.272704547187, 9708.660196893075, 4517.706349087299, 4595.839145629887, 4599.9080018399445, 4635.513271106553, 4644.814207431732, 1701.4810482802864, 1723.8189990051114, 1838.5219842840959, 1912.6079153773444, 1931.5364312565164, 859.8280343931239, 946.2004606107828, 949.8765160529088, 996.7209181429212, 1016.8830584482879, 18168.45568126724, 20551.588968220552, 20847.37457876266, 21621.35135945924, 21797.237096701538, 998.2962011781822, 1006.1246715357686, 1013.8327176016022, 1020.2042406922612, 1032.808930347888, 11161.162912781412, 11766.94116529429, 11775.705607359885, 11781.587644432575, 11915.70210744721, 657568.0, 677855.0, 681780.5910970513, 698284.0, 702665.0, 111677.0, 127332.0, 127753.0, 130525.0, 132721.0, 1048.2086025051037, 1054.7099547624464, 1069.764651776608, 1070.3952266969138, 1070.4808168038496, 15321.923390382977, 18670.346537871108, 19231.846089234692, 19469.318573849847, 19833.603327933648, 45953.540464594924, 49429.50570494249, 52649.210261845335, 53497.0, 53566.46433535615, 73086.0, 73290.26709732834, 75098.49803004018, 76248.0937976555, 77706.2229377699, 8069.273765361146, 8190.467619604707, 8354.749357519284, 8355.498670079814, 8569.014563325049, 12957.22256664603, 13418.261995590336, 13512.121712088683, 13765.311734413439, 13830.23933683657, 11532.88467115318, 11665.125115616533, 11819.621987306435, 11957.94021029924, 12189.75620487603, 11666.591669291534, 11749.941250294036, 11779.175457717878, 11986.340751258764, 12104.818427723767, 10230.590776368861, 11631.6510504685, 11967.461464233957, 12055.879441205476, 12227.0, 8223.506589604644, 8354.206350396702, 8383.077861435358, 8395.664173432995, 8829.16122968317, 8785.868211976698, 11260.436978150896, 12016.939915300718, 12142.635720928387, 12184.57353992606], "unit": "score", "name": "jquery.html", "value": 9534.341140011054}, {"lowerIsBetter": false, "replicates": [1480.5928369698324, 1614.6205641674208, 1639.483562677756, 1968.5373937124773, 2048.4059622709415, 8300.045494768103, 8975.281977441806, 9125.817483650322, 9136.49749263791, 9147.268218542516, 8861.73414797557, 9140.588673509692, 9155.404898681598, 9165.037671044527, 9170.403923744952, 2546.808989325799, 2575.1502004338618, 2578.1105518596055, 2579.1746641074865, 2581.1482210870463, 2510.987445062772, 2580.7677309042183, 2586.314626623946, 2586.469773696397, 2587.469568738404, 2542.3898264416594, 2558.475512519929, 2583.1217386088724, 2584.25056733548, 2586.612008198776, 2541.8601976891264, 2562.2185233503774, 2569.922902312924, 2580.6258092576472, 2584.6252293417538, 2531.6835395575554, 2567.9871600642064, 2568.0883286433423, 2583.3799888026924, 2593.8573378464175, 9135.634574617008, 9201.585928633227, 9213.0, 9227.984921658612, 9228.861567076432, 5587.916181257285, 5736.053551164053, 5770.2498675171955, 5784.68184249866, 5786.900488907094, 3101.6122984626923, 3598.4062629666337, 3674.063113905937, 3707.3512135376473, 3766.8681596144, 1054.6783231114507, 1075.9946200269028, 1082.410086502858, 1082.9512671929704, 1109.1736656191108, 12334.074944379117, 12517.874821251762, 12557.0, 12574.308413037279, 12584.119111662187, 4296.742195468282, 5028.673136246132, 5028.85123228573, 5060.139776237975, 5078.187490001582, 10544.841827372595, 10547.261691681508, 10549.20880933933, 10553.419561924014, 10562.049415552483, 5737.598368114275, 5753.712314384264, 5761.049426844524, 5764.99112655292, 5770.538356931519, 4640.79339371759, 4808.08646357191, 4986.601071914238, 5182.689038657617, 5226.189940559219, 4001.139754952699, 4065.89835254121, 4076.5515793262716, 4081.6326530612387, 4126.979365103215, 1328.2362641480993, 1337.1480499508252, 1342.4227582139579, 1353.0461024977565, 1357.3552562532718, 1381.3507651404025, 1401.5444980381212, 1407.9929600351932, 1428.5428662827796, 1429.4425174182077, 8445.831083378422, 8510.872336914952, 8593.613287402082, 8602.31181505478, 8639.308855291561, 528.9308393323283, 540.2868213957616, 543.3995435044219, 549.8103154411722, 551.0769461152554, 6290.24517057956, 6308.8422789429715, 6349.523785715997, 6359.8092057238355, 6361.236651601837, 10438.63464778729, 10911.563537458413, 10933.835992460119, 10936.890631093745, 10960.671179864617, 10351.585936562604, 10730.78538429227, 10807.243492955575, 10829.891701082888, 10830.458477076272, 8503.829923401498, 8512.404131710844, 8572.914270857336, 8594.484330940164, 8611.61247743853, 8022.237887400921, 8027.799305017421, 8110.861423220974, 8307.916920830714, 8369.539675317916, 9407.905920940977, 9711.514424278617, 9825.754356141153, 9911.45486998222, 9950.850737239092, 9887.0, 10060.49697515136, 10169.745756356151, 10236.283460157718, 10301.072903438726, 8311.376646751638, 8359.0, 8401.033881103507, 8492.660293588187, 8502.78743031429, 7201.819954501179, 8529.658813647384, 8529.701460448854, 8595.52724600153, 8609.741707748777, 7518.774436766687, 7660.502067365604, 7720.420968427503, 7782.455228134203, 7819.218078192363, 7322.121345438582, 7510.098788145458, 7534.35957943581, 7673.117591477052, 7702.0, 7002.964985175042, 8007.239312265326, 8054.872317875619, 8141.79645508867, 8167.509949403055, 7184.856302874018, 7234.421246300178, 7342.853142937004, 7382.966384706253, 7396.593187374746, 7482.625868706652, 7510.549367037996, 7541.35898448638, 7554.244575542401, 7559.244075592397], "unit": "score", "name": "mootools.html", "value": 5039.290264674484}, {"lowerIsBetter": false, "replicates": [333.76969890775365, 340.3193612774451, 342.9434143366345, 343.4059077795414, 349.3223147094636, 6613.9669301653485, 6722.126123603937, 6734.562253453528, 6736.629485378302, 6742.5617334873195, 6611.504137189718, 6649.135612370385, 6656.933430665692, 6660.933390666092, 6663.267040625527, 4769.5707386335225, 4859.635527335447, 4908.018396320732, 4969.180085285923, 4996.9500304996845, 3916.118873253524, 3969.3847453644585, 4704.176769065417, 4970.2296144097545, 4976.377952755905, 4267.935980960273, 4927.753612319388, 4936.728479933602, 4948.604111671076, 4960.280759289901, 4702.835400760974, 4765.642576806753, 4874.220124780054, 4879.731614761186, 4888.755562221874, 4185.958140418617, 4198.286291330481, 4200.348945913388, 4204.054087830214, 4216.683748718859, 6274.152989346471, 6409.935900641027, 6440.581362211441, 6446.677666116722, 6469.288378278386, 4698.859034228979, 5802.18769372284, 5805.477507024388, 5812.447817457335, 5835.5915085944, 6782.118324617817, 6977.79066628002, 6987.650617469157, 6989.475789315821, 6992.23085460604, 4736.242201247818, 4758.476567577598, 4762.52374762521, 4776.020915712305, 4787.425508938914, 5900.350961394243, 7345.742898998509, 7406.740764073232, 7425.368843648296, 7429.07136607924, 4818.397700287464, 4819.855404337875, 4821.252705830602, 4823.541763532458, 4829.517048295143, 4875.561709295766, 4877.707337559757, 4878.463369029404, 4885.096257192431, 4894.437139728977, 5758.510526605285, 5783.277090363705, 5783.942160578425, 5788.797392091256, 5793.333766616809, 6916.23921368649, 7138.214796372395, 7156.856862862714, 7160.570365777966, 7170.103737032871, 1624.7319192333368, 1653.2312474699322, 1653.2477722636172, 1655.3130450862757, 1657.2459530913407, 995.6067353395368, 1041.286718597763, 1044.2324891204958, 1046.9319494233002, 1068.033429746081, 5643.927653745775, 6146.877062458726, 6735.259121496631, 6748.223954245226, 6753.054572359873, 7318.890216646648, 7320.048393708784, 7337.669804858794, 7337.972683824269, 7343.3758130558435, 6678.766243181465, 6709.0, 6733.326667333227, 6740.62926539045, 6749.527533072736, 3837.0791010157363, 3873.186630807562, 3877.1664092219744, 3878.9806050969564, 3879.96120038796, 7409.259074092548, 7689.461737678421, 7707.614619269014, 7791.376689864797, 7795.298423141831, 7516.173220945691, 7619.390448764086, 7644.732434364771, 7649.541027538366, 7652.540847549165, 6224.408681175371, 7743.4966727162555, 7765.650545725456, 7771.455998080193, 7780.299773020455, 7110.680019399036, 7350.595717235603, 7366.300201480851, 7367.263273672805, 7370.462178154408, 7246.746363877239, 7277.0, 7290.270972902879, 7314.414846812136, 7328.816779580553, 7706.306432421109, 7730.806729831799, 7732.884006740016, 7741.92258077412, 7742.606330860499, 4161.396597493407, 4811.882771580494, 4864.100141473838, 4911.943932054625, 4921.13880070979, 7121.287871212838, 7241.167265764505, 7280.490365674352, 7281.963590182015, 7287.672054757655, 7051.823704407431, 7090.255523170199, 7098.112735908012, 7109.146902371748, 7125.216226215216, 7046.718131274692, 7050.5769653820935, 7069.717211311696, 7076.2923707628825, 7089.184743754328, 6034.883546543903, 6099.902017636868, 6424.196975378078, 6925.792226233221, 6926.0649812276715, 6648.800535983928, 6899.89650155238, 7078.115235595551, 7100.538464999759, 7139.2503787101605, 5377.8924421510565, 6803.4557235422035, 6859.965700171467, 6925.6190909500465, 7028.226895041644], "unit": "score", "name": "prototype.html", "value": 5179.344455978989}, {"lowerIsBetter": false, "replicates": [578.7598146769091, 672.549391907422, 700.7967689370083, 729.5731996781884, 754.7207533212712, 15582.220888955564, 15656.608584785386, 15660.295286712097, 15779.842201577982, 15782.053076815384, 14530.927345363258, 14608.707825843478, 14683.11901285921, 14698.706025879475, 14707.852921470781, 828.8010877389444, 841.7600983719641, 842.2503971465401, 984.7439665686919, 1010.5099026971913, 828.6354004238146, 838.8280402517499, 968.5302628225305, 999.635133176389, 1000.2398177385166, 1044.1542350695922, 1050.9894901051007, 1052.6631477927067, 1052.768390953989, 1055.076807793181, 1036.6475398364555, 1041.1098510773256, 1042.4631314872847, 1043.0143514378915, 1043.243648354944, 617.789951416516, 759.3204082346324, 770.8265640230903, 771.1055175995818, 772.088935056633, 15179.16514591708, 15283.465078722302, 15285.61785955349, 15291.388344466208, 15302.464413745465, 12569.0, 13329.533466328729, 13399.0, 13515.391807368693, 13648.36219653648, 11340.546378144863, 11446.484908179069, 11498.885011149949, 11514.94242528782, 11587.826182607268, 3561.626029266916, 3580.158662714286, 3635.2184280379847, 3642.5264715586804, 3811.5616704079107, 87643.56178219132, 89440.0, 89687.55156224243, 89734.0, 90496.54751726265, 9893.604255829687, 9935.65225217114, 9993.950030249947, 9998.450085245382, 10117.140043096271, 22039.89800509968, 22040.0, 22045.228417005328, 22069.11723531073, 22088.337349879206, 7674.0, 7767.961160194162, 7801.531908085533, 7869.868319132501, 7909.129995700583, 4283.892902677458, 4290.957090429056, 4313.956860431355, 4319.438472998553, 4544.159330523864, 3733.4586484959527, 3742.4947632069866, 3785.337565925952, 3787.886363409102, 3791.1659434924272, 653.9934600653933, 655.8786624474487, 665.9567128136656, 674.8022260487674, 689.3106893106893, 502.36450889624655, 611.8011646214998, 625.8904691678938, 634.4448607468465, 666.8732940741244, 7938.404619653549, 8378.874316885252, 8429.241368276884, 8547.401681882333, 8838.425502342456, 576.1012820000813, 601.4917394801386, 612.1495791471682, 617.102116420607, 643.1831573901119, 3891.922161556753, 3920.8235629396745, 3990.501187351581, 4026.174634199982, 4175.603317684815, 81968.0, 83916.58041709752, 84255.578722106, 84372.57813710893, 84682.0, 80599.0, 80845.59577202077, 81785.59107204426, 82234.58882705549, 84976.15023849806, 55103.44896550983, 65913.34086659168, 69273.65363173252, 70950.64524677343, 71628.2837171632, 6413.717256548803, 6832.658367081725, 7012.195121951284, 7850.764477065697, 7862.01724784402, 49443.75278123586, 52051.21923171231, 52477.55370189596, 53329.733351334544, 54668.0, 51357.48642513527, 67952.0, 68724.0, 68910.65544672245, 73295.90056149269, 11078.390688512209, 11323.15076369259, 11384.0, 11486.885131148581, 11737.530498779954, 11910.880891190976, 12085.335306558223, 12267.447964841782, 12318.0, 12691.301978391277, 10513.78972420532, 11406.315621062762, 11449.313041217554, 11897.0, 12263.75472490563, 9067.818643626959, 10342.0, 11640.35978021217, 11696.35670038156, 11831.112666550216, 11311.038561722016, 11514.481848317013, 11591.420428978687, 11677.76644467123, 11697.415129243675, 8288.958555207186, 8372.204640559137, 8931.330150238628, 9868.703938881845, 9987.800243995225, 8609.053004169411, 8982.685606003759, 9745.891472095727, 9814.950925245328, 9843.85234221473], "unit": "score", "name": "yui.html", "value": 7070.659036689326}], "name": "dromaeo_css", "value": 7670.114787417102}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [113.0, 112.0, 111.0, 113.0, 113.0, 113.0, 128.0, 112.0, 110.0, 111.0], "unit": "score", "name": "ai-astar", "value": 113.6}, {"lowerIsBetter": true, "replicates": [154.0, 120.0, 144.0, 153.0, 116.0, 135.0, 121.0, 179.0, 178.0, 145.0], "unit": "score", "name": "audio-beat-detection", "value": 144.5}, {"lowerIsBetter": true, "replicates": [185.0, 184.0, 181.0, 184.0, 191.0, 253.0, 226.0, 183.0, 182.0, 182.0], "unit": "score", "name": "audio-dft", "value": 195.1}, {"lowerIsBetter": true, "replicates": [72.0, 96.0, 106.0, 153.0, 55.0, 57.0, 56.0, 56.0, 111.0, 56.0], "unit": "score", "name": "audio-fft", "value": 81.8}, {"lowerIsBetter": true, "replicates": [87.0, 89.0, 132.0, 77.0, 80.0, 83.0, 118.0, 75.0, 79.0, 120.0], "unit": "score", "name": "audio-oscillator", "value": 94.0}, {"lowerIsBetter": true, "replicates": [87.0, 84.0, 86.0, 84.0, 86.0, 83.0, 116.0, 130.0, 75.0, 104.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 93.5}, {"lowerIsBetter": true, "replicates": [92.0, 92.0, 92.0, 93.0, 93.0, 92.0, 92.0, 92.0, 91.0, 92.0], "unit": "score", "name": "imaging-darkroom", "value": 92.1}, {"lowerIsBetter": true, "replicates": [85.0, 82.0, 87.0, 83.0, 85.0, 84.0, 84.0, 84.0, 83.0, 84.0], "unit": "score", "name": "imaging-desaturate", "value": 84.1}, {"lowerIsBetter": true, "replicates": [55.0, 57.0, 59.0, 57.0, 57.0, 58.0, 58.0, 56.0, 66.0, 51.0], "unit": "score", "name": "json-parse-financial", "value": 57.4}, {"lowerIsBetter": true, "replicates": [62.0, 60.0, 68.0, 67.0, 77.0, 72.0, 67.0, 65.0, 65.0, 66.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 66.9}, {"lowerIsBetter": true, "replicates": [82.0, 81.0, 80.0, 81.0, 80.0, 80.0, 81.0, 68.0, 80.0, 100.0], "unit": "score", "name": "stanford-crypto-aes", "value": 81.3}, {"lowerIsBetter": true, "replicates": [130.0, 117.0, 130.0, 125.0, 128.0, 126.0, 124.0, 114.0, 123.0, 106.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 122.3}, {"lowerIsBetter": true, "replicates": [135.0, 123.0, 162.0, 171.0, 137.0, 122.0, 122.0, 134.0, 137.0, 130.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 137.3}, {"lowerIsBetter": true, "replicates": [65.0, 66.0, 64.0, 65.0, 65.0, 66.0, 64.0, 64.0, 66.0, 67.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 65.2}], "name": "kraken", "value": 1429.1}]} 12:23:55 INFO - Return code: 0 12:23:55 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 12:23:55 INFO - # TBPL SUCCESS # 12:23:55 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:23:55 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:23:55 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:23:55 INFO - 2016-05-26 12:23:55.599 screenresolution[1677:9680] starting screenresolution argv=screenresolution get 12:23:55 INFO - 2016-05-26 12:23:55.601 screenresolution[1677:9680] Display 0: 1600x1200x32@60 12:23:55 INFO - 2016-05-26 12:23:55.607 screenresolution[1678:9683] starting screenresolution argv=screenresolution list 12:23:55 INFO - Available Modes on Display 0 12:23:55 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:23:55 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:23:55 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:23:55 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:23:55 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:23:55 INFO - Intel Iris: 12:23:55 INFO - Chipset Model: Intel Iris 12:23:55 INFO - Type: GPU 12:23:55 INFO - Bus: Built-In 12:23:55 INFO - VRAM (Dynamic, Max): 1536 MB 12:23:55 INFO - Vendor: Intel (0x8086) 12:23:55 INFO - Device ID: 0x0a2e 12:23:55 INFO - Revision ID: 0x0009 12:23:55 INFO - Displays: 12:23:55 INFO - Display: 12:23:55 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:23:55 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:23:55 INFO - Display Serial Number: 1600x1200 60 12:23:55 INFO - Main Display: Yes 12:23:55 INFO - Mirror: Off 12:23:55 INFO - Online: Yes 12:23:55 INFO - Rotation: Supported 12:23:55 INFO - Return code: 0 12:23:55 INFO - Running post-action listener: _resource_record_post_action 12:23:55 INFO - Running post-run listener: _resource_record_post_run 12:23:56 INFO - Total resource usage - Wall time: 1192s; CPU: 52.0%; Read bytes: 38114816; Write bytes: 508407808; Read time: 393; Write time: 1798 12:23:56 INFO - install - Wall time: 23s; CPU: 54.0%; Read bytes: 197630464; Write bytes: 198113280; Read time: 17980; Write time: 401 12:23:56 INFO - run-tests - Wall time: 1170s; CPU: 52.0%; Read bytes: 37193216; Write bytes: 300333056; Read time: 375; Write time: 1365 12:23:56 INFO - Running post-run listener: _upload_blobber_files 12:23:56 INFO - Blob upload gear active. 12:23:56 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:23:56 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 12:23:56 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'] 12:23:56 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 12:23:57 INFO - (blobuploader) - INFO - Open directory for files ... 12:23:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:23:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:23:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:23:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:23:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:23:58 INFO - (blobuploader) - INFO - Done attempting. 12:23:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:23:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:23:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:23:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:23:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:23:59 INFO - (blobuploader) - INFO - Done attempting. 12:23:59 INFO - (blobuploader) - INFO - Iteration through files over. 12:23:59 INFO - Return code: 0 12:23:59 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:23:59 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:23:59 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b42b0922036d85d147c97fe9204f6128ab6fc54cee03e58926191fc57a6ffab0aa28aa1f511788372acf6333d458bf40db4017d5ceadbd7964c8a5f9ffb1056e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d177a1d04adc9eb3e5d9881b3760e8e73d666d3a1808fe5728145c6b1fd043a52682a4b285332f7f58cec5b3579378eb506f9902f0fe193297e6f05b2497bca8"} 12:23:59 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:23:59 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:23:59 INFO - Contents: 12:23:59 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b42b0922036d85d147c97fe9204f6128ab6fc54cee03e58926191fc57a6ffab0aa28aa1f511788372acf6333d458bf40db4017d5ceadbd7964c8a5f9ffb1056e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d177a1d04adc9eb3e5d9881b3760e8e73d666d3a1808fe5728145c6b1fd043a52682a4b285332f7f58cec5b3579378eb506f9902f0fe193297e6f05b2497bca8"} 12:23:59 INFO - Running post-run listener: copy_logs_to_upload_dir 12:23:59 INFO - Copying logs to upload dir... 12:23:59 INFO - mkdir: /builds/slave/test/build/upload/logs 12:23:59 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1248.904487 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2016-05-26 12:23:59.672540) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-26 12:23:59.677941) ========= 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.8YFxuWTJvw/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.WbJmsCoFWt/Listeners TMPDIR=/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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/b42b0922036d85d147c97fe9204f6128ab6fc54cee03e58926191fc57a6ffab0aa28aa1f511788372acf6333d458bf40db4017d5ceadbd7964c8a5f9ffb1056e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d177a1d04adc9eb3e5d9881b3760e8e73d666d3a1808fe5728145c6b1fd043a52682a4b285332f7f58cec5b3579378eb506f9902f0fe193297e6f05b2497bca8"} build_url:https://queue.taskcluster.net/v1/task/R_7YRjrlSkitOrHzsewm-w/artifacts/public/build/firefox-47.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010890 build_url: 'https://queue.taskcluster.net/v1/task/R_7YRjrlSkitOrHzsewm-w/artifacts/public/build/firefox-47.0.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b42b0922036d85d147c97fe9204f6128ab6fc54cee03e58926191fc57a6ffab0aa28aa1f511788372acf6333d458bf40db4017d5ceadbd7964c8a5f9ffb1056e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d177a1d04adc9eb3e5d9881b3760e8e73d666d3a1808fe5728145c6b1fd043a52682a4b285332f7f58cec5b3579378eb506f9902f0fe193297e6f05b2497bca8"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-26 12:23:59.712195) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 12:23:59.712942) ========= 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.8YFxuWTJvw/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.WbJmsCoFWt/Listeners TMPDIR=/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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.005333 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 12:23:59.765930) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-26 12:23:59.766622) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-26 12:23:59.767350) ========= ========= Total master_lag: 0.18 =========