builder: pine_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0150 starttime: 1492824334.48 results: success (0) buildid: 20170421151719 builduid: 307d785614ac456a8655988a8892ce9c revision: 73d3f203c63c5aec21b15d10e36783c592c18d13 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-21 18:25:34.477541) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-21 18:25:34.477984) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-21 18:25:34.478284) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-21 18:25:34.520749) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-21 18:25:34.521052) ========= 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.8KJd9FRLdy/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.nWWSZQnhlI/Listeners TMPDIR=/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/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-04-21 18:25:34-- 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% 8.03M=0.001s 2017-04-21 18:25:34 (8.03 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.167269 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-21 18:25:34.714302) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-21 18:25:34.714766) ========= 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.8KJd9FRLdy/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.nWWSZQnhlI/Listeners TMPDIR=/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/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.022587 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-21 18:25:34.763161) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-21 18:25:34.763531) ========= bash -c 'python archiver_client.py mozharness --repo projects/pine --rev 73d3f203c63c5aec21b15d10e36783c592c18d13 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/pine --rev 73d3f203c63c5aec21b15d10e36783c592c18d13 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.8KJd9FRLdy/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.nWWSZQnhlI/Listeners TMPDIR=/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/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-04-21 18:25:34,856 truncating revision to first 12 chars 2017-04-21 18:25:34,856 Setting DEBUG logging. 2017-04-21 18:25:34,856 attempt 1/10 2017-04-21 18:25:34,856 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/pine/73d3f203c63c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-21 18:25:35,303 unpacking tar archive at: pine-73d3f203c63c/testing/mozharness/ program finished with exit code 0 elapsedTime=0.702841 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-21 18:25:35.493812) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-21 18:25:35.494175) ========= script_repo_revision: 73d3f203c63c5aec21b15d10e36783c592c18d13 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-21 18:25:35.494669) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-21 18:25:35.494986) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-21 18:25:35.519161) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2017-04-21 18:25:35.519628) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Pine --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Pine 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', 'Pine', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Pine'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.8KJd9FRLdy/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.nWWSZQnhlI/Listeners TMPDIR=/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/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 18:25:35 INFO - MultiFileLogger online at 20170421 18:25:35 in /builds/slave/test 18:25:35 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Pine --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Pine 18:25:35 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:25:35 INFO - {'append_to_log': False, 18:25:35 INFO - 'base_work_dir': '/builds/slave/test', 18:25:35 INFO - 'blob_upload_branch': 'Pine', 18:25:35 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:25:35 INFO - 'branch': 'Pine', 18:25:35 INFO - 'buildbot_json_path': 'buildprops.json', 18:25:35 INFO - 'config_files': ('talos/mac_config.py',), 18:25:35 INFO - 'default_actions': ('clobber', 18:25:35 INFO - 'read-buildbot-config', 18:25:35 INFO - 'download-and-extract', 18:25:35 INFO - 'populate-webroot', 18:25:35 INFO - 'create-virtualenv', 18:25:35 INFO - 'install', 18:25:35 INFO - 'run-tests'), 18:25:35 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:25:35 INFO - 'download_minidump_stackwalk': True, 18:25:35 INFO - 'download_symbols': 'ondemand', 18:25:35 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:25:35 INFO - 'tooltool.py': '/tools/tooltool.py', 18:25:35 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:25:35 INFO - '/tools/misc-python/virtualenv.py')}, 18:25:35 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:25:35 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:25:35 INFO - 'gecko_profile': False, 18:25:35 INFO - 'gecko_profile_interval': 0, 18:25:35 INFO - 'installer_path': 'installer.exe', 18:25:35 INFO - 'log_level': 'info', 18:25:35 INFO - 'log_name': 'talos', 18:25:35 INFO - 'log_to_console': True, 18:25:35 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:25:35 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:25:35 INFO - 'opt_config_files': (), 18:25:35 INFO - 'pip_index': False, 18:25:35 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:25:35 INFO - 'cmd': ('bash', 18:25:35 INFO - '-c', 18:25:35 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:25:35 INFO - 'enabled': True, 18:25:35 INFO - 'halt_on_failure': False, 18:25:35 INFO - 'name': 'check_screen_resolution'},), 18:25:35 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:25:35 INFO - 'cmd': ('bash', 18:25:35 INFO - '-c', 18:25:35 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:25:35 INFO - 'enabled': True, 18:25:35 INFO - 'halt_on_failure': False, 18:25:35 INFO - 'name': 'check_screen_resolution'},), 18:25:35 INFO - 'run_cmd_checks_enabled': True, 18:25:35 INFO - 'suite': 'dromaeojs-e10s', 18:25:35 INFO - 'system_bits': '32', 18:25:35 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:25:35 INFO - 'title': 't-yosemite-r7-0150', 18:25:35 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:25:35 INFO - 'use_talos_json': True, 18:25:35 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:25:35 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:25:35 INFO - 'work_dir': 'build'} 18:25:35 INFO - [mozharness: 2017-04-22 01:25:35.730262Z] Running clobber step. 18:25:35 INFO - Running pre-action listener: _resource_record_pre_action 18:25:35 INFO - Running main action method: clobber 18:25:35 INFO - rmtree: /builds/slave/test/build 18:25:35 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:25:37 INFO - Running post-action listener: _resource_record_post_action 18:25:37 INFO - [mozharness: 2017-04-22 01:25:37.619522Z] Finished clobber step (success) 18:25:37 INFO - [mozharness: 2017-04-22 01:25:37.619680Z] Running read-buildbot-config step. 18:25:37 INFO - Running pre-action listener: _resource_record_pre_action 18:25:37 INFO - Running main action method: read_buildbot_config 18:25:37 INFO - Using buildbot properties: 18:25:37 INFO - { 18:25:37 INFO - "project": "", 18:25:37 INFO - "product": "firefox", 18:25:37 INFO - "script_repo_revision": "default", 18:25:37 INFO - "builddir": "pine_yosemite_r7_test-dromaeojs-e10s", 18:25:37 INFO - "repository": "", 18:25:37 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 pine talos dromaeojs-e10s", 18:25:37 INFO - "buildid": "20170421151719", 18:25:37 INFO - "pgo_build": "False", 18:25:37 INFO - "basedir": "/builds/slave/test", 18:25:37 INFO - "buildnumber": 2, 18:25:37 INFO - "slavename": "t-yosemite-r7-0150", 18:25:37 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 18:25:37 INFO - "platform": "yosemite_r7", 18:25:37 INFO - "scheduler": "tests-pine-macosx64-talos", 18:25:37 INFO - "branch": "pine", 18:25:37 INFO - "repo_path": "projects/pine", 18:25:37 INFO - "revision": "73d3f203c63c5aec21b15d10e36783c592c18d13", 18:25:37 INFO - "stage_platform": "macosx64", 18:25:37 INFO - "builduid": "307d785614ac456a8655988a8892ce9c", 18:25:37 INFO - "slavebuilddir": "test" 18:25:37 INFO - } 18:25:37 INFO - Found installer url https://queue.taskcluster.net/v1/task/QAPOB5PRRoOcPDxZAQEj9w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 18:25:37 INFO - Running post-action listener: _resource_record_post_action 18:25:37 INFO - [mozharness: 2017-04-22 01:25:37.623027Z] Finished read-buildbot-config step (success) 18:25:37 INFO - [mozharness: 2017-04-22 01:25:37.623154Z] Running download-and-extract step. 18:25:37 INFO - Running pre-action listener: _resource_record_pre_action 18:25:37 INFO - Running main action method: download_and_extract 18:25:37 INFO - mkdir: /builds/slave/test/build/tests 18:25:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:25:37 INFO - trying https://queue.taskcluster.net/v1/task/QAPOB5PRRoOcPDxZAQEj9w/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 18:25:37 INFO - Downloading https://queue.taskcluster.net/v1/task/QAPOB5PRRoOcPDxZAQEj9w/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 18:25:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QAPOB5PRRoOcPDxZAQEj9w/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 18:25:38 INFO - Downloaded 1325 bytes. 18:25:38 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 18:25:38 INFO - Using the following test package requirements: 18:25:38 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:25:38 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 18:25:38 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 18:25:38 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:25:38 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 18:25:38 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:25:38 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 18:25:38 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:25:38 INFO - u'jsshell-mac.zip'], 18:25:38 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:25:38 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 18:25:38 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 18:25:38 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:25:38 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 18:25:38 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:25:38 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 18:25:38 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:25:38 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 18:25:38 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:25:38 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 18:25:38 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:25:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QAPOB5PRRoOcPDxZAQEj9w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 18:25:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QAPOB5PRRoOcPDxZAQEj9w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:25:38 INFO - Fetch https://queue.taskcluster.net/v1/task/QAPOB5PRRoOcPDxZAQEj9w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 18:25:40 INFO - Content-Length response header: 52526372 18:25:40 INFO - Bytes received: 52526372 18:25:56 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 18:25:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QAPOB5PRRoOcPDxZAQEj9w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 18:25:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QAPOB5PRRoOcPDxZAQEj9w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:25:56 INFO - Fetch https://queue.taskcluster.net/v1/task/QAPOB5PRRoOcPDxZAQEj9w/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 18:25:58 INFO - Content-Length response header: 52526372 18:25:58 INFO - Bytes received: 52526372 18:26:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QAPOB5PRRoOcPDxZAQEj9w/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 18:26:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QAPOB5PRRoOcPDxZAQEj9w/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 18:26:13 INFO - Fetch https://queue.taskcluster.net/v1/task/QAPOB5PRRoOcPDxZAQEj9w/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 18:26:14 INFO - Content-Length response header: 14408811 18:26:14 INFO - Bytes received: 14408811 18:26:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:26:14 INFO - trying https://queue.taskcluster.net/v1/task/QAPOB5PRRoOcPDxZAQEj9w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:26:14 INFO - Downloading https://queue.taskcluster.net/v1/task/QAPOB5PRRoOcPDxZAQEj9w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 18:26:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QAPOB5PRRoOcPDxZAQEj9w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 18:26:16 INFO - Downloaded 71108496 bytes. 18:26:16 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/QAPOB5PRRoOcPDxZAQEj9w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:26:16 INFO - mkdir: /builds/slave/test/properties 18:26:16 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:26:16 INFO - Writing to file /builds/slave/test/properties/build_url 18:26:16 INFO - Contents: 18:26:16 INFO - build_url:https://queue.taskcluster.net/v1/task/QAPOB5PRRoOcPDxZAQEj9w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:26:17 INFO - Running post-action listener: _resource_record_post_action 18:26:17 INFO - Running post-action listener: set_extra_try_arguments 18:26:17 INFO - [mozharness: 2017-04-22 01:26:17.454176Z] Finished download-and-extract step (success) 18:26:17 INFO - [mozharness: 2017-04-22 01:26:17.454314Z] Running populate-webroot step. 18:26:17 INFO - Running pre-action listener: _resource_record_pre_action 18:26:17 INFO - Running main action method: populate_webroot 18:26:17 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:26:17 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:26:17 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:26:17 INFO - '--tppagecycles', 18:26:17 INFO - '7'], 18:26:17 INFO - 'tests': ['tsvgm']}}, 18:26:17 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:26:17 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:26:17 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:26:17 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:26:17 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:26:17 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:26:17 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 18:26:17 INFO - 'talos_options': ['--disable-e10s'], 18:26:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:26:17 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:26:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:26:17 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 18:26:17 INFO - 'talos_options': ['--disable-e10s'], 18:26:17 INFO - 'tests': ['damp', 'tps']}, 18:26:17 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:26:17 INFO - 'tests': ['damp', 'tps']}, 18:26:17 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:26:17 INFO - 'tests': ['dromaeo_dom']}, 18:26:17 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:26:17 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:26:17 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 18:26:17 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 18:26:17 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:26:17 INFO - 'tests': ['a11yr', 18:26:17 INFO - 'ts_paint', 18:26:17 INFO - 'tpaint', 18:26:17 INFO - 'sessionrestore', 18:26:17 INFO - 'sessionrestore_no_auto_restore', 18:26:17 INFO - 'tabpaint']}, 18:26:17 INFO - 'other-e10s': {'tests': ['a11yr', 18:26:17 INFO - 'ts_paint', 18:26:17 INFO - 'tpaint', 18:26:17 INFO - 'sessionrestore', 18:26:17 INFO - 'sessionrestore_no_auto_restore', 18:26:17 INFO - 'tabpaint']}, 18:26:17 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:26:17 INFO - 'ts_paint', 18:26:17 INFO - 'tpaint', 18:26:17 INFO - 'sessionrestore', 18:26:17 INFO - 'sessionrestore_no_auto_restore', 18:26:17 INFO - 'tabpaint']}, 18:26:17 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:26:17 INFO - 'ts_paint', 18:26:17 INFO - 'tpaint', 18:26:17 INFO - 'sessionrestore', 18:26:17 INFO - 'sessionrestore_no_auto_restore', 18:26:17 INFO - 'tabpaint']}, 18:26:17 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:26:17 INFO - 'tests': ['a11yr', 18:26:17 INFO - 'ts_paint', 18:26:17 INFO - 'tpaint', 18:26:17 INFO - 'sessionrestore', 18:26:17 INFO - 'sessionrestore_no_auto_restore', 18:26:17 INFO - 'tabpaint']}, 18:26:17 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:26:17 INFO - 'tests': ['a11yr', 18:26:17 INFO - 'ts_paint', 18:26:17 INFO - 'tpaint', 18:26:17 INFO - 'sessionrestore', 18:26:17 INFO - 'sessionrestore_no_auto_restore', 18:26:17 INFO - 'tabpaint']}, 18:26:17 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 18:26:17 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 18:26:17 INFO - 'bloom_basic_ref']}, 18:26:17 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:26:17 INFO - 'tests': ['tsvgx', 18:26:17 INFO - 'tsvgr_opacity', 18:26:17 INFO - 'tart', 18:26:17 INFO - 'tscrollx', 18:26:17 INFO - 'cart', 18:26:17 INFO - 'tsvg_static']}, 18:26:17 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:26:17 INFO - 'tsvgr_opacity', 18:26:17 INFO - 'tart', 18:26:17 INFO - 'tscrollx', 18:26:17 INFO - 'cart', 18:26:17 INFO - 'tsvg_static']}, 18:26:17 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 18:26:17 INFO - 'talos_options': ['--disable-e10s'], 18:26:17 INFO - 'tests': ['tp5o']}, 18:26:17 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:26:17 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 18:26:17 INFO - 'talos_options': ['--disable-e10s', 18:26:17 INFO - '--xperf_path', 18:26:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:26:17 INFO - 'tests': ['tp5n']}, 18:26:17 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:26:17 INFO - 'talos_options': ['--xperf_path', 18:26:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:26:17 INFO - 'tests': ['tp5n']}}, 18:26:17 INFO - 'talos.zip': {'path': '', 18:26:17 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:26:17 INFO - Running post-action listener: _resource_record_post_action 18:26:17 INFO - [mozharness: 2017-04-22 01:26:17.473583Z] Finished populate-webroot step (success) 18:26:17 INFO - [mozharness: 2017-04-22 01:26:17.473684Z] Running create-virtualenv step. 18:26:17 INFO - Running pre-action listener: _resource_record_pre_action 18:26:17 INFO - Running main action method: create_virtualenv 18:26:17 INFO - Creating virtualenv /builds/slave/test/build/venv 18:26:17 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 18:26:17 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:26:17 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:26:17 INFO - Using real prefix '/tools/python27' 18:26:17 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:26:18 INFO - Installing distribute.............................................................................................................................................................................................done. 18:26:21 INFO - Installing pip.................done. 18:26:21 INFO - Return code: 0 18:26:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:26:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:26:21 INFO - Reading from file tmpfile_stdout 18:26:21 INFO - Output received: 18:26:21 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 18:26:21 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:26:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:26:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:26:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:26:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:26:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101b1bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b1e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa961d7dd50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018f9d30>, '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 0x10181fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1018eebe8>, '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.8KJd9FRLdy/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.nWWSZQnhlI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/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 18:26:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 18:26:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:26:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8KJd9FRLdy/Render', 18:26:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:26:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:26:21 INFO - 'HOME': '/Users/cltbld', 18:26:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:26:21 INFO - 'LOGNAME': 'cltbld', 18:26:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:26:21 INFO - 'MOZ_NO_REMOTE': '1', 18:26:21 INFO - 'NO_EM_RESTART': '1', 18:26:21 INFO - 'PAGER': '/bin/cat', 18:26:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:26:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:26:21 INFO - 'PWD': '/builds/slave/test', 18:26:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:26:21 INFO - 'SHELL': '/bin/bash', 18:26:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nWWSZQnhlI/Listeners', 18:26:21 INFO - 'TMPDIR': '/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/', 18:26:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:26:21 INFO - 'USER': 'cltbld', 18:26:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:26:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:26:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:26:21 INFO - 'XPC_FLAGS': '0x0', 18:26:21 INFO - 'XPC_SERVICE_NAME': '0', 18:26:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:26:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:26:21 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 18:26:21 INFO - Cleaning up... 18:26:21 INFO - Return code: 0 18:26:21 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:26:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:26:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:26:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:26:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:26:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101b1bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b1e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa961d7dd50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018f9d30>, '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 0x10181fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1018eebe8>, '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.8KJd9FRLdy/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.nWWSZQnhlI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/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 18:26:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 18:26:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 18:26:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8KJd9FRLdy/Render', 18:26:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:26:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:26:21 INFO - 'HOME': '/Users/cltbld', 18:26:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:26:21 INFO - 'LOGNAME': 'cltbld', 18:26:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:26:21 INFO - 'MOZ_NO_REMOTE': '1', 18:26:21 INFO - 'NO_EM_RESTART': '1', 18:26:21 INFO - 'PAGER': '/bin/cat', 18:26:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:26:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:26:21 INFO - 'PWD': '/builds/slave/test', 18:26:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:26:21 INFO - 'SHELL': '/bin/bash', 18:26:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nWWSZQnhlI/Listeners', 18:26:21 INFO - 'TMPDIR': '/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/', 18:26:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:26:21 INFO - 'USER': 'cltbld', 18:26:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:26:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:26:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:26:21 INFO - 'XPC_FLAGS': '0x0', 18:26:21 INFO - 'XPC_SERVICE_NAME': '0', 18:26:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:26:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:26:21 INFO - Downloading/unpacking psutil>=3.1.1 18:26:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:26:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:26:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:26:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:26:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:26:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:26:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:26:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:26:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:26:25 INFO - Installing collected packages: psutil 18:26:25 INFO - Running setup.py install for psutil 18:26:25 INFO - building 'psutil._psutil_osx' extension 18:26:25 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 18:26:25 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 18:26:25 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 18:26:26 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 18:26:26 INFO - building 'psutil._psutil_posix' extension 18:26:26 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 18:26:26 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:26:26 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:26:26 INFO - ^ 18:26:26 INFO - 1 warning generated. 18:26:26 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 18:26:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:26:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:26:26 INFO - Successfully installed psutil 18:26:26 INFO - Cleaning up... 18:26:26 INFO - Return code: 0 18:26:26 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:26:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:26:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:26:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:26:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:26:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101b1bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b1e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa961d7dd50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018f9d30>, '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 0x10181fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1018eebe8>, '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.8KJd9FRLdy/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.nWWSZQnhlI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/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 18:26:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:26:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:26:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8KJd9FRLdy/Render', 18:26:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:26:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:26:26 INFO - 'HOME': '/Users/cltbld', 18:26:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:26:26 INFO - 'LOGNAME': 'cltbld', 18:26:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:26:26 INFO - 'MOZ_NO_REMOTE': '1', 18:26:26 INFO - 'NO_EM_RESTART': '1', 18:26:26 INFO - 'PAGER': '/bin/cat', 18:26:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:26:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:26:26 INFO - 'PWD': '/builds/slave/test', 18:26:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:26:26 INFO - 'SHELL': '/bin/bash', 18:26:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nWWSZQnhlI/Listeners', 18:26:26 INFO - 'TMPDIR': '/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/', 18:26:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:26:26 INFO - 'USER': 'cltbld', 18:26:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:26:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:26:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:26:26 INFO - 'XPC_FLAGS': '0x0', 18:26:26 INFO - 'XPC_SERVICE_NAME': '0', 18:26:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:26:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:26:26 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:26:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:26:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:26:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:26:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:26:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:26:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:26:29 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:26:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:26:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:26:29 INFO - Installing collected packages: mozsystemmonitor 18:26:29 INFO - Running setup.py install for mozsystemmonitor 18:26:29 INFO - Successfully installed mozsystemmonitor 18:26:29 INFO - Cleaning up... 18:26:29 INFO - Return code: 0 18:26:29 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:26:29 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')]} 18:26:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:26:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26:29 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')]} 18:26:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:26:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101b1bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b1e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa961d7dd50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018f9d30>, '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 0x10181fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1018eebe8>, '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.8KJd9FRLdy/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.nWWSZQnhlI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/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 18:26:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 18:26:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 18:26:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8KJd9FRLdy/Render', 18:26:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:26:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:26:30 INFO - 'HOME': '/Users/cltbld', 18:26:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:26:30 INFO - 'LOGNAME': 'cltbld', 18:26:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:26:30 INFO - 'MOZ_NO_REMOTE': '1', 18:26:30 INFO - 'NO_EM_RESTART': '1', 18:26:30 INFO - 'PAGER': '/bin/cat', 18:26:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:26:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:26:30 INFO - 'PWD': '/builds/slave/test', 18:26:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:26:30 INFO - 'SHELL': '/bin/bash', 18:26:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nWWSZQnhlI/Listeners', 18:26:30 INFO - 'TMPDIR': '/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/', 18:26:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:26:30 INFO - 'USER': 'cltbld', 18:26:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:26:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:26:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:26:30 INFO - 'XPC_FLAGS': '0x0', 18:26:30 INFO - 'XPC_SERVICE_NAME': '0', 18:26:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:26:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:26:30 INFO - Downloading/unpacking jsonschema==2.5.1 18:26:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:26:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:26:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:26:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:26:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:26:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:26:33 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:26:33 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:26:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:26:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:26:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:26:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:26:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:26:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:26:33 INFO - Downloading functools32-3.2.3-2.tar.gz 18:26:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:26:33 INFO - warning: no files found matching '*.txt' 18:26:33 INFO - no previously-included directories found matching 'build' 18:26:33 INFO - no previously-included directories found matching 'dist' 18:26:33 INFO - no previously-included directories found matching '.git*' 18:26:33 INFO - Installing collected packages: jsonschema, functools32 18:26:33 INFO - Running setup.py install for functools32 18:26:33 INFO - warning: no files found matching '*.txt' 18:26:33 INFO - no previously-included directories found matching 'build' 18:26:33 INFO - no previously-included directories found matching 'dist' 18:26:33 INFO - no previously-included directories found matching '.git*' 18:26:33 INFO - Successfully installed jsonschema functools32 18:26:33 INFO - Cleaning up... 18:26:34 INFO - Return code: 0 18:26:34 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:26:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:26:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:26:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:26:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:26:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x101b1bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b1e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa961d7dd50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018f9d30>, '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 0x10181fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1018eebe8>, '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.8KJd9FRLdy/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.nWWSZQnhlI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/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 18:26:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 18:26:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 18:26:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8KJd9FRLdy/Render', 18:26:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:26:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:26:34 INFO - 'HOME': '/Users/cltbld', 18:26:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:26:34 INFO - 'LOGNAME': 'cltbld', 18:26:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:26:34 INFO - 'MOZ_NO_REMOTE': '1', 18:26:34 INFO - 'NO_EM_RESTART': '1', 18:26:34 INFO - 'PAGER': '/bin/cat', 18:26:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:26:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:26:34 INFO - 'PWD': '/builds/slave/test', 18:26:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:26:34 INFO - 'SHELL': '/bin/bash', 18:26:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nWWSZQnhlI/Listeners', 18:26:34 INFO - 'TMPDIR': '/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/', 18:26:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:26:34 INFO - 'USER': 'cltbld', 18:26:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:26:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:26:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:26:34 INFO - 'XPC_FLAGS': '0x0', 18:26:34 INFO - 'XPC_SERVICE_NAME': '0', 18:26:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:26:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:26:34 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:26:34 INFO - Cleaning up... 18:26:34 INFO - Return code: 0 18:26:34 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:26:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:26:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:26:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:26:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:26:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101b1bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b1e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa961d7dd50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018f9d30>, '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 0x10181fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1018eebe8>, '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.8KJd9FRLdy/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.nWWSZQnhlI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/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 18:26:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:26:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:26:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8KJd9FRLdy/Render', 18:26:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:26:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:26:34 INFO - 'HOME': '/Users/cltbld', 18:26:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:26:34 INFO - 'LOGNAME': 'cltbld', 18:26:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:26:34 INFO - 'MOZ_NO_REMOTE': '1', 18:26:34 INFO - 'NO_EM_RESTART': '1', 18:26:34 INFO - 'PAGER': '/bin/cat', 18:26:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:26:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:26:34 INFO - 'PWD': '/builds/slave/test', 18:26:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:26:34 INFO - 'SHELL': '/bin/bash', 18:26:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nWWSZQnhlI/Listeners', 18:26:34 INFO - 'TMPDIR': '/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/', 18:26:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:26:34 INFO - 'USER': 'cltbld', 18:26:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:26:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:26:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:26:34 INFO - 'XPC_FLAGS': '0x0', 18:26:34 INFO - 'XPC_SERVICE_NAME': '0', 18:26:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:26:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:26:34 INFO - Downloading/unpacking blobuploader==1.2.4 18:26:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:26:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:26:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:26:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:26:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:26:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:26:37 INFO - Downloading blobuploader-1.2.4.tar.gz 18:26:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:26:37 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:26:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:26:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:26:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:26:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:26:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:26:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:26:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:26:37 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:26:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:26:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:26:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:26:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:26:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:26:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:26:38 INFO - Downloading docopt-0.6.1.tar.gz 18:26:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:26:38 INFO - Installing collected packages: blobuploader, requests, docopt 18:26:38 INFO - Running setup.py install for blobuploader 18:26:38 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:26:38 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:26:38 INFO - Running setup.py install for requests 18:26:39 INFO - Running setup.py install for docopt 18:26:39 INFO - Successfully installed blobuploader requests docopt 18:26:39 INFO - Cleaning up... 18:26:39 INFO - Return code: 0 18:26:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:26:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:26:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:26:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:26:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:26:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101b1bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b1e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa961d7dd50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018f9d30>, '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 0x10181fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1018eebe8>, '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.8KJd9FRLdy/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.nWWSZQnhlI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/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 18:26:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:26:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:26:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8KJd9FRLdy/Render', 18:26:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:26:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:26:39 INFO - 'HOME': '/Users/cltbld', 18:26:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:26:39 INFO - 'LOGNAME': 'cltbld', 18:26:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:26:39 INFO - 'MOZ_NO_REMOTE': '1', 18:26:39 INFO - 'NO_EM_RESTART': '1', 18:26:39 INFO - 'PAGER': '/bin/cat', 18:26:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:26:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:26:39 INFO - 'PWD': '/builds/slave/test', 18:26:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:26:39 INFO - 'SHELL': '/bin/bash', 18:26:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nWWSZQnhlI/Listeners', 18:26:39 INFO - 'TMPDIR': '/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/', 18:26:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:26:39 INFO - 'USER': 'cltbld', 18:26:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:26:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:26:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:26:39 INFO - 'XPC_FLAGS': '0x0', 18:26:39 INFO - 'XPC_SERVICE_NAME': '0', 18:26:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:26:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:26:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:26:39 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-aLzAh4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:26:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:26:39 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-Tmy1eo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:26:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:26:39 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-xniWUX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:26:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:26:39 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-gZcwFm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:26:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:26:40 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-sCl1KM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:26:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:26:40 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-WpSCS5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:26:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:26:40 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-yWZYEF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:26:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:26:40 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-IZLDeD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:26:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:26:40 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-MwDRfd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:26:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:26:40 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-2tk6At-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:26:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:26:40 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-Jn9Uy2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:26:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:26:40 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-R5peSc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:26:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:26:40 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-RN4dv4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:26:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:26:40 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-rYG22j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:26:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:26:41 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-pWtrtD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:26:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:26:41 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-pH6d8h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:26:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:26:41 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-Hmzakt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:26:41 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:26:41 INFO - Running setup.py install for manifestparser 18:26:41 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:26:41 INFO - Running setup.py install for mozcrash 18:26:41 INFO - Running setup.py install for mozdebug 18:26:41 INFO - Running setup.py install for mozdevice 18:26:41 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:26:41 INFO - Running setup.py install for mozfile 18:26:42 INFO - Running setup.py install for mozhttpd 18:26:42 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:26:42 INFO - Running setup.py install for mozinfo 18:26:42 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:26:42 INFO - Running setup.py install for mozInstall 18:26:42 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:26:42 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:26:42 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:26:42 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:26:42 INFO - Running setup.py install for mozleak 18:26:42 INFO - Running setup.py install for mozlog 18:26:42 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:26:42 INFO - Running setup.py install for moznetwork 18:26:42 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:26:42 INFO - Running setup.py install for mozprocess 18:26:43 INFO - Running setup.py install for mozprofile 18:26:43 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:26:43 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:26:43 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:26:43 INFO - Running setup.py install for mozrunner 18:26:43 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:26:43 INFO - Running setup.py install for mozscreenshot 18:26:43 INFO - Running setup.py install for moztest 18:26:43 INFO - Running setup.py install for mozversion 18:26:43 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:26:43 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:26:43 INFO - Cleaning up... 18:26:43 INFO - Return code: 0 18:26:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:26:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:26:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:26:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:26:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:26:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101b1bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b1e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa961d7dd50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018f9d30>, '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 0x10181fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1018eebe8>, '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.8KJd9FRLdy/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.nWWSZQnhlI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/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 18:26:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:26:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:26:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8KJd9FRLdy/Render', 18:26:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:26:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:26:43 INFO - 'HOME': '/Users/cltbld', 18:26:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:26:43 INFO - 'LOGNAME': 'cltbld', 18:26:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:26:43 INFO - 'MOZ_NO_REMOTE': '1', 18:26:43 INFO - 'NO_EM_RESTART': '1', 18:26:43 INFO - 'PAGER': '/bin/cat', 18:26:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:26:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:26:43 INFO - 'PWD': '/builds/slave/test', 18:26:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:26:43 INFO - 'SHELL': '/bin/bash', 18:26:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nWWSZQnhlI/Listeners', 18:26:43 INFO - 'TMPDIR': '/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/', 18:26:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:26:43 INFO - 'USER': 'cltbld', 18:26:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:26:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:26:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:26:43 INFO - 'XPC_FLAGS': '0x0', 18:26:43 INFO - 'XPC_SERVICE_NAME': '0', 18:26:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:26:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:26:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:26:44 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-VHRMn5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:26:44 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 18:26:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:26:44 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-gDJu2C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:26:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:26:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:26:44 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-3J8XO1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:26:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:26:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:26:44 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-CzWPVW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:26:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 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)) 18:26:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:26:44 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-xDMMml-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:26:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 18:26:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:26:44 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-w_mkTA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:26:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 18:26:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:26:44 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-YskKV9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:26:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 18:26:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:26:44 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-TLGM4i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:26:45 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)) 18:26:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:26:45 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-43ynjp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:26:45 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)) 18:26:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:26:45 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-PctxAp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:26:45 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)) 18:26:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:26:45 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-eWwkuE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:26:45 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 18:26:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:26:45 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-dAK2Kj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:26:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 18:26:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:26:45 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-foYuFG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:26:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 18:26:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:26:45 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-MsCP4Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:26:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 18:26:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:26:45 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-dDgiFQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:26:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 18:26:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:26:45 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-62Cr3s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:26:45 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)) 18:26:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:26:45 INFO - Running setup.py (path:/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/pip-bAs5aX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:26:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 18:26:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:26:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:26:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:26:45 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:26:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:26:46 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:26:46 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 18:26:46 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 18:26:46 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 18:26:46 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 18:26:46 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 18:26:46 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 18:26:48 INFO - Downloading blessings-1.6.tar.gz 18:26:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:26:48 INFO - Installing collected packages: blessings 18:26:48 INFO - Running setup.py install for blessings 18:26:48 INFO - Successfully installed blessings 18:26:48 INFO - Cleaning up... 18:26:48 INFO - Return code: 0 18:26:48 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:26:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:26:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:26:49 INFO - Reading from file tmpfile_stdout 18:26:49 INFO - Current package versions: 18:26:49 INFO - blessings == 1.6 18:26:49 INFO - blobuploader == 1.2.4 18:26:49 INFO - docopt == 0.6.1 18:26:49 INFO - functools32 == 3.2.3-2 18:26:49 INFO - jsonschema == 2.5.1 18:26:49 INFO - manifestparser == 1.1 18:26:49 INFO - mozInstall == 1.12 18:26:49 INFO - mozcrash == 1.0 18:26:49 INFO - mozdebug == 0.1 18:26:49 INFO - mozdevice == 0.50 18:26:49 INFO - mozfile == 1.2 18:26:49 INFO - mozhttpd == 0.7 18:26:49 INFO - mozinfo == 0.9 18:26:49 INFO - mozleak == 0.1 18:26:49 INFO - mozlog == 3.4 18:26:49 INFO - moznetwork == 0.27 18:26:49 INFO - mozprocess == 0.25 18:26:49 INFO - mozprofile == 0.28 18:26:49 INFO - mozrunner == 6.13 18:26:49 INFO - mozscreenshot == 0.1 18:26:49 INFO - mozsystemmonitor == 0.3 18:26:49 INFO - moztest == 0.8 18:26:49 INFO - mozversion == 1.4 18:26:49 INFO - psutil == 3.1.1 18:26:49 INFO - requests == 1.2.3 18:26:49 INFO - wsgiref == 0.1.2 18:26:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:26: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')]} 18:26:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:26:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26: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')]} 18:26:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:26:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26:49 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 0x101b1bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b1e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa961d7dd50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018f9d30>, '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 0x10181fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1018eebe8>, '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.8KJd9FRLdy/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.nWWSZQnhlI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/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 18:26:49 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 18:26:49 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 18:26:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8KJd9FRLdy/Render', 18:26:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:26:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:26:49 INFO - 'HOME': '/Users/cltbld', 18:26:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:26:49 INFO - 'LOGNAME': 'cltbld', 18:26:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:26:49 INFO - 'MOZ_NO_REMOTE': '1', 18:26:49 INFO - 'NO_EM_RESTART': '1', 18:26:49 INFO - 'PAGER': '/bin/cat', 18:26:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:26:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:26:49 INFO - 'PWD': '/builds/slave/test', 18:26:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:26:49 INFO - 'SHELL': '/bin/bash', 18:26:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nWWSZQnhlI/Listeners', 18:26:49 INFO - 'TMPDIR': '/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/', 18:26:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:26:49 INFO - 'USER': 'cltbld', 18:26:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:26:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:26:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:26:49 INFO - 'XPC_FLAGS': '0x0', 18:26:49 INFO - 'XPC_SERVICE_NAME': '0', 18:26:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:26:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:26:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:26:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 18:26:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 18:26:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:26:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 18:26:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 18:26:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 18:26:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 18:26:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 18:26:49 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:26:49 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:26:49 INFO - Cleaning up... 18:26:49 INFO - Return code: 0 18:26:49 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:26: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')]} 18:26:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:26:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26: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')]} 18:26:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:26:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:26:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101b1bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b1e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa961d7dd50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018f9d30>, '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 0x10181fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1018eebe8>, '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.8KJd9FRLdy/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.nWWSZQnhlI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/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 18:26:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 18:26:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 18:26:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8KJd9FRLdy/Render', 18:26:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:26:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:26:49 INFO - 'HOME': '/Users/cltbld', 18:26:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:26:49 INFO - 'LOGNAME': 'cltbld', 18:26:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:26:49 INFO - 'MOZ_NO_REMOTE': '1', 18:26:49 INFO - 'NO_EM_RESTART': '1', 18:26:49 INFO - 'PAGER': '/bin/cat', 18:26:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:26:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:26:49 INFO - 'PWD': '/builds/slave/test', 18:26:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:26:49 INFO - 'SHELL': '/bin/bash', 18:26:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nWWSZQnhlI/Listeners', 18:26:49 INFO - 'TMPDIR': '/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/', 18:26:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:26:49 INFO - 'USER': 'cltbld', 18:26:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:26:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:26:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:26:49 INFO - 'XPC_FLAGS': '0x0', 18:26:49 INFO - 'XPC_SERVICE_NAME': '0', 18:26:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:26:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:26:49 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:26:49 INFO - Cleaning up... 18:26:49 INFO - Return code: 0 18:26:49 INFO - Running post-action listener: _resource_record_post_action 18:26:49 INFO - Running post-action listener: _start_resource_monitoring 18:26:49 INFO - Starting resource monitoring. 18:26:49 INFO - [mozharness: 2017-04-22 01:26:49.816800Z] Finished create-virtualenv step (success) 18:26:49 INFO - [mozharness: 2017-04-22 01:26:49.817729Z] Running install step. 18:26:49 INFO - Running pre-action listener: _resource_record_pre_action 18:26:49 INFO - Running main action method: install 18:26:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:26:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:26:50 INFO - Reading from file tmpfile_stdout 18:26:50 INFO - Detecting whether we're running mozinstall >=1.0... 18:26:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:26:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:26:50 INFO - Reading from file tmpfile_stdout 18:26:50 INFO - Output received: 18:26:50 INFO - Usage: mozinstall [options] installer 18:26:50 INFO - Options: 18:26:50 INFO - -h, --help show this help message and exit 18:26:50 INFO - -d DEST, --destination=DEST 18:26:50 INFO - Directory to install application into. [default: 18:26:50 INFO - "/builds/slave/test"] 18:26:50 INFO - --app=APP Application being installed. [default: firefox] 18:26:50 INFO - mkdir: /builds/slave/test/build/application 18:26:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 18:26:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 18:27:09 INFO - Reading from file tmpfile_stdout 18:27:09 INFO - Output received: 18:27:09 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:27:09 INFO - Running post-action listener: _resource_record_post_action 18:27:09 INFO - [mozharness: 2017-04-22 01:27:09.888625Z] Finished install step (success) 18:27:09 INFO - [mozharness: 2017-04-22 01:27:09.888784Z] Running run-tests step. 18:27:09 INFO - Running pre-action listener: _resource_record_pre_action 18:27:09 INFO - Running main action method: run_tests 18:27:09 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:27:09 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:27:09 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:27:09 INFO - 2017-04-21 18:27:09.903 screenresolution[2466:36888] starting screenresolution argv=screenresolution get 18:27:09 INFO - 2017-04-21 18:27:09.906 screenresolution[2466:36888] Display 0: 1600x1200x32@60 18:27:09 INFO - 2017-04-21 18:27:09.912 screenresolution[2467:36891] starting screenresolution argv=screenresolution list 18:27:09 INFO - Available Modes on Display 0 18:27:09 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:27:09 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:27:09 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:27:09 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:27:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:27:10 INFO - Intel Iris: 18:27:10 INFO - Chipset Model: Intel Iris 18:27:10 INFO - Type: GPU 18:27:10 INFO - Bus: Built-In 18:27:10 INFO - VRAM (Dynamic, Max): 1536 MB 18:27:10 INFO - Vendor: Intel (0x8086) 18:27:10 INFO - Device ID: 0x0a2e 18:27:10 INFO - Revision ID: 0x0009 18:27:10 INFO - Displays: 18:27:10 INFO - Display: 18:27:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:27:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:27:10 INFO - Display Serial Number: 1600x1200 60 18:27:10 INFO - Main Display: Yes 18:27:10 INFO - Mirror: Off 18:27:10 INFO - Online: Yes 18:27:10 INFO - Rotation: Supported 18:27:10 INFO - Return code: 0 18:27:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:27:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:27:10 INFO - Python 2.7.3 18:27:10 INFO - Return code: 0 18:27:10 INFO - grabbing minidump binary from tooltool 18:27:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:27:10 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 0x1018f9d30>, '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 0x10181fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1018eebe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:27:10 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 18:27:10 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 18:27:10 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 18:27:10 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:27:10 INFO - Return code: 0 18:27:10 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:27:10 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:27:10 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:27:10 INFO - ENV: RUST_BACKTRACE is now 1 18:27:10 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:27:10 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:27:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Pine', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QAPOB5PRRoOcPDxZAQEj9w/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0150', '--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 18:27:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Pine --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/QAPOB5PRRoOcPDxZAQEj9w/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0150 --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 18:27:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8KJd9FRLdy/Render', 18:27:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:27:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:27:10 INFO - 'HOME': '/Users/cltbld', 18:27:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:27:10 INFO - 'LOGNAME': 'cltbld', 18:27:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:27:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:27:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:27:10 INFO - 'MOZ_NO_REMOTE': '1', 18:27:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:27:10 INFO - 'NO_EM_RESTART': '1', 18:27:10 INFO - 'PAGER': '/bin/cat', 18:27:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:27:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:27:10 INFO - 'PWD': '/builds/slave/test', 18:27:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:27:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:27:10 INFO - 'RUST_BACKTRACE': '1', 18:27:10 INFO - 'SHELL': '/bin/bash', 18:27:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nWWSZQnhlI/Listeners', 18:27:10 INFO - 'TMPDIR': '/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/', 18:27:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:27:10 INFO - 'USER': 'cltbld', 18:27:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:27:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:27:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:27:10 INFO - 'XPC_FLAGS': '0x0', 18:27:10 INFO - 'XPC_SERVICE_NAME': '0', 18:27:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:27:10 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Pine', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QAPOB5PRRoOcPDxZAQEj9w/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0150', '--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 18:27:10 INFO - mozversion application_buildid: 20170421151719 18:27:10 INFO - mozversion application_changeset: 73d3f203c63c5aec21b15d10e36783c592c18d13 18:27:10 INFO - mozversion application_display_name: Nightly 18:27:10 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:27:10 INFO - mozversion application_name: Firefox 18:27:10 INFO - mozversion application_remotingname: firefox 18:27:10 INFO - mozversion application_repository: https://hg.mozilla.org/projects/pine 18:27:10 INFO - mozversion application_vendor: Mozilla 18:27:10 INFO - mozversion application_version: 55.0a1 18:27:10 INFO - mozversion platform_buildid: 20170421151719 18:27:10 INFO - mozversion platform_changeset: 73d3f203c63c5aec21b15d10e36783c592c18d13 18:27:10 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/pine 18:27:10 INFO - mozversion platform_version: 55.0a1 18:27:10 INFO - using testdate: 1492824430 18:27:10 INFO - actual date: 1492824430 18:27:10 INFO - starting webserver on 'localhost:60514' 18:27:10 INFO - SUITE-START | Running 2 tests 18:27:10 INFO - TEST-START | dromaeo_css 18:27:10 INFO - operating with platform_type : mac_ 18:27:10 INFO - Initialising browser for dromaeo_css test... 18:27:10 INFO - TEST-INFO | started process 2477 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/tmpEJuS4A/profile http://localhost:60514/getInfo.html) 18:27:13 INFO - PID 2477 | 2017-04-21 18:27:13.620 plugin-container[2479:37067] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a4b, name = 'com.apple.tsm.portname' 18:27:13 INFO - PID 2477 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:27:13 INFO - PID 2477 | 2017-04-21 18:27:13.622 plugin-container[2479:37067] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9f0f, name = 'com.apple.CFPasteboardClient' 18:27:13 INFO - PID 2477 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:27:13 INFO - PID 2477 | 2017-04-21 18:27:13.622 plugin-container[2479:37067] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:27:13 INFO - PID 2477 | __metrics Screen width/height:1600/1200 18:27:13 INFO - PID 2477 | colorDepth:24 18:27:13 INFO - PID 2477 | Browser inner width/height: 1024/689 18:27:13 INFO - PID 2477 | __metrics 18:27:13 ERROR - PID 2477 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3403: TypeError: this.tabs is undefined 18:27:14 INFO - TEST-INFO | 2477: exit 0 18:27:14 INFO - Browser initialized. 18:27:14 INFO - Running cycle 1/1 for dromaeo_css test... 18:27:14 INFO - TEST-INFO | started process 2480 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/tmpEJuS4A/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:27:17 INFO - PID 2480 | 2017-04-21 18:27:17.199 plugin-container[2481:37197] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x964b, name = 'com.apple.tsm.portname' 18:27:17 INFO - PID 2480 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:27:17 INFO - PID 2480 | 2017-04-21 18:27:17.200 plugin-container[2481:37197] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0f, name = 'com.apple.CFPasteboardClient' 18:27:17 INFO - PID 2480 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:27:17 INFO - PID 2480 | 2017-04-21 18:27:17.200 plugin-container[2481:37197] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:27:27 INFO - PID 2480 | RSS: Main: 236548096 18:27:27 INFO - PID 2480 | 18:30:31 INFO - PID 2480 | Cycle 1(1): loaded http://localhost:60514/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60514/tests/dromaeo/cssquery-ext.html) 18:30:32 INFO - PID 2480 | RSS: Main: 258052096 18:30:32 INFO - PID 2480 | 18:32:15 INFO - PID 2480 | ************************* 18:32:15 INFO - PID 2480 | A coding exception was thrown and uncaught in a Task. 18:32:15 INFO - PID 2480 | 18:32:15 ERROR - PID 2480 | Full message: TypeError: NetworkError when attempting to fetch resource. 18:32:15 INFO - PID 2480 | Full stack: 18:32:15 INFO - PID 2480 | ************************* 18:32:15 INFO - PID 2480 | ************************* 18:32:15 INFO - PID 2480 | A coding exception was thrown and uncaught in a Task. 18:32:15 INFO - PID 2480 | 18:32:15 ERROR - PID 2480 | Full message: TypeError: NetworkError when attempting to fetch resource. 18:32:15 INFO - PID 2480 | Full stack: 18:32:15 INFO - PID 2480 | ************************* 18:32:15 INFO - PID 2480 | ************************* 18:32:15 INFO - PID 2480 | A coding exception was thrown and uncaught in a Task. 18:32:15 INFO - PID 2480 | 18:32:15 ERROR - PID 2480 | Full message: TypeError: NetworkError when attempting to fetch resource. 18:32:15 INFO - PID 2480 | Full stack: 18:32:15 INFO - PID 2480 | ************************* 18:32:15 INFO - PID 2480 | ************************* 18:32:15 INFO - PID 2480 | A coding exception was thrown and uncaught in a Task. 18:32:15 INFO - PID 2480 | 18:32:15 ERROR - PID 2480 | Full message: TypeError: NetworkError when attempting to fetch resource. 18:32:15 INFO - PID 2480 | Full stack: 18:32:15 INFO - PID 2480 | ************************* 18:33:34 INFO - PID 2480 | Cycle 1(1): loaded http://localhost:60514/tests/dromaeo/cssquery-ext.html (next: http://localhost:60514/tests/dromaeo/cssquery-jquery.html) 18:33:35 INFO - PID 2480 | RSS: Main: 261435392 18:33:35 INFO - PID 2480 | 18:36:37 INFO - PID 2480 | Cycle 1(1): loaded http://localhost:60514/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60514/tests/dromaeo/cssquery-mootools.html) 18:36:37 INFO - PID 2480 | RSS: Main: 271474688 18:36:37 INFO - PID 2480 | 18:39:39 INFO - PID 2480 | Cycle 1(1): loaded http://localhost:60514/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60514/tests/dromaeo/cssquery-prototype.html) 18:39:40 INFO - PID 2480 | RSS: Main: 263868416 18:39:40 INFO - PID 2480 | 18:42:42 INFO - PID 2480 | Cycle 1(1): loaded http://localhost:60514/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60514/tests/dromaeo/cssquery-yui.html) 18:42:43 INFO - PID 2480 | RSS: Main: 273068032 18:42:43 INFO - PID 2480 | 18:45:45 INFO - PID 2480 | Cycle 1(1): loaded http://localhost:60514/tests/dromaeo/cssquery-yui.html (next: http://localhost:60514/tests/dromaeo/cssquery-dojo.html) 18:45:45 INFO - PID 2480 | RSS: Main: 267739136 18:45:45 INFO - PID 2480 | 18:45:45 INFO - PID 2480 | __start_tp_report 18:45:45 INFO - PID 2480 | _x_x_mozilla_page_load 18:45:45 INFO - PID 2480 | _x_x_mozilla_page_load_details 18:45:45 INFO - PID 2480 | |i|pagename|runs| 18:45:45 INFO - PID 2480 | |0;dojo.html;2909.316310666994;3598.37028520009;3600.8919732408035;3643.8360273787675;3664.633536646336;4658.650601204915;6067.726952287152;6094.048238764534;6253.405926436987;6256.812295631133;6158.938410615892;6235.345288744685;6240.812775616739;6255.62466252024;6317.936820631792;4595.931061034071;5518.751656175462;5519.199716041172;5530.308711411074;5539.335279766434;5434.83695489134;5510.1459273812625;5570.721463926808;5594.188842617818;5643.8024669136585;5458.8908221835345;5485.9725701371635;5533.916991245134;5543.722813859311;5554.638948468337;5495.642783219078;5500.1749737539285;5511.090670039439;5513.50378465936;5553.666779993213;5442;5465.207544906006;5475.260839786618;5507.146392309199;5563.777448902039;4590.724556526619;6117.143599896018;6145.340757995361;6197.349278325801;6227.470664993481;6768.390844823989;6782.491313151533;6789.830254243634;6791.966040169817;6805.149356330458;6835.07726456927;6889.448844092461;6892.483063770187;6896.689648965808;6908.447324214101;6228.626282423069;6241.906371404432;6260.624362538262;6374.426301632875;6386.840328991766;6942.618156001468;6958.304169583002;6993.440524758007;7008.334208250208;7019.508634395544;6360.745570177141;6429.292777794439;6462.935370646327;6615.966920165369;6703.932960670429;6629.502787290972;6800.285969973181;6803.863922721518;6807.965960170267;6812.284710105467;6433.871322573522;6504.544681872224;6760;6775.796726098127;6805.523613347018;6518.28298887122;6639.302873198245;6675.332466753286;6736.2253340865445;6766.49251306154;4392.407025051641;4506.369108324837;5326.5206131447585;5343.7060961647485;5345.251664766935;4444.599986001275;4760.571548560646;4765;4786.16242157621;4824.131656301899;5074.568661663726;5644.8024319148635;6221.782237621752;6314.905276420857;6357.04644303349;6853.520253582202;6859.485538584626;6863.38229559342;6873.278305777922;6888.035675005503;5032.421271553745;6310.716017779211;6330.430261276507;6378.968105159444;6400.743970241231;5784.479396854304;5904.822855314262;5927.9407205928255;5935.52515798735;6049.243844519435;5847.8245652630485;6856.24581296057;6933.82665433348;6975.1978522468535;7071.964640176766;6813.557118787461;6842.589444633338;6860.656967151723;6875.037494750639;6918.169819621678;6954.547954382949;6967.241775477778;7013.123359580052;7016.7544135954995;7045.718171273092;6450.387213214737;6541.574797638138;6669.866602667822;6700.798976030727;6754.324567543403;6532.281449040506;6825.52221344521;6888.793336199922;6889.896651550331;6892.827679307857;5243.68537887728;6365.077063825812;6600.933990660032;6615.404613584608;6632.933670663425;6102.206713127265;6570.605763654196;6570.8357291068105;6571.737130514726;6593.472522198117;5117.462666419972;5748.425157484218;6280.811575652738;6341.8731625368155;6362.04569314588;6617.933820661924;6647.268800431852;6659.300773418721;6670.466362691071;6671.566348187353;6429.485641148791;6456.9354306456335;6464.030395440722;6472.190976127984;6476.773312934025 18:45:45 INFO - PID 2480 | |1;ext.html;690.4338442477168;725.8112890648433;800.8318253166834;1256.666983249439;1262.242654407355;35987.64012359876;37192.25615487695;37255.999999999935;37278.813605932;37374.62625373746;37499.250014999685;37842.8107859461;37877.053073673174;37980.62019379805;37990.8100459498;7917.445778795487;8103.5948202589925;8359.164083591622;8602.139786021411;8610.225079742822;8628.266597339234;8646.481211127353;8727.345449091312;8733.737987860375;8752.256058235058;7867.291943725064;8687.174718401773;8740.825183496327;8748.518831464267;8769.25461335784;7919.485233459806;8062.8711980322805;8710.303175745925;8715.825683486295;8738.563071846414;8406.327493800482;8406.621702023362;8423.4945903246;8496.915030849736;8517.787055323604;36889.44665830015;37192.44211336832;37199.44200836989;37230.627693722985;37342.43986340207;3530.5586801649793;3554.502369668223;3560.3235385276716;3564.4475106358555;3575.4458059000895;24142.39644008914;29763.553546696818;30638.84680576605;30716.692833071607;30749;3136.184592006095;3160.4469217886776;3171.0962375722806;3182.4112539180096;3193.3772914281726;235614;239352;279762.99999999796;294783;297665;39669.20661586752;47159.5284047162;47404.57786266418;47997.76001120042;48125.51874481211;40316.79841600773;48998.10018998143;49600.78393336607;49605.751971240636;49668.75165624221;13676.179429234278;13751.724965500634;13768.655783605289;13864;13887.444502219798;11148.442577871074;11271.718207044889;11276.548938042386;11280.153988450898;11367.431628418546;10917.39954302505;13339.266340351374;13344.331133773285;13369.997250206274;13553.39009744564;5941.58408911372;5951.7383394310755;5968.791092311748;5995.340512543617;5996.16053752475;946.8779496296772;988.2785566536469;1001.449202938381;1033.5968972100886;1082.3451811653993;24564.877175614365;24657;24768;24772.628410573856;24784.876075619508;1135.1429670598645;1135.2053562506278;1172.202902026613;1174.6006357838376;1176.611718133014;12061.819072713917;12644.747105058032;12657.620271391874;12702.745945081231;12733.936330318289;285728;321106;321195;321799;322161.9459513531;54519;54616.453835461136;54750;54813.725931370886;54897.725511372184;1500.9174495402858;1521.5359315408684;1561.086764242908;1567.0675947811035;1575.2123938030984;20885.686714699597;21960.560788783816;22585.87070646494;23849;24158.75841241565;37613.24773504569;37932.62067379291;37934.43098353582;37950.0512487179;37971.43042854415;286050;286192;286443;286616;287209.56395218725;28609;28867.711322887342;29219.123426297247;33432;33736.66263337335;32891;42219.15561688811;42522.1495570093;43439;43517.564824352616;30162;30366.785328586608;30434.782608695405;30469.847650761603;30522.542161867146;24902.626460602743;29863;30305;30319.848400757855;30973.0708078758;36032.639673603975;36303.81848090742;36355.818220908724;36360.454593181654;36474.45288320624;26703.86648066747;26846.59730104011;29887.850560747058;30731;30948.69051309458;23735.52528949446;23892;24063.386188440854;24198.75801241965;24242.39394015093 18:45:45 INFO - PID 2480 | |2;jquery.html;427.0165083188589;429.0646390867907;431.1183629477717;448.056840351061;448.10602847319603;19681.01594920252;21316;22057.889710551426;22127.225547105856;22182.778172218274;19227.807721922778;19278.228870845185;19421.4173574793;19473.123709433075;20904.790952090476;6033.75864965402;6307.684615769216;6352.523560732963;6353.205849268841;6364.777232796853;5983.521318294548;6170.7223174957235;6195.876082478348;6243.750249989995;6244.219472565929;4958.677685950402;5105.897882042375;6198.349173336803;6198.8450288742915;6213.316535181127;5970.522358211333;6202.379762023784;6213.440790328847;6258.4680302174365;6264.185655864754;5224.73876306187;5567.248421463091;5578.191162281487;5587.7485513152005;5588.6646801192055;27728.584071238947;28143.155705328874;28192.859035704896;28243.293917652016;28450.573241401395;2274.4768703198356;2342.461233916209;2347.260612906929;2347.8708671023087;2349.6592994015773;15367;16442.588935276595;17856.732149017775;18183.63632727338;18456.907715461337;2125.107454868959;2167.2847960173262;2184.7159869217057;2187.58435897179;2188.4310079379316;160047.1997640016;161101.19449402677;162566.74866502604;163155;164074;12289.754204915851;12375.69060773488;12479.438425270884;12497.93751031239;12688.80966785499;16172.272247748879;16259.943103698222;16297.34810607586;16299.59251018734;16330.265138068815;8567.271781898484;8865.379423440427;8886.86669699955;9009.324300677476;9022.593983270768;8320.625571849281;8368.3305335573;8406.74779756596;8494.362922780816;8520.829583408296;7903.249191326815;8026.357891368676;8049.6377663005305;8054.556999364974;8102.270795628304;2151.4621344663833;2166.490874644489;2170.555036217572;2175.608390489727;2317.698699169131;943.2878176976476;955.2501286490071;973.5278389980854;1002.2783595811004;1012.2762225009154;21000.684989725167;21577.460563485725;21645.35063948084;21953.67069493927;22367.77632223657;826.2728798657263;954.1031430455488;999.4103478947311;1024.283001898659;1029.1818004686143;9337.813243735087;9477.431354118775;11383;11414.201005929504;11454.54181832734;562106;565065.174674124;571472;572207;574494;130400;136411;136885.31557342148;140376;140781.4804629872;1067.0023528001188;1161.252315265537;1196.7845787758113;1219.3963987825905;1225.9509619615114;18760.155792989077;22342.553148937255;22569.774302257425;23277;23450.765492344857;58824.1176382346;59018.70490647519;59427.81144377002;59629;60077.596896123665;80457;82841;83153.58423207846;84121.27029837476;85110.57444712736;8757.211850933447;9246.768830779285;12878.420471078636;13026;13469.035502782537;14017.649558761113;14158.362873670503;14606;14658.486952956595;14817.629559261104;11365.602203922823;11984.820227696417;12101.81847272274;12376.566820161252;12425.813612795635;9735.951320243354;10275.332103413255;11508;11606.013488853541;12004.39978001079;12282.754344912873;12475.563355282522;12496.562620308245;12731.427085780977;12733.426995785398;12039.699007524881;13414.463421463031;13462.528811491551;13549.32253387307;13778.448862045407;10280.331778434676;11779.29324240514;11863.525458981545;12004.459799309227;12416.689582760142 18:45:45 INFO - PID 2480 | |3;mootools.html;1389.5136702154246;1455.941762329507;1470.7132109238696;1474.476560820908;1475.9668232237436;11232.10143188545;11513.539458421663;11843.7631247375;12092.274463532183;12153.756924861498;10937.890621093788;11981.041516678666;12200.450979705913;12223.511059557606;12248.387580620956;2555.591105423128;2593.7146913839556;2598.116640342283;2601.5187190369747;2609.0607381342797;2282.7717228277206;2474.8368266914613;2482.5283196192668;2511.4098186926058;2566.5251928393213;2510.924672259826;2517.5216708825355;2539.733328000561;2546.554352988229;2547.9872600636972;2536.746325367467;2552.6553915221393;2562.6796650418696;2563.2310306907852;2576.497582971321;2520.180941194101;2535.125381743314;2536.8351057181226;2545.7963362930923;2546.987265063681;10579;11478.311301321864;11990.70023249417;12169.93915030428;12226.816597751042;6702.4302934250645;6919.204291506491;6961.129858767654;6963.268856770068;6963.6866341014265;3984.382420724792;4272.594103560157;4388.670849686287;4411.6250118739945;4415.558444155558;1267.1700036476122;1268.9746205075846;1288.871112888713;1313.382710126239;1315.8486774021014;16611.83388166115;16731.247093880684;16890.66218675632;16895.15524223796;16938.491845244538;5642.097264437671;5777.480026797609;5849.415058494116;5873.471387575053;5993.011153159679;12290.877091229153;12433.813492797615;12479.56321528742;12497.687557810945;12522.248665079942;6684.699188536528;6738.561993470409;6757.256701762803;6764.391204791593;6764.76323328691;6054.334023257438;6276.811695649138;6311.684415779193;6338.651374174372;6347.365263473615;4381.277089280296;4522.389477420506;4577.954220457819;4637.350770892078;4646.558576935254;1297.935103244834;1325.1372249891458;1344.5764584155959;1347.7169794343101;1351.1217708489364;729.6643543969727;757.2087168908504;784.1531146361915;810.3031393002013;811.6996711216888;6797.243633500946;7014.123234595676;7104.9704000953925;7106.289371062956;7131.144262688511;795.6896810244009;959.3811991265594;960.3277705606104;963.7686955130858;966.087047739879;4378.956210437855;4457.754823484739;4543.909121817545;4551.749653769075;4560.908781824345;14846.257687115602;14924.776128358084;15330.080195188324;15354.385824567116;15418.229088545751;12594.929430998467;14962.775558366633;15006.849931500763;15135.243237838064;15484.380624775109;8466.153384661484;8781.736547903573;8810.647574096964;8812.51531165792;8862.955685221661;5913.940860591339;6377.8519866424485;6648.905822877807;6829.180498340231;6920.792376228721;11701.005414539859;12674.683132921751;12962.611121666365;13657.863421365659;13806.447742090204;14085.507007254697;14149.151050936976;14194.858051419353;14240.931930105491;14544;7833.843323133619;7918.693415586364;7923.960380198062;8988.86516702237;9027.232685221566;9434.575444105169;9838.163756080814;10993.670189894316;11033.613823516138;11041.227114101936;8221.260086592236;9108.453492790453;9775.608975641178;9784.706458806248;9842.65550705722;9451.149396554343;9531.904680953101;9554.52227388614;9718.465484398426;9732.610695572097;8162.877556836771;8293.709720159766;8412.327013839;8414.957925210334;8435;6090.847728806815;6196.256449225942;6213.9067913980425;6227.782027629011;6306.085617585332;5230;6163.476104530986;6325.146105275729;6338.954072577973;6465.2564955030775 18:45:45 INFO - PID 2480 | |4;prototype.html;402.12938987092946;402.63963752441566;405.8072415602589;407.8042539581001;416.49811976568236;5376.301080859487;5873.500752436037;6172.290186628539;6237.812865614028;6275;5571.777128914849;5847.181394604748;5957.40425957404;6096.817095487131;6097.512199024079;3979.363301871701;4678.812847486097;4695.976520117411;4714.175019371613;4745.76271186441;4184.66522678185;4458.866234012985;4721.7639118044135;4742.596879265267;4759.785809638574;3840.692744580441;3979.621935916083;4463.33050042493;4627.953720462803;4676.906461870778;4015;4660.813567457298;4678.157931572316;4684.859454216379;4695.1548721230165;4159.376093585956;4162.854300099482;4172.520160181588;4177.707560470768;4180.665546756252;4375.912481750379;5286.365636123652;5443.727813609344;5497.5601951844155;6075.665838378887;5090.727318170458;5101.566366858821;5500.477454641843;5529.612927095105;5567.2762540869835;5739.684317362543;6225.533085018642;6333.46165575922;6568;6610.975658499575;4213;4657.953420465786;4698.38920940279;4705.458872229703;4712.868911461224;6151.984922487784;6614.933850661528;6655.633940133291;6763.898541521881;6776.42400395967;4457.153140903218;4816.8073277069225;4851.878703032452;4862.100511405401;4871.683340582851;4110.965072289236;4923.5814955729165;4927.236278376858;4956.306117143603;4957.3059771632;4954.851354459372;5239.423663397023;5491.313585801775;5542.307211598551;5554.805581804618;6572.934270657232;6574.80275591733;6606.504512161511;6606.7026983785845;6613.173353330833;1344.3009634989946;1666.5916850371736;1687.392538686072;1688.923998420074;1690.4252554131654;971.4220039076738;1023.744063984004;1123.2193625430248;1138.334074566388;1164.318873459036;5456.945430545723;5788.305403351625;6025.9153352396115;6203.069539569011;6224.408681175281;6184.381561843779;6728.831779205559;6761.425278851254;6761.797146085625;6770.593764374154;4681.906361872743;6037.034074548052;6054;6242.219722534684;6276.372362763687;3208.326251487214;3473.878414255489;3529.1177205698577;3560.7329450290813;3561.5904171020147;6502.382273683992;6898.931010689829;6976.302369762983;7011.964940175369;7017.859642807115;6871.587704737732;6967.049226216566;6973.02377667127;6977.7208911642965;7005.299470053056;5661.660300381908;5902.321233058254;6085.8741132890555;6644.2392765917075;6912.377885990285;5490.560755139659;5917.822465326047;6203.192525792547;6672.833179170365;6785.9660701696175;5592.853465039739;5746.827595172152;6051.667158306159;6673.466122710269;6715.1941766988275;7097.038487670382;7108.751193708195;7131.894556343775;7141.250168732416;7161.209697575606;3735.3276410246417;4448.621867141329;4468.4637843458995;4473.865784026354;4492.887677808081;6169.259688837368;6630.204375474974;6836.965815170893;6844.384005439534;6851.725930962905;6593.901091483535;6598.505112116514;6605.966970165118;6615.213892249162;6651.767188148391;5236.764345604533;6427.485801135991;6451.1936007999;6552.967235163794;6583.407493325623;5202;5388.29157698652;6476.6114033158165;6492.253390860112;6562.212534495891;5394.487523685244;5959.463648271676;6105.145279660762;6331.335209803088;6590.044443555754;6410.903836442364;6423.454006409507;6423.518236132402;6424.871502570016;6461.515386346136 18:45:45 INFO - PID 2480 | |5;yui.html;726.5277569579772;757.155771314984;807.0517142357725;859.2739135430559;860.7073594977709;16388.590285242874;16782.244798984044;16814.57963550912;18494.630107397843;19048;17779.37772177974;18147.54631134219;18185.181666824992;18331.633367332644;18335.54161145972;942.6465075596651;943.3396622364355;957.5260246178152;958.4345236310576;965.1120968708805;957.7749228931231;964.4502633498909;964.6189755046752;969.6751588217955;972.0959507657875;1018.0786388318584;1023.253025291534;1024;1024.933379330341;1029.6241871716807;1019.0828254570871;1019.2559431614889;1020.4591566469746;1021.1728499915092;1021.4841505039944;598.0013377659319;722.8771108911498;734.1850545894054;736.6869080640706;744.750508579621;16816.495505134866;16950.321987120624;17008.744868826976;17016.234269457906;17147.57131071721;16753.99476031442;16971.74542381865;17194.05432701201;17332.13339333028;17351.305947762074;13926.721465570734;13976.091554048955;14045.297735113305;14245.928770356082;14266.215358155298;3509.2455122148863;3567.1973805893726;3567.7145828333933;3571.5714114306447;3580.4987301777774;67154.66422667904;125778.74221257762;126610;127201;128793;11059.170562207706;12825.679358015937;13234.536791212262;13288.269145197108;13416.798748018788;24320.878395608263;24631.753682463306;25135.371615709755;25206.865691043946;25536.361590960005;7762.92237077622;7817.68729250835;7821.335186509211;7845.529268243924;7884.250996155356;3829.8468061277235;3872.941905871358;3891.20230352777;3898.7270891037356;3905.511811023622;4372.934405983913;4408.426904502394;4458.799354029076;4471.5081341052455;4481.977590112028;802.3180296747718;941.1695430077258;958.0993865766158;958.6405098088217;961.6345788600287;646.2083947164685;648.5265755998148;656.9901451478231;662.3806740697463;676.9559978601375;10947.397893115796;11648.708782280512;11884.881151188378;12043.096767742454;12081.397371232604;652.3118110393485;656.678227668439;660.8579155481499;668.2482207516543;687.8314812870778;5265.7103859287345;5313.362396512443;5454.972725136428;5483.75323110461;5641.379448260688;95383.52308238554;101980.49009754903;107474;108863;109763;59335;74476;85942.57028714816;96047;98101.50949245208;60387.698061509414;64634.67682661651;64864.0270395935;66110.33889661139;66127.33872661211;12058.336791476553;12408.007359411406;12421.316827574208;12443.128980971245;12520.499180032697;30720.849540531948;36371.63628363788;36971.815140924125;38771.806140969114;39194.80402597969;46475.53524464712;46570.534294656616;46905.296420554405;47032.52967470281;47522;15691.529254122395;15773.68452630918;15983.440579580125;15997.760033599272;16185.352585896433;17363.30546778115;17672.823271767116;17738.50074558804;17955.910220449336;17974.460766177035;16061.028709688744;16184.947978381368;16217.945833520784;16334;16379.672406551565;15909.840901591298;15960.680786384439;15969;16107.194640268173;16187.190640468165;15765.921170394075;15868.52394428169;15901.602459938595;15959.920200398923;16079.115648639437;9888.85166722514;11508.079353651521;11563.768724625292;11755.823662644896;11906.464209110438;12607.621771346874;13313.534026309033;13516.459341626618;13552.451165470775;13766.174029558259 18:45:45 INFO - PID 2480 | __end_tp_report 18:45:45 INFO - PID 2480 | __start_cc_report 18:45:45 INFO - PID 2480 | _x_x_mozilla_cycle_collect,432 18:45:45 INFO - PID 2480 | __end_cc_report 18:45:45 INFO - PID 2480 | __startTimestamp1492825545575__endTimestamp 18:45:45 INFO - PID 2480 | 18:45:45 INFO - PID 2480 | ------- Summary: start ------- 18:45:45 INFO - PID 2480 | Number of tests: 6 18:45:45 INFO - PID 2480 | 18:45:45 INFO - PID 2480 | [#0] dojo.html Cycles:165 Average:6145.67 Median:6378.97 stddev:811.98 (12.7%) stddev-sans-first:773.76 18:45:45 INFO - PID 2480 | Values: 2909.3 3598.4 3600.9 3643.8 3664.6 4658.7 6067.7 6094.0 6253.4 6256.8 6158.9 6235.3 6240.8 6255.6 6317.9 4595.9 5518.8 5519.2 5530.3 5539.3 5434.8 5510.1 5570.7 5594.2 5643.8 5458.9 5486.0 5533.9 5543.7 5554.6 5495.6 5500.2 5511.1 5513.5 5553.7 5442.0 5465.2 5475.3 5507.1 5563.8 4590.7 6117.1 6145.3 6197.3 6227.5 6768.4 6782.5 6789.8 6792.0 6805.1 6835.1 6889.4 6892.5 6896.7 6908.4 6228.6 6241.9 6260.6 6374.4 6386.8 6942.6 6958.3 6993.4 7008.3 7019.5 6360.7 6429.3 6462.9 6616.0 6703.9 6629.5 6800.3 6803.9 6808.0 6812.3 6433.9 6504.5 6760.0 6775.8 6805.5 6518.3 6639.3 6675.3 6736.2 6766.5 4392.4 4506.4 5326.5 5343.7 5345.3 4444.6 4760.6 4765.0 4786.2 4824.1 5074.6 5644.8 6221.8 6314.9 6357.0 6853.5 6859.5 6863.4 6873.3 6888.0 5032.4 6310.7 6330.4 6379.0 6400.7 5784.5 5904.8 5927.9 5935.5 6049.2 5847.8 6856.2 6933.8 6975.2 7072.0 6813.6 6842.6 6860.7 6875.0 6918.2 6954.5 6967.2 7013.1 7016.8 7045.7 6450.4 6541.6 6669.9 6700.8 6754.3 6532.3 6825.5 6888.8 6889.9 6892.8 5243.7 6365.1 6600.9 6615.4 6632.9 6102.2 6570.6 6570.8 6571.7 6593.5 5117.5 5748.4 6280.8 6341.9 6362.0 6617.9 6647.3 6659.3 6670.5 6671.6 6429.5 6456.9 6464.0 6472.2 6476.8 18:45:45 INFO - PID 2480 | 18:45:45 INFO - PID 2480 | [#1] ext.html Cycles:180 Average:43786.70 Median:321497.00 stddev:76348.24 (23.7%) stddev-sans-first:76493.85 18:45:45 INFO - PID 2480 | Values: 690.4 725.8 800.8 1256.7 1262.2 35987.6 37192.3 37256.0 37278.8 37374.6 37499.3 37842.8 37877.1 37980.6 37990.8 7917.4 8103.6 8359.2 8602.1 8610.2 8628.3 8646.5 8727.3 8733.7 8752.3 7867.3 8687.2 8740.8 8748.5 8769.3 7919.5 8062.9 8710.3 8715.8 8738.6 8406.3 8406.6 8423.5 8496.9 8517.8 36889.4 37192.4 37199.4 37230.6 37342.4 3530.6 3554.5 3560.3 3564.4 3575.4 24142.4 29763.6 30638.8 30716.7 30749.0 3136.2 3160.4 3171.1 3182.4 3193.4 235614.0 239352.0 279763.0 294783.0 297665.0 39669.2 47159.5 47404.6 47997.8 48125.5 40316.8 48998.1 49600.8 49605.8 49668.8 13676.2 13751.7 13768.7 13864.0 13887.4 11148.4 11271.7 11276.5 11280.2 11367.4 10917.4 13339.3 13344.3 13370.0 13553.4 5941.6 5951.7 5968.8 5995.3 5996.2 946.9 988.3 1001.4 1033.6 1082.3 24564.9 24657.0 24768.0 24772.6 24784.9 1135.1 1135.2 1172.2 1174.6 1176.6 12061.8 12644.7 12657.6 12702.7 12733.9 285728.0 321106.0 321195.0 321799.0 322161.9 54519.0 54616.5 54750.0 54813.7 54897.7 1500.9 1521.5 1561.1 1567.1 1575.2 20885.7 21960.6 22585.9 23849.0 24158.8 37613.2 37932.6 37934.4 37950.1 37971.4 286050.0 286192.0 286443.0 286616.0 287209.6 28609.0 28867.7 29219.1 33432.0 33736.7 32891.0 42219.2 42522.1 43439.0 43517.6 30162.0 30366.8 30434.8 30469.8 30522.5 24902.6 29863.0 30305.0 30319.8 30973.1 36032.6 36303.8 36355.8 36360.5 36474.5 26703.9 26846.6 29887.9 30731.0 30948.7 23735.5 23892.0 24063.4 24198.8 24242.4 18:45:45 INFO - PID 2480 | 18:45:45 INFO - PID 2480 | [#2] jquery.html Cycles:180 Average:37010.11 Median:22092.56 stddev:96735.20 (437.9%) stddev-sans-first:96967.57 18:45:45 INFO - PID 2480 | Values: 427.0 429.1 431.1 448.1 448.1 19681.0 21316.0 22057.9 22127.2 22182.8 19227.8 19278.2 19421.4 19473.1 20904.8 6033.8 6307.7 6352.5 6353.2 6364.8 5983.5 6170.7 6195.9 6243.8 6244.2 4958.7 5105.9 6198.3 6198.8 6213.3 5970.5 6202.4 6213.4 6258.5 6264.2 5224.7 5567.2 5578.2 5587.7 5588.7 27728.6 28143.2 28192.9 28243.3 28450.6 2274.5 2342.5 2347.3 2347.9 2349.7 15367.0 16442.6 17856.7 18183.6 18456.9 2125.1 2167.3 2184.7 2187.6 2188.4 160047.2 161101.2 162566.7 163155.0 164074.0 12289.8 12375.7 12479.4 12497.9 12688.8 16172.3 16259.9 16297.3 16299.6 16330.3 8567.3 8865.4 8886.9 9009.3 9022.6 8320.6 8368.3 8406.7 8494.4 8520.8 7903.2 8026.4 8049.6 8054.6 8102.3 2151.5 2166.5 2170.6 2175.6 2317.7 943.3 955.3 973.5 1002.3 1012.3 21000.7 21577.5 21645.4 21953.7 22367.8 826.3 954.1 999.4 1024.3 1029.2 9337.8 9477.4 11383.0 11414.2 11454.5 562106.0 565065.2 571472.0 572207.0 574494.0 130400.0 136411.0 136885.3 140376.0 140781.5 1067.0 1161.3 1196.8 1219.4 1226.0 18760.2 22342.6 22569.8 23277.0 23450.8 58824.1 59018.7 59427.8 59629.0 60077.6 80457.0 82841.0 83153.6 84121.3 85110.6 8757.2 9246.8 12878.4 13026.0 13469.0 14017.6 14158.4 14606.0 14658.5 14817.6 11365.6 11984.8 12101.8 12376.6 12425.8 9736.0 10275.3 11508.0 11606.0 12004.4 12282.8 12475.6 12496.6 12731.4 12733.4 12039.7 13414.5 13462.5 13549.3 13778.4 10280.3 11779.3 11863.5 12004.5 12416.7 18:45:45 INFO - PID 2480 | 18:45:45 INFO - PID 2480 | [#3] mootools.html Cycles:180 Average:7220.03 Median:4380.12 stddev:4531.66 (103.5%) stddev-sans-first:4523.19 18:45:45 INFO - PID 2480 | Values: 1389.5 1455.9 1470.7 1474.5 1476.0 11232.1 11513.5 11843.8 12092.3 12153.8 10937.9 11981.0 12200.5 12223.5 12248.4 2555.6 2593.7 2598.1 2601.5 2609.1 2282.8 2474.8 2482.5 2511.4 2566.5 2510.9 2517.5 2539.7 2546.6 2548.0 2536.7 2552.7 2562.7 2563.2 2576.5 2520.2 2535.1 2536.8 2545.8 2547.0 10579.0 11478.3 11990.7 12169.9 12226.8 6702.4 6919.2 6961.1 6963.3 6963.7 3984.4 4272.6 4388.7 4411.6 4415.6 1267.2 1269.0 1288.9 1313.4 1315.8 16611.8 16731.2 16890.7 16895.2 16938.5 5642.1 5777.5 5849.4 5873.5 5993.0 12290.9 12433.8 12479.6 12497.7 12522.2 6684.7 6738.6 6757.3 6764.4 6764.8 6054.3 6276.8 6311.7 6338.7 6347.4 4381.3 4522.4 4578.0 4637.4 4646.6 1297.9 1325.1 1344.6 1347.7 1351.1 729.7 757.2 784.2 810.3 811.7 6797.2 7014.1 7105.0 7106.3 7131.1 795.7 959.4 960.3 963.8 966.1 4379.0 4457.8 4543.9 4551.7 4560.9 14846.3 14924.8 15330.1 15354.4 15418.2 12594.9 14962.8 15006.8 15135.2 15484.4 8466.2 8781.7 8810.6 8812.5 8863.0 5913.9 6377.9 6648.9 6829.2 6920.8 11701.0 12674.7 12962.6 13657.9 13806.4 14085.5 14149.2 14194.9 14240.9 14544.0 7833.8 7918.7 7924.0 8988.9 9027.2 9434.6 9838.2 10993.7 11033.6 11041.2 8221.3 9108.5 9775.6 9784.7 9842.7 9451.1 9531.9 9554.5 9718.5 9732.6 8162.9 8293.7 8412.3 8415.0 8435.0 6090.8 6196.3 6213.9 6227.8 6306.1 5230.0 6163.5 6325.1 6339.0 6465.3 18:45:45 INFO - PID 2480 | 18:45:45 INFO - PID 2480 | [#4] prototype.html Cycles:180 Average:5297.95 Median:5767.57 stddev:1616.63 (28.0%) stddev-sans-first:1578.85 18:45:45 INFO - PID 2480 | Values: 402.1 402.6 405.8 407.8 416.5 5376.3 5873.5 6172.3 6237.8 6275.0 5571.8 5847.2 5957.4 6096.8 6097.5 3979.4 4678.8 4696.0 4714.2 4745.8 4184.7 4458.9 4721.8 4742.6 4759.8 3840.7 3979.6 4463.3 4628.0 4676.9 4015.0 4660.8 4678.2 4684.9 4695.2 4159.4 4162.9 4172.5 4177.7 4180.7 4375.9 5286.4 5443.7 5497.6 6075.7 5090.7 5101.6 5500.5 5529.6 5567.3 5739.7 6225.5 6333.5 6568.0 6611.0 4213.0 4658.0 4698.4 4705.5 4712.9 6152.0 6614.9 6655.6 6763.9 6776.4 4457.2 4816.8 4851.9 4862.1 4871.7 4111.0 4923.6 4927.2 4956.3 4957.3 4954.9 5239.4 5491.3 5542.3 5554.8 6572.9 6574.8 6606.5 6606.7 6613.2 1344.3 1666.6 1687.4 1688.9 1690.4 971.4 1023.7 1123.2 1138.3 1164.3 5456.9 5788.3 6025.9 6203.1 6224.4 6184.4 6728.8 6761.4 6761.8 6770.6 4681.9 6037.0 6054.0 6242.2 6276.4 3208.3 3473.9 3529.1 3560.7 3561.6 6502.4 6898.9 6976.3 7012.0 7017.9 6871.6 6967.0 6973.0 6977.7 7005.3 5661.7 5902.3 6085.9 6644.2 6912.4 5490.6 5917.8 6203.2 6672.8 6786.0 5592.9 5746.8 6051.7 6673.5 6715.2 7097.0 7108.8 7131.9 7141.3 7161.2 3735.3 4448.6 4468.5 4473.9 4492.9 6169.3 6630.2 6837.0 6844.4 6851.7 6593.9 6598.5 6606.0 6615.2 6651.8 5236.8 6427.5 6451.2 6553.0 6583.4 5202.0 5388.3 6476.6 6492.3 6562.2 5394.5 5959.5 6105.1 6331.3 6590.0 6410.9 6423.5 6423.5 6424.9 6461.5 18:45:45 INFO - PID 2480 | 18:45:45 INFO - PID 2480 | [#5] yui.html Cycles:180 Average:20427.06 Median:18771.32 stddev:28379.63 (151.2%) stddev-sans-first:28420.69 18:45:45 INFO - PID 2480 | Values: 726.5 757.2 807.1 859.3 860.7 16388.6 16782.2 16814.6 18494.6 19048.0 17779.4 18147.5 18185.2 18331.6 18335.5 942.6 943.3 957.5 958.4 965.1 957.8 964.5 964.6 969.7 972.1 1018.1 1023.3 1024.0 1024.9 1029.6 1019.1 1019.3 1020.5 1021.2 1021.5 598.0 722.9 734.2 736.7 744.8 16816.5 16950.3 17008.7 17016.2 17147.6 16754.0 16971.7 17194.1 17332.1 17351.3 13926.7 13976.1 14045.3 14245.9 14266.2 3509.2 3567.2 3567.7 3571.6 3580.5 67154.7 125778.7 126610.0 127201.0 128793.0 11059.2 12825.7 13234.5 13288.3 13416.8 24320.9 24631.8 25135.4 25206.9 25536.4 7762.9 7817.7 7821.3 7845.5 7884.3 3829.8 3872.9 3891.2 3898.7 3905.5 4372.9 4408.4 4458.8 4471.5 4482.0 802.3 941.2 958.1 958.6 961.6 646.2 648.5 657.0 662.4 677.0 10947.4 11648.7 11884.9 12043.1 12081.4 652.3 656.7 660.9 668.2 687.8 5265.7 5313.4 5455.0 5483.8 5641.4 95383.5 101980.5 107474.0 108863.0 109763.0 59335.0 74476.0 85942.6 96047.0 98101.5 60387.7 64634.7 64864.0 66110.3 66127.3 12058.3 12408.0 12421.3 12443.1 12520.5 30720.8 36371.6 36971.8 38771.8 39194.8 46475.5 46570.5 46905.3 47032.5 47522.0 15691.5 15773.7 15983.4 15997.8 16185.4 17363.3 17672.8 17738.5 17955.9 17974.5 16061.0 16184.9 16217.9 16334.0 16379.7 15909.8 15960.7 15969.0 16107.2 16187.2 15765.9 15868.5 15901.6 15959.9 16079.1 9888.9 11508.1 11563.8 11755.8 11906.5 12607.6 13313.5 13516.5 13552.5 13766.2 18:45:45 INFO - PID 2480 | -------- Summary: end -------- 18:45:45 INFO - PID 2480 | 18:45:46 INFO - TEST-INFO | 2480: exit 0 18:45:46 INFO - TEST-OK | dromaeo_css | took 1115718ms 18:45:46 INFO - TEST-START | kraken 18:45:46 INFO - operating with platform_type : mac_ 18:45:46 INFO - Initialising browser for kraken test... 18:45:46 INFO - TEST-INFO | started process 2487 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/tmpnm9a7e/profile http://localhost:60514/getInfo.html) 18:45:48 INFO - PID 2487 | 2017-04-21 18:45:48.963 plugin-container[2488:39414] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x943f, name = 'com.apple.tsm.portname' 18:45:48 INFO - PID 2487 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:45:48 INFO - PID 2487 | 2017-04-21 18:45:48.966 plugin-container[2488:39414] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9b0f, name = 'com.apple.CFPasteboardClient' 18:45:48 INFO - PID 2487 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:45:48 INFO - PID 2487 | 2017-04-21 18:45:48.966 plugin-container[2488:39414] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:45:49 INFO - PID 2487 | __metrics Screen width/height:1600/1200 18:45:49 INFO - PID 2487 | colorDepth:24 18:45:49 INFO - PID 2487 | Browser inner width/height: 1024/689 18:45:49 INFO - PID 2487 | __metrics 18:45:49 ERROR - PID 2487 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3403: TypeError: this.tabs is undefined 18:45:50 INFO - TEST-INFO | 2487: exit 0 18:45:50 INFO - Browser initialized. 18:45:50 INFO - Running cycle 1/1 for kraken test... 18:45:50 INFO - TEST-INFO | started process 2489 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/T/tmpnm9a7e/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:45:52 INFO - PID 2489 | 2017-04-21 18:45:52.579 plugin-container[2490:39543] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9343, name = 'com.apple.tsm.portname' 18:45:52 INFO - PID 2489 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:45:52 INFO - PID 2489 | 2017-04-21 18:45:52.579 plugin-container[2490:39543] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9347, name = 'com.apple.CFPasteboardClient' 18:45:52 INFO - PID 2489 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:45:52 INFO - PID 2489 | 2017-04-21 18:45:52.579 plugin-container[2490:39543] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:46:02 INFO - PID 2489 | RSS: Main: 237363200 18:46:02 INFO - PID 2489 | 18:46:05 INFO - PID 2489 | Cycle 1(1): loaded http://localhost:60514/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:60514/tests/kraken/driver.html?testName=audio-beat-detection) 18:46:06 INFO - PID 2489 | RSS: Main: 250429440 18:46:06 INFO - PID 2489 | 18:46:09 INFO - PID 2489 | Cycle 1(1): loaded http://localhost:60514/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:60514/tests/kraken/driver.html?testName=audio-dft) 18:46:09 INFO - PID 2489 | RSS: Main: 258879488 18:46:09 INFO - PID 2489 | 18:46:13 INFO - PID 2489 | Cycle 1(1): loaded http://localhost:60514/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:60514/tests/kraken/driver.html?testName=audio-fft) 18:46:13 INFO - PID 2489 | RSS: Main: 261619712 18:46:13 INFO - PID 2489 | 18:46:15 INFO - PID 2489 | Cycle 1(1): loaded http://localhost:60514/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:60514/tests/kraken/driver.html?testName=audio-oscillator) 18:46:16 INFO - PID 2489 | RSS: Main: 262479872 18:46:16 INFO - PID 2489 | 18:46:18 INFO - PID 2489 | Cycle 1(1): loaded http://localhost:60514/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:60514/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:46:19 INFO - PID 2489 | RSS: Main: 264261632 18:46:19 INFO - PID 2489 | 18:46:22 INFO - PID 2489 | Cycle 1(1): loaded http://localhost:60514/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:60514/tests/kraken/driver.html?testName=imaging-darkroom) 18:46:22 INFO - PID 2489 | RSS: Main: 269643776 18:46:22 INFO - PID 2489 | 18:46:25 INFO - PID 2489 | Cycle 1(1): loaded http://localhost:60514/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:60514/tests/kraken/driver.html?testName=imaging-desaturate) 18:46:26 INFO - PID 2489 | RSS: Main: 270462976 18:46:26 INFO - PID 2489 | 18:46:29 INFO - PID 2489 | Cycle 1(1): loaded http://localhost:60514/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:60514/tests/kraken/driver.html?testName=json-parse-financial) 18:46:30 INFO - PID 2489 | RSS: Main: 270385152 18:46:30 INFO - PID 2489 | 18:46:31 INFO - PID 2489 | Cycle 1(1): loaded http://localhost:60514/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:60514/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:46:32 INFO - PID 2489 | RSS: Main: 270225408 18:46:32 INFO - PID 2489 | 18:46:33 INFO - PID 2489 | Cycle 1(1): loaded http://localhost:60514/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:60514/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:46:34 INFO - PID 2489 | RSS: Main: 270184448 18:46:34 INFO - PID 2489 | 18:46:36 INFO - PID 2489 | Cycle 1(1): loaded http://localhost:60514/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:60514/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:46:37 INFO - PID 2489 | RSS: Main: 269918208 18:46:37 INFO - PID 2489 | 18:46:40 INFO - PID 2489 | Cycle 1(1): loaded http://localhost:60514/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:60514/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:46:41 INFO - PID 2489 | RSS: Main: 270077952 18:46:41 INFO - PID 2489 | 18:46:44 INFO - PID 2489 | Cycle 1(1): loaded http://localhost:60514/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:60514/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:46:44 INFO - PID 2489 | RSS: Main: 270483456 18:46:44 INFO - PID 2489 | 18:46:46 INFO - PID 2489 | Cycle 1(1): loaded http://localhost:60514/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:60514/tests/kraken/driver.html?testName=ai-astar) 18:46:46 INFO - PID 2489 | RSS: Main: 271970304 18:46:46 INFO - PID 2489 | 18:46:46 INFO - PID 2489 | __start_tp_report 18:46:46 INFO - PID 2489 | _x_x_mozilla_page_load 18:46:46 INFO - PID 2489 | _x_x_mozilla_page_load_details 18:46:46 INFO - PID 2489 | |i|pagename|runs| 18:46:46 INFO - PID 2489 | |0;ai-astar;109;171;163;115;121;167;116;114;120;167 18:46:46 INFO - PID 2489 | |1;audio-beat-detection;150;112;124;182;142;147;110;118;172;148 18:46:46 INFO - PID 2489 | |2;audio-dft;205;217;212;205;196;197;234;247;196;195 18:46:46 INFO - PID 2489 | |3;audio-fft;83;61;81;82;63;85;91;50;83;82 18:46:46 INFO - PID 2489 | |4;audio-oscillator;83;68;84;73;73;71;86;69;70;94 18:46:46 INFO - PID 2489 | |5;imaging-gaussian-blur;90;90;90;103;135;136;128;92;92;90 18:46:46 INFO - PID 2489 | |6;imaging-darkroom;98;98;97;97;97;97;97;97;103;98 18:46:46 INFO - PID 2489 | |7;imaging-desaturate;91;88;90;90;96;116;148;93;92;93 18:46:46 INFO - PID 2489 | |8;json-parse-financial;50;50;51;52;50;49;47;48;51;51 18:46:46 INFO - PID 2489 | |9;json-stringify-tinderbox;58;60;57;58;61;58;57;59;58;57 18:46:46 INFO - PID 2489 | |10;stanford-crypto-aes;84;83;85;79;99;113;106;107;81;85 18:46:46 INFO - PID 2489 | |11;stanford-crypto-ccm;138;141;139;135;139;139;136;149;185;170 18:46:46 INFO - PID 2489 | |12;stanford-crypto-pbkdf2;185;188;223;179;187;184;193;231;241;182 18:46:46 INFO - PID 2489 | |13;stanford-crypto-sha256-iterative;66;70;65;69;71;68;83;97;75;68 18:46:46 INFO - PID 2489 | __end_tp_report 18:46:46 INFO - PID 2489 | __start_cc_report 18:46:46 INFO - PID 2489 | _x_x_mozilla_cycle_collect,1005 18:46:46 INFO - PID 2489 | __end_cc_report 18:46:46 INFO - PID 2489 | __startTimestamp1492825606901__endTimestamp 18:46:46 INFO - PID 2489 | 18:46:46 INFO - PID 2489 | ------- Summary: start ------- 18:46:46 INFO - PID 2489 | Number of tests: 14 18:46:46 INFO - PID 2489 | 18:46:46 INFO - PID 2489 | [#0] ai-astar Cycles:10 Average:136.30 Median:142.00 stddev:26.69 (18.8%) stddev-sans-first:26.41 18:46:46 INFO - PID 2489 | Values: 109.0 171.0 163.0 115.0 121.0 167.0 116.0 114.0 120.0 167.0 18:46:46 INFO - PID 2489 | 18:46:46 INFO - PID 2489 | [#1] audio-beat-detection Cycles:10 Average:140.50 Median:147.50 stddev:24.55 (16.6%) stddev-sans-first:25.80 18:46:46 INFO - PID 2489 | Values: 150.0 112.0 124.0 182.0 142.0 147.0 110.0 118.0 172.0 148.0 18:46:46 INFO - PID 2489 | 18:46:46 INFO - PID 2489 | [#2] audio-dft Cycles:10 Average:210.40 Median:208.50 stddev:17.74 (8.5%) stddev-sans-first:18.71 18:46:46 INFO - PID 2489 | Values: 205.0 217.0 212.0 205.0 196.0 197.0 234.0 247.0 196.0 195.0 18:46:46 INFO - PID 2489 | 18:46:46 INFO - PID 2489 | [#3] audio-fft Cycles:10 Average:76.10 Median:82.50 stddev:13.21 (16.0%) stddev-sans-first:13.77 18:46:46 INFO - PID 2489 | Values: 83.0 61.0 81.0 82.0 63.0 85.0 91.0 50.0 83.0 82.0 18:46:46 INFO - PID 2489 | 18:46:46 INFO - PID 2489 | [#4] audio-oscillator Cycles:10 Average:77.10 Median:78.00 stddev:8.92 (11.4%) stddev-sans-first:9.21 18:46:46 INFO - PID 2489 | Values: 83.0 68.0 84.0 73.0 73.0 71.0 86.0 69.0 70.0 94.0 18:46:46 INFO - PID 2489 | 18:46:46 INFO - PID 2489 | [#5] imaging-gaussian-blur Cycles:10 Average:104.60 Median:90.00 stddev:20.08 (22.3%) stddev-sans-first:20.60 18:46:46 INFO - PID 2489 | Values: 90.0 90.0 90.0 103.0 135.0 136.0 128.0 92.0 92.0 90.0 18:46:46 INFO - PID 2489 | 18:46:46 INFO - PID 2489 | [#6] imaging-darkroom Cycles:10 Average:97.90 Median:97.00 stddev:1.85 (1.9%) stddev-sans-first:1.96 18:46:46 INFO - PID 2489 | Values: 98.0 98.0 97.0 97.0 97.0 97.0 97.0 97.0 103.0 98.0 18:46:46 INFO - PID 2489 | 18:46:46 INFO - PID 2489 | [#7] imaging-desaturate Cycles:10 Average:99.70 Median:91.50 stddev:18.74 (20.5%) stddev-sans-first:19.62 18:46:46 INFO - PID 2489 | Values: 91.0 88.0 90.0 90.0 96.0 116.0 148.0 93.0 92.0 93.0 18:46:46 INFO - PID 2489 | 18:46:46 INFO - PID 2489 | [#8] json-parse-financial Cycles:10 Average:49.90 Median:50.50 stddev:1.52 (3.0%) stddev-sans-first:1.62 18:46:46 INFO - PID 2489 | Values: 50.0 50.0 51.0 52.0 50.0 49.0 47.0 48.0 51.0 51.0 18:46:46 INFO - PID 2489 | 18:46:46 INFO - PID 2489 | [#9] json-stringify-tinderbox Cycles:10 Average:58.30 Median:58.00 stddev:1.34 (2.3%) stddev-sans-first:1.41 18:46:46 INFO - PID 2489 | Values: 58.0 60.0 57.0 58.0 61.0 58.0 57.0 59.0 58.0 57.0 18:46:46 INFO - PID 2489 | 18:46:46 INFO - PID 2489 | [#10] stanford-crypto-aes Cycles:10 Average:92.20 Median:83.50 stddev:12.66 (15.2%) stddev-sans-first:13.08 18:46:46 INFO - PID 2489 | Values: 84.0 83.0 85.0 79.0 99.0 113.0 106.0 107.0 81.0 85.0 18:46:46 INFO - PID 2489 | 18:46:46 INFO - PID 2489 | [#11] stanford-crypto-ccm Cycles:10 Average:147.10 Median:140.00 stddev:16.84 (12.0%) stddev-sans-first:17.53 18:46:46 INFO - PID 2489 | Values: 138.0 141.0 139.0 135.0 139.0 139.0 136.0 149.0 185.0 170.0 18:46:46 INFO - PID 2489 | 18:46:46 INFO - PID 2489 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:199.30 Median:190.50 stddev:23.03 (12.1%) stddev-sans-first:23.84 18:46:46 INFO - PID 2489 | Values: 185.0 188.0 223.0 179.0 187.0 184.0 193.0 231.0 241.0 182.0 18:46:46 INFO - PID 2489 | 18:46:46 INFO - PID 2489 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.20 Median:70.50 stddev:9.84 (14.0%) stddev-sans-first:10.09 18:46:46 INFO - PID 2489 | Values: 66.0 70.0 65.0 69.0 71.0 68.0 83.0 97.0 75.0 68.0 18:46:46 INFO - PID 2489 | -------- Summary: end -------- 18:46:46 INFO - PID 2489 | 18:46:47 INFO - TEST-INFO | 2489: exit 0 18:46:47 INFO - TEST-OK | kraken | took 61718ms 18:46:47 INFO - SUITE-END | took 1177s 18:46:48 INFO - Completed test suite (00:19:37) 18:46:48 INFO - javascript benchmark 18:46:48 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7901.038862459105, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2909.316310666994, 3598.37028520009, 3600.8919732408035, 3643.8360273787675, 3664.633536646336, 4658.650601204915, 6067.726952287152, 6094.048238764534, 6253.405926436987, 6256.812295631133, 6158.938410615892, 6235.345288744685, 6240.812775616739, 6255.62466252024, 6317.936820631792, 4595.931061034071, 5518.751656175462, 5519.199716041172, 5530.308711411074, 5539.335279766434, 5434.83695489134, 5510.1459273812625, 5570.721463926808, 5594.188842617818, 5643.8024669136585, 5458.8908221835345, 5485.9725701371635, 5533.916991245134, 5543.722813859311, 5554.638948468337, 5495.642783219078, 5500.1749737539285, 5511.090670039439, 5513.50378465936, 5553.666779993213, 5442.0, 5465.207544906006, 5475.260839786618, 5507.146392309199, 5563.777448902039, 4590.724556526619, 6117.143599896018, 6145.340757995361, 6197.349278325801, 6227.470664993481, 6768.390844823989, 6782.491313151533, 6789.830254243634, 6791.966040169817, 6805.149356330458, 6835.07726456927, 6889.448844092461, 6892.483063770187, 6896.689648965808, 6908.447324214101, 6228.626282423069, 6241.906371404432, 6260.624362538262, 6374.426301632875, 6386.840328991766, 6942.618156001468, 6958.304169583002, 6993.440524758007, 7008.334208250208, 7019.508634395544, 6360.745570177141, 6429.292777794439, 6462.935370646327, 6615.966920165369, 6703.932960670429, 6629.502787290972, 6800.285969973181, 6803.863922721518, 6807.965960170267, 6812.284710105467, 6433.871322573522, 6504.544681872224, 6760.0, 6775.796726098127, 6805.523613347018, 6518.28298887122, 6639.302873198245, 6675.332466753286, 6736.2253340865445, 6766.49251306154, 4392.407025051641, 4506.369108324837, 5326.5206131447585, 5343.7060961647485, 5345.251664766935, 4444.599986001275, 4760.571548560646, 4765.0, 4786.16242157621, 4824.131656301899, 5074.568661663726, 5644.8024319148635, 6221.782237621752, 6314.905276420857, 6357.04644303349, 6853.520253582202, 6859.485538584626, 6863.38229559342, 6873.278305777922, 6888.035675005503, 5032.421271553745, 6310.716017779211, 6330.430261276507, 6378.968105159444, 6400.743970241231, 5784.479396854304, 5904.822855314262, 5927.9407205928255, 5935.52515798735, 6049.243844519435, 5847.8245652630485, 6856.24581296057, 6933.82665433348, 6975.1978522468535, 7071.964640176766, 6813.557118787461, 6842.589444633338, 6860.656967151723, 6875.037494750639, 6918.169819621678, 6954.547954382949, 6967.241775477778, 7013.123359580052, 7016.7544135954995, 7045.718171273092, 6450.387213214737, 6541.574797638138, 6669.866602667822, 6700.798976030727, 6754.324567543403, 6532.281449040506, 6825.52221344521, 6888.793336199922, 6889.896651550331, 6892.827679307857, 5243.68537887728, 6365.077063825812, 6600.933990660032, 6615.404613584608, 6632.933670663425, 6102.206713127265, 6570.605763654196, 6570.8357291068105, 6571.737130514726, 6593.472522198117, 5117.462666419972, 5748.425157484218, 6280.811575652738, 6341.8731625368155, 6362.04569314588, 6617.933820661924, 6647.268800431852, 6659.300773418721, 6670.466362691071, 6671.566348187353, 6429.485641148791, 6456.9354306456335, 6464.030395440722, 6472.190976127984, 6476.773312934025], "value": 6090.52526633798, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [690.4338442477168, 725.8112890648433, 800.8318253166834, 1256.666983249439, 1262.242654407355, 35987.64012359876, 37192.25615487695, 37255.999999999935, 37278.813605932, 37374.62625373746, 37499.250014999685, 37842.8107859461, 37877.053073673174, 37980.62019379805, 37990.8100459498, 7917.445778795487, 8103.5948202589925, 8359.164083591622, 8602.139786021411, 8610.225079742822, 8628.266597339234, 8646.481211127353, 8727.345449091312, 8733.737987860375, 8752.256058235058, 7867.291943725064, 8687.174718401773, 8740.825183496327, 8748.518831464267, 8769.25461335784, 7919.485233459806, 8062.8711980322805, 8710.303175745925, 8715.825683486295, 8738.563071846414, 8406.327493800482, 8406.621702023362, 8423.4945903246, 8496.915030849736, 8517.787055323604, 36889.44665830015, 37192.44211336832, 37199.44200836989, 37230.627693722985, 37342.43986340207, 3530.5586801649793, 3554.502369668223, 3560.3235385276716, 3564.4475106358555, 3575.4458059000895, 24142.39644008914, 29763.553546696818, 30638.84680576605, 30716.692833071607, 30749.0, 3136.184592006095, 3160.4469217886776, 3171.0962375722806, 3182.4112539180096, 3193.3772914281726, 235614.0, 239352.0, 279762.99999999796, 294783.0, 297665.0, 39669.20661586752, 47159.5284047162, 47404.57786266418, 47997.76001120042, 48125.51874481211, 40316.79841600773, 48998.10018998143, 49600.78393336607, 49605.751971240636, 49668.75165624221, 13676.179429234278, 13751.724965500634, 13768.655783605289, 13864.0, 13887.444502219798, 11148.442577871074, 11271.718207044889, 11276.548938042386, 11280.153988450898, 11367.431628418546, 10917.39954302505, 13339.266340351374, 13344.331133773285, 13369.997250206274, 13553.39009744564, 5941.58408911372, 5951.7383394310755, 5968.791092311748, 5995.340512543617, 5996.16053752475, 946.8779496296772, 988.2785566536469, 1001.449202938381, 1033.5968972100886, 1082.3451811653993, 24564.877175614365, 24657.0, 24768.0, 24772.628410573856, 24784.876075619508, 1135.1429670598645, 1135.2053562506278, 1172.202902026613, 1174.6006357838376, 1176.611718133014, 12061.819072713917, 12644.747105058032, 12657.620271391874, 12702.745945081231, 12733.936330318289, 285728.0, 321106.0, 321195.0, 321799.0, 322161.9459513531, 54519.0, 54616.453835461136, 54750.0, 54813.725931370886, 54897.725511372184, 1500.9174495402858, 1521.5359315408684, 1561.086764242908, 1567.0675947811035, 1575.2123938030984, 20885.686714699597, 21960.560788783816, 22585.87070646494, 23849.0, 24158.75841241565, 37613.24773504569, 37932.62067379291, 37934.43098353582, 37950.0512487179, 37971.43042854415, 286050.0, 286192.0, 286443.0, 286616.0, 287209.56395218725, 28609.0, 28867.711322887342, 29219.123426297247, 33432.0, 33736.66263337335, 32891.0, 42219.15561688811, 42522.1495570093, 43439.0, 43517.564824352616, 30162.0, 30366.785328586608, 30434.782608695405, 30469.847650761603, 30522.542161867146, 24902.626460602743, 29863.0, 30305.0, 30319.848400757855, 30973.0708078758, 36032.639673603975, 36303.81848090742, 36355.818220908724, 36360.454593181654, 36474.45288320624, 26703.86648066747, 26846.59730104011, 29887.850560747058, 30731.0, 30948.69051309458, 23735.52528949446, 23892.0, 24063.386188440854, 24198.75801241965, 24242.39394015093], "value": 17262.52759816759, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [427.0165083188589, 429.0646390867907, 431.1183629477717, 448.056840351061, 448.10602847319603, 19681.01594920252, 21316.0, 22057.889710551426, 22127.225547105856, 22182.778172218274, 19227.807721922778, 19278.228870845185, 19421.4173574793, 19473.123709433075, 20904.790952090476, 6033.75864965402, 6307.684615769216, 6352.523560732963, 6353.205849268841, 6364.777232796853, 5983.521318294548, 6170.7223174957235, 6195.876082478348, 6243.750249989995, 6244.219472565929, 4958.677685950402, 5105.897882042375, 6198.349173336803, 6198.8450288742915, 6213.316535181127, 5970.522358211333, 6202.379762023784, 6213.440790328847, 6258.4680302174365, 6264.185655864754, 5224.73876306187, 5567.248421463091, 5578.191162281487, 5587.7485513152005, 5588.6646801192055, 27728.584071238947, 28143.155705328874, 28192.859035704896, 28243.293917652016, 28450.573241401395, 2274.4768703198356, 2342.461233916209, 2347.260612906929, 2347.8708671023087, 2349.6592994015773, 15367.0, 16442.588935276595, 17856.732149017775, 18183.63632727338, 18456.907715461337, 2125.107454868959, 2167.2847960173262, 2184.7159869217057, 2187.58435897179, 2188.4310079379316, 160047.1997640016, 161101.19449402677, 162566.74866502604, 163155.0, 164074.0, 12289.754204915851, 12375.69060773488, 12479.438425270884, 12497.93751031239, 12688.80966785499, 16172.272247748879, 16259.943103698222, 16297.34810607586, 16299.59251018734, 16330.265138068815, 8567.271781898484, 8865.379423440427, 8886.86669699955, 9009.324300677476, 9022.593983270768, 8320.625571849281, 8368.3305335573, 8406.74779756596, 8494.362922780816, 8520.829583408296, 7903.249191326815, 8026.357891368676, 8049.6377663005305, 8054.556999364974, 8102.270795628304, 2151.4621344663833, 2166.490874644489, 2170.555036217572, 2175.608390489727, 2317.698699169131, 943.2878176976476, 955.2501286490071, 973.5278389980854, 1002.2783595811004, 1012.2762225009154, 21000.684989725167, 21577.460563485725, 21645.35063948084, 21953.67069493927, 22367.77632223657, 826.2728798657263, 954.1031430455488, 999.4103478947311, 1024.283001898659, 1029.1818004686143, 9337.813243735087, 9477.431354118775, 11383.0, 11414.201005929504, 11454.54181832734, 562106.0, 565065.174674124, 571472.0, 572207.0, 574494.0, 130400.0, 136411.0, 136885.31557342148, 140376.0, 140781.4804629872, 1067.0023528001188, 1161.252315265537, 1196.7845787758113, 1219.3963987825905, 1225.9509619615114, 18760.155792989077, 22342.553148937255, 22569.774302257425, 23277.0, 23450.765492344857, 58824.1176382346, 59018.70490647519, 59427.81144377002, 59629.0, 60077.596896123665, 80457.0, 82841.0, 83153.58423207846, 84121.27029837476, 85110.57444712736, 8757.211850933447, 9246.768830779285, 12878.420471078636, 13026.0, 13469.035502782537, 14017.649558761113, 14158.362873670503, 14606.0, 14658.486952956595, 14817.629559261104, 11365.602203922823, 11984.820227696417, 12101.81847272274, 12376.566820161252, 12425.813612795635, 9735.951320243354, 10275.332103413255, 11508.0, 11606.013488853541, 12004.39978001079, 12282.754344912873, 12475.563355282522, 12496.562620308245, 12731.427085780977, 12733.426995785398, 12039.699007524881, 13414.463421463031, 13462.528811491551, 13549.32253387307, 13778.448862045407, 10280.331778434676, 11779.29324240514, 11863.525458981545, 12004.459799309227, 12416.689582760142], "value": 10758.066810009646, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1389.5136702154246, 1455.941762329507, 1470.7132109238696, 1474.476560820908, 1475.9668232237436, 11232.10143188545, 11513.539458421663, 11843.7631247375, 12092.274463532183, 12153.756924861498, 10937.890621093788, 11981.041516678666, 12200.450979705913, 12223.511059557606, 12248.387580620956, 2555.591105423128, 2593.7146913839556, 2598.116640342283, 2601.5187190369747, 2609.0607381342797, 2282.7717228277206, 2474.8368266914613, 2482.5283196192668, 2511.4098186926058, 2566.5251928393213, 2510.924672259826, 2517.5216708825355, 2539.733328000561, 2546.554352988229, 2547.9872600636972, 2536.746325367467, 2552.6553915221393, 2562.6796650418696, 2563.2310306907852, 2576.497582971321, 2520.180941194101, 2535.125381743314, 2536.8351057181226, 2545.7963362930923, 2546.987265063681, 10579.0, 11478.311301321864, 11990.70023249417, 12169.93915030428, 12226.816597751042, 6702.4302934250645, 6919.204291506491, 6961.129858767654, 6963.268856770068, 6963.6866341014265, 3984.382420724792, 4272.594103560157, 4388.670849686287, 4411.6250118739945, 4415.558444155558, 1267.1700036476122, 1268.9746205075846, 1288.871112888713, 1313.382710126239, 1315.8486774021014, 16611.83388166115, 16731.247093880684, 16890.66218675632, 16895.15524223796, 16938.491845244538, 5642.097264437671, 5777.480026797609, 5849.415058494116, 5873.471387575053, 5993.011153159679, 12290.877091229153, 12433.813492797615, 12479.56321528742, 12497.687557810945, 12522.248665079942, 6684.699188536528, 6738.561993470409, 6757.256701762803, 6764.391204791593, 6764.76323328691, 6054.334023257438, 6276.811695649138, 6311.684415779193, 6338.651374174372, 6347.365263473615, 4381.277089280296, 4522.389477420506, 4577.954220457819, 4637.350770892078, 4646.558576935254, 1297.935103244834, 1325.1372249891458, 1344.5764584155959, 1347.7169794343101, 1351.1217708489364, 729.6643543969727, 757.2087168908504, 784.1531146361915, 810.3031393002013, 811.6996711216888, 6797.243633500946, 7014.123234595676, 7104.9704000953925, 7106.289371062956, 7131.144262688511, 795.6896810244009, 959.3811991265594, 960.3277705606104, 963.7686955130858, 966.087047739879, 4378.956210437855, 4457.754823484739, 4543.909121817545, 4551.749653769075, 4560.908781824345, 14846.257687115602, 14924.776128358084, 15330.080195188324, 15354.385824567116, 15418.229088545751, 12594.929430998467, 14962.775558366633, 15006.849931500763, 15135.243237838064, 15484.380624775109, 8466.153384661484, 8781.736547903573, 8810.647574096964, 8812.51531165792, 8862.955685221661, 5913.940860591339, 6377.8519866424485, 6648.905822877807, 6829.180498340231, 6920.792376228721, 11701.005414539859, 12674.683132921751, 12962.611121666365, 13657.863421365659, 13806.447742090204, 14085.507007254697, 14149.151050936976, 14194.858051419353, 14240.931930105491, 14544.0, 7833.843323133619, 7918.693415586364, 7923.960380198062, 8988.86516702237, 9027.232685221566, 9434.575444105169, 9838.163756080814, 10993.670189894316, 11033.613823516138, 11041.227114101936, 8221.260086592236, 9108.453492790453, 9775.608975641178, 9784.706458806248, 9842.65550705722, 9451.149396554343, 9531.904680953101, 9554.52227388614, 9718.465484398426, 9732.610695572097, 8162.877556836771, 8293.709720159766, 8412.327013839, 8414.957925210334, 8435.0, 6090.847728806815, 6196.256449225942, 6213.9067913980425, 6227.782027629011, 6306.085617585332, 5230.0, 6163.476104530986, 6325.146105275729, 6338.954072577973, 6465.2564955030775], "value": 5481.533012003895, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [402.12938987092946, 402.63963752441566, 405.8072415602589, 407.8042539581001, 416.49811976568236, 5376.301080859487, 5873.500752436037, 6172.290186628539, 6237.812865614028, 6275.0, 5571.777128914849, 5847.181394604748, 5957.40425957404, 6096.817095487131, 6097.512199024079, 3979.363301871701, 4678.812847486097, 4695.976520117411, 4714.175019371613, 4745.76271186441, 4184.66522678185, 4458.866234012985, 4721.7639118044135, 4742.596879265267, 4759.785809638574, 3840.692744580441, 3979.621935916083, 4463.33050042493, 4627.953720462803, 4676.906461870778, 4015.0, 4660.813567457298, 4678.157931572316, 4684.859454216379, 4695.1548721230165, 4159.376093585956, 4162.854300099482, 4172.520160181588, 4177.707560470768, 4180.665546756252, 4375.912481750379, 5286.365636123652, 5443.727813609344, 5497.5601951844155, 6075.665838378887, 5090.727318170458, 5101.566366858821, 5500.477454641843, 5529.612927095105, 5567.2762540869835, 5739.684317362543, 6225.533085018642, 6333.46165575922, 6568.0, 6610.975658499575, 4213.0, 4657.953420465786, 4698.38920940279, 4705.458872229703, 4712.868911461224, 6151.984922487784, 6614.933850661528, 6655.633940133291, 6763.898541521881, 6776.42400395967, 4457.153140903218, 4816.8073277069225, 4851.878703032452, 4862.100511405401, 4871.683340582851, 4110.965072289236, 4923.5814955729165, 4927.236278376858, 4956.306117143603, 4957.3059771632, 4954.851354459372, 5239.423663397023, 5491.313585801775, 5542.307211598551, 5554.805581804618, 6572.934270657232, 6574.80275591733, 6606.504512161511, 6606.7026983785845, 6613.173353330833, 1344.3009634989946, 1666.5916850371736, 1687.392538686072, 1688.923998420074, 1690.4252554131654, 971.4220039076738, 1023.744063984004, 1123.2193625430248, 1138.334074566388, 1164.318873459036, 5456.945430545723, 5788.305403351625, 6025.9153352396115, 6203.069539569011, 6224.408681175281, 6184.381561843779, 6728.831779205559, 6761.425278851254, 6761.797146085625, 6770.593764374154, 4681.906361872743, 6037.034074548052, 6054.0, 6242.219722534684, 6276.372362763687, 3208.326251487214, 3473.878414255489, 3529.1177205698577, 3560.7329450290813, 3561.5904171020147, 6502.382273683992, 6898.931010689829, 6976.302369762983, 7011.964940175369, 7017.859642807115, 6871.587704737732, 6967.049226216566, 6973.02377667127, 6977.7208911642965, 7005.299470053056, 5661.660300381908, 5902.321233058254, 6085.8741132890555, 6644.2392765917075, 6912.377885990285, 5490.560755139659, 5917.822465326047, 6203.192525792547, 6672.833179170365, 6785.9660701696175, 5592.853465039739, 5746.827595172152, 6051.667158306159, 6673.466122710269, 6715.1941766988275, 7097.038487670382, 7108.751193708195, 7131.894556343775, 7141.250168732416, 7161.209697575606, 3735.3276410246417, 4448.621867141329, 4468.4637843458995, 4473.865784026354, 4492.887677808081, 6169.259688837368, 6630.204375474974, 6836.965815170893, 6844.384005439534, 6851.725930962905, 6593.901091483535, 6598.505112116514, 6605.966970165118, 6615.213892249162, 6651.767188148391, 5236.764345604533, 6427.485801135991, 6451.1936007999, 6552.967235163794, 6583.407493325623, 5202.0, 5388.29157698652, 6476.6114033158165, 6492.253390860112, 6562.212534495891, 5394.487523685244, 5959.463648271676, 6105.145279660762, 6331.335209803088, 6590.044443555754, 6410.903836442364, 6423.454006409507, 6423.518236132402, 6424.871502570016, 6461.515386346136], "value": 4805.011607259568, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [726.5277569579772, 757.155771314984, 807.0517142357725, 859.2739135430559, 860.7073594977709, 16388.590285242874, 16782.244798984044, 16814.57963550912, 18494.630107397843, 19048.0, 17779.37772177974, 18147.54631134219, 18185.181666824992, 18331.633367332644, 18335.54161145972, 942.6465075596651, 943.3396622364355, 957.5260246178152, 958.4345236310576, 965.1120968708805, 957.7749228931231, 964.4502633498909, 964.6189755046752, 969.6751588217955, 972.0959507657875, 1018.0786388318584, 1023.253025291534, 1024.0, 1024.933379330341, 1029.6241871716807, 1019.0828254570871, 1019.2559431614889, 1020.4591566469746, 1021.1728499915092, 1021.4841505039944, 598.0013377659319, 722.8771108911498, 734.1850545894054, 736.6869080640706, 744.750508579621, 16816.495505134866, 16950.321987120624, 17008.744868826976, 17016.234269457906, 17147.57131071721, 16753.99476031442, 16971.74542381865, 17194.05432701201, 17332.13339333028, 17351.305947762074, 13926.721465570734, 13976.091554048955, 14045.297735113305, 14245.928770356082, 14266.215358155298, 3509.2455122148863, 3567.1973805893726, 3567.7145828333933, 3571.5714114306447, 3580.4987301777774, 67154.66422667904, 125778.74221257762, 126610.0, 127201.0, 128793.0, 11059.170562207706, 12825.679358015937, 13234.536791212262, 13288.269145197108, 13416.798748018788, 24320.878395608263, 24631.753682463306, 25135.371615709755, 25206.865691043946, 25536.361590960005, 7762.92237077622, 7817.68729250835, 7821.335186509211, 7845.529268243924, 7884.250996155356, 3829.8468061277235, 3872.941905871358, 3891.20230352777, 3898.7270891037356, 3905.511811023622, 4372.934405983913, 4408.426904502394, 4458.799354029076, 4471.5081341052455, 4481.977590112028, 802.3180296747718, 941.1695430077258, 958.0993865766158, 958.6405098088217, 961.6345788600287, 646.2083947164685, 648.5265755998148, 656.9901451478231, 662.3806740697463, 676.9559978601375, 10947.397893115796, 11648.708782280512, 11884.881151188378, 12043.096767742454, 12081.397371232604, 652.3118110393485, 656.678227668439, 660.8579155481499, 668.2482207516543, 687.8314812870778, 5265.7103859287345, 5313.362396512443, 5454.972725136428, 5483.75323110461, 5641.379448260688, 95383.52308238554, 101980.49009754903, 107474.0, 108863.0, 109763.0, 59335.0, 74476.0, 85942.57028714816, 96047.0, 98101.50949245208, 60387.698061509414, 64634.67682661651, 64864.0270395935, 66110.33889661139, 66127.33872661211, 12058.336791476553, 12408.007359411406, 12421.316827574208, 12443.128980971245, 12520.499180032697, 30720.849540531948, 36371.63628363788, 36971.815140924125, 38771.806140969114, 39194.80402597969, 46475.53524464712, 46570.534294656616, 46905.296420554405, 47032.52967470281, 47522.0, 15691.529254122395, 15773.68452630918, 15983.440579580125, 15997.760033599272, 16185.352585896433, 17363.30546778115, 17672.823271767116, 17738.50074558804, 17955.910220449336, 17974.460766177035, 16061.028709688744, 16184.947978381368, 16217.945833520784, 16334.0, 16379.672406551565, 15909.840901591298, 15960.680786384439, 15969.0, 16107.194640268173, 16187.190640468165, 15765.921170394075, 15868.52394428169, 15901.602459938595, 15959.920200398923, 16079.115648639437, 9888.85166722514, 11508.079353651521, 11563.768724625292, 11755.823662644896, 11906.464209110438, 12607.621771346874, 13313.534026309033, 13516.459341626618, 13552.451165470775, 13766.174029558259], "value": 8165.551383354708, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1562.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 171.0, 163.0, 115.0, 121.0, 167.0, 116.0, 114.0, 120.0, 167.0], "value": 136.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 112.0, 124.0, 182.0, 142.0, 147.0, 110.0, 118.0, 172.0, 148.0], "value": 140.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [205.0, 217.0, 212.0, 205.0, 196.0, 197.0, 234.0, 247.0, 196.0, 195.0], "value": 210.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 61.0, 81.0, 82.0, 63.0, 85.0, 91.0, 50.0, 83.0, 82.0], "value": 76.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 68.0, 84.0, 73.0, 73.0, 71.0, 86.0, 69.0, 70.0, 94.0], "value": 77.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 90.0, 90.0, 103.0, 135.0, 136.0, 128.0, 92.0, 92.0, 90.0], "value": 104.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 98.0, 97.0, 97.0, 97.0, 97.0, 97.0, 97.0, 103.0, 98.0], "value": 97.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 88.0, 90.0, 90.0, 96.0, 116.0, 148.0, 93.0, 92.0, 93.0], "value": 99.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 50.0, 51.0, 52.0, 50.0, 49.0, 47.0, 48.0, 51.0, 51.0], "value": 49.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 60.0, 57.0, 58.0, 61.0, 58.0, 57.0, 59.0, 58.0, 57.0], "value": 58.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 85.0, 79.0, 99.0, 113.0, 106.0, 107.0, 81.0, 85.0], "value": 92.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 141.0, 139.0, 135.0, 139.0, 139.0, 136.0, 149.0, 185.0, 170.0], "value": 147.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 188.0, 223.0, 179.0, 187.0, 184.0, 193.0, 231.0, 241.0, 182.0], "value": 199.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 70.0, 65.0, 69.0, 71.0, 68.0, 83.0, 97.0, 75.0, 68.0], "value": 73.2, "unit": "score"}]}]} 18:46:48 INFO - Return code: 0 18:46:48 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:46:48 ERROR - # TBPL SUCCESS # 18:46:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:46:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:46:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:46:48 INFO - 2017-04-21 18:46:48.245 screenresolution[2494:39924] starting screenresolution argv=screenresolution get 18:46:48 INFO - 2017-04-21 18:46:48.248 screenresolution[2494:39924] Display 0: 1600x1200x32@60 18:46:48 INFO - 2017-04-21 18:46:48.254 screenresolution[2495:39927] starting screenresolution argv=screenresolution list 18:46:48 INFO - Available Modes on Display 0 18:46:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:46:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:46:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:46:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:46:48 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:46:48 INFO - Intel Iris: 18:46:48 INFO - Chipset Model: Intel Iris 18:46:48 INFO - Type: GPU 18:46:48 INFO - Bus: Built-In 18:46:48 INFO - VRAM (Dynamic, Max): 1536 MB 18:46:48 INFO - Vendor: Intel (0x8086) 18:46:48 INFO - Device ID: 0x0a2e 18:46:48 INFO - Revision ID: 0x0009 18:46:48 INFO - Displays: 18:46:48 INFO - Display: 18:46:48 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:46:48 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:46:48 INFO - Display Serial Number: 1600x1200 60 18:46:48 INFO - Main Display: Yes 18:46:48 INFO - Mirror: Off 18:46:48 INFO - Online: Yes 18:46:48 INFO - Rotation: Supported 18:46:48 INFO - Return code: 0 18:46:48 INFO - Running post-action listener: _resource_record_post_action 18:46:48 INFO - [mozharness: 2017-04-22 01:46:48.497388Z] Finished run-tests step (success) 18:46:48 INFO - Running post-run listener: _resource_record_post_run 18:46:48 INFO - Total resource usage - Wall time: 1198s; CPU: 54.0%; Read bytes: 1454080; Write bytes: 519568896; Read time: 11; Write time: 1717 18:46:48 INFO - TinderboxPrint: CPU usage
54.0% 18:46:48 INFO - TinderboxPrint: I/O read bytes / time
1,454,080 / 11 18:46:48 INFO - TinderboxPrint: I/O write bytes / time
519,568,896 / 1,717 18:46:48 INFO - TinderboxPrint: CPU idle
2,205.6 (46.0%) 18:46:48 INFO - TinderboxPrint: CPU system
961.8 (20.1%) 18:46:48 INFO - TinderboxPrint: CPU user
1,626.2 (33.9%) 18:46:48 INFO - TinderboxPrint: Swap in / out
208,973,824 / 0 18:46:48 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 209041920; Write bytes: 211982336; Read time: 16034; Write time: 450 18:46:48 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 524288; Write bytes: 306251264; Read time: 5; Write time: 1262 18:46:48 INFO - Running post-run listener: _upload_blobber_files 18:46:48 INFO - Blob upload gear active. 18:46:48 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:46:48 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 18:46:48 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', 'Pine', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:46:48 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 Pine -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:46:49 INFO - (blobuploader) - INFO - Open directory for files ... 18:46:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:46:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:46:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:46:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:46:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:46:50 INFO - (blobuploader) - INFO - Done attempting. 18:46:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:46:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:46:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:46:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:46:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:46:51 INFO - (blobuploader) - INFO - Done attempting. 18:46:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:46:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:46:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:46:51 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:46:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:46:51 INFO - (blobuploader) - INFO - Done attempting. 18:46:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:46:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:46:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:46:53 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:46:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:46:53 INFO - (blobuploader) - INFO - Done attempting. 18:46:53 INFO - (blobuploader) - INFO - Iteration through files over. 18:46:53 INFO - Return code: 0 18:46:53 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:46:53 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:46:53 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/f46c3de7407ac2a3950f841193973d3b4f3c191e186b3248d4a18dd9a067f64726c680249f4a80eabeaa2abcd1b57487abeaae801e999266179a6ea51bad6741", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/c31942bb29151e228bd544c3bf6ed7d71a072f4d614826bf589c008fc4ae99d7ea18643be29ff0d3b62e42be2d86e449b51efcb7286b10b1f87b47c0e1a0ff29", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/c3985d5f4f70f172d80c4d5b5a5590346f74746e073b89891e34fb0bae142674d127ec0438b802be683ce6fb505002da018349bb09829510f04f42e16acaf364", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/10da0b1846e746106557ce0ef152551d35ac4d5bece6db38f7a5e77609fcfb21db0b7abdfee783dbb93fd32b170af74f88d88ade4a7dab2284965683fec028c3"} 18:46:53 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:46:53 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:46:53 INFO - Contents: 18:46:53 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/f46c3de7407ac2a3950f841193973d3b4f3c191e186b3248d4a18dd9a067f64726c680249f4a80eabeaa2abcd1b57487abeaae801e999266179a6ea51bad6741", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/c31942bb29151e228bd544c3bf6ed7d71a072f4d614826bf589c008fc4ae99d7ea18643be29ff0d3b62e42be2d86e449b51efcb7286b10b1f87b47c0e1a0ff29", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/c3985d5f4f70f172d80c4d5b5a5590346f74746e073b89891e34fb0bae142674d127ec0438b802be683ce6fb505002da018349bb09829510f04f42e16acaf364", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/10da0b1846e746106557ce0ef152551d35ac4d5bece6db38f7a5e77609fcfb21db0b7abdfee783dbb93fd32b170af74f88d88ade4a7dab2284965683fec028c3"} 18:46:53 INFO - Running post-run listener: copy_logs_to_upload_dir 18:46:53 INFO - Copying logs to upload dir... 18:46:53 INFO - mkdir: /builds/slave/test/build/upload/logs 18:46:53 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1278.265760 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2017-04-21 18:46:53.826541) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-21 18:46:53.829665) ========= 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.8KJd9FRLdy/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.nWWSZQnhlI/Listeners TMPDIR=/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/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/Pine/sha512/f46c3de7407ac2a3950f841193973d3b4f3c191e186b3248d4a18dd9a067f64726c680249f4a80eabeaa2abcd1b57487abeaae801e999266179a6ea51bad6741", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/c31942bb29151e228bd544c3bf6ed7d71a072f4d614826bf589c008fc4ae99d7ea18643be29ff0d3b62e42be2d86e449b51efcb7286b10b1f87b47c0e1a0ff29", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/c3985d5f4f70f172d80c4d5b5a5590346f74746e073b89891e34fb0bae142674d127ec0438b802be683ce6fb505002da018349bb09829510f04f42e16acaf364", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/10da0b1846e746106557ce0ef152551d35ac4d5bece6db38f7a5e77609fcfb21db0b7abdfee783dbb93fd32b170af74f88d88ade4a7dab2284965683fec028c3"} build_url:https://queue.taskcluster.net/v1/task/QAPOB5PRRoOcPDxZAQEj9w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011279 build_url: 'https://queue.taskcluster.net/v1/task/QAPOB5PRRoOcPDxZAQEj9w/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/f46c3de7407ac2a3950f841193973d3b4f3c191e186b3248d4a18dd9a067f64726c680249f4a80eabeaa2abcd1b57487abeaae801e999266179a6ea51bad6741", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/c31942bb29151e228bd544c3bf6ed7d71a072f4d614826bf589c008fc4ae99d7ea18643be29ff0d3b62e42be2d86e449b51efcb7286b10b1f87b47c0e1a0ff29", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/c3985d5f4f70f172d80c4d5b5a5590346f74746e073b89891e34fb0bae142674d127ec0438b802be683ce6fb505002da018349bb09829510f04f42e16acaf364", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/10da0b1846e746106557ce0ef152551d35ac4d5bece6db38f7a5e77609fcfb21db0b7abdfee783dbb93fd32b170af74f88d88ade4a7dab2284965683fec028c3"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-21 18:46:53.865145) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-21 18:46:53.865498) ========= 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.8KJd9FRLdy/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.nWWSZQnhlI/Listeners TMPDIR=/var/folders/f4/zwr2n_y50tg1y44pl818tgt800000w/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.004784 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-21 18:46:53.914174) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-21 18:46:53.914496) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-21 18:46:53.914812) ========= ========= Total master_lag: 0.19 =========