builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0026 starttime: 1487111878.71 results: success (0) buildid: 20170214131245 builduid: 08554464d5a446e793e297102f7155c6 revision: 1060668405a9399774c205430de4a7001d3f27ac ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-14 14:37:58.706075) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-14 14:37:58.706555) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-14 14:37:58.706834) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-14 14:37:58.733097) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-14 14:37:58.733479) ========= 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.qY0AN8ItX1/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.UgMvAqTUhu/Listeners TMPDIR=/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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 --2017-02-14 14:37:58-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 157M=0s 2017-02-14 14:37:58 (157 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.100624 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-14 14:37:58.858056) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-14 14:37:58.858371) ========= 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.qY0AN8ItX1/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.UgMvAqTUhu/Listeners TMPDIR=/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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.026625 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-14 14:37:58.907247) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-14 14:37:58.907611) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 1060668405a9399774c205430de4a7001d3f27ac --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 1060668405a9399774c205430de4a7001d3f27ac --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.qY0AN8ItX1/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.UgMvAqTUhu/Listeners TMPDIR=/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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 2017-02-14 14:37:59,006 truncating revision to first 12 chars 2017-02-14 14:37:59,006 Setting DEBUG logging. 2017-02-14 14:37:59,006 attempt 1/10 2017-02-14 14:37:59,006 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/1060668405a9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-14 14:38:00,081 unpacking tar archive at: mozilla-central-1060668405a9/testing/mozharness/ program finished with exit code 0 elapsedTime=1.332897 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-14 14:38:00.264295) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-14 14:38:00.264649) ========= script_repo_revision: 1060668405a9399774c205430de4a7001d3f27ac ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-14 14:38:00.265108) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-14 14:38:00.265489) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-14 14:38:00.285072) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 27 secs) (at 2017-02-14 14:38:00.285404) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.qY0AN8ItX1/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.UgMvAqTUhu/Listeners TMPDIR=/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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 14:38:00 INFO - MultiFileLogger online at 20170214 14:38:00 in /builds/slave/test 14:38:00 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 14:38:00 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:38:00 INFO - {'append_to_log': False, 14:38:00 INFO - 'base_work_dir': '/builds/slave/test', 14:38:00 INFO - 'blob_upload_branch': 'Firefox', 14:38:00 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:38:00 INFO - 'branch': 'Firefox', 14:38:00 INFO - 'buildbot_json_path': 'buildprops.json', 14:38:00 INFO - 'config_files': ('talos/mac_config.py',), 14:38:00 INFO - 'default_actions': ('clobber', 14:38:00 INFO - 'read-buildbot-config', 14:38:00 INFO - 'download-and-extract', 14:38:00 INFO - 'populate-webroot', 14:38:00 INFO - 'create-virtualenv', 14:38:00 INFO - 'install', 14:38:00 INFO - 'run-tests'), 14:38:00 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:38:00 INFO - 'download_minidump_stackwalk': True, 14:38:00 INFO - 'download_symbols': 'ondemand', 14:38:00 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:38:00 INFO - 'tooltool.py': '/tools/tooltool.py', 14:38:00 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:38:00 INFO - '/tools/misc-python/virtualenv.py')}, 14:38:00 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:38:00 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:38:00 INFO - 'installer_path': 'installer.exe', 14:38:00 INFO - 'log_level': 'info', 14:38:00 INFO - 'log_name': 'talos', 14:38:00 INFO - 'log_to_console': True, 14:38:00 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 14:38:00 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 14:38:00 INFO - 'opt_config_files': (), 14:38:00 INFO - 'pip_index': False, 14:38:00 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:38:00 INFO - 'cmd': ('bash', 14:38:00 INFO - '-c', 14:38:00 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:38:00 INFO - 'enabled': True, 14:38:00 INFO - 'halt_on_failure': False, 14:38:00 INFO - 'name': 'check_screen_resolution'},), 14:38:00 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:38:00 INFO - 'cmd': ('bash', 14:38:00 INFO - '-c', 14:38:00 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:38:00 INFO - 'enabled': True, 14:38:00 INFO - 'halt_on_failure': False, 14:38:00 INFO - 'name': 'check_screen_resolution'},), 14:38:00 INFO - 'run_cmd_checks_enabled': True, 14:38:00 INFO - 'sps_profile': False, 14:38:00 INFO - 'sps_profile_interval': 0, 14:38:00 INFO - 'suite': 'dromaeojs-e10s', 14:38:00 INFO - 'system_bits': '32', 14:38:00 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:38:00 INFO - 'title': 't-yosemite-r7-0026', 14:38:00 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:38:00 INFO - 'use_talos_json': True, 14:38:00 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:38:00 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:38:00 INFO - 'work_dir': 'build'} 14:38:00 INFO - [mozharness: 2017-02-14 22:38:00.497022Z] Running clobber step. 14:38:00 INFO - Running pre-action listener: _resource_record_pre_action 14:38:00 INFO - Running main action method: clobber 14:38:00 INFO - rmtree: /builds/slave/test/build 14:38:00 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:38:02 INFO - Running post-action listener: _resource_record_post_action 14:38:02 INFO - [mozharness: 2017-02-14 22:38:02.933871Z] Finished clobber step (success) 14:38:02 INFO - [mozharness: 2017-02-14 22:38:02.933995Z] Running read-buildbot-config step. 14:38:02 INFO - Running pre-action listener: _resource_record_pre_action 14:38:02 INFO - Running main action method: read_buildbot_config 14:38:02 INFO - Using buildbot properties: 14:38:02 INFO - { 14:38:02 INFO - "project": "", 14:38:02 INFO - "product": "firefox", 14:38:02 INFO - "script_repo_revision": "production", 14:38:02 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 14:38:02 INFO - "repository": "", 14:38:02 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 14:38:02 INFO - "buildid": "20170214131245", 14:38:02 INFO - "pgo_build": "False", 14:38:02 INFO - "basedir": "/builds/slave/test", 14:38:02 INFO - "buildnumber": 142, 14:38:02 INFO - "slavename": "t-yosemite-r7-0026", 14:38:02 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 14:38:02 INFO - "platform": "yosemite_r7", 14:38:02 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 14:38:02 INFO - "branch": "mozilla-central", 14:38:02 INFO - "repo_path": "mozilla-central", 14:38:02 INFO - "revision": "1060668405a9399774c205430de4a7001d3f27ac", 14:38:02 INFO - "stage_platform": "macosx64", 14:38:02 INFO - "builduid": "08554464d5a446e793e297102f7155c6", 14:38:02 INFO - "slavebuilddir": "test" 14:38:02 INFO - } 14:38:02 INFO - Found installer url https://queue.taskcluster.net/v1/task/IHK3zIGPRqGH1_X-CS9fPQ/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg. 14:38:02 INFO - Running post-action listener: _resource_record_post_action 14:38:02 INFO - [mozharness: 2017-02-14 22:38:02.938839Z] Finished read-buildbot-config step (success) 14:38:02 INFO - [mozharness: 2017-02-14 22:38:02.938971Z] Running download-and-extract step. 14:38:02 INFO - Running pre-action listener: _resource_record_pre_action 14:38:02 INFO - Running main action method: download_and_extract 14:38:02 INFO - mkdir: /builds/slave/test/build/tests 14:38:02 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')]} 14:38:02 INFO - trying https://queue.taskcluster.net/v1/task/IHK3zIGPRqGH1_X-CS9fPQ/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json 14:38:02 INFO - Downloading https://queue.taskcluster.net/v1/task/IHK3zIGPRqGH1_X-CS9fPQ/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 14:38:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IHK3zIGPRqGH1_X-CS9fPQ/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json'}, attempt #1 14:38:04 INFO - Downloaded 1199 bytes. 14:38:04 INFO - Reading from file /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 14:38:04 INFO - Using the following test package requirements: 14:38:04 INFO - {u'common': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 14:38:04 INFO - u'cppunittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 14:38:04 INFO - u'firefox-54.0a1.en-US.mac.cppunittest.tests.zip'], 14:38:04 INFO - u'gtest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 14:38:04 INFO - u'firefox-54.0a1.en-US.mac.gtest.tests.zip'], 14:38:04 INFO - u'jittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 14:38:04 INFO - u'jsshell-mac.zip'], 14:38:04 INFO - u'mochitest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 14:38:04 INFO - u'firefox-54.0a1.en-US.mac.mochitest.tests.zip'], 14:38:04 INFO - u'mozbase': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 14:38:04 INFO - u'reftest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 14:38:04 INFO - u'firefox-54.0a1.en-US.mac.reftest.tests.zip'], 14:38:04 INFO - u'talos': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 14:38:04 INFO - u'firefox-54.0a1.en-US.mac.talos.tests.zip'], 14:38:04 INFO - u'web-platform': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 14:38:04 INFO - u'firefox-54.0a1.en-US.mac.web-platform.tests.zip'], 14:38:04 INFO - u'xpcshell': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 14:38:04 INFO - u'firefox-54.0a1.en-US.mac.xpcshell.tests.zip']} 14:38:04 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip'] for test suite category: common 14:38:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IHK3zIGPRqGH1_X-CS9fPQ/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 14:38:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IHK3zIGPRqGH1_X-CS9fPQ/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:38:04 INFO - Fetch https://queue.taskcluster.net/v1/task/IHK3zIGPRqGH1_X-CS9fPQ/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 14:38:06 INFO - Expected file size: 51505361 14:38:06 INFO - Obtained file size: 51505361 14:38:22 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip', u'firefox-54.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 14:38:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IHK3zIGPRqGH1_X-CS9fPQ/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 14:38:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IHK3zIGPRqGH1_X-CS9fPQ/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:38:22 INFO - Fetch https://queue.taskcluster.net/v1/task/IHK3zIGPRqGH1_X-CS9fPQ/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 14:38:24 INFO - Expected file size: 51505361 14:38:24 INFO - Obtained file size: 51505361 14:38:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IHK3zIGPRqGH1_X-CS9fPQ/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip 14:38:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IHK3zIGPRqGH1_X-CS9fPQ/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip'}, attempt #1 14:38:39 INFO - Fetch https://queue.taskcluster.net/v1/task/IHK3zIGPRqGH1_X-CS9fPQ/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip into memory 14:38:41 INFO - Expected file size: 14403647 14:38:41 INFO - Obtained file size: 14403647 14:38:42 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')]} 14:38:42 INFO - trying https://queue.taskcluster.net/v1/task/IHK3zIGPRqGH1_X-CS9fPQ/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 14:38:42 INFO - Downloading https://queue.taskcluster.net/v1/task/IHK3zIGPRqGH1_X-CS9fPQ/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg 14:38:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IHK3zIGPRqGH1_X-CS9fPQ/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg'}, attempt #1 14:38:45 INFO - Downloaded 70568426 bytes. 14:38:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/IHK3zIGPRqGH1_X-CS9fPQ/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 14:38:45 INFO - mkdir: /builds/slave/test/properties 14:38:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:38:45 INFO - Writing to file /builds/slave/test/properties/build_url 14:38:45 INFO - Contents: 14:38:45 INFO - build_url:https://queue.taskcluster.net/v1/task/IHK3zIGPRqGH1_X-CS9fPQ/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 14:38:45 INFO - Running post-action listener: _resource_record_post_action 14:38:45 INFO - Running post-action listener: set_extra_try_arguments 14:38:45 INFO - [mozharness: 2017-02-14 22:38:45.864531Z] Finished download-and-extract step (success) 14:38:45 INFO - [mozharness: 2017-02-14 22:38:45.864662Z] Running populate-webroot step. 14:38:45 INFO - Running pre-action listener: _resource_record_pre_action 14:38:45 INFO - Running main action method: populate_webroot 14:38:45 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:38:45 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:38:45 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:38:45 INFO - '--tppagecycles', 14:38:45 INFO - '7'], 14:38:45 INFO - 'tests': ['tsvgm']}}, 14:38:45 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:38:45 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:38:45 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:38:45 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:38:45 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:38:45 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:38:45 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 14:38:45 INFO - 'talos_options': ['--disable-e10s'], 14:38:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:38:45 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 14:38:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:38:45 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 14:38:45 INFO - 'talos_options': ['--disable-e10s'], 14:38:45 INFO - 'tests': ['damp', 'tps']}, 14:38:45 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 14:38:45 INFO - 'tests': ['damp', 'tps']}, 14:38:45 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:38:45 INFO - 'tests': ['dromaeo_dom']}, 14:38:45 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:38:45 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:38:45 INFO - 'tests': ['basic_compositor_video']}, 14:38:45 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:38:45 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:38:45 INFO - 'tests': ['a11yr', 14:38:45 INFO - 'ts_paint', 14:38:45 INFO - 'tpaint', 14:38:45 INFO - 'sessionrestore', 14:38:45 INFO - 'sessionrestore_no_auto_restore', 14:38:45 INFO - 'tabpaint']}, 14:38:45 INFO - 'other-e10s': {'tests': ['a11yr', 14:38:45 INFO - 'ts_paint', 14:38:45 INFO - 'tpaint', 14:38:45 INFO - 'sessionrestore', 14:38:45 INFO - 'sessionrestore_no_auto_restore', 14:38:45 INFO - 'tabpaint']}, 14:38:45 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:38:45 INFO - 'ts_paint', 14:38:45 INFO - 'tpaint', 14:38:45 INFO - 'sessionrestore', 14:38:45 INFO - 'sessionrestore_no_auto_restore', 14:38:45 INFO - 'tabpaint']}, 14:38:45 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:38:45 INFO - 'ts_paint', 14:38:45 INFO - 'tpaint', 14:38:45 INFO - 'sessionrestore', 14:38:45 INFO - 'sessionrestore_no_auto_restore', 14:38:45 INFO - 'tabpaint']}, 14:38:45 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:38:45 INFO - 'tests': ['a11yr', 14:38:45 INFO - 'ts_paint', 14:38:45 INFO - 'tpaint', 14:38:45 INFO - 'sessionrestore', 14:38:45 INFO - 'sessionrestore_no_auto_restore', 14:38:45 INFO - 'tabpaint']}, 14:38:45 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:38:45 INFO - 'tests': ['a11yr', 14:38:45 INFO - 'ts_paint', 14:38:45 INFO - 'tpaint', 14:38:45 INFO - 'sessionrestore', 14:38:45 INFO - 'sessionrestore_no_auto_restore', 14:38:45 INFO - 'tabpaint']}, 14:38:45 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:38:45 INFO - 'tests': ['tsvgx', 14:38:45 INFO - 'tsvgr_opacity', 14:38:45 INFO - 'tart', 14:38:45 INFO - 'tscrollx', 14:38:45 INFO - 'cart', 14:38:45 INFO - 'tsvg_static']}, 14:38:45 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:38:45 INFO - 'tsvgr_opacity', 14:38:45 INFO - 'tart', 14:38:45 INFO - 'tscrollx', 14:38:45 INFO - 'cart', 14:38:45 INFO - 'tsvg_static']}, 14:38:45 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 14:38:45 INFO - 'talos_options': ['--disable-e10s'], 14:38:45 INFO - 'tests': ['tp5o']}, 14:38:45 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 14:38:45 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 14:38:45 INFO - 'talos_options': ['--disable-e10s', 14:38:45 INFO - '--xperf_path', 14:38:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:38:45 INFO - 'tests': ['tp5n']}, 14:38:45 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 14:38:45 INFO - 'talos_options': ['--xperf_path', 14:38:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:38:45 INFO - 'tests': ['tp5n']}}, 14:38:45 INFO - 'talos.zip': {'path': '', 14:38:45 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:38:45 INFO - Running post-action listener: _resource_record_post_action 14:38:45 INFO - [mozharness: 2017-02-14 22:38:45.883245Z] Finished populate-webroot step (success) 14:38:45 INFO - [mozharness: 2017-02-14 22:38:45.883377Z] Running create-virtualenv step. 14:38:45 INFO - Running pre-action listener: _resource_record_pre_action 14:38:45 INFO - Running main action method: create_virtualenv 14:38:45 INFO - Creating virtualenv /builds/slave/test/build/venv 14:38:45 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 14:38:45 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:38:46 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:38:46 INFO - Using real prefix '/tools/python27' 14:38:46 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:38:46 INFO - Installing distribute.............................................................................................................................................................................................done. 14:38:49 INFO - Installing pip.................done. 14:38:49 INFO - Return code: 0 14:38:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 14:38:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 14:38:49 INFO - Reading from file tmpfile_stdout 14:38:49 INFO - Output received: 14:38:49 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) 14:38:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:38: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')]} 14:38:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:38:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:38: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')]} 14:38:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:38:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:38: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108892b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108895768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd629cac850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108670d30>, '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 0x108597db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108665be8>, '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.qY0AN8ItX1/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.UgMvAqTUhu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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 14:38: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', 'pip>=1.5'] in /builds/slave/test/build 14:38: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 pip>=1.5 14:38:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qY0AN8ItX1/Render', 14:38:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:38:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:38:49 INFO - 'HOME': '/Users/cltbld', 14:38:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:38:49 INFO - 'LOGNAME': 'cltbld', 14:38:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:38:49 INFO - 'MOZ_NO_REMOTE': '1', 14:38:49 INFO - 'NO_EM_RESTART': '1', 14:38:49 INFO - 'PAGER': '/bin/cat', 14:38:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:38:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:38:49 INFO - 'PWD': '/builds/slave/test', 14:38:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:38:49 INFO - 'SHELL': '/bin/bash', 14:38:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UgMvAqTUhu/Listeners', 14:38:49 INFO - 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/', 14:38:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:38:49 INFO - 'USER': 'cltbld', 14:38:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:38:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:38:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:38:49 INFO - 'XPC_FLAGS': '0x0', 14:38:49 INFO - 'XPC_SERVICE_NAME': '0', 14:38:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:38:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:38:50 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 14:38:50 INFO - Cleaning up... 14:38:50 INFO - Return code: 0 14:38:50 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:38:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:38:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:38:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:38:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:38:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:38:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:38:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x108892b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108895768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd629cac850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108670d30>, '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 0x108597db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108665be8>, '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.qY0AN8ItX1/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.UgMvAqTUhu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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 14:38:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 14:38:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 14:38:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qY0AN8ItX1/Render', 14:38:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:38:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:38:50 INFO - 'HOME': '/Users/cltbld', 14:38:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:38:50 INFO - 'LOGNAME': 'cltbld', 14:38:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:38:50 INFO - 'MOZ_NO_REMOTE': '1', 14:38:50 INFO - 'NO_EM_RESTART': '1', 14:38:50 INFO - 'PAGER': '/bin/cat', 14:38:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:38:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:38:50 INFO - 'PWD': '/builds/slave/test', 14:38:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:38:50 INFO - 'SHELL': '/bin/bash', 14:38:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UgMvAqTUhu/Listeners', 14:38:50 INFO - 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/', 14:38:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:38:50 INFO - 'USER': 'cltbld', 14:38:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:38:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:38:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:38:50 INFO - 'XPC_FLAGS': '0x0', 14:38:50 INFO - 'XPC_SERVICE_NAME': '0', 14:38:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:38:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:38:50 INFO - Downloading/unpacking psutil>=3.1.1 14:38:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:38:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:38:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:38:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:38:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:38:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:38:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:38:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:38:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:38:53 INFO - Installing collected packages: psutil 14:38:53 INFO - Running setup.py install for psutil 14:38:54 INFO - building 'psutil._psutil_osx' extension 14:38:54 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 14:38:54 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 14:38:54 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 14:38:54 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 14:38:54 INFO - building 'psutil._psutil_posix' extension 14:38:54 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 14:38:54 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 14:38:54 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 14:38:54 INFO - ^ 14:38:54 INFO - 1 warning generated. 14:38:54 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 14:38:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:38:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:38:55 INFO - Successfully installed psutil 14:38:55 INFO - Cleaning up... 14:38:55 INFO - Return code: 0 14:38:55 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:38:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:38:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:38:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:38: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')]} 14:38:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:38:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:38:56 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 0x108892b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108895768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd629cac850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108670d30>, '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 0x108597db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108665be8>, '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.qY0AN8ItX1/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.UgMvAqTUhu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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 14:38:56 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 14:38:56 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 14:38:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qY0AN8ItX1/Render', 14:38:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:38:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:38:56 INFO - 'HOME': '/Users/cltbld', 14:38:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:38:56 INFO - 'LOGNAME': 'cltbld', 14:38:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:38:56 INFO - 'MOZ_NO_REMOTE': '1', 14:38:56 INFO - 'NO_EM_RESTART': '1', 14:38:56 INFO - 'PAGER': '/bin/cat', 14:38:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:38:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:38:56 INFO - 'PWD': '/builds/slave/test', 14:38:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:38:56 INFO - 'SHELL': '/bin/bash', 14:38:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UgMvAqTUhu/Listeners', 14:38:56 INFO - 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/', 14:38:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:38:56 INFO - 'USER': 'cltbld', 14:38:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:38:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:38:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:38:56 INFO - 'XPC_FLAGS': '0x0', 14:38:56 INFO - 'XPC_SERVICE_NAME': '0', 14:38:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:38:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:38:56 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:38:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:38:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:38:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:38:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:38:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:38:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:38:59 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:38:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:38:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:38:59 INFO - Installing collected packages: mozsystemmonitor 14:38:59 INFO - Running setup.py install for mozsystemmonitor 14:38:59 INFO - Successfully installed mozsystemmonitor 14:38:59 INFO - Cleaning up... 14:38:59 INFO - Return code: 0 14:38:59 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:38:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:38:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:38:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:38:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:38:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:38:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:38:59 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 0x108892b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108895768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd629cac850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108670d30>, '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 0x108597db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108665be8>, '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.qY0AN8ItX1/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.UgMvAqTUhu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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 14:38:59 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 14:38:59 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 14:38:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qY0AN8ItX1/Render', 14:38:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:38:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:38:59 INFO - 'HOME': '/Users/cltbld', 14:38:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:38:59 INFO - 'LOGNAME': 'cltbld', 14:38:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:38:59 INFO - 'MOZ_NO_REMOTE': '1', 14:38:59 INFO - 'NO_EM_RESTART': '1', 14:38:59 INFO - 'PAGER': '/bin/cat', 14:38:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:38:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:38:59 INFO - 'PWD': '/builds/slave/test', 14:38:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:38:59 INFO - 'SHELL': '/bin/bash', 14:38:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UgMvAqTUhu/Listeners', 14:38:59 INFO - 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/', 14:38:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:38:59 INFO - 'USER': 'cltbld', 14:38:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:38:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:38:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:38:59 INFO - 'XPC_FLAGS': '0x0', 14:38:59 INFO - 'XPC_SERVICE_NAME': '0', 14:38:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:38:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:38:59 INFO - Downloading/unpacking jsonschema==2.5.1 14:38:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:38:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:38:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:38:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:38:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:38:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:39:02 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:39:02 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:39:02 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 14:39:02 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 14:39:02 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 14:39:02 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 14:39:02 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 14:39:02 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 14:39:02 INFO - Downloading functools32-3.2.3-2.tar.gz 14:39:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:39:02 INFO - warning: no files found matching '*.txt' 14:39:02 INFO - no previously-included directories found matching 'build' 14:39:02 INFO - no previously-included directories found matching 'dist' 14:39:02 INFO - no previously-included directories found matching '.git*' 14:39:02 INFO - Installing collected packages: jsonschema, functools32 14:39:02 INFO - Running setup.py install for functools32 14:39:02 INFO - warning: no files found matching '*.txt' 14:39:02 INFO - no previously-included directories found matching 'build' 14:39:02 INFO - no previously-included directories found matching 'dist' 14:39:02 INFO - no previously-included directories found matching '.git*' 14:39:02 INFO - Successfully installed jsonschema functools32 14:39:02 INFO - Cleaning up... 14:39:03 INFO - Return code: 0 14:39:03 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:39:03 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')]} 14:39:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:39:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:39:03 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')]} 14:39:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:39:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:39:03 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 0x108892b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108895768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd629cac850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108670d30>, '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 0x108597db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108665be8>, '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.qY0AN8ItX1/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.UgMvAqTUhu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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 14:39:03 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 14:39:03 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 14:39:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qY0AN8ItX1/Render', 14:39:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:39:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:39:03 INFO - 'HOME': '/Users/cltbld', 14:39:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:39:03 INFO - 'LOGNAME': 'cltbld', 14:39:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:39:03 INFO - 'MOZ_NO_REMOTE': '1', 14:39:03 INFO - 'NO_EM_RESTART': '1', 14:39:03 INFO - 'PAGER': '/bin/cat', 14:39:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:39:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:39:03 INFO - 'PWD': '/builds/slave/test', 14:39:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:39:03 INFO - 'SHELL': '/bin/bash', 14:39:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UgMvAqTUhu/Listeners', 14:39:03 INFO - 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/', 14:39:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:39:03 INFO - 'USER': 'cltbld', 14:39:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:39:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:39:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:39:03 INFO - 'XPC_FLAGS': '0x0', 14:39:03 INFO - 'XPC_SERVICE_NAME': '0', 14:39:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:39:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:39:03 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:39:03 INFO - Cleaning up... 14:39:03 INFO - Return code: 0 14:39:03 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:39:03 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')]} 14:39:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:39:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:39:03 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')]} 14:39:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:39:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:39:03 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 0x108892b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108895768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd629cac850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108670d30>, '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 0x108597db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108665be8>, '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.qY0AN8ItX1/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.UgMvAqTUhu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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 14:39:03 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 14:39:03 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 14:39:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qY0AN8ItX1/Render', 14:39:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:39:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:39:03 INFO - 'HOME': '/Users/cltbld', 14:39:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:39:03 INFO - 'LOGNAME': 'cltbld', 14:39:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:39:03 INFO - 'MOZ_NO_REMOTE': '1', 14:39:03 INFO - 'NO_EM_RESTART': '1', 14:39:03 INFO - 'PAGER': '/bin/cat', 14:39:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:39:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:39:03 INFO - 'PWD': '/builds/slave/test', 14:39:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:39:03 INFO - 'SHELL': '/bin/bash', 14:39:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UgMvAqTUhu/Listeners', 14:39:03 INFO - 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/', 14:39:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:39:03 INFO - 'USER': 'cltbld', 14:39:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:39:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:39:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:39:03 INFO - 'XPC_FLAGS': '0x0', 14:39:03 INFO - 'XPC_SERVICE_NAME': '0', 14:39:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:39:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:39:03 INFO - Downloading/unpacking blobuploader==1.2.4 14:39:03 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 14:39:03 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 14:39:03 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 14:39:03 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 14:39:03 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 14:39:03 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 14:39:06 INFO - Downloading blobuploader-1.2.4.tar.gz 14:39:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:39:06 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:39:06 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 14:39:06 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 14:39:06 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 14:39:06 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 14:39:06 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 14:39:06 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 14:39:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:39:06 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:39:06 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 14:39:06 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 14:39:06 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 14:39:06 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 14:39:06 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 14:39:06 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 14:39:07 INFO - Downloading docopt-0.6.1.tar.gz 14:39:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:39:07 INFO - Installing collected packages: blobuploader, requests, docopt 14:39:07 INFO - Running setup.py install for blobuploader 14:39:07 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:39:07 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:39:07 INFO - Running setup.py install for requests 14:39:08 INFO - Running setup.py install for docopt 14:39:08 INFO - Successfully installed blobuploader requests docopt 14:39:08 INFO - Cleaning up... 14:39:08 INFO - Return code: 0 14:39:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:39:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:39:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:39:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:39:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:39:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:39:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:39:08 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 0x108892b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108895768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd629cac850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108670d30>, '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 0x108597db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108665be8>, '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.qY0AN8ItX1/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.UgMvAqTUhu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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 14:39:08 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 14:39:08 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 14:39:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qY0AN8ItX1/Render', 14:39:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:39:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:39:08 INFO - 'HOME': '/Users/cltbld', 14:39:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:39:08 INFO - 'LOGNAME': 'cltbld', 14:39:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:39:08 INFO - 'MOZ_NO_REMOTE': '1', 14:39:08 INFO - 'NO_EM_RESTART': '1', 14:39:08 INFO - 'PAGER': '/bin/cat', 14:39:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:39:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:39:08 INFO - 'PWD': '/builds/slave/test', 14:39:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:39:08 INFO - 'SHELL': '/bin/bash', 14:39:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UgMvAqTUhu/Listeners', 14:39:08 INFO - 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/', 14:39:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:39:08 INFO - 'USER': 'cltbld', 14:39:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:39:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:39:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:39:08 INFO - 'XPC_FLAGS': '0x0', 14:39:08 INFO - 'XPC_SERVICE_NAME': '0', 14:39:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:39:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:39:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:39:08 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-qu61Ms-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:39:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:39:08 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-DuPizJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:39:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:39:08 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-3BgBg9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:39:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:39:08 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-ZevPuh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:39:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:39:09 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-xnDMGH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:39:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:39:09 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-stOUfg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:39:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:39:09 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-5F37G3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:39:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:39:09 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-fkOmEH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:39:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:39:09 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-kBT5zK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:39:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:39:09 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-5uakIT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:39:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:39:09 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-K7fPqv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:39:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:39:09 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-nF4P8r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:39:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:39:09 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-_JZ4sc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:39:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:39:09 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-lnGqwD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:39:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:39:10 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-TubyxD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:39:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:39:10 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-y3vqXu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:39:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:39:10 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-C6gPIJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:39:10 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:39:10 INFO - Running setup.py install for manifestparser 14:39:10 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:39:10 INFO - Running setup.py install for mozcrash 14:39:10 INFO - Running setup.py install for mozdebug 14:39:10 INFO - Running setup.py install for mozdevice 14:39:10 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:39:10 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:39:11 INFO - Running setup.py install for mozfile 14:39:11 INFO - Running setup.py install for mozhttpd 14:39:11 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:39:11 INFO - Running setup.py install for mozinfo 14:39:11 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:39:11 INFO - Running setup.py install for mozInstall 14:39:11 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:39:11 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:39:11 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:39:11 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:39:11 INFO - Running setup.py install for mozleak 14:39:11 INFO - Running setup.py install for mozlog 14:39:11 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:39:11 INFO - Running setup.py install for moznetwork 14:39:12 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:39:12 INFO - Running setup.py install for mozprocess 14:39:12 INFO - Running setup.py install for mozprofile 14:39:12 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:39:12 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:39:12 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:39:12 INFO - Running setup.py install for mozrunner 14:39:12 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:39:12 INFO - Running setup.py install for mozscreenshot 14:39:12 INFO - Running setup.py install for moztest 14:39:12 INFO - Running setup.py install for mozversion 14:39:12 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:39:12 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:39:12 INFO - Cleaning up... 14:39:12 INFO - Return code: 0 14:39:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:39:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:39:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:39:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:39:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:39:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:39:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:39:12 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 0x108892b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108895768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd629cac850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108670d30>, '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 0x108597db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108665be8>, '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.qY0AN8ItX1/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.UgMvAqTUhu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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 14:39:12 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 14:39:12 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 14:39:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qY0AN8ItX1/Render', 14:39:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:39:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:39:12 INFO - 'HOME': '/Users/cltbld', 14:39:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:39:12 INFO - 'LOGNAME': 'cltbld', 14:39:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:39:12 INFO - 'MOZ_NO_REMOTE': '1', 14:39:12 INFO - 'NO_EM_RESTART': '1', 14:39:12 INFO - 'PAGER': '/bin/cat', 14:39:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:39:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:39:12 INFO - 'PWD': '/builds/slave/test', 14:39:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:39:12 INFO - 'SHELL': '/bin/bash', 14:39:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UgMvAqTUhu/Listeners', 14:39:12 INFO - 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/', 14:39:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:39:12 INFO - 'USER': 'cltbld', 14:39:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:39:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:39:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:39:12 INFO - 'XPC_FLAGS': '0x0', 14:39:12 INFO - 'XPC_SERVICE_NAME': '0', 14:39:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:39:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:39:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:39:13 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-oxG9DQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:39:13 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)) 14:39:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:39:13 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-gFCGk6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:39:13 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)) 14:39:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:39:13 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-T3xkNd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:39:13 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)) 14:39:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:39:13 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-qNQtL8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:39:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 14:39:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:39:13 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-PzbWRY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:39:13 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)) 14:39:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:39:13 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-excYQd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:39:13 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)) 14:39:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:39:13 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-PiUF3l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:39:13 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)) 14:39:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:39:13 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-yYc9GN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:39:14 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)) 14:39:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:39:14 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-gJ4fV9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:39:14 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)) 14:39:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:39:14 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-R6RuGF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:39:14 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)) 14:39:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:39:14 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-1_mGJc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:39:14 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)) 14:39:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:39:14 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-Vv4TBR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:39:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 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)) 14:39:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:39:14 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-R9SaIl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:39:14 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)) 14:39:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:39:14 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-eKqCV5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:39:14 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)) 14:39:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:39:14 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-fBJeXI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:39:14 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)) 14:39:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:39:14 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-90pkos-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:39:14 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 14:39:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:39:14 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-OCJKu9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:39:15 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)) 14:39:15 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)) 14:39:15 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)) 14:39:15 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)) 14:39:15 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:39:15 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:39:15 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:39:15 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 14:39:15 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 14:39:15 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 14:39:15 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 14:39:15 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 14:39:15 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 14:39:17 INFO - Downloading blessings-1.6.tar.gz 14:39:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:39:17 INFO - Installing collected packages: blessings 14:39:17 INFO - Running setup.py install for blessings 14:39:18 INFO - Successfully installed blessings 14:39:18 INFO - Cleaning up... 14:39:18 INFO - Return code: 0 14:39:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:39:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:39:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:39:18 INFO - Reading from file tmpfile_stdout 14:39:18 INFO - Current package versions: 14:39:18 INFO - blessings == 1.6 14:39:18 INFO - blobuploader == 1.2.4 14:39:18 INFO - docopt == 0.6.1 14:39:18 INFO - functools32 == 3.2.3-2 14:39:18 INFO - jsonschema == 2.5.1 14:39:18 INFO - manifestparser == 1.1 14:39:18 INFO - mozInstall == 1.12 14:39:18 INFO - mozcrash == 1.0 14:39:18 INFO - mozdebug == 0.1 14:39:18 INFO - mozdevice == 0.49 14:39:18 INFO - mozfile == 1.2 14:39:18 INFO - mozhttpd == 0.7 14:39:18 INFO - mozinfo == 0.9 14:39:18 INFO - mozleak == 0.1 14:39:18 INFO - mozlog == 3.4 14:39:18 INFO - moznetwork == 0.27 14:39:18 INFO - mozprocess == 0.24 14:39:18 INFO - mozprofile == 0.28 14:39:18 INFO - mozrunner == 6.13 14:39:18 INFO - mozscreenshot == 0.1 14:39:18 INFO - mozsystemmonitor == 0.3 14:39:18 INFO - moztest == 0.8 14:39:18 INFO - mozversion == 1.4 14:39:18 INFO - psutil == 3.1.1 14:39:18 INFO - requests == 1.2.3 14:39:18 INFO - wsgiref == 0.1.2 14:39:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:39:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:39:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:39:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:39:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:39:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:39:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:39:18 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 0x108892b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108895768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd629cac850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108670d30>, '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 0x108597db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108665be8>, '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.qY0AN8ItX1/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.UgMvAqTUhu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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 14:39:18 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 14:39:18 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 14:39:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qY0AN8ItX1/Render', 14:39:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:39:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:39:18 INFO - 'HOME': '/Users/cltbld', 14:39:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:39:18 INFO - 'LOGNAME': 'cltbld', 14:39:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:39:18 INFO - 'MOZ_NO_REMOTE': '1', 14:39:18 INFO - 'NO_EM_RESTART': '1', 14:39:18 INFO - 'PAGER': '/bin/cat', 14:39:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:39:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:39:18 INFO - 'PWD': '/builds/slave/test', 14:39:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:39:18 INFO - 'SHELL': '/bin/bash', 14:39:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UgMvAqTUhu/Listeners', 14:39:18 INFO - 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/', 14:39:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:39:18 INFO - 'USER': 'cltbld', 14:39:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:39:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:39:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:39:18 INFO - 'XPC_FLAGS': '0x0', 14:39:18 INFO - 'XPC_SERVICE_NAME': '0', 14:39:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:39:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:39:18 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)) 14:39:18 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)) 14:39:18 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)) 14:39:18 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)) 14:39:18 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)) 14:39:18 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)) 14:39:18 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)) 14:39:18 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)) 14:39:18 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)) 14:39:18 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)) 14:39:18 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)) 14:39:18 INFO - Cleaning up... 14:39:18 INFO - Return code: 0 14:39:18 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:39:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:39:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:39:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:39:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:39:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:39:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:39:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108892b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108895768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd629cac850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108670d30>, '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 0x108597db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108665be8>, '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.qY0AN8ItX1/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.UgMvAqTUhu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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 14:39:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 14:39:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 14:39:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qY0AN8ItX1/Render', 14:39:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:39:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:39:18 INFO - 'HOME': '/Users/cltbld', 14:39:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:39:18 INFO - 'LOGNAME': 'cltbld', 14:39:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:39:18 INFO - 'MOZ_NO_REMOTE': '1', 14:39:18 INFO - 'NO_EM_RESTART': '1', 14:39:18 INFO - 'PAGER': '/bin/cat', 14:39:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:39:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:39:18 INFO - 'PWD': '/builds/slave/test', 14:39:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:39:18 INFO - 'SHELL': '/bin/bash', 14:39:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UgMvAqTUhu/Listeners', 14:39:18 INFO - 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/', 14:39:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:39:18 INFO - 'USER': 'cltbld', 14:39:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:39:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:39:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:39:18 INFO - 'XPC_FLAGS': '0x0', 14:39:18 INFO - 'XPC_SERVICE_NAME': '0', 14:39:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:39:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:39:18 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:39:18 INFO - Cleaning up... 14:39:18 INFO - Return code: 0 14:39:18 INFO - Running post-action listener: _resource_record_post_action 14:39:18 INFO - Running post-action listener: _start_resource_monitoring 14:39:18 INFO - Starting resource monitoring. 14:39:18 INFO - [mozharness: 2017-02-14 22:39:18.946846Z] Finished create-virtualenv step (success) 14:39:18 INFO - [mozharness: 2017-02-14 22:39:18.947795Z] Running install step. 14:39:18 INFO - Running pre-action listener: _resource_record_pre_action 14:39:18 INFO - Running main action method: install 14:39:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:39:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:39:19 INFO - Reading from file tmpfile_stdout 14:39:19 INFO - Detecting whether we're running mozinstall >=1.0... 14:39:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:39:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:39:19 INFO - Reading from file tmpfile_stdout 14:39:19 INFO - Output received: 14:39:19 INFO - Usage: mozinstall [options] installer 14:39:19 INFO - Options: 14:39:19 INFO - -h, --help show this help message and exit 14:39:19 INFO - -d DEST, --destination=DEST 14:39:19 INFO - Directory to install application into. [default: 14:39:19 INFO - "/builds/slave/test"] 14:39:19 INFO - --app=APP Application being installed. [default: firefox] 14:39:19 INFO - mkdir: /builds/slave/test/build/application 14:39:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 14:39:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 14:39:41 INFO - Reading from file tmpfile_stdout 14:39:41 INFO - Output received: 14:39:41 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 14:39:41 INFO - Running post-action listener: _resource_record_post_action 14:39:41 INFO - [mozharness: 2017-02-14 22:39:41.224168Z] Finished install step (success) 14:39:41 INFO - [mozharness: 2017-02-14 22:39:41.224302Z] Running run-tests step. 14:39:41 INFO - Running pre-action listener: _resource_record_pre_action 14:39:41 INFO - Running main action method: run_tests 14:39:41 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:39:41 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:39:41 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:39:41 INFO - 2017-02-14 14:39:41.238 screenresolution[1622:7484] starting screenresolution argv=screenresolution get 14:39:41 INFO - 2017-02-14 14:39:41.241 screenresolution[1622:7484] Display 0: 1600x1200x32@60 14:39:41 INFO - 2017-02-14 14:39:41.247 screenresolution[1623:7486] starting screenresolution argv=screenresolution list 14:39:41 INFO - Available Modes on Display 0 14:39:41 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:39:41 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:39:41 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:39:41 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:39:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:39:41 INFO - Intel Iris: 14:39:41 INFO - Chipset Model: Intel Iris 14:39:41 INFO - Type: GPU 14:39:41 INFO - Bus: Built-In 14:39:41 INFO - VRAM (Dynamic, Max): 1536 MB 14:39:41 INFO - Vendor: Intel (0x8086) 14:39:41 INFO - Device ID: 0x0a2e 14:39:41 INFO - Revision ID: 0x0009 14:39:41 INFO - Displays: 14:39:41 INFO - Display: 14:39:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:39:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:39:41 INFO - Display Serial Number: 1600x1200 60 14:39:41 INFO - Main Display: Yes 14:39:41 INFO - Mirror: Off 14:39:41 INFO - Online: Yes 14:39:41 INFO - Rotation: Supported 14:39:41 INFO - Return code: 0 14:39:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:39:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:39:41 INFO - Python 2.7.3 14:39:41 INFO - Return code: 0 14:39:41 INFO - grabbing minidump binary from tooltool 14:39:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:39:41 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 0x108670d30>, '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 0x108597db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108665be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:39:41 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 14:39:41 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 14:39:41 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 14:39:41 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:39:41 INFO - Return code: 0 14:39:41 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:39:41 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:39:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:39:41 INFO - ENV: RUST_BACKTRACE is now 1 14:39:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:39:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:39:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IHK3zIGPRqGH1_X-CS9fPQ/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0026', '--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 14:39:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/IHK3zIGPRqGH1_X-CS9fPQ/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0026 --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 14:39:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qY0AN8ItX1/Render', 14:39:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:39:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:39:41 INFO - 'HOME': '/Users/cltbld', 14:39:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:39:41 INFO - 'LOGNAME': 'cltbld', 14:39:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:39:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:39:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:39:41 INFO - 'MOZ_NO_REMOTE': '1', 14:39:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:39:41 INFO - 'NO_EM_RESTART': '1', 14:39:41 INFO - 'PAGER': '/bin/cat', 14:39:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:39:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:39:41 INFO - 'PWD': '/builds/slave/test', 14:39:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:39:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:39:41 INFO - 'RUST_BACKTRACE': '1', 14:39:41 INFO - 'SHELL': '/bin/bash', 14:39:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UgMvAqTUhu/Listeners', 14:39:41 INFO - 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/', 14:39:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:39:41 INFO - 'USER': 'cltbld', 14:39:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:39:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:39:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:39:41 INFO - 'XPC_FLAGS': '0x0', 14:39:41 INFO - 'XPC_SERVICE_NAME': '0', 14:39:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:39:41 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IHK3zIGPRqGH1_X-CS9fPQ/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0026', '--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 14:39:41 INFO - mozversion application_buildid: 20170214131245 14:39:41 INFO - mozversion application_changeset: 1060668405a9399774c205430de4a7001d3f27ac 14:39:41 INFO - mozversion application_display_name: Nightly 14:39:41 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:39:41 INFO - mozversion application_name: Firefox 14:39:41 INFO - mozversion application_remotingname: firefox 14:39:41 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 14:39:41 INFO - mozversion application_vendor: Mozilla 14:39:41 INFO - mozversion application_version: 54.0a1 14:39:41 INFO - mozversion platform_buildid: 20170214131245 14:39:41 INFO - mozversion platform_changeset: 1060668405a9399774c205430de4a7001d3f27ac 14:39:41 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 14:39:41 INFO - mozversion platform_version: 54.0a1 14:39:41 INFO - using testdate: 1487111981 14:39:41 INFO - actual date: 1487111981 14:39:41 INFO - starting webserver on 'localhost:49241' 14:39:41 INFO - SUITE-START | Running 2 tests 14:39:41 INFO - TEST-START | dromaeo_css 14:39:41 INFO - operating with platform_type : mac_ 14:39:41 INFO - Initialising browser for dromaeo_css test... 14:39:41 INFO - TEST-INFO | started process 1633 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/tmp_1hqge/profile http://localhost:49241/getInfo.html) 14:39:44 INFO - PROCESS | 1633 | 2017-02-14 14:39:44.822 plugin-container[1635:7655] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x484f, name = 'com.apple.tsm.portname' 14:39:44 INFO - PROCESS | 1633 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:39:44 INFO - PROCESS | 1633 | 2017-02-14 14:39:44.825 plugin-container[1635:7655] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x940b, name = 'com.apple.CFPasteboardClient' 14:39:44 INFO - PROCESS | 1633 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:39:44 INFO - PROCESS | 1633 | 2017-02-14 14:39:44.825 plugin-container[1635:7655] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:39:44 INFO - PROCESS | 1633 | __metrics Screen width/height:1600/1200 14:39:44 INFO - PROCESS | 1633 | colorDepth:24 14:39:44 INFO - PROCESS | 1633 | Browser inner width/height: 1024/689 14:39:44 INFO - PROCESS | 1633 | __metrics 14:39:45 ERROR - PROCESS | 1633 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3283: TypeError: this.tabs is undefined 14:39:45 INFO - TEST-INFO | 1633: exit 0 14:39:45 INFO - Browser initialized. 14:39:45 INFO - Running cycle 1/1 for dromaeo_css test... 14:39:45 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/tmp_1hqge/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:39:48 INFO - PROCESS | 1636 | 2017-02-14 14:39:48.045 plugin-container[1637:7786] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 14:39:48 INFO - PROCESS | 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:39:48 INFO - PROCESS | 1636 | 2017-02-14 14:39:48.045 plugin-container[1637:7786] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 14:39:48 INFO - PROCESS | 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:39:48 INFO - PROCESS | 1636 | 2017-02-14 14:39:48.045 plugin-container[1637:7786] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:39:57 INFO - PROCESS | 1636 | RSS: Main: 234446848 14:39:57 INFO - PROCESS | 1636 | 14:43:00 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 14:43:03 INFO - PROCESS | 1636 | RSS: Main: 255975424 14:43:03 INFO - PROCESS | 1636 | 14:44:46 INFO - PROCESS | 1636 | ************************* 14:44:46 INFO - PROCESS | 1636 | A coding exception was thrown and uncaught in a Task. 14:44:46 INFO - PROCESS | 1636 | 14:44:46 ERROR - PROCESS | 1636 | Full message: TypeError: NetworkError when attempting to fetch resource. 14:44:46 INFO - PROCESS | 1636 | Full stack: 14:44:46 INFO - PROCESS | 1636 | ************************* 14:46:05 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 14:46:06 INFO - PROCESS | 1636 | RSS: Main: 260276224 14:46:06 INFO - PROCESS | 1636 | 14:49:08 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 14:49:08 INFO - PROCESS | 1636 | RSS: Main: 272048128 14:49:08 INFO - PROCESS | 1636 | 14:52:11 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 14:52:11 INFO - PROCESS | 1636 | RSS: Main: 265039872 14:52:11 INFO - PROCESS | 1636 | 14:55:14 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 14:55:15 INFO - PROCESS | 1636 | RSS: Main: 269012992 14:55:15 INFO - PROCESS | 1636 | 14:58:17 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 14:58:18 INFO - PROCESS | 1636 | RSS: Main: 270217216 14:58:18 INFO - PROCESS | 1636 | 14:58:18 INFO - PROCESS | 1636 | __start_tp_report 14:58:18 INFO - PROCESS | 1636 | _x_x_mozilla_page_load 14:58:18 INFO - PROCESS | 1636 | _x_x_mozilla_page_load_details 14:58:18 INFO - PROCESS | 1636 | |i|pagename|runs| 14:58:18 INFO - PROCESS | 1636 | |0;dojo.html;3310.966890331096;3357.6642335766423;3372.949405758914;3387.813670248135;3405.1657343950737;4436.824241575985;5468.28912241409;5486.3690675572325;5659.094544872821;5692.573057020724;5533.0870406383;5611.523020543259;5615.9157612635745;5620.185073164389;5698.7435565399555;5202.713850738208;5214.713190774506;5227.817026404076;5300.363956325246;5336.9859726652;5234.241035049916;5303.496167864047;5312.76092575835;5322.361316641989;5331.920021199685;4482.574155455243;5154.355705536808;5247.160454327309;5266.6833291677085;5383.16560933054;5231.372235331784;5242.711650859202;5263.736813159327;5277.234800953878;5282.762275697583;5023.472535383805;5043.697378157322;5064.113780088506;5074.898502029939;5087.338645976037;4972.502749725035;5671.659700417947;5702.030654788696;5778.335491418498;5783.971080144573;6233.5948163369685;6236.064590311445;6241.843953901143;6252.062190671436;6274.403931626488;4429.6899217054815;6202.28673702521;6213.378662133796;6217.6891155442045;6218.4714299284615;5675.3757086720425;5754.539636829086;5938.198343223696;5944.7027648617395;5946.494547963472;5353.437889021597;5515.393306736256;6257.468115210167;6273.404026617464;6287;6036.63780173191;6090.025595904723;6146.692665366714;6168.352323006109;6193.349698281616;5960.940390596039;6056.242969628796;6072.787452439144;6097.5731698780655;6098.26820781509;6039.607425517327;6108.1143234230785;6108.6456319605295;6121.148138557471;6144.692765361714;6014.6391216527145;6055.697215139226;6084.056971169476;6115.6330620162935;6135.509159267337;4873.756312184448;4936.90126197474;4959.057779021974;5013.523715247045;5024.221245706921;4446.62203712688;4461.196984542748;4469.620082292977;4490.618297444754;4504.6396288296855;5577.52591029767;5628.352739435018;5640.661560306395;5701.116326969243;5720.742566584514;5221.738913054332;6124.326324104345;6169.413905678954;6173.96913015441;6215.627062376272;5816.825495235065;5826.679532625746;5844.211031510692;5853.795117170878;5855.502282305966;5275.129603615437;5477.507024367746;5483.506484416422;5494.148406996922;5502.614816962774;6149.262088549313;6158.168647232746;6185.6907154641185;6213.751449941951;6295.9370406297185;6060.8181404625675;6186.1648677427975;6194.442500175006;6197.225346831646;6201.10084037803;6252.406021427957;6260.624362538262;6261.499080073492;6268.216472940882;6302.873942521215;5992.850178745566;6018.037114061729;6032.577719559588;6112.510999120149;6114.419025732044;6138.5396095292135;6176.073588961692;6205.596636218631;6208.720607572761;6221.2845522765465;5913.294222929406;6053.273607166992;6145.893739126821;6164;6164.660943648142;5585.469380408855;5848.327442344185;5928.377520360473;6102.908456373247;6134.171886795226;5668.291463567054;5845.970770146122;5903.321118071477;5967.940320596738;6001.519878409804;5931.822045338647;6097.500273765439;6160.353162918008;6166.044263139221;6173.166622505904;5320.787168513216;5586.581006424453;5822.446867547576;5885.94114058871;5943.910841337297 14:58:18 INFO - PROCESS | 1636 | |1;ext.html;434.969123178068;439.0954633455083;461.82681494439606;476.4901555335791;535.8312131678523;37119;39851.60148398511;39884.8034558963;40051;40127.39490420384;41130;41626.58373416265;41682;41750.37374439378;41801.58198418015;7420.628968551578;7614.961925190366;9314.86027709585;9567.904320956772;11191.2725672831;9636;11317.773644527104;11611.419429028558;11627.825582616226;11636.709082272926;11517;11548.133889958244;11610.071194304477;11628.302301861873;11633.6509904703;9343.486108264044;11474.967252947275;11497;11552.422378881023;11556.364399957998;11284.717882052932;11383.373914434702;11390.430478476042;11404.54381824726;11411.030062444703;39575.8021209895;39680.801595992125;40094.59905400938;40118.39822402666;40129.79935100335;3821.9426708599394;3855.151866589374;3865.40086286626;3868.613138686137;3874.6900247980093;29394.412111757858;29638.851805741047;29825;30191.547126793117;30409.292422417308;3074;3088.274255549944;3089.274020605156;3159.6524382317925;3212.389645967282;340148;340707;341428.2928585366;341919.29040354886;343475.84786228224;51961.843052555705;53230;60458;62467;63303.36696632975;64262;64414.355856441776;64742.35257647457;64768.676156618916;64872.02691959625;13666.863331366758;14352.421233664289;15098.698026039638;15100.471483498024;15152.924235378752;11563.959243667941;11643.767124657628;11646.825297620542;11647;11651.242669226644;17437;17484.5628859277;17496;17509.912450437667;17524.649507009788;6491.902621460682;7481.401487880957;7542.47202695817;7589.241075892367;7704.959830422933;693.4729605499856;779.933705635016;830.2983978538126;849.9362547808939;861.2765277167209;26180.476390472082;27668.893244270406;29046;29372.559411608843;29782;844.27814218842;914.4376208631777;1012.4583347908833;1063.4576366053368;1185.8458400407892;13804.447822087004;15273.465428710164;15305.08169509833;15519.258994803435;15620.76568851468;561335;564940;565512;569523;569914;57256.71371643115;57691.42308576944;57901.71049144727;57913.13130303049;58431.4156858426;1219.5853409840697;1344.072589912957;1345.7350090914508;1395.3999780094657;1400.3138462153472;25259.49481010406;27420.58869116925;28139.859300704185;29140.562891557067;29722.256943576584;41196.970075748344;49431.25853112278;49662;49733;49759.751201243766;496012;499171;499787;500812;501566.4921675368;31532.211694707818;32762;32762.689492420035;37537.43693844649;39625.80187099046;44039;45785.542144579464;45968.31047534223;47275;47368.05263894634;36958.63041369552;37122.25755484929;37614;38274.617253827106;38284;26602;31345.686543134278;35684.57261709502;36597.634023659426;36992.07519812026;34029;35004.47493287654;35014.47478287879;37239.441408378356;37746.43380349242;27875.721242787313;29878.402431951676;30059.84970075223;31710.36579268448;32219.03342899717;23477.647835282143;23763;24006.15978440746;24276.271711848673;24342.148024819046 14:58:18 INFO - PROCESS | 1636 | |2;jquery.html;354.36745409444137;383.8905911815132;390.3215783750194;399.0761387388195;425.75093570261396;20312.492187695298;20885;21085;21164.8941755291;21611.45971350713;21260.362189134314;21624.2431514897;21676.458088547755;21855.781442185573;21960.670589941165;5070.923936140961;5661.207430959669;5876.382979787125;5922.644641321513;5936.406359364072;5553.16702494627;5767.452092051249;5780.913286300709;5857.26784151981;5877.2947246330505;5761.251037365137;5766.135079738031;5766.884662306751;5773.971130144364;5774.538036957033;5478.91781623278;5668.263125793662;5746.48281654651;5764.33710123337;5783.5084017858535;5061;5078.619103567247;5090.872728181788;5092.898142037176;5096.261042148868;25552.23343299704;25588.74411255901;25628.48743025129;25682.48635027289;25941;2334.568104900599;2708.282305189107;2742.616033755276;2787.02454141051;2788.442311537701;15120.924395378062;15213.243154140466;15375.615609609737;15511.069335839886;15860.127692976881;1755.3680674957004;1778.9288428462846;1789.4363275568155;1798.7661603991398;1845.0682405385328;123180.76819231783;123237;124004.89987750289;127112;128093.35953320267;11222.04612607921;11465.426728663533;11669.299842009506;11709.707257318465;11939.283642981449;14255.714885702228;14315.427382904776;14329.211893345757;14376.928115359357;14384.568462946128;8029.3175080118845;8169.673213071529;8275;8416.873746893802;8438.873416898752;8053.27520523144;8066.072401673882;8087.069986951458;8186.631601577943;8227.012758469022;5787.94212057874;7084.964575177194;7090.822729431702;7091.609961452066;7095.858082838417;1575.527341797456;1930.0639189992842;1933.3136736458637;1934.3423236099793;1937.447827369193;854.9313358302122;879.62176264207;893.946363218209;901.1439132823843;917.8481006337005;18935.053247337855;19906.800931990783;20043.59912801736;21955.341339759834;21980.681159130505;859.2696208223085;913.2830727878584;916.9679061232824;917.5685929949301;924.2282693950492;10167.593296268215;11326.490307936163;11407.714807129889;11439.084873210124;11464.828027579593;484348;512223.8777612251;681247;700374;700865;132079;134428;134465;135725;135855;1031.5564307347913;1058.079470860341;1074.5486895504064;1081.329575663094;1081.6646839479786;17150.056746879043;17252.913735431244;17428.912855435643;17909.820901790816;18193.181306840957;55882.720586396805;58770.4122958782;59184.04079796079;60446.395536045835;61294.69352653208;79153;89225.55387223023;90547.5472622659;90925.09074909166;91006;7100.680469378994;7353.614589762418;7482.326590606872;7779.183185765413;8539.8719019216;10977.615783447542;12186.51253949832;12277.710840361633;12728.809067863804;13076.346182691017;9401.623935042522;10916.399598022184;10932.398718070583;11247.943760281147;11481.96662300397;10817.675469735921;10895.727606809893;11045.005949464587;11181.832272515756;11328.32030078198;10087.646932357333;10728.946355268174;11203.71990700239;11466.31202127875;11659.008984236434;8230.21812927771;8567.357448191286;8599.742007739778;8635.222829945335;8689.087645797354;11006.834897476385;11358.318500889973;11513.53945842157;11694.4737486815;11705.297682139098 14:58:18 INFO - PROCESS | 1636 | |3;mootools.html;1183.3077649574998;1429.2496439369324;1443.4226309476214;1450.0212356659251;1467.4717101843337;7545.622718864055;8121.634526446309;8188.754337369874;8316.958415207931;8618.396712230146;8502.659893604265;8594.570271486416;8620.698275560357;8644.481331120138;8660.826783464343;1745.5112568480845;1793.4529968359645;2078.0337143228394;2138.433315171477;2183.257692384589;2238.9664155037685;2318.6290193569034;2327.8021368183727;2332.6501024846325;2344.0623750499767;1897.7058555923854;2279.737830149529;2288.4278930267433;2300.9654855177187;2304.446932736148;2240.4174914522177;2255.1655887321795;2268.897899594522;2269.7162854643007;2270.761570035147;2249.707538020063;2264.7169103862016;2320.8027317678016;2331.860088394702;2334.0780391745334;8485.787855303604;8543.786405339853;8550.01674807399;8555.10171431997;8557.358198135102;4552.271636538138;5126.128558145087;5218.321618189649;5235.267062611238;5292.92060619091;2931.853407329625;3314.8895120134785;3442.638522955104;3447.844846981892;3503.702185314226;1162.436218434059;1231.1074471008537;1249.1505776072268;1268.3594784633792;1270.218815428514;10780.299280546818;10931;10955.50700218492;11043;11133.109351251962;4269.893252668709;4445.866624001285;4454.643628509712;4541.977290113595;4564.109998550317;8784.165504277084;8847.115288471101;8848.424852384574;8852.557372131369;8875.423097498642;5042.117629414838;5045.445001049881;5075.416327122354;5082.695038297714;5085.067674283749;4147.668186545069;4637.744924029211;4729.148753224453;4770.403699537558;4772.451168115642;3583.51622530955;3611.5485564304463;3667.358212312888;3680.392735198716;3702.7593206441493;1145.5360578965533;1148.465963327057;1150.8216226484908;1164.7670465906851;1169.1465230381698;755.9168491465934;760.9619519024027;782.3311069036006;789.1004255149135;794.1502592226378;5413.83758487246;5450.5367043800925;5670.06443936754;5679.488846003879;5713.143028545668;555.6915911669063;557.0641322578027;568.5139205978911;573.9668596526236;584.2492397269489;3221.7261532769508;3672.081979505124;3711.46183803347;3767.56672982605;3816.904577385588;9395.765105872271;9457.810843783085;9809.21526277896;10023.949880250551;10032.1472674822;8983.46099234048;10033.698989030341;10061.849072263922;10066.245031622511;10078.445685487222;6724.092247546617;6865.897011544831;7506.962465187639;7741.264579864904;7802;5660.971695141498;5778.797742079007;5802.82591522255;5860.501857342003;5892.292924849045;9216.354855160074;9343.15911567935;9355.06449355059;9360.344775865624;9362.063793620584;7115.537490063336;9354.064593540592;9406.012368701187;9591.9040809591;9599.232061435208;7156.928430715834;7213.278672132955;7244.130704315516;7280.526765760209;7315.30504602062;7869.724559640614;7941.722039728582;7955.164707705608;7960.522368657899;7974.242446967529;6947.131608548932;6998;7093.716251349888;7198.7840364789145;7283.126024877049;6181.381861813783;6983.930160698531;6986.91702786069;7075.964620176866;7098.148222213367;5840.970795145997;6315.368463153648;6323.241211054703;6434.903476447764;6448.355164483514;4328.913421731485;4432.977835110803;4840.709557426566;4888.60891128716;4923.803047878188;4950.2043091564265;5087.109755792795;5110.131277682877;5138.97430512845;5169.198774190007 14:58:18 INFO - PROCESS | 1636 | |4;prototype.html;357.24621049585386;357.77996532132744;364.7884227148254;370.21883825128975;370.32415841090017;4898.444446664605;4907.656464047518;5044.672096313737;5258.369905329346;5446.591505637083;4701.952980470194;5287.127623942055;5413.891722165555;5416.268803711498;5429.348478182624;3444.328355970574;4099.508059032905;4119.052617897885;4119.546849846507;4120.402541631462;3842.865499707511;4117.52948231062;4125.546189919107;4137.172565486914;4139.882231797413;4146.834126634931;4152.733416308025;4153.335466325389;4154.916901661965;4157.521884983235;3634.200475895299;3958.782266975315;3987.322155233617;4040.6969477289026;4089.9386509202386;2981.5378616314506;3400.8129552874584;3484.1289677580603;3495.6329585393423;3536.3732682965865;4083.5099788025627;4349.478062632473;4454.30958201476;4890.750254882758;5045.073857162837;4380.167768124046;4728.392346602137;4733.290006499018;4765.428148622153;4776.761161941924;4989.57588604969;5675.914861277084;5790.449907258803;5793.275840519935;5861.648301101947;3314.983425082883;3626.0056968667127;3920.666743326821;3923.372260438316;3936.6850651947775;5726.3044303671;5748.971255143698;5867.647941123546;6031.044788053866;6099.542534309944;3490.7556471046782;3547.52108465359;3783.6405541473514;3828.3300422425964;3950.846788500274;3273.459879119966;3696.2792255510453;3767.3047128791604;4015.8393664253103;4022;4646.581807637329;4667.976660116746;4722.929156062662;4732.550407711262;4744.454387745384;5559.749811258503;5761.711914404263;5825;5846.766129354864;5948.464638182584;1125.859267591551;1136.3125309187822;1270.8157317188957;1301.3233118778182;1326.9933650331686;815.4414226255034;827.2141465607697;836.5273620404452;876.3471213945651;889.8487257166297;4576.908461830812;4615.307753796949;4875.293082503017;5038.143515602356;5042.319286896295;4698.2952557116705;4809.5430934061205;5489.752961116759;5646.774129034875;5704.9763625278665;4268.246980697495;4995;5059.836237665358;5107.106256405188;5145.9485405146215;2609.9217023489327;2661.9086174668682;2844.8293102413922;2947.4547208766016;3001.529250667201;5211.895762084813;5344.6258761887075;5494.148406996922;5639.915401268984;5895.587308888336;5445.992491389026;5586.581006424453;5761.798337058267;5985.40145985398;6148.077788331697;5912.290525137011;6027.367126451747;6039.946418217827;6157.89157951573;6231.501479881689;5020.6234532410945;5518.750562067105;5711.287663019071;5858.301092683074;6014.48876845473;5170.922436163536;5539;5764.596478246483;5881.411858814085;5952.077427998667;5227.817026404057;5401.378841433286;5541.224228607917;5900.055991041413;6197.96901015492;3672.540932383452;3934.5278566572197;3956.2878681837547;3981.3629819228786;3988;5369;5617.494425501724;5873.973479149687;5947.918228619948;5953.315368732651;4958.776855041604;5089.414717307557;5644.238027866186;5718.1136923776885;5755.3381361144;4628.467726211529;5322.787088516416;5585.542361360988;5605.467480589337;5687.857803554944;5288.867778305573;5446.988770331384;5545.334559852843;5556.638818476786;5686.829395118153;5071.442141364373;5507.7246137692155;5655.4910058094965;5788.308436577012;5801.216835727123;5069.645124841373;5232.561045712459;5547.2511210986;5620.522255608319;5659.54723622101 14:58:18 INFO - PROCESS | 1636 | |5;yui.html;685.7359916432175;688.4664385101543;691.5643144818764;757.0914902117461;825.7894236969572;14381.137131772088;15266.465673701408;15271.160086195256;15279.770803437958;15308.923455382721;13553;13973.860261397384;14126.717465650681;14164.645883852909;14195.929020354884;796.9481983671058;887.5872719185577;909.7952960583882;912.3476714149382;914.4056363363834;900.8063266397724;906.4470672889565;907.5870478932103;907.9137481939205;909.3997961345514;906.9229115525188;947.9241660667165;949.9762505937338;952.6284748947916;957.2389949989774;943.7404713703718;945.0171821305868;948.2035090523958;950.681521690238;952.6284748947916;693.4209934704528;704.8977898204731;705.9541129826545;706.8550947055841;710.2542330552899;15011.924940375338;15346.923265383713;15386;15525;15544.512877939474;13649.863501365058;13734.65663358414;13992.30038498071;14001.859981400157;14020.71958560823;11209.389521549234;11421.314721116676;11492.310461372344;11539.076873850072;11854.703632409173;3637.363461394272;3645.400172309908;3680.9447858282147;3726.329260733067;3804.1630841214887;73494.63252683755;74284.25715742828;92171.53914230454;97777.5111124447;100048.49975750147;10607.734806629896;10740.677779666623;10860.954867252216;10902.454877256105;10970.506327215295;21320.893395532923;21924;21949.890250548964;21960.560788784136;22009;7403.407727381797;7478.738244161537;7498.775036748906;7593.810154746176;7722.150563438017;4084.6936479764136;4188.392683060939;4193.391958166048;4205.474315710536;4213.094184750232;3577.821108944542;3640.3265395901844;3654.1230104775195;3656.03115174479;3690.833912473945;650.2132419772034;676.9492288078413;679.9762008329685;694.3958755882313;697.8290318871909;634.3814780588981;634.8444631065419;666.5334266013809;674.9527533072638;696.3280434380847;7853.48952318109;9439.575219115157;9566.473843938511;9673.274504412197;9944.303898727165;557.0057447456271;649.1089639565848;679.2222904774006;685.6366125953252;689.4622194688151;4692.436907571045;4753.5959443447;4778.737169455644;4783.162946484351;4793.616510679138;59427.5429342801;71384.21539461451;77856;84720.57639711886;87908.24183516295;73558;74487.62756186184;78599;78687.60656196797;81840.59079704563;58003;64114;67143.32141696497;70463.2953670467;70598.64700676464;7809.375249980101;7903.56530390834;8999.910000900169;10143.898561014295;10312.226583006155;43672;44635.330470042325;45388;46669.53330466652;46832.53167468282;54837.451625483234;62872.4281892938;63100.68449657906;63563;63717;12371.628851134481;12524.69047182929;12525.686857828627;12557.246565206116;12991.220526768424;13512.054156209364;13540.661483462993;13674.658133546343;13754.449822007007;13772.517961871687;12817.743645126859;12974.675633109247;13061.34693265314;13336.799948000982;13350.332483375987;11706.707332316419;12562.87437125617;13043;13058.738825223632;13171.943759791582;10589.894101058891;12494.937525312314;12595.24428534291;12609.810852837032;12727.490900363882;9977.501124943868;10465.215108866712;10519.21105917044;10649.840252396065;10976.945115274373;9031.50326732036;9240.815183696423;10208.846867297145;10278.537465814206;10381.117605003357 14:58:18 INFO - PROCESS | 1636 | __end_tp_report 14:58:18 INFO - PROCESS | 1636 | __start_cc_report 14:58:18 INFO - PROCESS | 1636 | _x_x_mozilla_cycle_collect,422 14:58:18 INFO - PROCESS | 1636 | __end_cc_report 14:58:18 INFO - PROCESS | 1636 | __startTimestamp1487113098131__endTimestamp 14:58:18 INFO - PROCESS | 1636 | 14:58:18 INFO - PROCESS | 1636 | ------- Summary: start ------- 14:58:18 INFO - PROCESS | 1636 | Number of tests: 6 14:58:18 INFO - PROCESS | 1636 | 14:58:18 INFO - PROCESS | 1636 | [#0] dojo.html Cycles:165 Average:5669.70 Median:5855.50 stddev:631.38 (10.8%) stddev-sans-first:605.59 14:58:18 INFO - PROCESS | 1636 | Values: 3311.0 3357.7 3372.9 3387.8 3405.2 4436.8 5468.3 5486.4 5659.1 5692.6 5533.1 5611.5 5615.9 5620.2 5698.7 5202.7 5214.7 5227.8 5300.4 5337.0 5234.2 5303.5 5312.8 5322.4 5331.9 4482.6 5154.4 5247.2 5266.7 5383.2 5231.4 5242.7 5263.7 5277.2 5282.8 5023.5 5043.7 5064.1 5074.9 5087.3 4972.5 5671.7 5702.0 5778.3 5784.0 6233.6 6236.1 6241.8 6252.1 6274.4 4429.7 6202.3 6213.4 6217.7 6218.5 5675.4 5754.5 5938.2 5944.7 5946.5 5353.4 5515.4 6257.5 6273.4 6287.0 6036.6 6090.0 6146.7 6168.4 6193.3 5960.9 6056.2 6072.8 6097.6 6098.3 6039.6 6108.1 6108.6 6121.1 6144.7 6014.6 6055.7 6084.1 6115.6 6135.5 4873.8 4936.9 4959.1 5013.5 5024.2 4446.6 4461.2 4469.6 4490.6 4504.6 5577.5 5628.4 5640.7 5701.1 5720.7 5221.7 6124.3 6169.4 6174.0 6215.6 5816.8 5826.7 5844.2 5853.8 5855.5 5275.1 5477.5 5483.5 5494.1 5502.6 6149.3 6158.2 6185.7 6213.8 6295.9 6060.8 6186.2 6194.4 6197.2 6201.1 6252.4 6260.6 6261.5 6268.2 6302.9 5992.9 6018.0 6032.6 6112.5 6114.4 6138.5 6176.1 6205.6 6208.7 6221.3 5913.3 6053.3 6145.9 6164.0 6164.7 5585.5 5848.3 5928.4 6102.9 6134.2 5668.3 5846.0 5903.3 5967.9 6001.5 5931.8 6097.5 6160.4 6166.0 6173.2 5320.8 5586.6 5822.4 5885.9 5943.9 14:58:18 INFO - PROCESS | 1636 | 14:58:18 INFO - PROCESS | 1636 | [#1] ext.html Cycles:180 Average:61565.44 Median:36141.10 stddev:127412.69 (352.5%) stddev-sans-first:127687.45 14:58:18 INFO - PROCESS | 1636 | Values: 435.0 439.1 461.8 476.5 535.8 37119.0 39851.6 39884.8 40051.0 40127.4 41130.0 41626.6 41682.0 41750.4 41801.6 7420.6 7615.0 9314.9 9567.9 11191.3 9636.0 11317.8 11611.4 11627.8 11636.7 11517.0 11548.1 11610.1 11628.3 11633.7 9343.5 11475.0 11497.0 11552.4 11556.4 11284.7 11383.4 11390.4 11404.5 11411.0 39575.8 39680.8 40094.6 40118.4 40129.8 3821.9 3855.2 3865.4 3868.6 3874.7 29394.4 29638.9 29825.0 30191.5 30409.3 3074.0 3088.3 3089.3 3159.7 3212.4 340148.0 340707.0 341428.3 341919.3 343475.8 51961.8 53230.0 60458.0 62467.0 63303.4 64262.0 64414.4 64742.4 64768.7 64872.0 13666.9 14352.4 15098.7 15100.5 15152.9 11564.0 11643.8 11646.8 11647.0 11651.2 17437.0 17484.6 17496.0 17509.9 17524.6 6491.9 7481.4 7542.5 7589.2 7705.0 693.5 779.9 830.3 849.9 861.3 26180.5 27668.9 29046.0 29372.6 29782.0 844.3 914.4 1012.5 1063.5 1185.8 13804.4 15273.5 15305.1 15519.3 15620.8 561335.0 564940.0 565512.0 569523.0 569914.0 57256.7 57691.4 57901.7 57913.1 58431.4 1219.6 1344.1 1345.7 1395.4 1400.3 25259.5 27420.6 28139.9 29140.6 29722.3 41197.0 49431.3 49662.0 49733.0 49759.8 496012.0 499171.0 499787.0 500812.0 501566.5 31532.2 32762.0 32762.7 37537.4 39625.8 44039.0 45785.5 45968.3 47275.0 47368.1 36958.6 37122.3 37614.0 38274.6 38284.0 26602.0 31345.7 35684.6 36597.6 36992.1 34029.0 35004.5 35014.5 37239.4 37746.4 27875.7 29878.4 30059.8 31710.4 32219.0 23477.6 23763.0 24006.2 24276.3 24342.1 14:58:18 INFO - PROCESS | 1636 | 14:58:18 INFO - PROCESS | 1636 | [#2] jquery.html Cycles:180 Average:36537.32 Median:25570.49 stddev:104331.74 (408.0%) stddev-sans-first:104589.04 14:58:18 INFO - PROCESS | 1636 | Values: 354.4 383.9 390.3 399.1 425.8 20312.5 20885.0 21085.0 21164.9 21611.5 21260.4 21624.2 21676.5 21855.8 21960.7 5070.9 5661.2 5876.4 5922.6 5936.4 5553.2 5767.5 5780.9 5857.3 5877.3 5761.3 5766.1 5766.9 5774.0 5774.5 5478.9 5668.3 5746.5 5764.3 5783.5 5061.0 5078.6 5090.9 5092.9 5096.3 25552.2 25588.7 25628.5 25682.5 25941.0 2334.6 2708.3 2742.6 2787.0 2788.4 15120.9 15213.2 15375.6 15511.1 15860.1 1755.4 1778.9 1789.4 1798.8 1845.1 123180.8 123237.0 124004.9 127112.0 128093.4 11222.0 11465.4 11669.3 11709.7 11939.3 14255.7 14315.4 14329.2 14376.9 14384.6 8029.3 8169.7 8275.0 8416.9 8438.9 8053.3 8066.1 8087.1 8186.6 8227.0 5787.9 7085.0 7090.8 7091.6 7095.9 1575.5 1930.1 1933.3 1934.3 1937.4 854.9 879.6 893.9 901.1 917.8 18935.1 19906.8 20043.6 21955.3 21980.7 859.3 913.3 917.0 917.6 924.2 10167.6 11326.5 11407.7 11439.1 11464.8 484348.0 512223.9 681247.0 700374.0 700865.0 132079.0 134428.0 134465.0 135725.0 135855.0 1031.6 1058.1 1074.5 1081.3 1081.7 17150.1 17252.9 17428.9 17909.8 18193.2 55882.7 58770.4 59184.0 60446.4 61294.7 79153.0 89225.6 90547.5 90925.1 91006.0 7100.7 7353.6 7482.3 7779.2 8539.9 10977.6 12186.5 12277.7 12728.8 13076.3 9401.6 10916.4 10932.4 11247.9 11482.0 10817.7 10895.7 11045.0 11181.8 11328.3 10087.6 10728.9 11203.7 11466.3 11659.0 8230.2 8567.4 8599.7 8635.2 8689.1 11006.8 11358.3 11513.5 11694.5 11705.3 14:58:18 INFO - PROCESS | 1636 | 14:58:18 INFO - PROCESS | 1636 | [#3] mootools.html Cycles:180 Average:5360.88 Median:5132.55 stddev:2985.09 (58.2%) stddev-sans-first:2976.95 14:58:18 INFO - PROCESS | 1636 | Values: 1183.3 1429.2 1443.4 1450.0 1467.5 7545.6 8121.6 8188.8 8317.0 8618.4 8502.7 8594.6 8620.7 8644.5 8660.8 1745.5 1793.5 2078.0 2138.4 2183.3 2239.0 2318.6 2327.8 2332.7 2344.1 1897.7 2279.7 2288.4 2301.0 2304.4 2240.4 2255.2 2268.9 2269.7 2270.8 2249.7 2264.7 2320.8 2331.9 2334.1 8485.8 8543.8 8550.0 8555.1 8557.4 4552.3 5126.1 5218.3 5235.3 5292.9 2931.9 3314.9 3442.6 3447.8 3503.7 1162.4 1231.1 1249.2 1268.4 1270.2 10780.3 10931.0 10955.5 11043.0 11133.1 4269.9 4445.9 4454.6 4542.0 4564.1 8784.2 8847.1 8848.4 8852.6 8875.4 5042.1 5045.4 5075.4 5082.7 5085.1 4147.7 4637.7 4729.1 4770.4 4772.5 3583.5 3611.5 3667.4 3680.4 3702.8 1145.5 1148.5 1150.8 1164.8 1169.1 755.9 761.0 782.3 789.1 794.2 5413.8 5450.5 5670.1 5679.5 5713.1 555.7 557.1 568.5 574.0 584.2 3221.7 3672.1 3711.5 3767.6 3816.9 9395.8 9457.8 9809.2 10023.9 10032.1 8983.5 10033.7 10061.8 10066.2 10078.4 6724.1 6865.9 7507.0 7741.3 7802.0 5661.0 5778.8 5802.8 5860.5 5892.3 9216.4 9343.2 9355.1 9360.3 9362.1 7115.5 9354.1 9406.0 9591.9 9599.2 7156.9 7213.3 7244.1 7280.5 7315.3 7869.7 7941.7 7955.2 7960.5 7974.2 6947.1 6998.0 7093.7 7198.8 7283.1 6181.4 6983.9 6986.9 7076.0 7098.1 5841.0 6315.4 6323.2 6434.9 6448.4 4328.9 4433.0 4840.7 4888.6 4923.8 4950.2 5087.1 5110.1 5139.0 5169.2 14:58:18 INFO - PROCESS | 1636 | 14:58:18 INFO - PROCESS | 1636 | [#4] prototype.html Cycles:180 Average:4552.82 Median:5044.87 stddev:1419.72 (28.1%) stddev-sans-first:1388.34 14:58:18 INFO - PROCESS | 1636 | Values: 357.2 357.8 364.8 370.2 370.3 4898.4 4907.7 5044.7 5258.4 5446.6 4702.0 5287.1 5413.9 5416.3 5429.3 3444.3 4099.5 4119.1 4119.5 4120.4 3842.9 4117.5 4125.5 4137.2 4139.9 4146.8 4152.7 4153.3 4154.9 4157.5 3634.2 3958.8 3987.3 4040.7 4089.9 2981.5 3400.8 3484.1 3495.6 3536.4 4083.5 4349.5 4454.3 4890.8 5045.1 4380.2 4728.4 4733.3 4765.4 4776.8 4989.6 5675.9 5790.4 5793.3 5861.6 3315.0 3626.0 3920.7 3923.4 3936.7 5726.3 5749.0 5867.6 6031.0 6099.5 3490.8 3547.5 3783.6 3828.3 3950.8 3273.5 3696.3 3767.3 4015.8 4022.0 4646.6 4668.0 4722.9 4732.6 4744.5 5559.7 5761.7 5825.0 5846.8 5948.5 1125.9 1136.3 1270.8 1301.3 1327.0 815.4 827.2 836.5 876.3 889.8 4576.9 4615.3 4875.3 5038.1 5042.3 4698.3 4809.5 5489.8 5646.8 5705.0 4268.2 4995.0 5059.8 5107.1 5145.9 2609.9 2661.9 2844.8 2947.5 3001.5 5211.9 5344.6 5494.1 5639.9 5895.6 5446.0 5586.6 5761.8 5985.4 6148.1 5912.3 6027.4 6039.9 6157.9 6231.5 5020.6 5518.8 5711.3 5858.3 6014.5 5170.9 5539.0 5764.6 5881.4 5952.1 5227.8 5401.4 5541.2 5900.1 6198.0 3672.5 3934.5 3956.3 3981.4 3988.0 5369.0 5617.5 5874.0 5947.9 5953.3 4958.8 5089.4 5644.2 5718.1 5755.3 4628.5 5322.8 5585.5 5605.5 5687.9 5288.9 5447.0 5545.3 5556.6 5686.8 5071.4 5507.7 5655.5 5788.3 5801.2 5069.6 5232.6 5547.3 5620.5 5659.5 14:58:18 INFO - PROCESS | 1636 | 14:58:18 INFO - PROCESS | 1636 | [#5] yui.html Cycles:180 Average:18076.10 Median:4723.02 stddev:24296.78 (514.4%) stddev-sans-first:24329.84 14:58:18 INFO - PROCESS | 1636 | Values: 685.7 688.5 691.6 757.1 825.8 14381.1 15266.5 15271.2 15279.8 15308.9 13553.0 13973.9 14126.7 14164.6 14195.9 796.9 887.6 909.8 912.3 914.4 900.8 906.4 907.6 907.9 909.4 906.9 947.9 950.0 952.6 957.2 943.7 945.0 948.2 950.7 952.6 693.4 704.9 706.0 706.9 710.3 15011.9 15346.9 15386.0 15525.0 15544.5 13649.9 13734.7 13992.3 14001.9 14020.7 11209.4 11421.3 11492.3 11539.1 11854.7 3637.4 3645.4 3680.9 3726.3 3804.2 73494.6 74284.3 92171.5 97777.5 100048.5 10607.7 10740.7 10861.0 10902.5 10970.5 21320.9 21924.0 21949.9 21960.6 22009.0 7403.4 7478.7 7498.8 7593.8 7722.2 4084.7 4188.4 4193.4 4205.5 4213.1 3577.8 3640.3 3654.1 3656.0 3690.8 650.2 676.9 680.0 694.4 697.8 634.4 634.8 666.5 675.0 696.3 7853.5 9439.6 9566.5 9673.3 9944.3 557.0 649.1 679.2 685.6 689.5 4692.4 4753.6 4778.7 4783.2 4793.6 59427.5 71384.2 77856.0 84720.6 87908.2 73558.0 74487.6 78599.0 78687.6 81840.6 58003.0 64114.0 67143.3 70463.3 70598.6 7809.4 7903.6 8999.9 10143.9 10312.2 43672.0 44635.3 45388.0 46669.5 46832.5 54837.5 62872.4 63100.7 63563.0 63717.0 12371.6 12524.7 12525.7 12557.2 12991.2 13512.1 13540.7 13674.7 13754.4 13772.5 12817.7 12974.7 13061.3 13336.8 13350.3 11706.7 12562.9 13043.0 13058.7 13171.9 10589.9 12494.9 12595.2 12609.8 12727.5 9977.5 10465.2 10519.2 10649.8 10976.9 9031.5 9240.8 10208.8 10278.5 10381.1 14:58:18 INFO - PROCESS | 1636 | -------- Summary: end -------- 14:58:18 INFO - PROCESS | 1636 | 14:58:18 INFO - TEST-INFO | 1636: exit 0 14:58:18 INFO - TEST-OK | dromaeo_css | took 1116946ms 14:58:18 INFO - TEST-START | kraken 14:58:18 INFO - operating with platform_type : mac_ 14:58:18 INFO - Initialising browser for kraken test... 14:58:18 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/tmpkBfPSZ/profile http://localhost:49241/getInfo.html) 14:58:21 INFO - PROCESS | 1647 | 2017-02-14 14:58:21.740 plugin-container[1648:10194] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 14:58:21 INFO - PROCESS | 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:58:21 INFO - PROCESS | 1647 | 2017-02-14 14:58:21.742 plugin-container[1648:10194] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0f, name = 'com.apple.CFPasteboardClient' 14:58:21 INFO - PROCESS | 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:58:21 INFO - PROCESS | 1647 | 2017-02-14 14:58:21.742 plugin-container[1648:10194] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:58:21 INFO - PROCESS | 1647 | __metrics Screen width/height:1600/1200 14:58:21 INFO - PROCESS | 1647 | colorDepth:24 14:58:21 INFO - PROCESS | 1647 | Browser inner width/height: 1024/689 14:58:21 INFO - PROCESS | 1647 | __metrics 14:58:21 ERROR - PROCESS | 1647 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3283: TypeError: this.tabs is undefined 14:58:22 INFO - TEST-INFO | 1647: exit 0 14:58:22 INFO - Browser initialized. 14:58:22 INFO - Running cycle 1/1 for kraken test... 14:58:22 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/tmpkBfPSZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:58:25 INFO - PROCESS | 1649 | 2017-02-14 14:58:25.109 plugin-container[1650:10311] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893f, name = 'com.apple.tsm.portname' 14:58:25 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:58:25 INFO - PROCESS | 1649 | 2017-02-14 14:58:25.110 plugin-container[1650:10311] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4743, name = 'com.apple.CFPasteboardClient' 14:58:25 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:58:25 INFO - PROCESS | 1649 | 2017-02-14 14:58:25.110 plugin-container[1650:10311] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:58:34 INFO - PROCESS | 1649 | RSS: Main: 234008576 14:58:34 INFO - PROCESS | 1649 | 14:58:38 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 14:58:39 INFO - PROCESS | 1649 | RSS: Main: 247975936 14:58:39 INFO - PROCESS | 1649 | 14:58:41 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 14:58:42 INFO - PROCESS | 1649 | RSS: Main: 255938560 14:58:42 INFO - PROCESS | 1649 | 14:58:45 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 14:58:46 INFO - PROCESS | 1649 | RSS: Main: 258527232 14:58:46 INFO - PROCESS | 1649 | 14:58:48 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 14:58:48 INFO - PROCESS | 1649 | RSS: Main: 259735552 14:58:48 INFO - PROCESS | 1649 | 14:58:50 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:58:51 INFO - PROCESS | 1649 | RSS: Main: 260362240 14:58:51 INFO - PROCESS | 1649 | 14:58:54 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 14:58:55 INFO - PROCESS | 1649 | RSS: Main: 267415552 14:58:55 INFO - PROCESS | 1649 | 14:58:58 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 14:58:59 INFO - PROCESS | 1649 | RSS: Main: 266416128 14:58:59 INFO - PROCESS | 1649 | 14:59:01 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 14:59:02 INFO - PROCESS | 1649 | RSS: Main: 266059776 14:59:02 INFO - PROCESS | 1649 | 14:59:04 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:59:04 INFO - PROCESS | 1649 | RSS: Main: 265674752 14:59:04 INFO - PROCESS | 1649 | 14:59:06 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:59:07 INFO - PROCESS | 1649 | RSS: Main: 265715712 14:59:07 INFO - PROCESS | 1649 | 14:59:09 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:59:10 INFO - PROCESS | 1649 | RSS: Main: 265768960 14:59:10 INFO - PROCESS | 1649 | 14:59:13 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:59:14 INFO - PROCESS | 1649 | RSS: Main: 266047488 14:59:14 INFO - PROCESS | 1649 | 14:59:17 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:59:17 INFO - PROCESS | 1649 | RSS: Main: 265945088 14:59:17 INFO - PROCESS | 1649 | 14:59:19 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 14:59:19 INFO - PROCESS | 1649 | RSS: Main: 267968512 14:59:19 INFO - PROCESS | 1649 | 14:59:19 INFO - PROCESS | 1649 | __start_tp_report 14:59:19 INFO - PROCESS | 1649 | _x_x_mozilla_page_load 14:59:19 INFO - PROCESS | 1649 | _x_x_mozilla_page_load_details 14:59:19 INFO - PROCESS | 1649 | |i|pagename|runs| 14:59:19 INFO - PROCESS | 1649 | |0;ai-astar;105;117;122;145;132;174;177;97;177;174 14:59:19 INFO - PROCESS | 1649 | |1;audio-beat-detection;130;114;112;148;114;126;114;135;130;104 14:59:19 INFO - PROCESS | 1649 | |2;audio-dft;221;237;204;200;201;198;196;237;274;213 14:59:19 INFO - PROCESS | 1649 | |3;audio-fft;73;59;60;99;61;61;60;61;76;64 14:59:19 INFO - PROCESS | 1649 | |4;audio-oscillator;93;72;85;70;71;70;84;71;70;82 14:59:19 INFO - PROCESS | 1649 | |5;imaging-gaussian-blur;88;99;88;111;87;135;134;111;90;89 14:59:19 INFO - PROCESS | 1649 | |6;imaging-darkroom;99;97;97;96;97;122;153;113;146;153 14:59:19 INFO - PROCESS | 1649 | |7;imaging-desaturate;94;94;104;92;93;91;92;93;92;93 14:59:19 INFO - PROCESS | 1649 | |8;json-parse-financial;63;63;63;64;63;66;64;61;65;63 14:59:19 INFO - PROCESS | 1649 | |9;json-stringify-tinderbox;65;74;78;75;76;69;68;68;77;99 14:59:19 INFO - PROCESS | 1649 | |10;stanford-crypto-aes;90;87;88;87;90;91;90;91;91;89 14:59:19 INFO - PROCESS | 1649 | |11;stanford-crypto-ccm;147;150;146;145;141;163;151;147;189;161 14:59:19 INFO - PROCESS | 1649 | |12;stanford-crypto-pbkdf2;175;176;178;180;189;243;192;197;240;244 14:59:19 INFO - PROCESS | 1649 | |13;stanford-crypto-sha256-iterative;70;67;69;69;69;72;69;68;66;67 14:59:19 INFO - PROCESS | 1649 | __end_tp_report 14:59:19 INFO - PROCESS | 1649 | __start_cc_report 14:59:19 INFO - PROCESS | 1649 | _x_x_mozilla_cycle_collect,1000 14:59:19 INFO - PROCESS | 1649 | __end_cc_report 14:59:19 INFO - PROCESS | 1649 | __startTimestamp1487113159805__endTimestamp 14:59:19 INFO - PROCESS | 1649 | 14:59:19 INFO - PROCESS | 1649 | ------- Summary: start ------- 14:59:19 INFO - PROCESS | 1649 | Number of tests: 14 14:59:19 INFO - PROCESS | 1649 | 14:59:19 INFO - PROCESS | 1649 | [#0] ai-astar Cycles:10 Average:142.00 Median:174.00 stddev:31.67 (18.2%) stddev-sans-first:30.63 14:59:19 INFO - PROCESS | 1649 | Values: 105.0 117.0 122.0 145.0 132.0 174.0 177.0 97.0 177.0 174.0 14:59:19 INFO - PROCESS | 1649 | 14:59:19 INFO - PROCESS | 1649 | [#1] audio-beat-detection Cycles:10 Average:122.70 Median:128.00 stddev:13.33 (10.4%) stddev-sans-first:13.88 14:59:19 INFO - PROCESS | 1649 | Values: 130.0 114.0 112.0 148.0 114.0 126.0 114.0 135.0 130.0 104.0 14:59:19 INFO - PROCESS | 1649 | 14:59:19 INFO - PROCESS | 1649 | [#2] audio-dft Cycles:10 Average:218.10 Median:217.00 stddev:24.91 (11.5%) stddev-sans-first:26.40 14:59:19 INFO - PROCESS | 1649 | Values: 221.0 237.0 204.0 200.0 201.0 198.0 196.0 237.0 274.0 213.0 14:59:19 INFO - PROCESS | 1649 | 14:59:19 INFO - PROCESS | 1649 | [#3] audio-fft Cycles:10 Average:67.40 Median:62.50 stddev:12.55 (20.1%) stddev-sans-first:13.15 14:59:19 INFO - PROCESS | 1649 | Values: 73.0 59.0 60.0 99.0 61.0 61.0 60.0 61.0 76.0 64.0 14:59:19 INFO - PROCESS | 1649 | 14:59:19 INFO - PROCESS | 1649 | [#4] audio-oscillator Cycles:10 Average:76.80 Median:77.00 stddev:8.42 (10.9%) stddev-sans-first:6.58 14:59:19 INFO - PROCESS | 1649 | Values: 93.0 72.0 85.0 70.0 71.0 70.0 84.0 71.0 70.0 82.0 14:59:19 INFO - PROCESS | 1649 | 14:59:19 INFO - PROCESS | 1649 | [#5] imaging-gaussian-blur Cycles:10 Average:103.20 Median:88.00 stddev:18.86 (21.4%) stddev-sans-first:19.18 14:59:19 INFO - PROCESS | 1649 | Values: 88.0 99.0 88.0 111.0 87.0 135.0 134.0 111.0 90.0 89.0 14:59:19 INFO - PROCESS | 1649 | 14:59:19 INFO - PROCESS | 1649 | [#6] imaging-darkroom Cycles:10 Average:117.30 Median:96.50 stddev:24.58 (25.5%) stddev-sans-first:25.16 14:59:19 INFO - PROCESS | 1649 | Values: 99.0 97.0 97.0 96.0 97.0 122.0 153.0 113.0 146.0 153.0 14:59:19 INFO - PROCESS | 1649 | 14:59:19 INFO - PROCESS | 1649 | [#7] imaging-desaturate Cycles:10 Average:93.80 Median:93.00 stddev:3.71 (4.0%) stddev-sans-first:3.93 14:59:19 INFO - PROCESS | 1649 | Values: 94.0 94.0 104.0 92.0 93.0 91.0 92.0 93.0 92.0 93.0 14:59:19 INFO - PROCESS | 1649 | 14:59:19 INFO - PROCESS | 1649 | [#8] json-parse-financial Cycles:10 Average:63.50 Median:63.50 stddev:1.35 (2.1%) stddev-sans-first:1.42 14:59:19 INFO - PROCESS | 1649 | Values: 63.0 63.0 63.0 64.0 63.0 66.0 64.0 61.0 65.0 63.0 14:59:19 INFO - PROCESS | 1649 | 14:59:19 INFO - PROCESS | 1649 | [#9] json-stringify-tinderbox Cycles:10 Average:74.90 Median:75.50 stddev:9.57 (12.7%) stddev-sans-first:9.46 14:59:19 INFO - PROCESS | 1649 | Values: 65.0 74.0 78.0 75.0 76.0 69.0 68.0 68.0 77.0 99.0 14:59:19 INFO - PROCESS | 1649 | 14:59:19 INFO - PROCESS | 1649 | [#10] stanford-crypto-aes Cycles:10 Average:89.40 Median:90.00 stddev:1.58 (1.8%) stddev-sans-first:1.66 14:59:19 INFO - PROCESS | 1649 | Values: 90.0 87.0 88.0 87.0 90.0 91.0 90.0 91.0 91.0 89.0 14:59:19 INFO - PROCESS | 1649 | 14:59:19 INFO - PROCESS | 1649 | [#11] stanford-crypto-ccm Cycles:10 Average:154.00 Median:150.50 stddev:14.11 (9.4%) stddev-sans-first:14.74 14:59:19 INFO - PROCESS | 1649 | Values: 147.0 150.0 146.0 145.0 141.0 163.0 151.0 147.0 189.0 161.0 14:59:19 INFO - PROCESS | 1649 | 14:59:19 INFO - PROCESS | 1649 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:201.40 Median:194.50 stddev:29.14 (15.0%) stddev-sans-first:29.30 14:59:19 INFO - PROCESS | 1649 | Values: 175.0 176.0 178.0 180.0 189.0 243.0 192.0 197.0 240.0 244.0 14:59:19 INFO - PROCESS | 1649 | 14:59:19 INFO - PROCESS | 1649 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.60 Median:69.00 stddev:1.71 (2.5%) stddev-sans-first:1.74 14:59:19 INFO - PROCESS | 1649 | Values: 70.0 67.0 69.0 69.0 69.0 72.0 69.0 68.0 66.0 67.0 14:59:19 INFO - PROCESS | 1649 | -------- Summary: end -------- 14:59:19 INFO - PROCESS | 1649 | 14:59:20 INFO - TEST-INFO | 1649: exit 0 14:59:20 INFO - TEST-OK | kraken | took 61971ms 14:59:20 INFO - SUITE-END | took 1178s 14:59:20 INFO - Completed test suite (00:19:39) 14:59:20 INFO - javascript benchmark 14:59:21 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7177.603645943104, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3310.966890331096, 3357.6642335766423, 3372.949405758914, 3387.813670248135, 3405.1657343950737, 4436.824241575985, 5468.28912241409, 5486.3690675572325, 5659.094544872821, 5692.573057020724, 5533.0870406383, 5611.523020543259, 5615.9157612635745, 5620.185073164389, 5698.7435565399555, 5202.713850738208, 5214.713190774506, 5227.817026404076, 5300.363956325246, 5336.9859726652, 5234.241035049916, 5303.496167864047, 5312.76092575835, 5322.361316641989, 5331.920021199685, 4482.574155455243, 5154.355705536808, 5247.160454327309, 5266.6833291677085, 5383.16560933054, 5231.372235331784, 5242.711650859202, 5263.736813159327, 5277.234800953878, 5282.762275697583, 5023.472535383805, 5043.697378157322, 5064.113780088506, 5074.898502029939, 5087.338645976037, 4972.502749725035, 5671.659700417947, 5702.030654788696, 5778.335491418498, 5783.971080144573, 6233.5948163369685, 6236.064590311445, 6241.843953901143, 6252.062190671436, 6274.403931626488, 4429.6899217054815, 6202.28673702521, 6213.378662133796, 6217.6891155442045, 6218.4714299284615, 5675.3757086720425, 5754.539636829086, 5938.198343223696, 5944.7027648617395, 5946.494547963472, 5353.437889021597, 5515.393306736256, 6257.468115210167, 6273.404026617464, 6287.0, 6036.63780173191, 6090.025595904723, 6146.692665366714, 6168.352323006109, 6193.349698281616, 5960.940390596039, 6056.242969628796, 6072.787452439144, 6097.5731698780655, 6098.26820781509, 6039.607425517327, 6108.1143234230785, 6108.6456319605295, 6121.148138557471, 6144.692765361714, 6014.6391216527145, 6055.697215139226, 6084.056971169476, 6115.6330620162935, 6135.509159267337, 4873.756312184448, 4936.90126197474, 4959.057779021974, 5013.523715247045, 5024.221245706921, 4446.62203712688, 4461.196984542748, 4469.620082292977, 4490.618297444754, 4504.6396288296855, 5577.52591029767, 5628.352739435018, 5640.661560306395, 5701.116326969243, 5720.742566584514, 5221.738913054332, 6124.326324104345, 6169.413905678954, 6173.96913015441, 6215.627062376272, 5816.825495235065, 5826.679532625746, 5844.211031510692, 5853.795117170878, 5855.502282305966, 5275.129603615437, 5477.507024367746, 5483.506484416422, 5494.148406996922, 5502.614816962774, 6149.262088549313, 6158.168647232746, 6185.6907154641185, 6213.751449941951, 6295.9370406297185, 6060.8181404625675, 6186.1648677427975, 6194.442500175006, 6197.225346831646, 6201.10084037803, 6252.406021427957, 6260.624362538262, 6261.499080073492, 6268.216472940882, 6302.873942521215, 5992.850178745566, 6018.037114061729, 6032.577719559588, 6112.510999120149, 6114.419025732044, 6138.5396095292135, 6176.073588961692, 6205.596636218631, 6208.720607572761, 6221.2845522765465, 5913.294222929406, 6053.273607166992, 6145.893739126821, 6164.0, 6164.660943648142, 5585.469380408855, 5848.327442344185, 5928.377520360473, 6102.908456373247, 6134.171886795226, 5668.291463567054, 5845.970770146122, 5903.321118071477, 5967.940320596738, 6001.519878409804, 5931.822045338647, 6097.500273765439, 6160.353162918008, 6166.044263139221, 6173.166622505904, 5320.787168513216, 5586.581006424453, 5822.446867547576, 5885.94114058871, 5943.910841337297], "value": 5632.535238623866, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [434.969123178068, 439.0954633455083, 461.82681494439606, 476.4901555335791, 535.8312131678523, 37119.0, 39851.60148398511, 39884.8034558963, 40051.0, 40127.39490420384, 41130.0, 41626.58373416265, 41682.0, 41750.37374439378, 41801.58198418015, 7420.628968551578, 7614.961925190366, 9314.86027709585, 9567.904320956772, 11191.2725672831, 9636.0, 11317.773644527104, 11611.419429028558, 11627.825582616226, 11636.709082272926, 11517.0, 11548.133889958244, 11610.071194304477, 11628.302301861873, 11633.6509904703, 9343.486108264044, 11474.967252947275, 11497.0, 11552.422378881023, 11556.364399957998, 11284.717882052932, 11383.373914434702, 11390.430478476042, 11404.54381824726, 11411.030062444703, 39575.8021209895, 39680.801595992125, 40094.59905400938, 40118.39822402666, 40129.79935100335, 3821.9426708599394, 3855.151866589374, 3865.40086286626, 3868.613138686137, 3874.6900247980093, 29394.412111757858, 29638.851805741047, 29825.0, 30191.547126793117, 30409.292422417308, 3074.0, 3088.274255549944, 3089.274020605156, 3159.6524382317925, 3212.389645967282, 340148.0, 340707.0, 341428.2928585366, 341919.29040354886, 343475.84786228224, 51961.843052555705, 53230.0, 60458.0, 62467.0, 63303.36696632975, 64262.0, 64414.355856441776, 64742.35257647457, 64768.676156618916, 64872.02691959625, 13666.863331366758, 14352.421233664289, 15098.698026039638, 15100.471483498024, 15152.924235378752, 11563.959243667941, 11643.767124657628, 11646.825297620542, 11647.0, 11651.242669226644, 17437.0, 17484.5628859277, 17496.0, 17509.912450437667, 17524.649507009788, 6491.902621460682, 7481.401487880957, 7542.47202695817, 7589.241075892367, 7704.959830422933, 693.4729605499856, 779.933705635016, 830.2983978538126, 849.9362547808939, 861.2765277167209, 26180.476390472082, 27668.893244270406, 29046.0, 29372.559411608843, 29782.0, 844.27814218842, 914.4376208631777, 1012.4583347908833, 1063.4576366053368, 1185.8458400407892, 13804.447822087004, 15273.465428710164, 15305.08169509833, 15519.258994803435, 15620.76568851468, 561335.0, 564940.0, 565512.0, 569523.0, 569914.0, 57256.71371643115, 57691.42308576944, 57901.71049144727, 57913.13130303049, 58431.4156858426, 1219.5853409840697, 1344.072589912957, 1345.7350090914508, 1395.3999780094657, 1400.3138462153472, 25259.49481010406, 27420.58869116925, 28139.859300704185, 29140.562891557067, 29722.256943576584, 41196.970075748344, 49431.25853112278, 49662.0, 49733.0, 49759.751201243766, 496012.0, 499171.0, 499787.0, 500812.0, 501566.4921675368, 31532.211694707818, 32762.0, 32762.689492420035, 37537.43693844649, 39625.80187099046, 44039.0, 45785.542144579464, 45968.31047534223, 47275.0, 47368.05263894634, 36958.63041369552, 37122.25755484929, 37614.0, 38274.617253827106, 38284.0, 26602.0, 31345.686543134278, 35684.57261709502, 36597.634023659426, 36992.07519812026, 34029.0, 35004.47493287654, 35014.47478287879, 37239.441408378356, 37746.43380349242, 27875.721242787313, 29878.402431951676, 30059.84970075223, 31710.36579268448, 32219.03342899717, 23477.647835282143, 23763.0, 24006.15978440746, 24276.271711848673, 24342.148024819046], "value": 19441.753674937358, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [354.36745409444137, 383.8905911815132, 390.3215783750194, 399.0761387388195, 425.75093570261396, 20312.492187695298, 20885.0, 21085.0, 21164.8941755291, 21611.45971350713, 21260.362189134314, 21624.2431514897, 21676.458088547755, 21855.781442185573, 21960.670589941165, 5070.923936140961, 5661.207430959669, 5876.382979787125, 5922.644641321513, 5936.406359364072, 5553.16702494627, 5767.452092051249, 5780.913286300709, 5857.26784151981, 5877.2947246330505, 5761.251037365137, 5766.135079738031, 5766.884662306751, 5773.971130144364, 5774.538036957033, 5478.91781623278, 5668.263125793662, 5746.48281654651, 5764.33710123337, 5783.5084017858535, 5061.0, 5078.619103567247, 5090.872728181788, 5092.898142037176, 5096.261042148868, 25552.23343299704, 25588.74411255901, 25628.48743025129, 25682.48635027289, 25941.0, 2334.568104900599, 2708.282305189107, 2742.616033755276, 2787.02454141051, 2788.442311537701, 15120.924395378062, 15213.243154140466, 15375.615609609737, 15511.069335839886, 15860.127692976881, 1755.3680674957004, 1778.9288428462846, 1789.4363275568155, 1798.7661603991398, 1845.0682405385328, 123180.76819231783, 123237.0, 124004.89987750289, 127112.0, 128093.35953320267, 11222.04612607921, 11465.426728663533, 11669.299842009506, 11709.707257318465, 11939.283642981449, 14255.714885702228, 14315.427382904776, 14329.211893345757, 14376.928115359357, 14384.568462946128, 8029.3175080118845, 8169.673213071529, 8275.0, 8416.873746893802, 8438.873416898752, 8053.27520523144, 8066.072401673882, 8087.069986951458, 8186.631601577943, 8227.012758469022, 5787.94212057874, 7084.964575177194, 7090.822729431702, 7091.609961452066, 7095.858082838417, 1575.527341797456, 1930.0639189992842, 1933.3136736458637, 1934.3423236099793, 1937.447827369193, 854.9313358302122, 879.62176264207, 893.946363218209, 901.1439132823843, 917.8481006337005, 18935.053247337855, 19906.800931990783, 20043.59912801736, 21955.341339759834, 21980.681159130505, 859.2696208223085, 913.2830727878584, 916.9679061232824, 917.5685929949301, 924.2282693950492, 10167.593296268215, 11326.490307936163, 11407.714807129889, 11439.084873210124, 11464.828027579593, 484348.0, 512223.8777612251, 681247.0, 700374.0, 700865.0, 132079.0, 134428.0, 134465.0, 135725.0, 135855.0, 1031.5564307347913, 1058.079470860341, 1074.5486895504064, 1081.329575663094, 1081.6646839479786, 17150.056746879043, 17252.913735431244, 17428.912855435643, 17909.820901790816, 18193.181306840957, 55882.720586396805, 58770.4122958782, 59184.04079796079, 60446.395536045835, 61294.69352653208, 79153.0, 89225.55387223023, 90547.5472622659, 90925.09074909166, 91006.0, 7100.680469378994, 7353.614589762418, 7482.326590606872, 7779.183185765413, 8539.8719019216, 10977.615783447542, 12186.51253949832, 12277.710840361633, 12728.809067863804, 13076.346182691017, 9401.623935042522, 10916.399598022184, 10932.398718070583, 11247.943760281147, 11481.96662300397, 10817.675469735921, 10895.727606809893, 11045.005949464587, 11181.832272515756, 11328.32030078198, 10087.646932357333, 10728.946355268174, 11203.71990700239, 11466.31202127875, 11659.008984236434, 8230.21812927771, 8567.357448191286, 8599.742007739778, 8635.222829945335, 8689.087645797354, 11006.834897476385, 11358.318500889973, 11513.53945842157, 11694.4737486815, 11705.297682139098], "value": 9857.956735436666, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1183.3077649574998, 1429.2496439369324, 1443.4226309476214, 1450.0212356659251, 1467.4717101843337, 7545.622718864055, 8121.634526446309, 8188.754337369874, 8316.958415207931, 8618.396712230146, 8502.659893604265, 8594.570271486416, 8620.698275560357, 8644.481331120138, 8660.826783464343, 1745.5112568480845, 1793.4529968359645, 2078.0337143228394, 2138.433315171477, 2183.257692384589, 2238.9664155037685, 2318.6290193569034, 2327.8021368183727, 2332.6501024846325, 2344.0623750499767, 1897.7058555923854, 2279.737830149529, 2288.4278930267433, 2300.9654855177187, 2304.446932736148, 2240.4174914522177, 2255.1655887321795, 2268.897899594522, 2269.7162854643007, 2270.761570035147, 2249.707538020063, 2264.7169103862016, 2320.8027317678016, 2331.860088394702, 2334.0780391745334, 8485.787855303604, 8543.786405339853, 8550.01674807399, 8555.10171431997, 8557.358198135102, 4552.271636538138, 5126.128558145087, 5218.321618189649, 5235.267062611238, 5292.92060619091, 2931.853407329625, 3314.8895120134785, 3442.638522955104, 3447.844846981892, 3503.702185314226, 1162.436218434059, 1231.1074471008537, 1249.1505776072268, 1268.3594784633792, 1270.218815428514, 10780.299280546818, 10931.0, 10955.50700218492, 11043.0, 11133.109351251962, 4269.893252668709, 4445.866624001285, 4454.643628509712, 4541.977290113595, 4564.109998550317, 8784.165504277084, 8847.115288471101, 8848.424852384574, 8852.557372131369, 8875.423097498642, 5042.117629414838, 5045.445001049881, 5075.416327122354, 5082.695038297714, 5085.067674283749, 4147.668186545069, 4637.744924029211, 4729.148753224453, 4770.403699537558, 4772.451168115642, 3583.51622530955, 3611.5485564304463, 3667.358212312888, 3680.392735198716, 3702.7593206441493, 1145.5360578965533, 1148.465963327057, 1150.8216226484908, 1164.7670465906851, 1169.1465230381698, 755.9168491465934, 760.9619519024027, 782.3311069036006, 789.1004255149135, 794.1502592226378, 5413.83758487246, 5450.5367043800925, 5670.06443936754, 5679.488846003879, 5713.143028545668, 555.6915911669063, 557.0641322578027, 568.5139205978911, 573.9668596526236, 584.2492397269489, 3221.7261532769508, 3672.081979505124, 3711.46183803347, 3767.56672982605, 3816.904577385588, 9395.765105872271, 9457.810843783085, 9809.21526277896, 10023.949880250551, 10032.1472674822, 8983.46099234048, 10033.698989030341, 10061.849072263922, 10066.245031622511, 10078.445685487222, 6724.092247546617, 6865.897011544831, 7506.962465187639, 7741.264579864904, 7802.0, 5660.971695141498, 5778.797742079007, 5802.82591522255, 5860.501857342003, 5892.292924849045, 9216.354855160074, 9343.15911567935, 9355.06449355059, 9360.344775865624, 9362.063793620584, 7115.537490063336, 9354.064593540592, 9406.012368701187, 9591.9040809591, 9599.232061435208, 7156.928430715834, 7213.278672132955, 7244.130704315516, 7280.526765760209, 7315.30504602062, 7869.724559640614, 7941.722039728582, 7955.164707705608, 7960.522368657899, 7974.242446967529, 6947.131608548932, 6998.0, 7093.716251349888, 7198.7840364789145, 7283.126024877049, 6181.381861813783, 6983.930160698531, 6986.91702786069, 7075.964620176866, 7098.148222213367, 5840.970795145997, 6315.368463153648, 6323.241211054703, 6434.903476447764, 6448.355164483514, 4328.913421731485, 4432.977835110803, 4840.709557426566, 4888.60891128716, 4923.803047878188, 4950.2043091564265, 5087.109755792795, 5110.131277682877, 5138.97430512845, 5169.198774190007], "value": 4273.189339729074, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [357.24621049585386, 357.77996532132744, 364.7884227148254, 370.21883825128975, 370.32415841090017, 4898.444446664605, 4907.656464047518, 5044.672096313737, 5258.369905329346, 5446.591505637083, 4701.952980470194, 5287.127623942055, 5413.891722165555, 5416.268803711498, 5429.348478182624, 3444.328355970574, 4099.508059032905, 4119.052617897885, 4119.546849846507, 4120.402541631462, 3842.865499707511, 4117.52948231062, 4125.546189919107, 4137.172565486914, 4139.882231797413, 4146.834126634931, 4152.733416308025, 4153.335466325389, 4154.916901661965, 4157.521884983235, 3634.200475895299, 3958.782266975315, 3987.322155233617, 4040.6969477289026, 4089.9386509202386, 2981.5378616314506, 3400.8129552874584, 3484.1289677580603, 3495.6329585393423, 3536.3732682965865, 4083.5099788025627, 4349.478062632473, 4454.30958201476, 4890.750254882758, 5045.073857162837, 4380.167768124046, 4728.392346602137, 4733.290006499018, 4765.428148622153, 4776.761161941924, 4989.57588604969, 5675.914861277084, 5790.449907258803, 5793.275840519935, 5861.648301101947, 3314.983425082883, 3626.0056968667127, 3920.666743326821, 3923.372260438316, 3936.6850651947775, 5726.3044303671, 5748.971255143698, 5867.647941123546, 6031.044788053866, 6099.542534309944, 3490.7556471046782, 3547.52108465359, 3783.6405541473514, 3828.3300422425964, 3950.846788500274, 3273.459879119966, 3696.2792255510453, 3767.3047128791604, 4015.8393664253103, 4022.0, 4646.581807637329, 4667.976660116746, 4722.929156062662, 4732.550407711262, 4744.454387745384, 5559.749811258503, 5761.711914404263, 5825.0, 5846.766129354864, 5948.464638182584, 1125.859267591551, 1136.3125309187822, 1270.8157317188957, 1301.3233118778182, 1326.9933650331686, 815.4414226255034, 827.2141465607697, 836.5273620404452, 876.3471213945651, 889.8487257166297, 4576.908461830812, 4615.307753796949, 4875.293082503017, 5038.143515602356, 5042.319286896295, 4698.2952557116705, 4809.5430934061205, 5489.752961116759, 5646.774129034875, 5704.9763625278665, 4268.246980697495, 4995.0, 5059.836237665358, 5107.106256405188, 5145.9485405146215, 2609.9217023489327, 2661.9086174668682, 2844.8293102413922, 2947.4547208766016, 3001.529250667201, 5211.895762084813, 5344.6258761887075, 5494.148406996922, 5639.915401268984, 5895.587308888336, 5445.992491389026, 5586.581006424453, 5761.798337058267, 5985.40145985398, 6148.077788331697, 5912.290525137011, 6027.367126451747, 6039.946418217827, 6157.89157951573, 6231.501479881689, 5020.6234532410945, 5518.750562067105, 5711.287663019071, 5858.301092683074, 6014.48876845473, 5170.922436163536, 5539.0, 5764.596478246483, 5881.411858814085, 5952.077427998667, 5227.817026404057, 5401.378841433286, 5541.224228607917, 5900.055991041413, 6197.96901015492, 3672.540932383452, 3934.5278566572197, 3956.2878681837547, 3981.3629819228786, 3988.0, 5369.0, 5617.494425501724, 5873.973479149687, 5947.918228619948, 5953.315368732651, 4958.776855041604, 5089.414717307557, 5644.238027866186, 5718.1136923776885, 5755.3381361144, 4628.467726211529, 5322.787088516416, 5585.542361360988, 5605.467480589337, 5687.857803554944, 5288.867778305573, 5446.988770331384, 5545.334559852843, 5556.638818476786, 5686.829395118153, 5071.442141364373, 5507.7246137692155, 5655.4910058094965, 5788.308436577012, 5801.216835727123, 5069.645124841373, 5232.561045712459, 5547.2511210986, 5620.522255608319, 5659.54723622101], "value": 4108.639913158192, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [685.7359916432175, 688.4664385101543, 691.5643144818764, 757.0914902117461, 825.7894236969572, 14381.137131772088, 15266.465673701408, 15271.160086195256, 15279.770803437958, 15308.923455382721, 13553.0, 13973.860261397384, 14126.717465650681, 14164.645883852909, 14195.929020354884, 796.9481983671058, 887.5872719185577, 909.7952960583882, 912.3476714149382, 914.4056363363834, 900.8063266397724, 906.4470672889565, 907.5870478932103, 907.9137481939205, 909.3997961345514, 906.9229115525188, 947.9241660667165, 949.9762505937338, 952.6284748947916, 957.2389949989774, 943.7404713703718, 945.0171821305868, 948.2035090523958, 950.681521690238, 952.6284748947916, 693.4209934704528, 704.8977898204731, 705.9541129826545, 706.8550947055841, 710.2542330552899, 15011.924940375338, 15346.923265383713, 15386.0, 15525.0, 15544.512877939474, 13649.863501365058, 13734.65663358414, 13992.30038498071, 14001.859981400157, 14020.71958560823, 11209.389521549234, 11421.314721116676, 11492.310461372344, 11539.076873850072, 11854.703632409173, 3637.363461394272, 3645.400172309908, 3680.9447858282147, 3726.329260733067, 3804.1630841214887, 73494.63252683755, 74284.25715742828, 92171.53914230454, 97777.5111124447, 100048.49975750147, 10607.734806629896, 10740.677779666623, 10860.954867252216, 10902.454877256105, 10970.506327215295, 21320.893395532923, 21924.0, 21949.890250548964, 21960.560788784136, 22009.0, 7403.407727381797, 7478.738244161537, 7498.775036748906, 7593.810154746176, 7722.150563438017, 4084.6936479764136, 4188.392683060939, 4193.391958166048, 4205.474315710536, 4213.094184750232, 3577.821108944542, 3640.3265395901844, 3654.1230104775195, 3656.03115174479, 3690.833912473945, 650.2132419772034, 676.9492288078413, 679.9762008329685, 694.3958755882313, 697.8290318871909, 634.3814780588981, 634.8444631065419, 666.5334266013809, 674.9527533072638, 696.3280434380847, 7853.48952318109, 9439.575219115157, 9566.473843938511, 9673.274504412197, 9944.303898727165, 557.0057447456271, 649.1089639565848, 679.2222904774006, 685.6366125953252, 689.4622194688151, 4692.436907571045, 4753.5959443447, 4778.737169455644, 4783.162946484351, 4793.616510679138, 59427.5429342801, 71384.21539461451, 77856.0, 84720.57639711886, 87908.24183516295, 73558.0, 74487.62756186184, 78599.0, 78687.60656196797, 81840.59079704563, 58003.0, 64114.0, 67143.32141696497, 70463.2953670467, 70598.64700676464, 7809.375249980101, 7903.56530390834, 8999.910000900169, 10143.898561014295, 10312.226583006155, 43672.0, 44635.330470042325, 45388.0, 46669.53330466652, 46832.53167468282, 54837.451625483234, 62872.4281892938, 63100.68449657906, 63563.0, 63717.0, 12371.628851134481, 12524.69047182929, 12525.686857828627, 12557.246565206116, 12991.220526768424, 13512.054156209364, 13540.661483462993, 13674.658133546343, 13754.449822007007, 13772.517961871687, 12817.743645126859, 12974.675633109247, 13061.34693265314, 13336.799948000982, 13350.332483375987, 11706.707332316419, 12562.87437125617, 13043.0, 13058.738825223632, 13171.943759791582, 10589.894101058891, 12494.937525312314, 12595.24428534291, 12609.810852837032, 12727.490900363882, 9977.501124943868, 10465.215108866712, 10519.21105917044, 10649.840252396065, 10976.945115274373, 9031.50326732036, 9240.815183696423, 10208.846867297145, 10278.537465814206, 10381.117605003357], "value": 7213.605252204602, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1593.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 117.0, 122.0, 145.0, 132.0, 174.0, 177.0, 97.0, 177.0, 174.0], "value": 142.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 114.0, 112.0, 148.0, 114.0, 126.0, 114.0, 135.0, 130.0, 104.0], "value": 122.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [221.0, 237.0, 204.0, 200.0, 201.0, 198.0, 196.0, 237.0, 274.0, 213.0], "value": 218.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 59.0, 60.0, 99.0, 61.0, 61.0, 60.0, 61.0, 76.0, 64.0], "value": 67.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 72.0, 85.0, 70.0, 71.0, 70.0, 84.0, 71.0, 70.0, 82.0], "value": 76.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 99.0, 88.0, 111.0, 87.0, 135.0, 134.0, 111.0, 90.0, 89.0], "value": 103.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 97.0, 97.0, 96.0, 97.0, 122.0, 153.0, 113.0, 146.0, 153.0], "value": 117.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 104.0, 92.0, 93.0, 91.0, 92.0, 93.0, 92.0, 93.0], "value": 93.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 63.0, 64.0, 63.0, 66.0, 64.0, 61.0, 65.0, 63.0], "value": 63.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 74.0, 78.0, 75.0, 76.0, 69.0, 68.0, 68.0, 77.0, 99.0], "value": 74.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 88.0, 87.0, 90.0, 91.0, 90.0, 91.0, 91.0, 89.0], "value": 89.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 150.0, 146.0, 145.0, 141.0, 163.0, 151.0, 147.0, 189.0, 161.0], "value": 154.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 176.0, 178.0, 180.0, 189.0, 243.0, 192.0, 197.0, 240.0, 244.0], "value": 201.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 67.0, 69.0, 69.0, 69.0, 72.0, 69.0, 68.0, 66.0, 67.0], "value": 68.6, "unit": "score"}]}]} 14:59:21 INFO - Return code: 0 14:59:21 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:59:21 ERROR - # TBPL SUCCESS # 14:59:21 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:59:21 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:59:21 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:59:21 INFO - 2017-02-14 14:59:21.170 screenresolution[1654:10698] starting screenresolution argv=screenresolution get 14:59:21 INFO - 2017-02-14 14:59:21.173 screenresolution[1654:10698] Display 0: 1600x1200x32@60 14:59:21 INFO - 2017-02-14 14:59:21.179 screenresolution[1655:10701] starting screenresolution argv=screenresolution list 14:59:21 INFO - Available Modes on Display 0 14:59:21 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:59:21 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:59:21 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:59:21 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:59:21 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:59:21 INFO - Intel Iris: 14:59:21 INFO - Chipset Model: Intel Iris 14:59:21 INFO - Type: GPU 14:59:21 INFO - Bus: Built-In 14:59:21 INFO - VRAM (Dynamic, Max): 1536 MB 14:59:21 INFO - Vendor: Intel (0x8086) 14:59:21 INFO - Device ID: 0x0a2e 14:59:21 INFO - Revision ID: 0x0009 14:59:21 INFO - Displays: 14:59:21 INFO - Display: 14:59:21 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:59:21 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:59:21 INFO - Display Serial Number: 1600x1200 60 14:59:21 INFO - Main Display: Yes 14:59:21 INFO - Mirror: Off 14:59:21 INFO - Online: Yes 14:59:21 INFO - Rotation: Supported 14:59:21 INFO - Return code: 0 14:59:21 INFO - Running post-action listener: _resource_record_post_action 14:59:21 INFO - [mozharness: 2017-02-14 22:59:21.416575Z] Finished run-tests step (success) 14:59:21 INFO - Running post-run listener: _resource_record_post_run 14:59:21 INFO - Total resource usage - Wall time: 1202s; CPU: 55.0%; Read bytes: 39946752; Write bytes: 557588480; Read time: 412; Write time: 1960 14:59:21 INFO - TinderboxPrint: CPU usage
54.6% 14:59:21 INFO - TinderboxPrint: I/O read bytes / time
39,946,752 / 412 14:59:21 INFO - TinderboxPrint: I/O write bytes / time
557,588,480 / 1,960 14:59:21 INFO - TinderboxPrint: CPU idle
2,183.2 (45.4%) 14:59:21 INFO - TinderboxPrint: CPU system
967.3 (20.1%) 14:59:21 INFO - TinderboxPrint: CPU user
1,659.1 (34.5%) 14:59:21 INFO - TinderboxPrint: Swap in / out
292,864,000 / 0 14:59:21 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 249428480; Write bytes: 243644416; Read time: 17972; Write time: 455 14:59:21 INFO - run-tests - Wall time: 1180s; CPU: 55.0%; Read bytes: 35330560; Write bytes: 300402688; Read time: 380; Write time: 1468 14:59:21 INFO - Running post-run listener: _upload_blobber_files 14:59:21 INFO - Blob upload gear active. 14:59:21 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:59:21 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 14:59:21 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:59:21 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:59:22 INFO - (blobuploader) - INFO - Open directory for files ... 14:59:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 14:59:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:59:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:59:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 14:59:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:59:23 INFO - (blobuploader) - INFO - Done attempting. 14:59:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 14:59:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:59:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:59:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 14:59:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:59:24 INFO - (blobuploader) - INFO - Done attempting. 14:59:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 14:59:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:59:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:59:25 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 14:59:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:59:25 INFO - (blobuploader) - INFO - Done attempting. 14:59:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:59:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:59:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:59:27 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:59:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:59:27 INFO - (blobuploader) - INFO - Done attempting. 14:59:27 INFO - (blobuploader) - INFO - Iteration through files over. 14:59:27 INFO - Return code: 0 14:59:27 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:59:27 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:59:27 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b5554350dbbf1742cfc6905f4a2de9f579a9cd20e15a0e7a5f313d37d8a1af64f625a6883ce5f84b496ffdcbe570552f6d407bae78ec706f2439b800e24d3f25", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cb91b8a2033e3df094410e003c06abd81f6970f6cc729bf233ec07281eb728c33fc121d444f247024751557c9a0ea8ef22088509e89f17f70df2b1a4e09f00c3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9ea32bcfa447de9a2fef5720ac61cb09b630948319b8071280bd2a4a8d5bba96b7ab6bd10e8a489676923b965991e153fb0d1f43dec98d9c76a82bd2a9defceb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e77d39af005ccc57cd600daf0f7134f40108feb1dd4253e0b45fefe3169b851d919e921996f001d99039f660d18d91a6a42ba1b1a8be17668c9869fba868230c"} 14:59:27 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:59:27 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:59:27 INFO - Contents: 14:59:27 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b5554350dbbf1742cfc6905f4a2de9f579a9cd20e15a0e7a5f313d37d8a1af64f625a6883ce5f84b496ffdcbe570552f6d407bae78ec706f2439b800e24d3f25", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cb91b8a2033e3df094410e003c06abd81f6970f6cc729bf233ec07281eb728c33fc121d444f247024751557c9a0ea8ef22088509e89f17f70df2b1a4e09f00c3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9ea32bcfa447de9a2fef5720ac61cb09b630948319b8071280bd2a4a8d5bba96b7ab6bd10e8a489676923b965991e153fb0d1f43dec98d9c76a82bd2a9defceb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e77d39af005ccc57cd600daf0f7134f40108feb1dd4253e0b45fefe3169b851d919e921996f001d99039f660d18d91a6a42ba1b1a8be17668c9869fba868230c"} 14:59:27 INFO - Running post-run listener: copy_logs_to_upload_dir 14:59:27 INFO - Copying logs to upload dir... 14:59:27 INFO - mkdir: /builds/slave/test/build/upload/logs 14:59:27 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1287.425587 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 27 secs) (at 2017-02-14 14:59:27.759414) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-14 14:59:27.764947) ========= 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.qY0AN8ItX1/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.UgMvAqTUhu/Listeners TMPDIR=/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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/Firefox/sha512/b5554350dbbf1742cfc6905f4a2de9f579a9cd20e15a0e7a5f313d37d8a1af64f625a6883ce5f84b496ffdcbe570552f6d407bae78ec706f2439b800e24d3f25", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cb91b8a2033e3df094410e003c06abd81f6970f6cc729bf233ec07281eb728c33fc121d444f247024751557c9a0ea8ef22088509e89f17f70df2b1a4e09f00c3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9ea32bcfa447de9a2fef5720ac61cb09b630948319b8071280bd2a4a8d5bba96b7ab6bd10e8a489676923b965991e153fb0d1f43dec98d9c76a82bd2a9defceb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e77d39af005ccc57cd600daf0f7134f40108feb1dd4253e0b45fefe3169b851d919e921996f001d99039f660d18d91a6a42ba1b1a8be17668c9869fba868230c"} build_url:https://queue.taskcluster.net/v1/task/IHK3zIGPRqGH1_X-CS9fPQ/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010748 build_url: 'https://queue.taskcluster.net/v1/task/IHK3zIGPRqGH1_X-CS9fPQ/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b5554350dbbf1742cfc6905f4a2de9f579a9cd20e15a0e7a5f313d37d8a1af64f625a6883ce5f84b496ffdcbe570552f6d407bae78ec706f2439b800e24d3f25", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cb91b8a2033e3df094410e003c06abd81f6970f6cc729bf233ec07281eb728c33fc121d444f247024751557c9a0ea8ef22088509e89f17f70df2b1a4e09f00c3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9ea32bcfa447de9a2fef5720ac61cb09b630948319b8071280bd2a4a8d5bba96b7ab6bd10e8a489676923b965991e153fb0d1f43dec98d9c76a82bd2a9defceb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e77d39af005ccc57cd600daf0f7134f40108feb1dd4253e0b45fefe3169b851d919e921996f001d99039f660d18d91a6a42ba1b1a8be17668c9869fba868230c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-14 14:59:27.799414) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-14 14:59:27.799886) ========= 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.qY0AN8ItX1/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.UgMvAqTUhu/Listeners TMPDIR=/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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.004881 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-14 14:59:27.848423) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-14 14:59:27.848746) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-14 14:59:27.849078) ========= ========= Total master_lag: 0.19 =========