builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0372 starttime: 1482596452.64 results: success (0) buildid: 20161224062636 builduid: bf03869168b14236a3bae5a42b8ebce0 revision: 1deac793ef1dc655f2095e1f8d15deb32e2f666f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-24 08:20:52.640155) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-24 08:20:52.640594) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-24 08:20:52.640885) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-24 08:20:52.671514) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-24 08:20:52.671890) ========= 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.3EvC8DkL0e/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.aAIDv5Vvez/Listeners TMPDIR=/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/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-12-24 08:20:52-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.8M=0.001s 2016-12-24 08:20:52 (18.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.210366 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-24 08:20:52.931463) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-24 08:20:52.931795) ========= 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.3EvC8DkL0e/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.aAIDv5Vvez/Listeners TMPDIR=/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/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.027113 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-24 08:20:52.987933) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-24 08:20:52.988249) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 1deac793ef1dc655f2095e1f8d15deb32e2f666f --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-aurora --rev 1deac793ef1dc655f2095e1f8d15deb32e2f666f --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.3EvC8DkL0e/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.aAIDv5Vvez/Listeners TMPDIR=/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/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-12-24 08:20:53,086 truncating revision to first 12 chars 2016-12-24 08:20:53,086 Setting DEBUG logging. 2016-12-24 08:20:53,086 attempt 1/10 2016-12-24 08:20:53,086 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/1deac793ef1d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-24 08:20:53,715 unpacking tar archive at: mozilla-aurora-1deac793ef1d/testing/mozharness/ program finished with exit code 0 elapsedTime=0.898712 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-24 08:20:53.912283) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-24 08:20:53.912555) ========= script_repo_revision: 1deac793ef1dc655f2095e1f8d15deb32e2f666f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-24 08:20:53.912937) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-24 08:20:53.913205) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-24 08:20:53.932675) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-12-24 08:20:53.932948) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.3EvC8DkL0e/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.aAIDv5Vvez/Listeners TMPDIR=/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/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 08:20:54 INFO - MultiFileLogger online at 20161224 08:20:54 in /builds/slave/test 08:20:54 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 08:20:54 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:20:54 INFO - {'append_to_log': False, 08:20:54 INFO - 'base_work_dir': '/builds/slave/test', 08:20:54 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 08:20:54 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:20:54 INFO - 'branch': 'Mozilla-Aurora', 08:20:54 INFO - 'buildbot_json_path': 'buildprops.json', 08:20:54 INFO - 'config_files': ('talos/mac_config.py',), 08:20:54 INFO - 'default_actions': ('clobber', 08:20:54 INFO - 'read-buildbot-config', 08:20:54 INFO - 'download-and-extract', 08:20:54 INFO - 'populate-webroot', 08:20:54 INFO - 'create-virtualenv', 08:20:54 INFO - 'install', 08:20:54 INFO - 'run-tests'), 08:20:54 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:20:54 INFO - 'download_minidump_stackwalk': True, 08:20:54 INFO - 'download_symbols': 'ondemand', 08:20:54 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:20:54 INFO - 'tooltool.py': '/tools/tooltool.py', 08:20:54 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:20:54 INFO - '/tools/misc-python/virtualenv.py')}, 08:20:54 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:20:54 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:20:54 INFO - 'installer_path': 'installer.exe', 08:20:54 INFO - 'log_level': 'info', 08:20:54 INFO - 'log_name': 'talos', 08:20:54 INFO - 'log_to_console': True, 08:20:54 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:20:54 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:20:54 INFO - 'opt_config_files': (), 08:20:54 INFO - 'pip_index': False, 08:20:54 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:20:54 INFO - 'cmd': ('bash', 08:20:54 INFO - '-c', 08:20:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:20:54 INFO - 'enabled': True, 08:20:54 INFO - 'halt_on_failure': False, 08:20:54 INFO - 'name': 'check_screen_resolution'},), 08:20:54 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:20:54 INFO - 'cmd': ('bash', 08:20:54 INFO - '-c', 08:20:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:20:54 INFO - 'enabled': True, 08:20:54 INFO - 'halt_on_failure': False, 08:20:54 INFO - 'name': 'check_screen_resolution'},), 08:20:54 INFO - 'run_cmd_checks_enabled': True, 08:20:54 INFO - 'sps_profile': False, 08:20:54 INFO - 'sps_profile_interval': 0, 08:20:54 INFO - 'suite': 'dromaeojs-e10s', 08:20:54 INFO - 'system_bits': '32', 08:20:54 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:20:54 INFO - 'title': 't-yosemite-r7-0372', 08:20:54 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:20:54 INFO - 'use_talos_json': True, 08:20:54 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:20:54 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:20:54 INFO - 'work_dir': 'build'} 08:20:54 INFO - [mozharness: 2016-12-24 16:20:54.138554Z] Running clobber step. 08:20:54 INFO - Running pre-action listener: _resource_record_pre_action 08:20:54 INFO - Running main action method: clobber 08:20:54 INFO - rmtree: /builds/slave/test/build 08:20:54 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:20:56 INFO - Running post-action listener: _resource_record_post_action 08:20:56 INFO - [mozharness: 2016-12-24 16:20:56.427507Z] Finished clobber step (success) 08:20:56 INFO - [mozharness: 2016-12-24 16:20:56.427632Z] Running read-buildbot-config step. 08:20:56 INFO - Running pre-action listener: _resource_record_pre_action 08:20:56 INFO - Running main action method: read_buildbot_config 08:20:56 INFO - Using buildbot properties: 08:20:56 INFO - { 08:20:56 INFO - "project": "", 08:20:56 INFO - "product": "firefox", 08:20:56 INFO - "script_repo_revision": "production", 08:20:56 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 08:20:56 INFO - "repository": "", 08:20:56 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 08:20:56 INFO - "buildid": "20161224062636", 08:20:56 INFO - "pgo_build": "False", 08:20:56 INFO - "basedir": "/builds/slave/test", 08:20:56 INFO - "buildnumber": 66, 08:20:56 INFO - "slavename": "t-yosemite-r7-0372", 08:20:56 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 08:20:56 INFO - "platform": "yosemite_r7", 08:20:56 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 08:20:56 INFO - "branch": "mozilla-aurora", 08:20:56 INFO - "repo_path": "releases/mozilla-aurora", 08:20:56 INFO - "revision": "1deac793ef1dc655f2095e1f8d15deb32e2f666f", 08:20:56 INFO - "stage_platform": "macosx64", 08:20:56 INFO - "builduid": "bf03869168b14236a3bae5a42b8ebce0", 08:20:56 INFO - "slavebuilddir": "test" 08:20:56 INFO - } 08:20:56 INFO - Found installer url https://queue.taskcluster.net/v1/task/Q6uInrPtRgOX8HnUExrG1g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 08:20:56 INFO - Running post-action listener: _resource_record_post_action 08:20:56 INFO - [mozharness: 2016-12-24 16:20:56.430928Z] Finished read-buildbot-config step (success) 08:20:56 INFO - [mozharness: 2016-12-24 16:20:56.431042Z] Running download-and-extract step. 08:20:56 INFO - Running pre-action listener: _resource_record_pre_action 08:20:56 INFO - Running main action method: download_and_extract 08:20:56 INFO - mkdir: /builds/slave/test/build/tests 08:20:56 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:20:56 INFO - trying https://queue.taskcluster.net/v1/task/Q6uInrPtRgOX8HnUExrG1g/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 08:20:56 INFO - Downloading https://queue.taskcluster.net/v1/task/Q6uInrPtRgOX8HnUExrG1g/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 08:20:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Q6uInrPtRgOX8HnUExrG1g/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 08:20:57 INFO - Downloaded 1199 bytes. 08:20:57 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 08:20:57 INFO - Using the following test package requirements: 08:20:57 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 08:20:57 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:20:57 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 08:20:57 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:20:57 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 08:20:57 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:20:57 INFO - u'jsshell-mac.zip'], 08:20:57 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:20:57 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 08:20:57 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 08:20:57 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:20:57 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 08:20:57 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:20:57 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 08:20:57 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:20:57 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 08:20:57 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:20:57 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 08:20:57 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 08:20:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Q6uInrPtRgOX8HnUExrG1g/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 08:20:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Q6uInrPtRgOX8HnUExrG1g/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:20:57 INFO - Fetch https://queue.taskcluster.net/v1/task/Q6uInrPtRgOX8HnUExrG1g/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 08:20:58 INFO - Http code: 200 08:20:58 INFO - via: 1.1 ff555a0cc6346f82fe74596f8ed6cc33.cloudfront.net (CloudFront) 08:20:58 INFO - x-amz-cf-id: giJTSmUAXr91JxJDzlz7_nPLMrBOwEWwG5XRcLP_YzgVZ-CuD3xH9Q== 08:20:58 INFO - x-amz-version-id: TQq1GZh01Kpsv5NtxWuFFqyF6niuL.Jp 08:20:58 INFO - Expected file size: 16271146 08:20:58 INFO - Obtained file size: 16271146 08:21:05 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 08:21:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Q6uInrPtRgOX8HnUExrG1g/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 08:21:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Q6uInrPtRgOX8HnUExrG1g/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:21:05 INFO - Fetch https://queue.taskcluster.net/v1/task/Q6uInrPtRgOX8HnUExrG1g/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 08:21:06 INFO - Http code: 200 08:21:06 INFO - via: 1.1 ba26a6eef2785fa2e20e601890b882b2.cloudfront.net (CloudFront) 08:21:06 INFO - x-amz-cf-id: IylvZ_7vz5mNq6OoClPcv0N-23V92Ce5Xz7VV74pOxMuHSSuOQ0cIA== 08:21:06 INFO - x-amz-version-id: TQq1GZh01Kpsv5NtxWuFFqyF6niuL.Jp 08:21:06 INFO - Expected file size: 16271146 08:21:06 INFO - Obtained file size: 16271146 08:21:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Q6uInrPtRgOX8HnUExrG1g/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 08:21:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Q6uInrPtRgOX8HnUExrG1g/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 08:21:12 INFO - Fetch https://queue.taskcluster.net/v1/task/Q6uInrPtRgOX8HnUExrG1g/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 08:21:13 INFO - Http code: 200 08:21:13 INFO - via: 1.1 bfd7406fdd888e34dfca79886cb7c964.cloudfront.net (CloudFront) 08:21:13 INFO - x-amz-cf-id: KQSb1cxf_ZqEwjxuLf9eK38z8ruJWMNtjClJ1qA2JCYaShpS5ADHAQ== 08:21:13 INFO - x-amz-version-id: 0rMsfpbFcI3AgDct4IX0oAUle80vXfHz 08:21:13 INFO - Expected file size: 14409038 08:21:13 INFO - Obtained file size: 14409038 08:21:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:21:13 INFO - trying https://queue.taskcluster.net/v1/task/Q6uInrPtRgOX8HnUExrG1g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:21:13 INFO - Downloading https://queue.taskcluster.net/v1/task/Q6uInrPtRgOX8HnUExrG1g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 08:21:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Q6uInrPtRgOX8HnUExrG1g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 08:21:16 INFO - Downloaded 109787364 bytes. 08:21:16 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Q6uInrPtRgOX8HnUExrG1g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:21:16 INFO - mkdir: /builds/slave/test/properties 08:21:16 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:21:16 INFO - Writing to file /builds/slave/test/properties/build_url 08:21:16 INFO - Contents: 08:21:16 INFO - build_url:https://queue.taskcluster.net/v1/task/Q6uInrPtRgOX8HnUExrG1g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:21:17 INFO - Running post-action listener: _resource_record_post_action 08:21:17 INFO - Running post-action listener: set_extra_try_arguments 08:21:17 INFO - [mozharness: 2016-12-24 16:21:17.175904Z] Finished download-and-extract step (success) 08:21:17 INFO - [mozharness: 2016-12-24 16:21:17.176041Z] Running populate-webroot step. 08:21:17 INFO - Running pre-action listener: _resource_record_pre_action 08:21:17 INFO - Running main action method: populate_webroot 08:21:17 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:21:17 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:21:17 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:21:17 INFO - '--tppagecycles', 08:21:17 INFO - '7'], 08:21:17 INFO - 'tests': ['tsvgm']}}, 08:21:17 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:21:17 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:21:17 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:21:17 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:21:17 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:21:17 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:21:17 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:21:17 INFO - 'talos_options': ['--disable-e10s'], 08:21:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:21:17 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:21:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:21:17 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:21:17 INFO - 'talos_options': ['--disable-e10s'], 08:21:17 INFO - 'tests': ['damp', 'tps']}, 08:21:17 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:21:17 INFO - 'tests': ['damp', 'tps']}, 08:21:17 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:21:17 INFO - 'tests': ['dromaeo_dom']}, 08:21:17 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:21:17 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:21:17 INFO - 'tests': ['basic_compositor_video']}, 08:21:17 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:21:17 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:21:17 INFO - 'tests': ['a11yr', 08:21:17 INFO - 'ts_paint', 08:21:17 INFO - 'tpaint', 08:21:17 INFO - 'sessionrestore', 08:21:17 INFO - 'sessionrestore_no_auto_restore', 08:21:17 INFO - 'tabpaint']}, 08:21:17 INFO - 'other-e10s': {'tests': ['a11yr', 08:21:17 INFO - 'ts_paint', 08:21:17 INFO - 'tpaint', 08:21:17 INFO - 'sessionrestore', 08:21:17 INFO - 'sessionrestore_no_auto_restore', 08:21:17 INFO - 'tabpaint']}, 08:21:17 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:21:17 INFO - 'ts_paint', 08:21:17 INFO - 'tpaint', 08:21:17 INFO - 'sessionrestore', 08:21:17 INFO - 'sessionrestore_no_auto_restore', 08:21:17 INFO - 'tabpaint']}, 08:21:17 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:21:17 INFO - 'ts_paint', 08:21:17 INFO - 'tpaint', 08:21:17 INFO - 'sessionrestore', 08:21:17 INFO - 'sessionrestore_no_auto_restore', 08:21:17 INFO - 'tabpaint']}, 08:21:17 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:21:17 INFO - 'tests': ['a11yr', 08:21:17 INFO - 'ts_paint', 08:21:17 INFO - 'tpaint', 08:21:17 INFO - 'sessionrestore', 08:21:17 INFO - 'sessionrestore_no_auto_restore', 08:21:17 INFO - 'tabpaint']}, 08:21:17 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:21:17 INFO - 'tests': ['a11yr', 08:21:17 INFO - 'ts_paint', 08:21:17 INFO - 'tpaint', 08:21:17 INFO - 'sessionrestore', 08:21:17 INFO - 'sessionrestore_no_auto_restore', 08:21:17 INFO - 'tabpaint']}, 08:21:17 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:21:17 INFO - 'tests': ['tsvgx', 08:21:17 INFO - 'tsvgr_opacity', 08:21:17 INFO - 'tart', 08:21:17 INFO - 'tscrollx', 08:21:17 INFO - 'cart']}, 08:21:17 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:21:17 INFO - 'tsvgr_opacity', 08:21:17 INFO - 'tart', 08:21:17 INFO - 'tscrollx', 08:21:17 INFO - 'cart']}, 08:21:17 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:21:17 INFO - 'talos_options': ['--disable-e10s'], 08:21:17 INFO - 'tests': ['tp5o']}, 08:21:17 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:21:17 INFO - 'tests': ['tp5o']}, 08:21:17 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:21:17 INFO - 'talos_options': ['--disable-e10s', 08:21:17 INFO - '--xperf_path', 08:21:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:21:17 INFO - 'tests': ['tp5n']}, 08:21:17 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:21:17 INFO - 'talos_options': ['--xperf_path', 08:21:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:21:17 INFO - 'tests': ['tp5n']}}, 08:21:17 INFO - 'talos.zip': {'path': '', 08:21:17 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:21:17 INFO - Running post-action listener: _resource_record_post_action 08:21:17 INFO - [mozharness: 2016-12-24 16:21:17.194814Z] Finished populate-webroot step (success) 08:21:17 INFO - [mozharness: 2016-12-24 16:21:17.194928Z] Running create-virtualenv step. 08:21:17 INFO - Running pre-action listener: _resource_record_pre_action 08:21:17 INFO - Running main action method: create_virtualenv 08:21:17 INFO - Creating virtualenv /builds/slave/test/build/venv 08:21:17 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 08:21:17 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:21:17 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:21:17 INFO - Using real prefix '/tools/python27' 08:21:17 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:21:18 INFO - Installing distribute.............................................................................................................................................................................................done. 08:21:20 INFO - Installing pip.................done. 08:21:20 INFO - Return code: 0 08:21:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:21:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:21:21 INFO - Reading from file tmpfile_stdout 08:21:21 INFO - Output received: 08:21:21 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 08:21:21 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:21:21 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:21:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:21:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:21:21 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:21:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:21:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:21:21 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 0x10b3ef490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b365570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd99acc1460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b203d30>, '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 0x10b069db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b1fbb20>, '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.3EvC8DkL0e/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.aAIDv5Vvez/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/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 08:21:21 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 08:21:21 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 08:21:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3EvC8DkL0e/Render', 08:21:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:21:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:21:21 INFO - 'HOME': '/Users/cltbld', 08:21:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:21:21 INFO - 'LOGNAME': 'cltbld', 08:21:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:21:21 INFO - 'MOZ_NO_REMOTE': '1', 08:21:21 INFO - 'NO_EM_RESTART': '1', 08:21:21 INFO - 'PAGER': '/bin/cat', 08:21:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:21:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:21:21 INFO - 'PWD': '/builds/slave/test', 08:21:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:21:21 INFO - 'SHELL': '/bin/bash', 08:21:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aAIDv5Vvez/Listeners', 08:21:21 INFO - 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/', 08:21:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:21:21 INFO - 'USER': 'cltbld', 08:21:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:21:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:21:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:21:21 INFO - 'XPC_FLAGS': '0x0', 08:21:21 INFO - 'XPC_SERVICE_NAME': '0', 08:21:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:21:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:21:21 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 08:21:21 INFO - Cleaning up... 08:21:21 INFO - Return code: 0 08:21:21 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:21:21 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:21:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:21:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:21:21 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:21:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:21:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:21:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b3ef490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b365570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd99acc1460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b203d30>, '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 0x10b069db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b1fbb20>, '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.3EvC8DkL0e/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.aAIDv5Vvez/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/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 08:21:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:21:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:21:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3EvC8DkL0e/Render', 08:21:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:21:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:21:21 INFO - 'HOME': '/Users/cltbld', 08:21:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:21:21 INFO - 'LOGNAME': 'cltbld', 08:21:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:21:21 INFO - 'MOZ_NO_REMOTE': '1', 08:21:21 INFO - 'NO_EM_RESTART': '1', 08:21:21 INFO - 'PAGER': '/bin/cat', 08:21:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:21:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:21:21 INFO - 'PWD': '/builds/slave/test', 08:21:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:21:21 INFO - 'SHELL': '/bin/bash', 08:21:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aAIDv5Vvez/Listeners', 08:21:21 INFO - 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/', 08:21:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:21:21 INFO - 'USER': 'cltbld', 08:21:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:21:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:21:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:21:21 INFO - 'XPC_FLAGS': '0x0', 08:21:21 INFO - 'XPC_SERVICE_NAME': '0', 08:21:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:21:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:21:21 INFO - Downloading/unpacking psutil>=3.1.1 08:21:21 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 08:21:21 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 08:21:21 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 08:21:21 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 08:21:21 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 08:21:21 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 08:21:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:21:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:21:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:21:25 INFO - Installing collected packages: psutil 08:21:25 INFO - Running setup.py install for psutil 08:21:25 INFO - building 'psutil._psutil_osx' extension 08:21:25 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 08:21:26 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 08:21:26 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 08:21:26 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 08:21:26 INFO - building 'psutil._psutil_posix' extension 08:21:26 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 08:21:26 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:21:26 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:21:26 INFO - ^ 08:21:26 INFO - 1 warning generated. 08:21:26 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 08:21:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:21:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:21:26 INFO - Successfully installed psutil 08:21:26 INFO - Cleaning up... 08:21:26 INFO - Return code: 0 08:21:26 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:21:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:21:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:21:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:21:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:21:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:21:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:21:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b3ef490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b365570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd99acc1460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b203d30>, '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 0x10b069db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b1fbb20>, '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.3EvC8DkL0e/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.aAIDv5Vvez/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/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 08:21:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:21:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 08:21:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3EvC8DkL0e/Render', 08:21:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:21:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:21:26 INFO - 'HOME': '/Users/cltbld', 08:21:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:21:26 INFO - 'LOGNAME': 'cltbld', 08:21:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:21:26 INFO - 'MOZ_NO_REMOTE': '1', 08:21:26 INFO - 'NO_EM_RESTART': '1', 08:21:26 INFO - 'PAGER': '/bin/cat', 08:21:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:21:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:21:26 INFO - 'PWD': '/builds/slave/test', 08:21:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:21:26 INFO - 'SHELL': '/bin/bash', 08:21:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aAIDv5Vvez/Listeners', 08:21:26 INFO - 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/', 08:21:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:21:26 INFO - 'USER': 'cltbld', 08:21:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:21:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:21:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:21:26 INFO - 'XPC_FLAGS': '0x0', 08:21:26 INFO - 'XPC_SERVICE_NAME': '0', 08:21:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:21:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:21:27 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:21:27 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 08:21:27 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 08:21:27 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 08:21:27 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 08:21:27 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 08:21:27 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 08:21:29 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:21:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:21:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:21:29 INFO - Installing collected packages: mozsystemmonitor 08:21:29 INFO - Running setup.py install for mozsystemmonitor 08:21:29 INFO - Successfully installed mozsystemmonitor 08:21:29 INFO - Cleaning up... 08:21:30 INFO - Return code: 0 08:21:30 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:21:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:21:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:21:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:21:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:21:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:21:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:21:30 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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b3ef490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b365570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd99acc1460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b203d30>, '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 0x10b069db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b1fbb20>, '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.3EvC8DkL0e/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.aAIDv5Vvez/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/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 08:21:30 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==2.5.1'] in /builds/slave/test/build 08:21:30 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==2.5.1 08:21:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3EvC8DkL0e/Render', 08:21:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:21:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:21:30 INFO - 'HOME': '/Users/cltbld', 08:21:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:21:30 INFO - 'LOGNAME': 'cltbld', 08:21:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:21:30 INFO - 'MOZ_NO_REMOTE': '1', 08:21:30 INFO - 'NO_EM_RESTART': '1', 08:21:30 INFO - 'PAGER': '/bin/cat', 08:21:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:21:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:21:30 INFO - 'PWD': '/builds/slave/test', 08:21:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:21:30 INFO - 'SHELL': '/bin/bash', 08:21:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aAIDv5Vvez/Listeners', 08:21:30 INFO - 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/', 08:21:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:21:30 INFO - 'USER': 'cltbld', 08:21:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:21:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:21:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:21:30 INFO - 'XPC_FLAGS': '0x0', 08:21:30 INFO - 'XPC_SERVICE_NAME': '0', 08:21:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:21:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:21:30 INFO - Downloading/unpacking jsonschema==2.5.1 08:21:30 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 08:21:30 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 08:21:30 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 08:21:30 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 08:21:30 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 08:21:30 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 08:21:32 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:21:32 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:21:32 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 08:21:32 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 08:21:32 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 08:21:32 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 08:21:32 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 08:21:32 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 08:21:33 INFO - Downloading functools32-3.2.3-2.tar.gz 08:21:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:21:33 INFO - warning: no files found matching '*.txt' 08:21:33 INFO - no previously-included directories found matching 'build' 08:21:33 INFO - no previously-included directories found matching 'dist' 08:21:33 INFO - no previously-included directories found matching '.git*' 08:21:33 INFO - Installing collected packages: jsonschema, functools32 08:21:33 INFO - Running setup.py install for functools32 08:21:33 INFO - warning: no files found matching '*.txt' 08:21:33 INFO - no previously-included directories found matching 'build' 08:21:33 INFO - no previously-included directories found matching 'dist' 08:21:33 INFO - no previously-included directories found matching '.git*' 08:21:33 INFO - Successfully installed jsonschema functools32 08:21:33 INFO - Cleaning up... 08:21:33 INFO - Return code: 0 08:21:33 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:21:33 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:21:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:21:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:21:33 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:21:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:21:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:21:33 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', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b3ef490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b365570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd99acc1460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b203d30>, '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 0x10b069db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b1fbb20>, '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.3EvC8DkL0e/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.aAIDv5Vvez/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/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 08:21:33 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', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:21:33 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 functools32==3.2.3-2 08:21:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3EvC8DkL0e/Render', 08:21:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:21:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:21:33 INFO - 'HOME': '/Users/cltbld', 08:21:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:21:33 INFO - 'LOGNAME': 'cltbld', 08:21:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:21:33 INFO - 'MOZ_NO_REMOTE': '1', 08:21:33 INFO - 'NO_EM_RESTART': '1', 08:21:33 INFO - 'PAGER': '/bin/cat', 08:21:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:21:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:21:33 INFO - 'PWD': '/builds/slave/test', 08:21:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:21:33 INFO - 'SHELL': '/bin/bash', 08:21:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aAIDv5Vvez/Listeners', 08:21:33 INFO - 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/', 08:21:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:21:33 INFO - 'USER': 'cltbld', 08:21:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:21:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:21:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:21:33 INFO - 'XPC_FLAGS': '0x0', 08:21:33 INFO - 'XPC_SERVICE_NAME': '0', 08:21:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:21:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:21:34 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:21:34 INFO - Cleaning up... 08:21:34 INFO - Return code: 0 08:21:34 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:21:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:21:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:21:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:21:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:21:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:21:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:21:34 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 0x10b3ef490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b365570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd99acc1460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b203d30>, '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 0x10b069db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b1fbb20>, '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.3EvC8DkL0e/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.aAIDv5Vvez/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/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 08:21:34 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 08:21:34 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 08:21:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3EvC8DkL0e/Render', 08:21:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:21:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:21:34 INFO - 'HOME': '/Users/cltbld', 08:21:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:21:34 INFO - 'LOGNAME': 'cltbld', 08:21:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:21:34 INFO - 'MOZ_NO_REMOTE': '1', 08:21:34 INFO - 'NO_EM_RESTART': '1', 08:21:34 INFO - 'PAGER': '/bin/cat', 08:21:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:21:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:21:34 INFO - 'PWD': '/builds/slave/test', 08:21:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:21:34 INFO - 'SHELL': '/bin/bash', 08:21:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aAIDv5Vvez/Listeners', 08:21:34 INFO - 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/', 08:21:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:21:34 INFO - 'USER': 'cltbld', 08:21:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:21:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:21:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:21:34 INFO - 'XPC_FLAGS': '0x0', 08:21:34 INFO - 'XPC_SERVICE_NAME': '0', 08:21:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:21:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:21:34 INFO - Downloading/unpacking blobuploader==1.2.4 08:21:34 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 08:21:34 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 08:21:34 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 08:21:34 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 08:21:34 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 08:21:34 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 08:21:36 INFO - Downloading blobuploader-1.2.4.tar.gz 08:21:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:21:36 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:21:36 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 08:21:36 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 08:21:36 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 08:21:36 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 08:21:36 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 08:21:36 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 08:21:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:21:37 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:21: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 08:21: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 08:21: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 08:21: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 08:21: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 08:21: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 08:21:38 INFO - Downloading docopt-0.6.1.tar.gz 08:21:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:21:38 INFO - Installing collected packages: blobuploader, requests, docopt 08:21:38 INFO - Running setup.py install for blobuploader 08:21:38 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:21:38 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:21:38 INFO - Running setup.py install for requests 08:21:38 INFO - Running setup.py install for docopt 08:21:38 INFO - Successfully installed blobuploader requests docopt 08:21:38 INFO - Cleaning up... 08:21:39 INFO - Return code: 0 08:21:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:21:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:21:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:21:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:21:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:21:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:21:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:21:39 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 0x10b3ef490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b365570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd99acc1460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b203d30>, '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 0x10b069db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b1fbb20>, '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.3EvC8DkL0e/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.aAIDv5Vvez/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/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 08:21:39 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 08:21:39 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 08:21:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3EvC8DkL0e/Render', 08:21:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:21:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:21:39 INFO - 'HOME': '/Users/cltbld', 08:21:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:21:39 INFO - 'LOGNAME': 'cltbld', 08:21:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:21:39 INFO - 'MOZ_NO_REMOTE': '1', 08:21:39 INFO - 'NO_EM_RESTART': '1', 08:21:39 INFO - 'PAGER': '/bin/cat', 08:21:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:21:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:21:39 INFO - 'PWD': '/builds/slave/test', 08:21:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:21:39 INFO - 'SHELL': '/bin/bash', 08:21:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aAIDv5Vvez/Listeners', 08:21:39 INFO - 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/', 08:21:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:21:39 INFO - 'USER': 'cltbld', 08:21:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:21:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:21:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:21:39 INFO - 'XPC_FLAGS': '0x0', 08:21:39 INFO - 'XPC_SERVICE_NAME': '0', 08:21:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:21:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:21:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:21:39 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-lWa6bX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:21:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:21:39 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-MQr1IO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:21:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:21:39 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-OAYwVp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:21:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:21:39 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-Td0Mou-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:21:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:21:39 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-w8Vh1u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:21:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:21:39 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-lSZBLj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:21:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:21:39 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-hmIWk4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:21:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:21:40 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-o76dTd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:21:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:21:40 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-yakPRP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:21:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:21:40 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-gb8w2X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:21:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:21:40 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-DOaEi4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:21:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:21:40 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-D6ibur-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:21:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:21:40 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-Vm2klZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:21:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:21:40 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-PznLIH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:21:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:21:40 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-MCtddq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:21:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:21:40 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-FDrFZs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:21:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:21:40 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-mkAP14-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:21:41 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:21:41 INFO - Running setup.py install for manifestparser 08:21:41 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:21:41 INFO - Running setup.py install for mozcrash 08:21:41 INFO - Running setup.py install for mozdebug 08:21:41 INFO - Running setup.py install for mozdevice 08:21:41 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:21:41 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:21:41 INFO - Running setup.py install for mozfile 08:21:41 INFO - Running setup.py install for mozhttpd 08:21:41 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:21:41 INFO - Running setup.py install for mozinfo 08:21:42 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:21:42 INFO - Running setup.py install for mozInstall 08:21:42 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:21:42 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:21:42 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:21:42 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:21:42 INFO - Running setup.py install for mozleak 08:21:42 INFO - Running setup.py install for mozlog 08:21:42 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:21:42 INFO - Running setup.py install for moznetwork 08:21:42 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:21:42 INFO - Running setup.py install for mozprocess 08:21:42 INFO - Running setup.py install for mozprofile 08:21:43 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:21:43 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:21:43 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:21:43 INFO - Running setup.py install for mozrunner 08:21:43 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:21:43 INFO - Running setup.py install for mozscreenshot 08:21:43 INFO - Running setup.py install for moztest 08:21:43 INFO - Running setup.py install for mozversion 08:21:43 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:21:43 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:21:43 INFO - Cleaning up... 08:21:43 INFO - Return code: 0 08:21:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:21:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:21:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:21:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:21:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:21:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:21:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:21:43 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 0x10b3ef490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b365570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd99acc1460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b203d30>, '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 0x10b069db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b1fbb20>, '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.3EvC8DkL0e/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.aAIDv5Vvez/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/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 08:21:43 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 08:21:43 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 08:21:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3EvC8DkL0e/Render', 08:21:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:21:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:21:43 INFO - 'HOME': '/Users/cltbld', 08:21:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:21:43 INFO - 'LOGNAME': 'cltbld', 08:21:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:21:43 INFO - 'MOZ_NO_REMOTE': '1', 08:21:43 INFO - 'NO_EM_RESTART': '1', 08:21:43 INFO - 'PAGER': '/bin/cat', 08:21:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:21:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:21:43 INFO - 'PWD': '/builds/slave/test', 08:21:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:21:43 INFO - 'SHELL': '/bin/bash', 08:21:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aAIDv5Vvez/Listeners', 08:21:43 INFO - 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/', 08:21:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:21:43 INFO - 'USER': 'cltbld', 08:21:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:21:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:21:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:21:43 INFO - 'XPC_FLAGS': '0x0', 08:21:43 INFO - 'XPC_SERVICE_NAME': '0', 08:21:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:21:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:21:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:21:43 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-fGDQ14-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:21:44 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)) 08:21:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:21:44 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-OdcCL7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:21:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 08:21:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:21:44 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-QJysnP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:21:44 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)) 08:21:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:21:44 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-P0bOps-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:21:44 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)) 08:21:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:21:44 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-BwGa9T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:21:44 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)) 08:21:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:21:44 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-YQtl3m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:21:44 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)) 08:21:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:21:44 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-0BySja-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:21:44 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)) 08:21:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:21:44 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-wDnq20-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:21:44 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)) 08:21:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:21:44 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-rrl0uo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:21:44 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)) 08:21:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:21:44 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-y3tARb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:21:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 08:21:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:21:44 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-0AJZco-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:21:45 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)) 08:21:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:21:45 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-kEeWgm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:21:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 08:21:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:21:45 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-_w9P6x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:21:45 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)) 08:21:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:21:45 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-h_7ZZf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:21:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 08:21:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:21:45 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-M4jSuT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:21:45 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)) 08:21:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:21:45 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-ICraNN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:21:45 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)) 08:21:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:21:45 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-L77cZO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:21:45 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)) 08:21:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:21:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:21:45 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)) 08:21:45 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)) 08:21:45 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)) 08:21:45 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:21:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:21:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:21:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:21:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:21:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:21:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:21:48 INFO - Downloading blessings-1.6.tar.gz 08:21:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:21:48 INFO - Installing collected packages: blessings 08:21:48 INFO - Running setup.py install for blessings 08:21:48 INFO - Successfully installed blessings 08:21:48 INFO - Cleaning up... 08:21:48 INFO - Return code: 0 08:21:48 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:21:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:21:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:21:48 INFO - Reading from file tmpfile_stdout 08:21:48 INFO - Current package versions: 08:21:48 INFO - blessings == 1.6 08:21:48 INFO - blobuploader == 1.2.4 08:21:48 INFO - docopt == 0.6.1 08:21:48 INFO - functools32 == 3.2.3-2 08:21:48 INFO - jsonschema == 2.5.1 08:21:48 INFO - manifestparser == 1.1 08:21:48 INFO - mozInstall == 1.12 08:21:48 INFO - mozcrash == 1.0 08:21:48 INFO - mozdebug == 0.1 08:21:48 INFO - mozdevice == 0.48 08:21:48 INFO - mozfile == 1.2 08:21:48 INFO - mozhttpd == 0.7 08:21:48 INFO - mozinfo == 0.9 08:21:48 INFO - mozleak == 0.1 08:21:48 INFO - mozlog == 3.4 08:21:48 INFO - moznetwork == 0.27 08:21:48 INFO - mozprocess == 0.23 08:21:48 INFO - mozprofile == 0.28 08:21:48 INFO - mozrunner == 6.13 08:21:48 INFO - mozscreenshot == 0.1 08:21:48 INFO - mozsystemmonitor == 0.3 08:21:48 INFO - moztest == 0.7 08:21:48 INFO - mozversion == 1.4 08:21:48 INFO - psutil == 3.1.1 08:21:48 INFO - requests == 1.2.3 08:21:48 INFO - wsgiref == 0.1.2 08:21:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:21:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:21:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:21:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:21:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:21:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:21:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:21:48 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 0x10b3ef490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b365570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd99acc1460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b203d30>, '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 0x10b069db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b1fbb20>, '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.3EvC8DkL0e/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.aAIDv5Vvez/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/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 08:21:48 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 08:21:48 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 08:21:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3EvC8DkL0e/Render', 08:21:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:21:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:21:48 INFO - 'HOME': '/Users/cltbld', 08:21:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:21:48 INFO - 'LOGNAME': 'cltbld', 08:21:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:21:48 INFO - 'MOZ_NO_REMOTE': '1', 08:21:48 INFO - 'NO_EM_RESTART': '1', 08:21:48 INFO - 'PAGER': '/bin/cat', 08:21:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:21:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:21:48 INFO - 'PWD': '/builds/slave/test', 08:21:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:21:48 INFO - 'SHELL': '/bin/bash', 08:21:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aAIDv5Vvez/Listeners', 08:21:48 INFO - 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/', 08:21:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:21:48 INFO - 'USER': 'cltbld', 08:21:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:21:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:21:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:21:48 INFO - 'XPC_FLAGS': '0x0', 08:21:48 INFO - 'XPC_SERVICE_NAME': '0', 08:21:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:21:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:21:49 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)) 08:21:49 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)) 08:21:49 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)) 08:21:49 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)) 08:21:49 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)) 08:21:49 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)) 08:21:49 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)) 08:21:49 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)) 08:21:49 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)) 08:21:49 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)) 08:21:49 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)) 08:21:49 INFO - Cleaning up... 08:21:49 INFO - Return code: 0 08:21:49 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:21: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:21:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:21:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:21: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:21:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:21:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:21:49 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 0x10b3ef490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b365570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd99acc1460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b203d30>, '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 0x10b069db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b1fbb20>, '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.3EvC8DkL0e/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.aAIDv5Vvez/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/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 08:21:49 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 08:21:49 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 08:21:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3EvC8DkL0e/Render', 08:21:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:21:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:21:49 INFO - 'HOME': '/Users/cltbld', 08:21:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:21:49 INFO - 'LOGNAME': 'cltbld', 08:21:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:21:49 INFO - 'MOZ_NO_REMOTE': '1', 08:21:49 INFO - 'NO_EM_RESTART': '1', 08:21:49 INFO - 'PAGER': '/bin/cat', 08:21:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:21:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:21:49 INFO - 'PWD': '/builds/slave/test', 08:21:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:21:49 INFO - 'SHELL': '/bin/bash', 08:21:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aAIDv5Vvez/Listeners', 08:21:49 INFO - 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/', 08:21:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:21:49 INFO - 'USER': 'cltbld', 08:21:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:21:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:21:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:21:49 INFO - 'XPC_FLAGS': '0x0', 08:21:49 INFO - 'XPC_SERVICE_NAME': '0', 08:21:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:21:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:21:49 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:21:49 INFO - Cleaning up... 08:21:49 INFO - Return code: 0 08:21:49 INFO - Running post-action listener: _resource_record_post_action 08:21:49 INFO - Running post-action listener: _start_resource_monitoring 08:21:49 INFO - Starting resource monitoring. 08:21:49 INFO - [mozharness: 2016-12-24 16:21:49.513939Z] Finished create-virtualenv step (success) 08:21:49 INFO - [mozharness: 2016-12-24 16:21:49.514913Z] Running install step. 08:21:49 INFO - Running pre-action listener: _resource_record_pre_action 08:21:49 INFO - Running main action method: install 08:21:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:21:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:21:49 INFO - Reading from file tmpfile_stdout 08:21:49 INFO - Detecting whether we're running mozinstall >=1.0... 08:21:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:21:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:21:49 INFO - Reading from file tmpfile_stdout 08:21:49 INFO - Output received: 08:21:49 INFO - Usage: mozinstall [options] installer 08:21:49 INFO - Options: 08:21:49 INFO - -h, --help show this help message and exit 08:21:49 INFO - -d DEST, --destination=DEST 08:21:49 INFO - Directory to install application into. [default: 08:21:49 INFO - "/builds/slave/test"] 08:21:49 INFO - --app=APP Application being installed. [default: firefox] 08:21:49 INFO - mkdir: /builds/slave/test/build/application 08:21:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:21:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 08:22:21 INFO - Reading from file tmpfile_stdout 08:22:21 INFO - Output received: 08:22:21 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 08:22:21 INFO - Running post-action listener: _resource_record_post_action 08:22:21 INFO - [mozharness: 2016-12-24 16:22:21.581805Z] Finished install step (success) 08:22:21 INFO - [mozharness: 2016-12-24 16:22:21.581939Z] Running run-tests step. 08:22:21 INFO - Running pre-action listener: _resource_record_pre_action 08:22:21 INFO - Running main action method: run_tests 08:22:21 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:22:21 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:22:21 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:22:21 INFO - 2016-12-24 08:22:21.594 screenresolution[1639:8675] starting screenresolution argv=screenresolution get 08:22:21 INFO - 2016-12-24 08:22:21.596 screenresolution[1639:8675] Display 0: 1600x1200x32@60 08:22:21 INFO - 2016-12-24 08:22:21.602 screenresolution[1640:8679] starting screenresolution argv=screenresolution list 08:22:21 INFO - Available Modes on Display 0 08:22:21 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:22:21 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:22:21 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:22:21 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:22:21 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:22:21 INFO - Intel Iris: 08:22:21 INFO - Chipset Model: Intel Iris 08:22:21 INFO - Type: GPU 08:22:21 INFO - Bus: Built-In 08:22:21 INFO - VRAM (Dynamic, Max): 1536 MB 08:22:21 INFO - Vendor: Intel (0x8086) 08:22:21 INFO - Device ID: 0x0a2e 08:22:21 INFO - Revision ID: 0x0009 08:22:21 INFO - Displays: 08:22:21 INFO - Display: 08:22:21 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:22:21 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:22:21 INFO - Display Serial Number: 1600x1200 60 08:22:21 INFO - Main Display: Yes 08:22:21 INFO - Mirror: Off 08:22:21 INFO - Online: Yes 08:22:21 INFO - Rotation: Supported 08:22:21 INFO - Return code: 0 08:22:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:22:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:22:21 INFO - Python 2.7.3 08:22:21 INFO - Return code: 0 08:22:21 INFO - grabbing minidump binary from tooltool 08:22:21 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:22:21 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b203d30>, '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 0x10b069db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b1fbb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:22:21 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 08:22:21 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 08:22:21 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 08:22:21 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:22:21 INFO - Return code: 0 08:22:21 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:22:21 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:22:21 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:22:21 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:22:21 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:22:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Q6uInrPtRgOX8HnUExrG1g/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0372', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 08:22:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Q6uInrPtRgOX8HnUExrG1g/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0372 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 08:22:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3EvC8DkL0e/Render', 08:22:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:22:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:22:21 INFO - 'HOME': '/Users/cltbld', 08:22:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:22:21 INFO - 'LOGNAME': 'cltbld', 08:22:21 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:22:21 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:22:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:22:21 INFO - 'MOZ_NO_REMOTE': '1', 08:22:21 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:22:21 INFO - 'NO_EM_RESTART': '1', 08:22:21 INFO - 'PAGER': '/bin/cat', 08:22:21 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:22:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:22:21 INFO - 'PWD': '/builds/slave/test', 08:22:21 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:22:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:22:21 INFO - 'SHELL': '/bin/bash', 08:22:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aAIDv5Vvez/Listeners', 08:22:21 INFO - 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/', 08:22:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:22:21 INFO - 'USER': 'cltbld', 08:22:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:22:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:22:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:22:21 INFO - 'XPC_FLAGS': '0x0', 08:22:21 INFO - 'XPC_SERVICE_NAME': '0', 08:22:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:22:21 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Q6uInrPtRgOX8HnUExrG1g/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0372', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 08:22:22 INFO - mozversion application_buildid: 20161224062636 08:22:22 INFO - mozversion application_changeset: 1deac793ef1dc655f2095e1f8d15deb32e2f666f 08:22:22 INFO - mozversion application_display_name: FirefoxDeveloperEdition 08:22:22 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:22:22 INFO - mozversion application_name: Firefox 08:22:22 INFO - mozversion application_remotingname: firefox-dev 08:22:22 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:22:22 INFO - mozversion application_vendor: Mozilla 08:22:22 INFO - mozversion application_version: 52.0a2 08:22:22 INFO - mozversion platform_buildid: 20161224062636 08:22:22 INFO - mozversion platform_changeset: 1deac793ef1dc655f2095e1f8d15deb32e2f666f 08:22:22 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:22:22 INFO - mozversion platform_version: 52.0a2 08:22:22 INFO - using testdate: 1482596542 08:22:22 INFO - actual date: 1482596542 08:22:22 INFO - starting webserver on 'localhost:49243' 08:22:22 INFO - SUITE-START | Running 2 tests 08:22:22 INFO - TEST-START | dromaeo_css 08:22:22 INFO - operating with platform_type : mac_ 08:22:22 INFO - Initialising browser for dromaeo_css test... 08:22:22 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/tmpHNvXkZ/profile http://localhost:49243/getInfo.html) 08:22:25 INFO - PROCESS | 1650 | 2016-12-24 08:22:25.125 plugin-container[1652:8870] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b3b, name = 'com.apple.tsm.portname' 08:22:25 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:22:25 INFO - PROCESS | 1650 | 2016-12-24 08:22:25.128 plugin-container[1652:8870] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a1b, name = 'com.apple.CFPasteboardClient' 08:22:25 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:22:25 INFO - PROCESS | 1650 | 2016-12-24 08:22:25.128 plugin-container[1652:8870] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:22:25 INFO - PROCESS | 1650 | __metrics Screen width/height:1600/1200 08:22:25 INFO - PROCESS | 1650 | colorDepth:24 08:22:25 INFO - PROCESS | 1650 | Browser inner width/height: 1024/663 08:22:25 INFO - PROCESS | 1650 | __metrics 08:22:25 ERROR - PROCESS | 1650 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 08:22:26 INFO - TEST-INFO | 1650: exit 0 08:22:26 INFO - Browser initialized. 08:22:26 INFO - Running cycle 1/1 for dromaeo_css test... 08:22:26 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/tmpHNvXkZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:22:28 INFO - PROCESS | 1654 | 2016-12-24 08:22:28.806 plugin-container[1655:8998] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x843f, name = 'com.apple.tsm.portname' 08:22:28 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:22:28 INFO - PROCESS | 1654 | 2016-12-24 08:22:28.807 plugin-container[1655:8998] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a0b, name = 'com.apple.CFPasteboardClient' 08:22:28 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:22:28 INFO - PROCESS | 1654 | 2016-12-24 08:22:28.807 plugin-container[1655:8998] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:22:38 INFO - PROCESS | 1654 | RSS: Main: 243142656 08:22:38 INFO - PROCESS | 1654 | 08:23:28 INFO - PROCESS | 1654 | 1482596608071 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] 08:25:42 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49243/tests/dromaeo/cssquery-ext.html) 08:25:44 INFO - PROCESS | 1654 | RSS: Main: 245624832 08:25:44 INFO - PROCESS | 1654 | 08:28:46 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-ext.html (next: http://localhost:49243/tests/dromaeo/cssquery-jquery.html) 08:28:47 INFO - PROCESS | 1654 | RSS: Main: 254935040 08:28:47 INFO - PROCESS | 1654 | 08:31:49 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49243/tests/dromaeo/cssquery-mootools.html) 08:31:49 INFO - PROCESS | 1654 | RSS: Main: 257781760 08:31:49 INFO - PROCESS | 1654 | 08:34:51 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49243/tests/dromaeo/cssquery-prototype.html) 08:34:52 INFO - PROCESS | 1654 | RSS: Main: 257994752 08:34:52 INFO - PROCESS | 1654 | 08:37:55 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49243/tests/dromaeo/cssquery-yui.html) 08:37:55 INFO - PROCESS | 1654 | RSS: Main: 261431296 08:37:55 INFO - PROCESS | 1654 | 08:40:58 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-yui.html (next: http://localhost:49243/tests/dromaeo/cssquery-dojo.html) 08:40:58 INFO - PROCESS | 1654 | RSS: Main: 262856704 08:40:58 INFO - PROCESS | 1654 | 08:40:58 INFO - PROCESS | 1654 | __start_tp_report 08:40:58 INFO - PROCESS | 1654 | _x_x_mozilla_page_load 08:40:58 INFO - PROCESS | 1654 | _x_x_mozilla_page_load_details 08:40:58 INFO - PROCESS | 1654 | |i|pagename|runs| 08:40:58 INFO - PROCESS | 1654 | |0;dojo.html;3363.8990830275084;3406.9148271293216;3411.1813164840432;3414.3342048300574;3422.589289285286;5715.657060576363;5747.913781293279;5769.855753606162;5782.797602083928;5813.389594092623;5755.740991655375;5778.393268706788;5833.183354330397;5838.503727183194;5840.707964601774;4281.507626622932;5230.320058392404;5267.394249661281;5308.893822123555;5331.600129990247;5257.868553286179;5265.368155821307;5281.392639846409;5314.415414304423;5366.141417373221;5226.111561034633;5241.79032838686;5330.92003619946;5335.573154147659;5357.946420535784;5292.444293349181;5299.364076310867;5321.733913304339;5341.2255222992835;5348.3849357323625;5236.8690782730355;5245.344331958505;5323.946760532383;5334.253204551366;5375;5877.177195192677;5880.176775251425;5893.58744887858;5938.7624495020145;5939.406059394069;6103.792276408629;6168.568200225986;6323.20959880015;6329.620222786648;6337.2395312562785;6241.812745617639;6260.186175797163;6278.277998030239;6306.968465157691;6312.715927783215;4936.284238785428;5381.730913454312;5728;5801.477866991991;5865.706714664249;5313.946860531422;6383.425491705676;6390.488760899117;6404.455621272244;6405.711742971577;5119.87200319995;6080.179175811209;6112.266528016667;6112.297085835097;6271.874562508816;4911.435184953704;6286.119943207955;6291.307956124822;6315.873682526416;6330.841728956813;6134.079888016833;6253.593516421418;6295.779647712308;6306.148669929593;6312.021636646327;6050.485708714808;6122.2761798032625;6143.696113957266;6211.720472578745;6246.6876656167005;4359.607635312837;4906.950930490721;4942.184539551005;4947.307376967227;4984.950150498521;4429.955700442954;4440.622547083534;4448.488423831301;4449.377087207794;4480.641548676098;5748.511376532958;5775.249217601685;5821.2141360916585;5828.737706803204;5955.166276721263;6232.470240029648;6252.124702541648;6267.4045965633195;6300.243970723543;6301.873962520724;5953.851153721105;5988.640681559121;5994.190784244159;5994.22075130239;6053.061775424816;5408.45915408456;5411.377691565521;5437.021336159451;5473.781048758085;5477.20580515831;6209.627422354763;6311.368863113652;6380.330065343258;6397.0724244983285;6452.064450654535;6230;6266.968665156645;6274.780382686584;6276.874462510816;6293.465055470337;6267.96866015667;6406.711697973696;6428.389303016206;6439.45264652491;6487.708053137714;5175.715335656424;6242.812715618357;6269.874602508015;6290.087937248982;6294.71673774672;6112.26032301701;6141.324454309933;6182.690865456799;6298.653574053471;6316.368363163647;6125.937554509019;6163.445289923929;6183.84540386494;6196.349383314687;6254.249490061222;6122.663253521099;6223.595466294676;6228.875422491434;6242.063690446469;6268.780592679234;5711.771529138788;5907.438793314628;6044.742260845399;6158.230221222348;6182.969085154546;5034.194528875362;5581.190727344593;6113.816585502442;6143.358355907003;6147.538934580014;5979.671118088547;6035.939640603713;6042.123892035718;6057.42454466825;6059.272887253558 08:40:58 INFO - PROCESS | 1654 | |1;ext.html;473.45789071513116;477.584501483709;517.4179048570359;524.9422563518012;539.9082156033473;37003.44494832576;38622.420663690034;38647.806760966225;40488.595114048854;40503.39244911321;39227;39665;39707.00732481689;39740.40389394154;40023.59976400235;8369.414141010131;9235.261179105672;9403.43579385239;9417.952910235439;9440.763980900463;7756.495827771205;8715.520646364448;9329.440233585972;9345.205657519118;9433.151016408521;6975.825604359881;9426.952865235664;9427.057294270553;9509;9522.904770952306;9230.400023998485;9307.441553506811;9368.20370268528;9387.249020078445;9390.999999999965;7915.168907264712;9172;9280.814383712288;9293.303002274857;9301.767455813591;37715.86852394432;37786.811065944494;37881.43177852334;37926.24147517062;38107.04732381685;2789.6652401711926;3105.4720697481484;3225.8064516129107;3285.4086264472385;3329.250918543333;29161.85419072891;29841.70158298411;29905.252368690955;29994;30415.847920760476;3133.2950086230417;3148.622165340174;3174.68253174683;3219.1630176154135;3225.854836532362;481976;488554;491654;492023;492388;48002.759986199846;52470.47529524732;54512.72743636256;54635.180472292945;55063.724681376334;53703;54744;57007;57055.14417283744;57254.7137264311;15355;15374.769378459332;15504.147271899928;15574.688506229812;15606;12222.572209972786;12277.815832762337;12288.815667764991;12290.631281061582;12312.13815032939;14198.219097949712;14292.285385730673;14440.205788681513;14508.854911450962;14678;6376.266729326187;6532.379423954717;6542.214934207926;6548.8362790930605;6595.8021259362295;856.9485830850169;901.6348378906554;948.6252930092713;964.1370972979145;1012.6708819633501;22383.104675813112;24747.752522474904;27482.763275652644;28313.31023552759;28325;838.6771093129067;875.2866413872673;887.9911200888038;904.7834946764185;1017.5522769981185;12718.745625087631;12847.743045139045;14480.610699234509;15004.474843380647;15107;167740;167949;168579;168652;169416;53123.46876531185;53674.73162634162;53901.460985390426;53933.730331348874;53973.730131349876;1325.9734805304033;1361.0948719101686;1371.7736573465465;1374.6288502104376;1378.6691194113341;24190.51618967646;24548;28154.888383371737;28404.57393139146;28667.56998645064;40545.594544054184;40817;46189;46810.06379872315;46862.06275874531;451745;454304;454377;454833;456218;30921;37476;37704.81147594244;38061;38362.6163738359;43498.75201147765;43839.56160438355;44272.11455770931;44392.7780361109;44675.10649787051;36654;37062.814685926394;37124.88625341244;37578.436323454625;38441.11691623196;36577;36752.63247367492;37593.62406376011;37790.62209377871;37861.81069094637;30950.380992379574;31566.842165789025;34687.306253875286;35802.641973580976;36978.815105924295;31291.374172516877;33295.33409331848;33750;33760.81837135689;33821;25208.621870672323;25297.367565811;25358.239252822445;25488.762564094883;25620.615690765026 08:40:58 INFO - PROCESS | 1654 | |2;jquery.html;371.27601177703474;372.90863738384115;379.5331741957392;381.5326225373919;402.51496946179856;18507.16717747701;19307.131179096938;19843.206271749114;19966;20000.000000000015;19493.317733879318;19568.11943462544;19913.004349782525;20006.59986800263;20289.492762680904;4806.423229212482;5676.716164191795;5915.704214789265;5946.405359464062;6547;6556.704948277315;6630.801075967729;6641.302663220341;6646.136002319716;6652.966735166317;6352.555321127523;6504.349565043505;6514.120593719835;6526.314736952623;6526.967365163167;3154.9211269718326;3185.8725650973934;3186.9043928682177;3200.695933886277;3466.9425825123326;5491.835244942658;5579.609427340088;5630.662160270397;5715.714214289269;5715.914261286084;24288.39279018021;24522.896469658728;25215.495690086278;25241.747582524305;25389.492210155877;2051.2820512820545;2192.090282532747;2391.641253811925;2431.185552839801;3041.9961412733746;7219.458540609476;7867.567283799389;9954.203663706887;9957.70549828525;10025.298229123964;1821.253286152671;1884.660761063008;1893.2237782509244;1901.277514544478;1947.123794292574;104092;116522;122594.38702806429;123789;124288;9073.274138068939;11586.47860846264;11627.55955700739;11753.529858805723;11955.76088478243;14514.854851451562;14642.707145857235;14689.19209443491;14693.853061469463;14731.631709207355;7171.995920571124;7932.762017139495;8348.081711011784;8475.14944152134;8645.783855403664;6832.6925288362145;8384.538850363166;8403;8589.226969572768;8607.61265743043;4458.9554104459185;4537.296719008559;4539.432570928634;4544.818207271738;4596.5863072323655;1611.9113448760197;1859.9721004184948;1934.864559480851;1940.6506828770957;2010.336588925651;888.6623083228461;889.1775108025051;915.1671978499534;934.7149119518476;939.3612343606413;18903.960282184336;20748.48128796762;20790.48023799387;20806;21012.579748404947;879.4283715584921;906.8549032154824;920.3557509743073;923.3398120343986;985.4629227071264;10430.322029068086;10983.505742241618;11075.889241107647;11120.666380008613;11140.49867755953;514867;514955;517524;612627;634423;103802.48098759458;120844;126566;126690.36654816852;139623.30188349198;812.6749300279936;883.2095274729081;959.4195511715312;985.9901400986042;994.1599348550463;18085.457436276934;18828.905855470635;18924.14841332171;19282.74662146956;19300.32448864283;38261.617383825804;55159.17261241165;55247;55566.444335557746;56932.715336423054;73373.633131834;74788;76352.85470718055;76583;77409;7029.261927497743;8031.397645176518;8056.907302409203;8105.635246413807;8404.957975210085;10802.783944321227;12742.554010609585;12804.23544689444;12890.162139460905;13231.073824832169;5374.973125134349;5866.618669786621;7816.866554349701;10942;11390.886091138982;9554.665586704432;9723.611055557698;10767.84405396317;11109.611163609557;11363.943180284046;9322.95338523303;10935.507902144263;10959.01368876805;11140.774514803203;11652.009579185526;7415.184329723618;7441.404687625085;7518.624068796647;7546.094468663796;7698.92301076982;8947.865782013145;10799.460026998775;10850.620228291971;11098;11126.221164518405 08:40:58 INFO - PROCESS | 1654 | |3;mootools.html;1444.8410674825768;1478.0318891126312;1625.341736596678;1724.5602371395291;1758.8592912567003;7440.6651700673465;7685.6925722971155;7939;8401.957990210056;8439.62021709023;6624.966875165629;7918.841623167533;8243.175682431769;8254.257116859482;8314.210150035737;2237.865728056322;2293.74768775435;2350.2244259394442;2353.670486131943;2353.8587684738886;2014.6272939506166;2334.902595779981;2362.3503536527505;2375.6436534519876;2379.4289370551114;2298.9655155172686;2313.884305784704;2330.9650355244685;2337.2988103568946;2354.223106374901;2296.494771150344;2318.953620927589;2319.4665226997718;2326.627739561662;2328.662343960125;2264.320703788857;2276.988615056914;2280.920167794119;2284.383216531527;2291.747907730148;8303.66785328586;8336.749897503025;8414.747557573282;8430.199131082538;8445.239928406472;5018.623603229772;5146.867689108391;5197.740112994373;5210.843674689765;5229.450907654679;3298.290867463507;3458.6368431314854;3532.947005794915;3625.1299688074937;3628.7459877808565;1014.843078890066;1036.7356324137297;1072.9731756706067;1081.826907694773;1097.2922465010024;10879.401632910187;10984.286021408583;10988.560457581687;11007;11008.614698485595;3824.8661296854475;4634.536546345406;4693.929591056137;4712.151812673683;4721.102990431807;9280.071992800666;9288.210502107397;9288.814223715488;9322.720318390458;9323.533823308808;5180.1711726124395;5181;5184.974075129676;5200.531952124327;5201.375834899836;4271.380649805761;4302.160574021131;4641.25739881624;4725.976370118127;4765.928511072337;3805.353089974711;3829.100161462054;3903.1608204236227;3919.862804801818;3931.4299426582984;1272.7645385603694;1279.33474593211;1282.4421376701164;1285.2352850054203;1293.275765571283;649.7498463091649;778.1051790441038;882.6778225947482;916.6745805239177;933.0855761353911;5580.135079062752;5625.409332020161;5674.893395787782;5711.114777209539;5830.7084645767545;882.2192359761657;891.3359547137295;900.8828852249165;906.6917248135534;921.8663293822358;3815.4467602197524;3829.8468061277795;3838.9424158637644;3864.478295430081;3932.941005884914;9751.073648003428;10083;10091.899081009244;10142;10216.335938163995;10194.592216311414;10238.53926573306;10246.282760206863;10262.486875656188;10336.844947325797;7536.359409450149;7789.182135875765;8021.679132834738;8029.518228906284;8039.075506316848;4421.823127074881;4617.538246175355;4754.881127971828;5429.565634749131;5551.195076713934;7700.345470634835;9226.492542910204;9280.535973201178;9373;9482.19401350893;9375.484348360906;9544.236461082957;9564.33049686543;9632.711018669452;9779;6010.519158467399;6180.165677633462;6185.505159587132;6660.533762636569;6751.93248067513;7338.15611204697;7574.848503030019;7797.571133587707;8038.477498962548;8044;6019.759209631741;7067.187273463617;7226.89159662616;7251.347378735939;7324.340809327186;7023.192332881784;7089.149302083783;7098.148222213367;7130.2156762757095;7138.678759455941;6628.337166283526;6646.302138275411;6704.83237919056;6739.966300168468;6741.258461569126;4263.955330943939;4731.645126615449;4832.44426890912;4867.464578896389;4892;3951.3677811550015;4039.6566291864347;4965.4041515018425;5136.9743151284;5173.3533308336455 08:40:58 INFO - PROCESS | 1654 | |4;prototype.html;256.2428025185576;265.9415526205702;282.3548192380411;289.53963198514356;289.83189749945035;4291.459545874654;4868.926966095502;5055.469175736541;5334.626576139672;5377.596680248982;5025.195968645017;5122.359705036871;5197.7920883164625;5224.817131400402;5282.5245727884485;3788.223414200096;3968.730006397961;3987.98006009971;3994.362311452305;4011.6790656747535;3859.8420473857873;3982.2433737589763;4000.6999475039343;4003.1393250451147;4021.3163762160357;3576.6959808416186;3914.451976723261;3942.7634341939574;3950.980245098785;3964.5638979712207;3934.881953541398;3954.584768599313;3959.703022273341;3960.683145348365;3965.2267807777494;2781.638387009696;3229.8223597702117;3295.538624592559;3361.252148882592;3446.672566106216;5076.974615126937;5083.440821509631;5086.898262034775;5098.566621837148;5106.387233531963;4459.353393757887;4460.910781784379;4464.308032255006;4476.84331048415;4480.14877173336;5266.8419947401635;5503.504684578407;5522.475364840334;5541.806036788693;5555.2778138842095;3119.688031196885;3710.7588006779474;3720.9069773255887;3727.5835182630426;3729.198222382201;4624.953750462486;5802.999999999958;5859.7949071782705;5873.823785286449;5888.558358123158;3019.698030196963;3476.4785282207367;3744.288585168864;3744.981275093607;3754.718396120302;3217.5656286401504;3268.297316077055;3732.9813350933073;3770.699108807457;3791.194516058152;4213.957860421357;4426;4427.845025424094;4435.560441757021;4440.134173836071;4553.681242313006;4833.245050321353;5150.223173005307;5303.957976179198;5480.725963701799;961.3991255465335;1068.1614932278123;1227.4660522672666;1237.1340061956666;1246.1588427811191;700.4956431369405;818.4107442641289;843.4475418600827;844.1895780051096;847.5337665838134;4568.588827005586;4693.887750512283;4757.452892917289;4836.95163048365;4997.875053123701;4587.495375508692;5174.741262936838;5236.266922630834;5376.91934620981;5440.6735595864375;3845.8461661533784;4663.930041049387;4923.77842997066;4935.555799977948;4939.75301234944;2707.564990555009;2735.685396179425;2787.372841110734;2813.5357665985293;2822.50606143924;5216.58267338612;5580.9441905580425;5647.774089036475;5652.434756524397;5681.687507187062;5191.5846732261325;5470.343558772893;5527.806526771544;5530.806421775219;5673.205751194836;5273.1035723927025;5595.608307418523;5678.261825962681;5926.940730592639;5993.790217342459;5007.37407824022;5782.595218334676;5786.623869448473;5803.691895619457;5840.423085766734;4810.831620893252;5204.427512973494;5380.977614253279;5603.607747457639;5671.40450252734;4851.102546028996;5184.662996905189;5426.829268292715;5681.0910254359105;5944.881102377842;3179.3959147761852;3234.7573931954726;3528.7825700133412;3750.3999360102252;3751.174741556853;5314.256004159498;5702.429757024264;5731.5701322400155;5737.885242295214;5741.741621626953;5121.8463446096675;5399.002369123449;5515.448455154453;5582.7766889323975;5652.660840349592;4890.339804126397;4961.925571116509;5326.8014696692935;5574.96513772462;5689.288838895138;4670.883227919329;4778.593819525379;5556.9719601873785;5564.33228012641;5609.607327487037;4709.858704238878;4710.717356958594;5726.828195154152;5734.738357561329;5736.110902810071;4567.6117530009;5338.252644629677;5456.645318054314;5485.6434331768305;5561.277033985556 08:40:58 INFO - PROCESS | 1654 | |5;yui.html;684.3054299885617;694.4548529404418;737.9926200737991;747.5327920049968;784.40777213204;11330.886691133086;12663.62009139726;14367.497137600187;14798.26008699566;14851.702965940674;13137.67155821105;13146.474141034347;13165.473381064747;13175.802362964563;13290.800637990415;621.2762132116079;645.1645119570168;774.4346626962321;780.239266714954;803.9879401808948;788.5150632361085;801.1587832775591;801.8757092650649;802.2619190344897;803.0243254445847;846.0989046665312;849.5964416901984;849.7280870121564;849.9192576705203;851.2508992086955;823.5511646152831;829.7718127514952;844.994456596651;845.0239972831329;845.3321875718175;615.7475435071644;621.2451870976785;622.8411755002445;623.8097380299007;625.6809027396015;13681;13756.312184390841;13872.167669939736;13902.374393152333;14169.78745318821;12570.811437828439;13505.527306544322;13615.319234038358;13646.931765341109;13829.377678004514;10761;10883.619073332475;11007.834882476769;11110.833337499944;11275.60535381266;3559.7864128152396;3628.11111277736;3651.2879988402274;3656.945145822815;3702.629737026303;68365.65817170932;80544.9863753405;80688.19311806807;84258;87589;10071.0432508913;10214.744631384126;10237.539310731036;10409.791804163875;10414.427206503564;20436.591268174852;20464.795352046585;20497.590048198952;20502.642196147634;20503.69244461334;7532.8493430132185;7542.358899493494;7589.848203035908;7604.80987975305;7609.201033891472;3644.7448678592245;4142.523609784853;4210.873673789792;4252.936205956913;4272.530021697611;2863.4273145371008;3034.544818277276;3255.6489057041063;3292.489664102068;3655.3054919565197;691.3881215124551;697.2678687378334;713.4149997002512;713.7180813578711;715.4669771020519;546.9389384593875;625.3746253746253;640.9711562979677;654.499308029358;671.9899201511981;8624.655013799504;8961.820763584823;8992.8309319788;9123.863142052875;9195.77010574741;583.2243116654839;585.318104408368;609.2384519350811;648.2642201101784;661.441082285468;3697.6487233712755;4028.395740638869;4240.448741663564;4456.665750068758;4456.910861782746;87165;96135;106891;109091;114163.42918285355;33627;60839;98194.5090274544;100128;101901.49049254706;74812.25187748052;90702.63946040814;90710.18579628371;91156.63265051029;91550.54224728834;9263.490508022123;9463.195628371666;9519.857202142111;9562.713118606453;9595.808083838145;40956.59043409528;45547.31679024751;46124.30813537867;48531.51468485411;50833;62961.425964351256;69519;74566.2543374581;76412.32556860484;77821.44357112939;11733.530658773554;11815.82276265874;12068.094892882893;12104.878951210727;12277.631671049883;13302.93348533251;13574.457021719021;13588.59234222975;13841.238731869442;13939.860601394263;11967;12300.938495307466;12655.683607909876;13083.869161308265;13119.672008199872;12404.75190496167;12876.969842412773;12982.350882456029;13386.397612107285;13407.061505694508;12002.699932501757;12588;12618.242905425705;12949.935250323688;12962.611121666365;10112;10156.796864062826;11057.502412391623;11255;11283.435828208721;8918.286537077149;10850.348979061282;11021.44892755375;11059.115270778479;11137.164712646745 08:40:58 INFO - PROCESS | 1654 | __end_tp_report 08:40:58 INFO - PROCESS | 1654 | __start_cc_report 08:40:58 INFO - PROCESS | 1654 | _x_x_mozilla_cycle_collect,366 08:40:58 INFO - PROCESS | 1654 | __end_cc_report 08:40:58 INFO - PROCESS | 1654 | __startTimestamp1482597658759__endTimestamp 08:40:58 INFO - PROCESS | 1654 | 08:40:58 INFO - PROCESS | 1654 | ------- Summary: start ------- 08:40:58 INFO - PROCESS | 1654 | Number of tests: 6 08:40:58 INFO - PROCESS | 1654 | 08:40:58 INFO - PROCESS | 1654 | [#0] dojo.html Cycles:165 Average:5762.69 Median:5994.22 stddev:663.60 (11.1%) stddev-sans-first:638.40 08:40:58 INFO - PROCESS | 1654 | Values: 3363.9 3406.9 3411.2 3414.3 3422.6 5715.7 5747.9 5769.9 5782.8 5813.4 5755.7 5778.4 5833.2 5838.5 5840.7 4281.5 5230.3 5267.4 5308.9 5331.6 5257.9 5265.4 5281.4 5314.4 5366.1 5226.1 5241.8 5330.9 5335.6 5357.9 5292.4 5299.4 5321.7 5341.2 5348.4 5236.9 5245.3 5323.9 5334.3 5375.0 5877.2 5880.2 5893.6 5938.8 5939.4 6103.8 6168.6 6323.2 6329.6 6337.2 6241.8 6260.2 6278.3 6307.0 6312.7 4936.3 5381.7 5728.0 5801.5 5865.7 5313.9 6383.4 6390.5 6404.5 6405.7 5119.9 6080.2 6112.3 6112.3 6271.9 4911.4 6286.1 6291.3 6315.9 6330.8 6134.1 6253.6 6295.8 6306.1 6312.0 6050.5 6122.3 6143.7 6211.7 6246.7 4359.6 4907.0 4942.2 4947.3 4985.0 4430.0 4440.6 4448.5 4449.4 4480.6 5748.5 5775.2 5821.2 5828.7 5955.2 6232.5 6252.1 6267.4 6300.2 6301.9 5953.9 5988.6 5994.2 5994.2 6053.1 5408.5 5411.4 5437.0 5473.8 5477.2 6209.6 6311.4 6380.3 6397.1 6452.1 6230.0 6267.0 6274.8 6276.9 6293.5 6268.0 6406.7 6428.4 6439.5 6487.7 5175.7 6242.8 6269.9 6290.1 6294.7 6112.3 6141.3 6182.7 6298.7 6316.4 6125.9 6163.4 6183.8 6196.3 6254.2 6122.7 6223.6 6228.9 6242.1 6268.8 5711.8 5907.4 6044.7 6158.2 6183.0 5034.2 5581.2 6113.8 6143.4 6147.5 5979.7 6035.9 6042.1 6057.4 6059.3 08:40:58 INFO - PROCESS | 1654 | 08:40:58 INFO - PROCESS | 1654 | [#1] ext.html Cycles:180 Average:52347.85 Median:38234.83 stddev:106160.53 (277.7%) stddev-sans-first:106386.89 08:40:58 INFO - PROCESS | 1654 | Values: 473.5 477.6 517.4 524.9 539.9 37003.4 38622.4 38647.8 40488.6 40503.4 39227.0 39665.0 39707.0 39740.4 40023.6 8369.4 9235.3 9403.4 9418.0 9440.8 7756.5 8715.5 9329.4 9345.2 9433.2 6975.8 9427.0 9427.1 9509.0 9522.9 9230.4 9307.4 9368.2 9387.2 9391.0 7915.2 9172.0 9280.8 9293.3 9301.8 37715.9 37786.8 37881.4 37926.2 38107.0 2789.7 3105.5 3225.8 3285.4 3329.3 29161.9 29841.7 29905.3 29994.0 30415.8 3133.3 3148.6 3174.7 3219.2 3225.9 481976.0 488554.0 491654.0 492023.0 492388.0 48002.8 52470.5 54512.7 54635.2 55063.7 53703.0 54744.0 57007.0 57055.1 57254.7 15355.0 15374.8 15504.1 15574.7 15606.0 12222.6 12277.8 12288.8 12290.6 12312.1 14198.2 14292.3 14440.2 14508.9 14678.0 6376.3 6532.4 6542.2 6548.8 6595.8 856.9 901.6 948.6 964.1 1012.7 22383.1 24747.8 27482.8 28313.3 28325.0 838.7 875.3 888.0 904.8 1017.6 12718.7 12847.7 14480.6 15004.5 15107.0 167740.0 167949.0 168579.0 168652.0 169416.0 53123.5 53674.7 53901.5 53933.7 53973.7 1326.0 1361.1 1371.8 1374.6 1378.7 24190.5 24548.0 28154.9 28404.6 28667.6 40545.6 40817.0 46189.0 46810.1 46862.1 451745.0 454304.0 454377.0 454833.0 456218.0 30921.0 37476.0 37704.8 38061.0 38362.6 43498.8 43839.6 44272.1 44392.8 44675.1 36654.0 37062.8 37124.9 37578.4 38441.1 36577.0 36752.6 37593.6 37790.6 37861.8 30950.4 31566.8 34687.3 35802.6 36978.8 31291.4 33295.3 33750.0 33760.8 33821.0 25208.6 25297.4 25358.2 25488.8 25620.6 08:40:58 INFO - PROCESS | 1654 | 08:40:58 INFO - PROCESS | 1654 | [#2] jquery.html Cycles:180 Average:33445.02 Median:380.53 stddev:94039.34 (24712.5%) stddev-sans-first:94270.35 08:40:58 INFO - PROCESS | 1654 | Values: 371.3 372.9 379.5 381.5 402.5 18507.2 19307.1 19843.2 19966.0 20000.0 19493.3 19568.1 19913.0 20006.6 20289.5 4806.4 5676.7 5915.7 5946.4 6547.0 6556.7 6630.8 6641.3 6646.1 6653.0 6352.6 6504.3 6514.1 6526.3 6527.0 3154.9 3185.9 3186.9 3200.7 3466.9 5491.8 5579.6 5630.7 5715.7 5715.9 24288.4 24522.9 25215.5 25241.7 25389.5 2051.3 2192.1 2391.6 2431.2 3042.0 7219.5 7867.6 9954.2 9957.7 10025.3 1821.3 1884.7 1893.2 1901.3 1947.1 104092.0 116522.0 122594.4 123789.0 124288.0 9073.3 11586.5 11627.6 11753.5 11955.8 14514.9 14642.7 14689.2 14693.9 14731.6 7172.0 7932.8 8348.1 8475.1 8645.8 6832.7 8384.5 8403.0 8589.2 8607.6 4459.0 4537.3 4539.4 4544.8 4596.6 1611.9 1860.0 1934.9 1940.7 2010.3 888.7 889.2 915.2 934.7 939.4 18904.0 20748.5 20790.5 20806.0 21012.6 879.4 906.9 920.4 923.3 985.5 10430.3 10983.5 11075.9 11120.7 11140.5 514867.0 514955.0 517524.0 612627.0 634423.0 103802.5 120844.0 126566.0 126690.4 139623.3 812.7 883.2 959.4 986.0 994.2 18085.5 18828.9 18924.1 19282.7 19300.3 38261.6 55159.2 55247.0 55566.4 56932.7 73373.6 74788.0 76352.9 76583.0 77409.0 7029.3 8031.4 8056.9 8105.6 8405.0 10802.8 12742.6 12804.2 12890.2 13231.1 5375.0 5866.6 7816.9 10942.0 11390.9 9554.7 9723.6 10767.8 11109.6 11363.9 9323.0 10935.5 10959.0 11140.8 11652.0 7415.2 7441.4 7518.6 7546.1 7698.9 8947.9 10799.5 10850.6 11098.0 11126.2 08:40:58 INFO - PROCESS | 1654 | 08:40:58 INFO - PROCESS | 1654 | [#3] mootools.html Cycles:180 Average:5390.64 Median:5077.80 stddev:2971.12 (58.5%) stddev-sans-first:2964.66 08:40:58 INFO - PROCESS | 1654 | Values: 1444.8 1478.0 1625.3 1724.6 1758.9 7440.7 7685.7 7939.0 8402.0 8439.6 6625.0 7918.8 8243.2 8254.3 8314.2 2237.9 2293.7 2350.2 2353.7 2353.9 2014.6 2334.9 2362.4 2375.6 2379.4 2299.0 2313.9 2331.0 2337.3 2354.2 2296.5 2319.0 2319.5 2326.6 2328.7 2264.3 2277.0 2280.9 2284.4 2291.7 8303.7 8336.7 8414.7 8430.2 8445.2 5018.6 5146.9 5197.7 5210.8 5229.5 3298.3 3458.6 3532.9 3625.1 3628.7 1014.8 1036.7 1073.0 1081.8 1097.3 10879.4 10984.3 10988.6 11007.0 11008.6 3824.9 4634.5 4693.9 4712.2 4721.1 9280.1 9288.2 9288.8 9322.7 9323.5 5180.2 5181.0 5185.0 5200.5 5201.4 4271.4 4302.2 4641.3 4726.0 4765.9 3805.4 3829.1 3903.2 3919.9 3931.4 1272.8 1279.3 1282.4 1285.2 1293.3 649.7 778.1 882.7 916.7 933.1 5580.1 5625.4 5674.9 5711.1 5830.7 882.2 891.3 900.9 906.7 921.9 3815.4 3829.8 3838.9 3864.5 3932.9 9751.1 10083.0 10091.9 10142.0 10216.3 10194.6 10238.5 10246.3 10262.5 10336.8 7536.4 7789.2 8021.7 8029.5 8039.1 4421.8 4617.5 4754.9 5429.6 5551.2 7700.3 9226.5 9280.5 9373.0 9482.2 9375.5 9544.2 9564.3 9632.7 9779.0 6010.5 6180.2 6185.5 6660.5 6751.9 7338.2 7574.8 7797.6 8038.5 8044.0 6019.8 7067.2 7226.9 7251.3 7324.3 7023.2 7089.1 7098.1 7130.2 7138.7 6628.3 6646.3 6704.8 6740.0 6741.3 4264.0 4731.6 4832.4 4867.5 4892.0 3951.4 4039.7 4965.4 5137.0 5173.4 08:40:58 INFO - PROCESS | 1654 | 08:40:58 INFO - PROCESS | 1654 | [#4] prototype.html Cycles:180 Average:4399.49 Median:4937.65 stddev:1391.58 (28.2%) stddev-sans-first:1360.30 08:40:58 INFO - PROCESS | 1654 | Values: 256.2 265.9 282.4 289.5 289.8 4291.5 4868.9 5055.5 5334.6 5377.6 5025.2 5122.4 5197.8 5224.8 5282.5 3788.2 3968.7 3988.0 3994.4 4011.7 3859.8 3982.2 4000.7 4003.1 4021.3 3576.7 3914.5 3942.8 3951.0 3964.6 3934.9 3954.6 3959.7 3960.7 3965.2 2781.6 3229.8 3295.5 3361.3 3446.7 5077.0 5083.4 5086.9 5098.6 5106.4 4459.4 4460.9 4464.3 4476.8 4480.1 5266.8 5503.5 5522.5 5541.8 5555.3 3119.7 3710.8 3720.9 3727.6 3729.2 4625.0 5803.0 5859.8 5873.8 5888.6 3019.7 3476.5 3744.3 3745.0 3754.7 3217.6 3268.3 3733.0 3770.7 3791.2 4214.0 4426.0 4427.8 4435.6 4440.1 4553.7 4833.2 5150.2 5304.0 5480.7 961.4 1068.2 1227.5 1237.1 1246.2 700.5 818.4 843.4 844.2 847.5 4568.6 4693.9 4757.5 4837.0 4997.9 4587.5 5174.7 5236.3 5376.9 5440.7 3845.8 4663.9 4923.8 4935.6 4939.8 2707.6 2735.7 2787.4 2813.5 2822.5 5216.6 5580.9 5647.8 5652.4 5681.7 5191.6 5470.3 5527.8 5530.8 5673.2 5273.1 5595.6 5678.3 5926.9 5993.8 5007.4 5782.6 5786.6 5803.7 5840.4 4810.8 5204.4 5381.0 5603.6 5671.4 4851.1 5184.7 5426.8 5681.1 5944.9 3179.4 3234.8 3528.8 3750.4 3751.2 5314.3 5702.4 5731.6 5737.9 5741.7 5121.8 5399.0 5515.4 5582.8 5652.7 4890.3 4961.9 5326.8 5575.0 5689.3 4670.9 4778.6 5557.0 5564.3 5609.6 4709.9 4710.7 5726.8 5734.7 5736.1 4567.6 5338.3 5456.6 5485.6 5561.3 08:40:58 INFO - PROCESS | 1654 | 08:40:58 INFO - PROCESS | 1654 | [#5] yui.html Cycles:180 Average:19366.23 Median:4225.66 stddev:28207.93 (667.5%) stddev-sans-first:28252.18 08:40:58 INFO - PROCESS | 1654 | Values: 684.3 694.5 738.0 747.5 784.4 11330.9 12663.6 14367.5 14798.3 14851.7 13137.7 13146.5 13165.5 13175.8 13290.8 621.3 645.2 774.4 780.2 804.0 788.5 801.2 801.9 802.3 803.0 846.1 849.6 849.7 849.9 851.3 823.6 829.8 845.0 845.0 845.3 615.7 621.2 622.8 623.8 625.7 13681.0 13756.3 13872.2 13902.4 14169.8 12570.8 13505.5 13615.3 13646.9 13829.4 10761.0 10883.6 11007.8 11110.8 11275.6 3559.8 3628.1 3651.3 3656.9 3702.6 68365.7 80545.0 80688.2 84258.0 87589.0 10071.0 10214.7 10237.5 10409.8 10414.4 20436.6 20464.8 20497.6 20502.6 20503.7 7532.8 7542.4 7589.8 7604.8 7609.2 3644.7 4142.5 4210.9 4252.9 4272.5 2863.4 3034.5 3255.6 3292.5 3655.3 691.4 697.3 713.4 713.7 715.5 546.9 625.4 641.0 654.5 672.0 8624.7 8961.8 8992.8 9123.9 9195.8 583.2 585.3 609.2 648.3 661.4 3697.6 4028.4 4240.4 4456.7 4456.9 87165.0 96135.0 106891.0 109091.0 114163.4 33627.0 60839.0 98194.5 100128.0 101901.5 74812.3 90702.6 90710.2 91156.6 91550.5 9263.5 9463.2 9519.9 9562.7 9595.8 40956.6 45547.3 46124.3 48531.5 50833.0 62961.4 69519.0 74566.3 76412.3 77821.4 11733.5 11815.8 12068.1 12104.9 12277.6 13302.9 13574.5 13588.6 13841.2 13939.9 11967.0 12300.9 12655.7 13083.9 13119.7 12404.8 12877.0 12982.4 13386.4 13407.1 12002.7 12588.0 12618.2 12949.9 12962.6 10112.0 10156.8 11057.5 11255.0 11283.4 8918.3 10850.3 11021.4 11059.1 11137.2 08:40:58 INFO - PROCESS | 1654 | -------- Summary: end -------- 08:40:58 INFO - PROCESS | 1654 | 08:40:59 INFO - TEST-INFO | 1654: exit 0 08:40:59 INFO - TEST-OK | dromaeo_css | took 1117216ms 08:40:59 INFO - TEST-START | kraken 08:40:59 INFO - operating with platform_type : mac_ 08:40:59 INFO - Initialising browser for kraken test... 08:40:59 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/tmpmOZ6aq/profile http://localhost:49243/getInfo.html) 08:41:02 INFO - PROCESS | 1664 | 2016-12-24 08:41:02.195 plugin-container[1665:11328] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4537, name = 'com.apple.tsm.portname' 08:41:02 INFO - PROCESS | 1664 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:41:02 INFO - PROCESS | 1664 | 2016-12-24 08:41:02.198 plugin-container[1665:11328] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d13, name = 'com.apple.CFPasteboardClient' 08:41:02 INFO - PROCESS | 1664 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:41:02 INFO - PROCESS | 1664 | 2016-12-24 08:41:02.198 plugin-container[1665:11328] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:41:02 INFO - PROCESS | 1664 | __metrics Screen width/height:1600/1200 08:41:02 INFO - PROCESS | 1664 | colorDepth:24 08:41:02 INFO - PROCESS | 1664 | Browser inner width/height: 1024/663 08:41:02 INFO - PROCESS | 1664 | __metrics 08:41:02 ERROR - PROCESS | 1664 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 08:41:03 INFO - TEST-INFO | 1664: exit 0 08:41:03 INFO - Browser initialized. 08:41:03 INFO - Running cycle 1/1 for kraken test... 08:41:03 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/tmpmOZ6aq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:41:06 INFO - PROCESS | 1666 | 2016-12-24 08:41:06.089 plugin-container[1667:11449] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4247, name = 'com.apple.tsm.portname' 08:41:06 INFO - PROCESS | 1666 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:41:06 INFO - PROCESS | 1666 | 2016-12-24 08:41:06.090 plugin-container[1667:11449] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 08:41:06 INFO - PROCESS | 1666 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:41:06 INFO - PROCESS | 1666 | 2016-12-24 08:41:06.090 plugin-container[1667:11449] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:41:15 INFO - PROCESS | 1666 | RSS: Main: 243286016 08:41:15 INFO - PROCESS | 1666 | 08:41:18 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection) 08:41:19 INFO - PROCESS | 1666 | RSS: Main: 253136896 08:41:19 INFO - PROCESS | 1666 | 08:41:22 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-dft) 08:41:23 INFO - PROCESS | 1666 | RSS: Main: 260669440 08:41:23 INFO - PROCESS | 1666 | 08:41:26 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-fft) 08:41:27 INFO - PROCESS | 1666 | RSS: Main: 261595136 08:41:27 INFO - PROCESS | 1666 | 08:41:28 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator) 08:41:29 INFO - PROCESS | 1666 | RSS: Main: 261750784 08:41:29 INFO - PROCESS | 1666 | 08:41:31 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:41:32 INFO - PROCESS | 1666 | RSS: Main: 261488640 08:41:32 INFO - PROCESS | 1666 | 08:41:35 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom) 08:41:35 INFO - PROCESS | 1666 | RSS: Main: 265658368 08:41:35 INFO - PROCESS | 1666 | 08:41:38 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate) 08:41:39 INFO - PROCESS | 1666 | RSS: Main: 265097216 08:41:39 INFO - PROCESS | 1666 | 08:41:42 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial) 08:41:43 INFO - PROCESS | 1666 | RSS: Main: 265007104 08:41:43 INFO - PROCESS | 1666 | 08:41:44 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:41:45 INFO - PROCESS | 1666 | RSS: Main: 263831552 08:41:45 INFO - PROCESS | 1666 | 08:41:47 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:41:48 INFO - PROCESS | 1666 | RSS: Main: 263819264 08:41:48 INFO - PROCESS | 1666 | 08:41:50 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:41:50 INFO - PROCESS | 1666 | RSS: Main: 263913472 08:41:50 INFO - PROCESS | 1666 | 08:41:53 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:41:54 INFO - PROCESS | 1666 | RSS: Main: 264175616 08:41:54 INFO - PROCESS | 1666 | 08:41:57 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:41:57 INFO - PROCESS | 1666 | RSS: Main: 264806400 08:41:57 INFO - PROCESS | 1666 | 08:41:59 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49243/tests/kraken/driver.html?testName=ai-astar) 08:41:59 INFO - PROCESS | 1666 | RSS: Main: 266330112 08:41:59 INFO - PROCESS | 1666 | 08:41:59 INFO - PROCESS | 1666 | __start_tp_report 08:41:59 INFO - PROCESS | 1666 | _x_x_mozilla_page_load 08:41:59 INFO - PROCESS | 1666 | _x_x_mozilla_page_load_details 08:41:59 INFO - PROCESS | 1666 | |i|pagename|runs| 08:41:59 INFO - PROCESS | 1666 | |0;ai-astar;105;119;128;104;169;178;175;118;120;120 08:41:59 INFO - PROCESS | 1666 | |1;audio-beat-detection;138;111;121;173;100;138;147;152;129;144 08:41:59 INFO - PROCESS | 1666 | |2;audio-dft;193;200;194;202;194;198;197;199;203;199 08:41:59 INFO - PROCESS | 1666 | |3;audio-fft;81;60;60;83;60;60;59;59;77;59 08:41:59 INFO - PROCESS | 1666 | |4;audio-oscillator;87;71;89;73;69;70;83;69;71;88 08:41:59 INFO - PROCESS | 1666 | |5;imaging-gaussian-blur;92;95;136;89;95;89;93;73;139;132 08:41:59 INFO - PROCESS | 1666 | |6;imaging-darkroom;97;101;97;113;147;90;153;154;97;98 08:41:59 INFO - PROCESS | 1666 | |7;imaging-desaturate;130;72;141;139;123;88;80;148;142;89 08:41:59 INFO - PROCESS | 1666 | |8;json-parse-financial;58;57;59;60;62;62;62;68;84;94 08:41:59 INFO - PROCESS | 1666 | |9;json-stringify-tinderbox;72;73;75;75;77;71;72;90;61;87 08:41:59 INFO - PROCESS | 1666 | |10;stanford-crypto-aes;84;87;85;88;84;83;84;87;86;86 08:41:59 INFO - PROCESS | 1666 | |11;stanford-crypto-ccm;142;150;137;138;172;145;145;144;193;140 08:41:59 INFO - PROCESS | 1666 | |12;stanford-crypto-pbkdf2;169;170;168;166;172;169;166;170;168;167 08:41:59 INFO - PROCESS | 1666 | |13;stanford-crypto-sha256-iterative;69;68;69;68;70;67;69;68;66;68 08:41:59 INFO - PROCESS | 1666 | __end_tp_report 08:41:59 INFO - PROCESS | 1666 | __start_cc_report 08:41:59 INFO - PROCESS | 1666 | _x_x_mozilla_cycle_collect,988 08:41:59 INFO - PROCESS | 1666 | __end_cc_report 08:41:59 INFO - PROCESS | 1666 | __startTimestamp1482597719865__endTimestamp 08:41:59 INFO - PROCESS | 1666 | 08:41:59 INFO - PROCESS | 1666 | ------- Summary: start ------- 08:41:59 INFO - PROCESS | 1666 | Number of tests: 14 08:41:59 INFO - PROCESS | 1666 | 08:41:59 INFO - PROCESS | 1666 | [#0] ai-astar Cycles:10 Average:133.60 Median:124.00 stddev:28.85 (23.3%) stddev-sans-first:28.68 08:41:59 INFO - PROCESS | 1666 | Values: 105.0 119.0 128.0 104.0 169.0 178.0 175.0 118.0 120.0 120.0 08:41:59 INFO - PROCESS | 1666 | 08:41:59 INFO - PROCESS | 1666 | [#1] audio-beat-detection Cycles:10 Average:135.30 Median:141.00 stddev:21.10 (15.0%) stddev-sans-first:22.36 08:41:59 INFO - PROCESS | 1666 | Values: 138.0 111.0 121.0 173.0 100.0 138.0 147.0 152.0 129.0 144.0 08:41:59 INFO - PROCESS | 1666 | 08:41:59 INFO - PROCESS | 1666 | [#2] audio-dft Cycles:10 Average:197.90 Median:199.00 stddev:3.41 (1.7%) stddev-sans-first:3.13 08:41:59 INFO - PROCESS | 1666 | Values: 193.0 200.0 194.0 202.0 194.0 198.0 197.0 199.0 203.0 199.0 08:41:59 INFO - PROCESS | 1666 | 08:41:59 INFO - PROCESS | 1666 | [#3] audio-fft Cycles:10 Average:65.80 Median:60.00 stddev:10.14 (16.9%) stddev-sans-first:9.14 08:41:59 INFO - PROCESS | 1666 | Values: 81.0 60.0 60.0 83.0 60.0 60.0 59.0 59.0 77.0 59.0 08:41:59 INFO - PROCESS | 1666 | 08:41:59 INFO - PROCESS | 1666 | [#4] audio-oscillator Cycles:10 Average:77.00 Median:78.00 stddev:8.60 (11.0%) stddev-sans-first:8.33 08:41:59 INFO - PROCESS | 1666 | Values: 87.0 71.0 89.0 73.0 69.0 70.0 83.0 69.0 71.0 88.0 08:41:59 INFO - PROCESS | 1666 | 08:41:59 INFO - PROCESS | 1666 | [#5] imaging-gaussian-blur Cycles:10 Average:103.30 Median:90.50 stddev:23.25 (25.7%) stddev-sans-first:24.30 08:41:59 INFO - PROCESS | 1666 | Values: 92.0 95.0 136.0 89.0 95.0 89.0 93.0 73.0 139.0 132.0 08:41:59 INFO - PROCESS | 1666 | 08:41:59 INFO - PROCESS | 1666 | [#6] imaging-darkroom Cycles:10 Average:114.70 Median:93.50 stddev:25.98 (27.8%) stddev-sans-first:26.75 08:41:59 INFO - PROCESS | 1666 | Values: 97.0 101.0 97.0 113.0 147.0 90.0 153.0 154.0 97.0 98.0 08:41:59 INFO - PROCESS | 1666 | 08:41:59 INFO - PROCESS | 1666 | [#7] imaging-desaturate Cycles:10 Average:115.20 Median:110.00 stddev:29.51 (26.8%) stddev-sans-first:30.81 08:41:59 INFO - PROCESS | 1666 | Values: 130.0 72.0 141.0 139.0 123.0 88.0 80.0 148.0 142.0 89.0 08:41:59 INFO - PROCESS | 1666 | 08:41:59 INFO - PROCESS | 1666 | [#8] json-parse-financial Cycles:10 Average:66.60 Median:62.00 stddev:12.41 (20.0%) stddev-sans-first:12.77 08:41:59 INFO - PROCESS | 1666 | Values: 58.0 57.0 59.0 60.0 62.0 62.0 62.0 68.0 84.0 94.0 08:41:59 INFO - PROCESS | 1666 | 08:41:59 INFO - PROCESS | 1666 | [#9] json-stringify-tinderbox Cycles:10 Average:75.30 Median:75.00 stddev:8.21 (10.9%) stddev-sans-first:8.62 08:41:59 INFO - PROCESS | 1666 | Values: 72.0 73.0 75.0 75.0 77.0 71.0 72.0 90.0 61.0 87.0 08:41:59 INFO - PROCESS | 1666 | 08:41:59 INFO - PROCESS | 1666 | [#10] stanford-crypto-aes Cycles:10 Average:85.40 Median:86.00 stddev:1.65 (1.9%) stddev-sans-first:1.67 08:41:59 INFO - PROCESS | 1666 | Values: 84.0 87.0 85.0 88.0 84.0 83.0 84.0 87.0 86.0 86.0 08:41:59 INFO - PROCESS | 1666 | 08:41:59 INFO - PROCESS | 1666 | [#11] stanford-crypto-ccm Cycles:10 Average:150.60 Median:145.00 stddev:17.93 (12.4%) stddev-sans-first:18.74 08:41:59 INFO - PROCESS | 1666 | Values: 142.0 150.0 137.0 138.0 172.0 145.0 145.0 144.0 193.0 140.0 08:41:59 INFO - PROCESS | 1666 | 08:41:59 INFO - PROCESS | 1666 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.50 Median:169.00 stddev:1.90 (1.1%) stddev-sans-first:2.01 08:41:59 INFO - PROCESS | 1666 | Values: 169.0 170.0 168.0 166.0 172.0 169.0 166.0 170.0 168.0 167.0 08:41:59 INFO - PROCESS | 1666 | 08:41:59 INFO - PROCESS | 1666 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.20 Median:68.50 stddev:1.14 (1.7%) stddev-sans-first:1.17 08:41:59 INFO - PROCESS | 1666 | Values: 69.0 68.0 69.0 68.0 70.0 67.0 69.0 68.0 66.0 68.0 08:41:59 INFO - PROCESS | 1666 | -------- Summary: end -------- 08:41:59 INFO - PROCESS | 1666 | 08:42:00 INFO - TEST-INFO | 1666: exit 0 08:42:00 INFO - TEST-OK | kraken | took 61485ms 08:42:00 INFO - SUITE-END | took 1178s 08:42:01 INFO - Completed test suite (00:19:38) 08:42:01 INFO - javascript benchmark 08:42:01 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6985.89166872988, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3363.8990830275084, 3406.9148271293216, 3411.1813164840432, 3414.3342048300574, 3422.589289285286, 5715.657060576363, 5747.913781293279, 5769.855753606162, 5782.797602083928, 5813.389594092623, 5755.740991655375, 5778.393268706788, 5833.183354330397, 5838.503727183194, 5840.707964601774, 4281.507626622932, 5230.320058392404, 5267.394249661281, 5308.893822123555, 5331.600129990247, 5257.868553286179, 5265.368155821307, 5281.392639846409, 5314.415414304423, 5366.141417373221, 5226.111561034633, 5241.79032838686, 5330.92003619946, 5335.573154147659, 5357.946420535784, 5292.444293349181, 5299.364076310867, 5321.733913304339, 5341.2255222992835, 5348.3849357323625, 5236.8690782730355, 5245.344331958505, 5323.946760532383, 5334.253204551366, 5375.0, 5877.177195192677, 5880.176775251425, 5893.58744887858, 5938.7624495020145, 5939.406059394069, 6103.792276408629, 6168.568200225986, 6323.20959880015, 6329.620222786648, 6337.2395312562785, 6241.812745617639, 6260.186175797163, 6278.277998030239, 6306.968465157691, 6312.715927783215, 4936.284238785428, 5381.730913454312, 5728.0, 5801.477866991991, 5865.706714664249, 5313.946860531422, 6383.425491705676, 6390.488760899117, 6404.455621272244, 6405.711742971577, 5119.87200319995, 6080.179175811209, 6112.266528016667, 6112.297085835097, 6271.874562508816, 4911.435184953704, 6286.119943207955, 6291.307956124822, 6315.873682526416, 6330.841728956813, 6134.079888016833, 6253.593516421418, 6295.779647712308, 6306.148669929593, 6312.021636646327, 6050.485708714808, 6122.2761798032625, 6143.696113957266, 6211.720472578745, 6246.6876656167005, 4359.607635312837, 4906.950930490721, 4942.184539551005, 4947.307376967227, 4984.950150498521, 4429.955700442954, 4440.622547083534, 4448.488423831301, 4449.377087207794, 4480.641548676098, 5748.511376532958, 5775.249217601685, 5821.2141360916585, 5828.737706803204, 5955.166276721263, 6232.470240029648, 6252.124702541648, 6267.4045965633195, 6300.243970723543, 6301.873962520724, 5953.851153721105, 5988.640681559121, 5994.190784244159, 5994.22075130239, 6053.061775424816, 5408.45915408456, 5411.377691565521, 5437.021336159451, 5473.781048758085, 5477.20580515831, 6209.627422354763, 6311.368863113652, 6380.330065343258, 6397.0724244983285, 6452.064450654535, 6230.0, 6266.968665156645, 6274.780382686584, 6276.874462510816, 6293.465055470337, 6267.96866015667, 6406.711697973696, 6428.389303016206, 6439.45264652491, 6487.708053137714, 5175.715335656424, 6242.812715618357, 6269.874602508015, 6290.087937248982, 6294.71673774672, 6112.26032301701, 6141.324454309933, 6182.690865456799, 6298.653574053471, 6316.368363163647, 6125.937554509019, 6163.445289923929, 6183.84540386494, 6196.349383314687, 6254.249490061222, 6122.663253521099, 6223.595466294676, 6228.875422491434, 6242.063690446469, 6268.780592679234, 5711.771529138788, 5907.438793314628, 6044.742260845399, 6158.230221222348, 6182.969085154546, 5034.194528875362, 5581.190727344593, 6113.816585502442, 6143.358355907003, 6147.538934580014, 5979.671118088547, 6035.939640603713, 6042.123892035718, 6057.42454466825, 6059.272887253558], "value": 5722.050794268343, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [473.45789071513116, 477.584501483709, 517.4179048570359, 524.9422563518012, 539.9082156033473, 37003.44494832576, 38622.420663690034, 38647.806760966225, 40488.595114048854, 40503.39244911321, 39227.0, 39665.0, 39707.00732481689, 39740.40389394154, 40023.59976400235, 8369.414141010131, 9235.261179105672, 9403.43579385239, 9417.952910235439, 9440.763980900463, 7756.495827771205, 8715.520646364448, 9329.440233585972, 9345.205657519118, 9433.151016408521, 6975.825604359881, 9426.952865235664, 9427.057294270553, 9509.0, 9522.904770952306, 9230.400023998485, 9307.441553506811, 9368.20370268528, 9387.249020078445, 9390.999999999965, 7915.168907264712, 9172.0, 9280.814383712288, 9293.303002274857, 9301.767455813591, 37715.86852394432, 37786.811065944494, 37881.43177852334, 37926.24147517062, 38107.04732381685, 2789.6652401711926, 3105.4720697481484, 3225.8064516129107, 3285.4086264472385, 3329.250918543333, 29161.85419072891, 29841.70158298411, 29905.252368690955, 29994.0, 30415.847920760476, 3133.2950086230417, 3148.622165340174, 3174.68253174683, 3219.1630176154135, 3225.854836532362, 481976.0, 488554.0, 491654.0, 492023.0, 492388.0, 48002.759986199846, 52470.47529524732, 54512.72743636256, 54635.180472292945, 55063.724681376334, 53703.0, 54744.0, 57007.0, 57055.14417283744, 57254.7137264311, 15355.0, 15374.769378459332, 15504.147271899928, 15574.688506229812, 15606.0, 12222.572209972786, 12277.815832762337, 12288.815667764991, 12290.631281061582, 12312.13815032939, 14198.219097949712, 14292.285385730673, 14440.205788681513, 14508.854911450962, 14678.0, 6376.266729326187, 6532.379423954717, 6542.214934207926, 6548.8362790930605, 6595.8021259362295, 856.9485830850169, 901.6348378906554, 948.6252930092713, 964.1370972979145, 1012.6708819633501, 22383.104675813112, 24747.752522474904, 27482.763275652644, 28313.31023552759, 28325.0, 838.6771093129067, 875.2866413872673, 887.9911200888038, 904.7834946764185, 1017.5522769981185, 12718.745625087631, 12847.743045139045, 14480.610699234509, 15004.474843380647, 15107.0, 167740.0, 167949.0, 168579.0, 168652.0, 169416.0, 53123.46876531185, 53674.73162634162, 53901.460985390426, 53933.730331348874, 53973.730131349876, 1325.9734805304033, 1361.0948719101686, 1371.7736573465465, 1374.6288502104376, 1378.6691194113341, 24190.51618967646, 24548.0, 28154.888383371737, 28404.57393139146, 28667.56998645064, 40545.594544054184, 40817.0, 46189.0, 46810.06379872315, 46862.06275874531, 451745.0, 454304.0, 454377.0, 454833.0, 456218.0, 30921.0, 37476.0, 37704.81147594244, 38061.0, 38362.6163738359, 43498.75201147765, 43839.56160438355, 44272.11455770931, 44392.7780361109, 44675.10649787051, 36654.0, 37062.814685926394, 37124.88625341244, 37578.436323454625, 38441.11691623196, 36577.0, 36752.63247367492, 37593.62406376011, 37790.62209377871, 37861.81069094637, 30950.380992379574, 31566.842165789025, 34687.306253875286, 35802.641973580976, 36978.815105924295, 31291.374172516877, 33295.33409331848, 33750.0, 33760.81837135689, 33821.0, 25208.621870672323, 25297.367565811, 25358.239252822445, 25488.762564094883, 25620.615690765026], "value": 18103.54360921813, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [371.27601177703474, 372.90863738384115, 379.5331741957392, 381.5326225373919, 402.51496946179856, 18507.16717747701, 19307.131179096938, 19843.206271749114, 19966.0, 20000.000000000015, 19493.317733879318, 19568.11943462544, 19913.004349782525, 20006.59986800263, 20289.492762680904, 4806.423229212482, 5676.716164191795, 5915.704214789265, 5946.405359464062, 6547.0, 6556.704948277315, 6630.801075967729, 6641.302663220341, 6646.136002319716, 6652.966735166317, 6352.555321127523, 6504.349565043505, 6514.120593719835, 6526.314736952623, 6526.967365163167, 3154.9211269718326, 3185.8725650973934, 3186.9043928682177, 3200.695933886277, 3466.9425825123326, 5491.835244942658, 5579.609427340088, 5630.662160270397, 5715.714214289269, 5715.914261286084, 24288.39279018021, 24522.896469658728, 25215.495690086278, 25241.747582524305, 25389.492210155877, 2051.2820512820545, 2192.090282532747, 2391.641253811925, 2431.185552839801, 3041.9961412733746, 7219.458540609476, 7867.567283799389, 9954.203663706887, 9957.70549828525, 10025.298229123964, 1821.253286152671, 1884.660761063008, 1893.2237782509244, 1901.277514544478, 1947.123794292574, 104092.0, 116522.0, 122594.38702806429, 123789.0, 124288.0, 9073.274138068939, 11586.47860846264, 11627.55955700739, 11753.529858805723, 11955.76088478243, 14514.854851451562, 14642.707145857235, 14689.19209443491, 14693.853061469463, 14731.631709207355, 7171.995920571124, 7932.762017139495, 8348.081711011784, 8475.14944152134, 8645.783855403664, 6832.6925288362145, 8384.538850363166, 8403.0, 8589.226969572768, 8607.61265743043, 4458.9554104459185, 4537.296719008559, 4539.432570928634, 4544.818207271738, 4596.5863072323655, 1611.9113448760197, 1859.9721004184948, 1934.864559480851, 1940.6506828770957, 2010.336588925651, 888.6623083228461, 889.1775108025051, 915.1671978499534, 934.7149119518476, 939.3612343606413, 18903.960282184336, 20748.48128796762, 20790.48023799387, 20806.0, 21012.579748404947, 879.4283715584921, 906.8549032154824, 920.3557509743073, 923.3398120343986, 985.4629227071264, 10430.322029068086, 10983.505742241618, 11075.889241107647, 11120.666380008613, 11140.49867755953, 514867.0, 514955.0, 517524.0, 612627.0, 634423.0, 103802.48098759458, 120844.0, 126566.0, 126690.36654816852, 139623.30188349198, 812.6749300279936, 883.2095274729081, 959.4195511715312, 985.9901400986042, 994.1599348550463, 18085.457436276934, 18828.905855470635, 18924.14841332171, 19282.74662146956, 19300.32448864283, 38261.617383825804, 55159.17261241165, 55247.0, 55566.444335557746, 56932.715336423054, 73373.633131834, 74788.0, 76352.85470718055, 76583.0, 77409.0, 7029.261927497743, 8031.397645176518, 8056.907302409203, 8105.635246413807, 8404.957975210085, 10802.783944321227, 12742.554010609585, 12804.23544689444, 12890.162139460905, 13231.073824832169, 5374.973125134349, 5866.618669786621, 7816.866554349701, 10942.0, 11390.886091138982, 9554.665586704432, 9723.611055557698, 10767.84405396317, 11109.611163609557, 11363.943180284046, 9322.95338523303, 10935.507902144263, 10959.01368876805, 11140.774514803203, 11652.009579185526, 7415.184329723618, 7441.404687625085, 7518.624068796647, 7546.094468663796, 7698.92301076982, 8947.865782013145, 10799.460026998775, 10850.620228291971, 11098.0, 11126.221164518405], "value": 9203.408000436802, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1444.8410674825768, 1478.0318891126312, 1625.341736596678, 1724.5602371395291, 1758.8592912567003, 7440.6651700673465, 7685.6925722971155, 7939.0, 8401.957990210056, 8439.62021709023, 6624.966875165629, 7918.841623167533, 8243.175682431769, 8254.257116859482, 8314.210150035737, 2237.865728056322, 2293.74768775435, 2350.2244259394442, 2353.670486131943, 2353.8587684738886, 2014.6272939506166, 2334.902595779981, 2362.3503536527505, 2375.6436534519876, 2379.4289370551114, 2298.9655155172686, 2313.884305784704, 2330.9650355244685, 2337.2988103568946, 2354.223106374901, 2296.494771150344, 2318.953620927589, 2319.4665226997718, 2326.627739561662, 2328.662343960125, 2264.320703788857, 2276.988615056914, 2280.920167794119, 2284.383216531527, 2291.747907730148, 8303.66785328586, 8336.749897503025, 8414.747557573282, 8430.199131082538, 8445.239928406472, 5018.623603229772, 5146.867689108391, 5197.740112994373, 5210.843674689765, 5229.450907654679, 3298.290867463507, 3458.6368431314854, 3532.947005794915, 3625.1299688074937, 3628.7459877808565, 1014.843078890066, 1036.7356324137297, 1072.9731756706067, 1081.826907694773, 1097.2922465010024, 10879.401632910187, 10984.286021408583, 10988.560457581687, 11007.0, 11008.614698485595, 3824.8661296854475, 4634.536546345406, 4693.929591056137, 4712.151812673683, 4721.102990431807, 9280.071992800666, 9288.210502107397, 9288.814223715488, 9322.720318390458, 9323.533823308808, 5180.1711726124395, 5181.0, 5184.974075129676, 5200.531952124327, 5201.375834899836, 4271.380649805761, 4302.160574021131, 4641.25739881624, 4725.976370118127, 4765.928511072337, 3805.353089974711, 3829.100161462054, 3903.1608204236227, 3919.862804801818, 3931.4299426582984, 1272.7645385603694, 1279.33474593211, 1282.4421376701164, 1285.2352850054203, 1293.275765571283, 649.7498463091649, 778.1051790441038, 882.6778225947482, 916.6745805239177, 933.0855761353911, 5580.135079062752, 5625.409332020161, 5674.893395787782, 5711.114777209539, 5830.7084645767545, 882.2192359761657, 891.3359547137295, 900.8828852249165, 906.6917248135534, 921.8663293822358, 3815.4467602197524, 3829.8468061277795, 3838.9424158637644, 3864.478295430081, 3932.941005884914, 9751.073648003428, 10083.0, 10091.899081009244, 10142.0, 10216.335938163995, 10194.592216311414, 10238.53926573306, 10246.282760206863, 10262.486875656188, 10336.844947325797, 7536.359409450149, 7789.182135875765, 8021.679132834738, 8029.518228906284, 8039.075506316848, 4421.823127074881, 4617.538246175355, 4754.881127971828, 5429.565634749131, 5551.195076713934, 7700.345470634835, 9226.492542910204, 9280.535973201178, 9373.0, 9482.19401350893, 9375.484348360906, 9544.236461082957, 9564.33049686543, 9632.711018669452, 9779.0, 6010.519158467399, 6180.165677633462, 6185.505159587132, 6660.533762636569, 6751.93248067513, 7338.15611204697, 7574.848503030019, 7797.571133587707, 8038.477498962548, 8044.0, 6019.759209631741, 7067.187273463617, 7226.89159662616, 7251.347378735939, 7324.340809327186, 7023.192332881784, 7089.149302083783, 7098.148222213367, 7130.2156762757095, 7138.678759455941, 6628.337166283526, 6646.302138275411, 6704.83237919056, 6739.966300168468, 6741.258461569126, 4263.955330943939, 4731.645126615449, 4832.44426890912, 4867.464578896389, 4892.0, 3951.3677811550015, 4039.6566291864347, 4965.4041515018425, 5136.9743151284, 5173.3533308336455], "value": 4374.124895195872, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [256.2428025185576, 265.9415526205702, 282.3548192380411, 289.53963198514356, 289.83189749945035, 4291.459545874654, 4868.926966095502, 5055.469175736541, 5334.626576139672, 5377.596680248982, 5025.195968645017, 5122.359705036871, 5197.7920883164625, 5224.817131400402, 5282.5245727884485, 3788.223414200096, 3968.730006397961, 3987.98006009971, 3994.362311452305, 4011.6790656747535, 3859.8420473857873, 3982.2433737589763, 4000.6999475039343, 4003.1393250451147, 4021.3163762160357, 3576.6959808416186, 3914.451976723261, 3942.7634341939574, 3950.980245098785, 3964.5638979712207, 3934.881953541398, 3954.584768599313, 3959.703022273341, 3960.683145348365, 3965.2267807777494, 2781.638387009696, 3229.8223597702117, 3295.538624592559, 3361.252148882592, 3446.672566106216, 5076.974615126937, 5083.440821509631, 5086.898262034775, 5098.566621837148, 5106.387233531963, 4459.353393757887, 4460.910781784379, 4464.308032255006, 4476.84331048415, 4480.14877173336, 5266.8419947401635, 5503.504684578407, 5522.475364840334, 5541.806036788693, 5555.2778138842095, 3119.688031196885, 3710.7588006779474, 3720.9069773255887, 3727.5835182630426, 3729.198222382201, 4624.953750462486, 5802.999999999958, 5859.7949071782705, 5873.823785286449, 5888.558358123158, 3019.698030196963, 3476.4785282207367, 3744.288585168864, 3744.981275093607, 3754.718396120302, 3217.5656286401504, 3268.297316077055, 3732.9813350933073, 3770.699108807457, 3791.194516058152, 4213.957860421357, 4426.0, 4427.845025424094, 4435.560441757021, 4440.134173836071, 4553.681242313006, 4833.245050321353, 5150.223173005307, 5303.957976179198, 5480.725963701799, 961.3991255465335, 1068.1614932278123, 1227.4660522672666, 1237.1340061956666, 1246.1588427811191, 700.4956431369405, 818.4107442641289, 843.4475418600827, 844.1895780051096, 847.5337665838134, 4568.588827005586, 4693.887750512283, 4757.452892917289, 4836.95163048365, 4997.875053123701, 4587.495375508692, 5174.741262936838, 5236.266922630834, 5376.91934620981, 5440.6735595864375, 3845.8461661533784, 4663.930041049387, 4923.77842997066, 4935.555799977948, 4939.75301234944, 2707.564990555009, 2735.685396179425, 2787.372841110734, 2813.5357665985293, 2822.50606143924, 5216.58267338612, 5580.9441905580425, 5647.774089036475, 5652.434756524397, 5681.687507187062, 5191.5846732261325, 5470.343558772893, 5527.806526771544, 5530.806421775219, 5673.205751194836, 5273.1035723927025, 5595.608307418523, 5678.261825962681, 5926.940730592639, 5993.790217342459, 5007.37407824022, 5782.595218334676, 5786.623869448473, 5803.691895619457, 5840.423085766734, 4810.831620893252, 5204.427512973494, 5380.977614253279, 5603.607747457639, 5671.40450252734, 4851.102546028996, 5184.662996905189, 5426.829268292715, 5681.0910254359105, 5944.881102377842, 3179.3959147761852, 3234.7573931954726, 3528.7825700133412, 3750.3999360102252, 3751.174741556853, 5314.256004159498, 5702.429757024264, 5731.5701322400155, 5737.885242295214, 5741.741621626953, 5121.8463446096675, 5399.002369123449, 5515.448455154453, 5582.7766889323975, 5652.660840349592, 4890.339804126397, 4961.925571116509, 5326.8014696692935, 5574.96513772462, 5689.288838895138, 4670.883227919329, 4778.593819525379, 5556.9719601873785, 5564.33228012641, 5609.607327487037, 4709.858704238878, 4710.717356958594, 5726.828195154152, 5734.738357561329, 5736.110902810071, 4567.6117530009, 5338.252644629677, 5456.645318054314, 5485.6434331768305, 5561.277033985556], "value": 3940.163601411042, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [684.3054299885617, 694.4548529404418, 737.9926200737991, 747.5327920049968, 784.40777213204, 11330.886691133086, 12663.62009139726, 14367.497137600187, 14798.26008699566, 14851.702965940674, 13137.67155821105, 13146.474141034347, 13165.473381064747, 13175.802362964563, 13290.800637990415, 621.2762132116079, 645.1645119570168, 774.4346626962321, 780.239266714954, 803.9879401808948, 788.5150632361085, 801.1587832775591, 801.8757092650649, 802.2619190344897, 803.0243254445847, 846.0989046665312, 849.5964416901984, 849.7280870121564, 849.9192576705203, 851.2508992086955, 823.5511646152831, 829.7718127514952, 844.994456596651, 845.0239972831329, 845.3321875718175, 615.7475435071644, 621.2451870976785, 622.8411755002445, 623.8097380299007, 625.6809027396015, 13681.0, 13756.312184390841, 13872.167669939736, 13902.374393152333, 14169.78745318821, 12570.811437828439, 13505.527306544322, 13615.319234038358, 13646.931765341109, 13829.377678004514, 10761.0, 10883.619073332475, 11007.834882476769, 11110.833337499944, 11275.60535381266, 3559.7864128152396, 3628.11111277736, 3651.2879988402274, 3656.945145822815, 3702.629737026303, 68365.65817170932, 80544.9863753405, 80688.19311806807, 84258.0, 87589.0, 10071.0432508913, 10214.744631384126, 10237.539310731036, 10409.791804163875, 10414.427206503564, 20436.591268174852, 20464.795352046585, 20497.590048198952, 20502.642196147634, 20503.69244461334, 7532.8493430132185, 7542.358899493494, 7589.848203035908, 7604.80987975305, 7609.201033891472, 3644.7448678592245, 4142.523609784853, 4210.873673789792, 4252.936205956913, 4272.530021697611, 2863.4273145371008, 3034.544818277276, 3255.6489057041063, 3292.489664102068, 3655.3054919565197, 691.3881215124551, 697.2678687378334, 713.4149997002512, 713.7180813578711, 715.4669771020519, 546.9389384593875, 625.3746253746253, 640.9711562979677, 654.499308029358, 671.9899201511981, 8624.655013799504, 8961.820763584823, 8992.8309319788, 9123.863142052875, 9195.77010574741, 583.2243116654839, 585.318104408368, 609.2384519350811, 648.2642201101784, 661.441082285468, 3697.6487233712755, 4028.395740638869, 4240.448741663564, 4456.665750068758, 4456.910861782746, 87165.0, 96135.0, 106891.0, 109091.0, 114163.42918285355, 33627.0, 60839.0, 98194.5090274544, 100128.0, 101901.49049254706, 74812.25187748052, 90702.63946040814, 90710.18579628371, 91156.63265051029, 91550.54224728834, 9263.490508022123, 9463.195628371666, 9519.857202142111, 9562.713118606453, 9595.808083838145, 40956.59043409528, 45547.31679024751, 46124.30813537867, 48531.51468485411, 50833.0, 62961.425964351256, 69519.0, 74566.2543374581, 76412.32556860484, 77821.44357112939, 11733.530658773554, 11815.82276265874, 12068.094892882893, 12104.878951210727, 12277.631671049883, 13302.93348533251, 13574.457021719021, 13588.59234222975, 13841.238731869442, 13939.860601394263, 11967.0, 12300.938495307466, 12655.683607909876, 13083.869161308265, 13119.672008199872, 12404.75190496167, 12876.969842412773, 12982.350882456029, 13386.397612107285, 13407.061505694508, 12002.699932501757, 12588.0, 12618.242905425705, 12949.935250323688, 12962.611121666365, 10112.0, 10156.796864062826, 11057.502412391623, 11255.0, 11283.435828208721, 8918.286537077149, 10850.348979061282, 11021.44892755375, 11059.115270778479, 11137.164712646745], "value": 7073.212416604743, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1557.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 119.0, 128.0, 104.0, 169.0, 178.0, 175.0, 118.0, 120.0, 120.0], "value": 133.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 111.0, 121.0, 173.0, 100.0, 138.0, 147.0, 152.0, 129.0, 144.0], "value": 135.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [193.0, 200.0, 194.0, 202.0, 194.0, 198.0, 197.0, 199.0, 203.0, 199.0], "value": 197.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 60.0, 60.0, 83.0, 60.0, 60.0, 59.0, 59.0, 77.0, 59.0], "value": 65.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 71.0, 89.0, 73.0, 69.0, 70.0, 83.0, 69.0, 71.0, 88.0], "value": 77.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 95.0, 136.0, 89.0, 95.0, 89.0, 93.0, 73.0, 139.0, 132.0], "value": 103.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 101.0, 97.0, 113.0, 147.0, 90.0, 153.0, 154.0, 97.0, 98.0], "value": 114.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 72.0, 141.0, 139.0, 123.0, 88.0, 80.0, 148.0, 142.0, 89.0], "value": 115.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 57.0, 59.0, 60.0, 62.0, 62.0, 62.0, 68.0, 84.0, 94.0], "value": 66.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 73.0, 75.0, 75.0, 77.0, 71.0, 72.0, 90.0, 61.0, 87.0], "value": 75.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 87.0, 85.0, 88.0, 84.0, 83.0, 84.0, 87.0, 86.0, 86.0], "value": 85.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 150.0, 137.0, 138.0, 172.0, 145.0, 145.0, 144.0, 193.0, 140.0], "value": 150.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 170.0, 168.0, 166.0, 172.0, 169.0, 166.0, 170.0, 168.0, 167.0], "value": 168.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 68.0, 69.0, 68.0, 70.0, 67.0, 69.0, 68.0, 66.0, 68.0], "value": 68.2, "unit": "score"}]}]} 08:42:01 INFO - Return code: 0 08:42:01 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:42:01 ERROR - # TBPL SUCCESS # 08:42:01 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:42:01 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:42:01 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:42:01 INFO - 2016-12-24 08:42:01.173 screenresolution[1671:11845] starting screenresolution argv=screenresolution get 08:42:01 INFO - 2016-12-24 08:42:01.176 screenresolution[1671:11845] Display 0: 1600x1200x32@60 08:42:01 INFO - 2016-12-24 08:42:01.183 screenresolution[1672:11848] starting screenresolution argv=screenresolution list 08:42:01 INFO - Available Modes on Display 0 08:42:01 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:42:01 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:42:01 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:42:01 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:42:01 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:42:01 INFO - Intel Iris: 08:42:01 INFO - Chipset Model: Intel Iris 08:42:01 INFO - Type: GPU 08:42:01 INFO - Bus: Built-In 08:42:01 INFO - VRAM (Dynamic, Max): 1536 MB 08:42:01 INFO - Vendor: Intel (0x8086) 08:42:01 INFO - Device ID: 0x0a2e 08:42:01 INFO - Revision ID: 0x0009 08:42:01 INFO - Displays: 08:42:01 INFO - Display: 08:42:01 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:42:01 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:42:01 INFO - Display Serial Number: 1600x1200 60 08:42:01 INFO - Main Display: Yes 08:42:01 INFO - Mirror: Off 08:42:01 INFO - Online: Yes 08:42:01 INFO - Rotation: Supported 08:42:01 INFO - Return code: 0 08:42:01 INFO - Running post-action listener: _resource_record_post_action 08:42:01 INFO - [mozharness: 2016-12-24 16:42:01.426869Z] Finished run-tests step (success) 08:42:01 INFO - Running post-run listener: _resource_record_post_run 08:42:01 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 32985600; Write bytes: 663839232; Read time: 202; Write time: 1913 08:42:01 INFO - TinderboxPrint: CPU usage
54.2% 08:42:01 INFO - TinderboxPrint: I/O read bytes / time
32,985,600 / 202 08:42:01 INFO - TinderboxPrint: I/O write bytes / time
663,839,232 / 1,913 08:42:01 INFO - TinderboxPrint: CPU idle
2,220.2 (45.8%) 08:42:01 INFO - TinderboxPrint: CPU system
979.5 (20.2%) 08:42:01 INFO - TinderboxPrint: CPU user
1,646.0 (34.0%) 08:42:01 INFO - TinderboxPrint: Swap in / out
379,125,760 / 0 08:42:01 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 349770240; Write bytes: 352380416; Read time: 27984; Write time: 724 08:42:01 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 29716992; Write bytes: 310119424; Read time: 178; Write time: 1182 08:42:01 INFO - Running post-run listener: _upload_blobber_files 08:42:01 INFO - Blob upload gear active. 08:42:01 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:42:01 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 08:42:01 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-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:42:01 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-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:42:02 INFO - (blobuploader) - INFO - Open directory for files ... 08:42:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 08:42:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:42:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:42:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 08:42:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:42:03 INFO - (blobuploader) - INFO - Done attempting. 08:42:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 08:42:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:42:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:42:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 08:42:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:42:04 INFO - (blobuploader) - INFO - Done attempting. 08:42:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:42:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:42:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:42:06 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:42:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:42:06 INFO - (blobuploader) - INFO - Done attempting. 08:42:06 INFO - (blobuploader) - INFO - Iteration through files over. 08:42:06 INFO - Return code: 0 08:42:06 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:42:06 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:42:06 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cec95b11b8dcedcc318a478b6ba05137186dde25165b6e16f78dcadc594dec84f95a0d25f3b377793548b353090fb486da2c0162ff068000322431ab427bd573", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9286661381eba5b1f40d4f8a8c430f38ca160e7558ab4d56c7070f8d10e3a257dfa842836cfd2ae199dc107bcd4786a1f28e632b0ff6cef6bf242c96177b0bd6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/501a442782e8850fc2a9705a43a1e74e077b76f2bb771e3e084fd70fb8063db9363d919e8b8e392a06efc82882dae7449522509f5cad3ae4c7821b1e15d55435"} 08:42:06 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:42:06 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:42:06 INFO - Contents: 08:42:06 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cec95b11b8dcedcc318a478b6ba05137186dde25165b6e16f78dcadc594dec84f95a0d25f3b377793548b353090fb486da2c0162ff068000322431ab427bd573", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9286661381eba5b1f40d4f8a8c430f38ca160e7558ab4d56c7070f8d10e3a257dfa842836cfd2ae199dc107bcd4786a1f28e632b0ff6cef6bf242c96177b0bd6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/501a442782e8850fc2a9705a43a1e74e077b76f2bb771e3e084fd70fb8063db9363d919e8b8e392a06efc82882dae7449522509f5cad3ae4c7821b1e15d55435"} 08:42:06 INFO - Running post-run listener: copy_logs_to_upload_dir 08:42:06 INFO - Copying logs to upload dir... 08:42:06 INFO - mkdir: /builds/slave/test/build/upload/logs 08:42:06 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.802955 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-12-24 08:42:06.775302) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-24 08:42:06.781112) ========= 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.3EvC8DkL0e/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.aAIDv5Vvez/Listeners TMPDIR=/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cec95b11b8dcedcc318a478b6ba05137186dde25165b6e16f78dcadc594dec84f95a0d25f3b377793548b353090fb486da2c0162ff068000322431ab427bd573", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9286661381eba5b1f40d4f8a8c430f38ca160e7558ab4d56c7070f8d10e3a257dfa842836cfd2ae199dc107bcd4786a1f28e632b0ff6cef6bf242c96177b0bd6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/501a442782e8850fc2a9705a43a1e74e077b76f2bb771e3e084fd70fb8063db9363d919e8b8e392a06efc82882dae7449522509f5cad3ae4c7821b1e15d55435"} build_url:https://queue.taskcluster.net/v1/task/Q6uInrPtRgOX8HnUExrG1g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010883 build_url: 'https://queue.taskcluster.net/v1/task/Q6uInrPtRgOX8HnUExrG1g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cec95b11b8dcedcc318a478b6ba05137186dde25165b6e16f78dcadc594dec84f95a0d25f3b377793548b353090fb486da2c0162ff068000322431ab427bd573", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9286661381eba5b1f40d4f8a8c430f38ca160e7558ab4d56c7070f8d10e3a257dfa842836cfd2ae199dc107bcd4786a1f28e632b0ff6cef6bf242c96177b0bd6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/501a442782e8850fc2a9705a43a1e74e077b76f2bb771e3e084fd70fb8063db9363d919e8b8e392a06efc82882dae7449522509f5cad3ae4c7821b1e15d55435"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-24 08:42:06.815439) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-24 08:42:06.815791) ========= 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.3EvC8DkL0e/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.aAIDv5Vvez/Listeners TMPDIR=/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/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.005101 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-24 08:42:06.864649) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-24 08:42:06.864963) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-24 08:42:06.865294) ========= ========= Total master_lag: 0.21 =========