builder: pine_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0099 starttime: 1493272043.38 results: success (0) buildid: 20170426090525 builduid: 5a5b915244ad42ba8f6a9b27ba7a58db revision: ba912b0f3ea88b1ee2a06d2e26cf77bfcf5a3f3e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-26 22:47:23.384887) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-26 22:47:23.385311) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-26 22:47:23.385594) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-26 22:47:23.412948) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-26 22:47:23.413349) ========= 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.4xVuKgeWXB/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.7T9L0PGXHs/Listeners TMPDIR=/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/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-26 22:47:23-- 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% 65.6M=0s 2017-04-26 22:47:23 (65.6 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.156842 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-26 22:47:23.595807) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-26 22:47:23.596134) ========= 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.4xVuKgeWXB/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.7T9L0PGXHs/Listeners TMPDIR=/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/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.031098 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-26 22:47:23.650406) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-26 22:47:23.650835) ========= bash -c 'python archiver_client.py mozharness --repo projects/pine --rev ba912b0f3ea88b1ee2a06d2e26cf77bfcf5a3f3e --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 ba912b0f3ea88b1ee2a06d2e26cf77bfcf5a3f3e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.4xVuKgeWXB/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.7T9L0PGXHs/Listeners TMPDIR=/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/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-26 22:47:23,761 truncating revision to first 12 chars 2017-04-26 22:47:23,761 Setting DEBUG logging. 2017-04-26 22:47:23,761 attempt 1/10 2017-04-26 22:47:23,761 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/pine/ba912b0f3ea8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-26 22:47:24,400 unpacking tar archive at: pine-ba912b0f3ea8/testing/mozharness/ program finished with exit code 0 elapsedTime=0.919950 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-26 22:47:24.596744) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-26 22:47:24.597121) ========= script_repo_revision: ba912b0f3ea88b1ee2a06d2e26cf77bfcf5a3f3e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-26 22:47:24.597517) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-26 22:47:24.597789) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-26 22:47:24.619894) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2017-04-26 22:47:24.620315) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --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', '--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.4xVuKgeWXB/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.7T9L0PGXHs/Listeners TMPDIR=/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 22:47:24 INFO - MultiFileLogger online at 20170426 22:47:24 in /builds/slave/test 22:47:24 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Pine --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Pine 22:47:24 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:47:24 INFO - {'append_to_log': False, 22:47:24 INFO - 'base_work_dir': '/builds/slave/test', 22:47:24 INFO - 'blob_upload_branch': 'Pine', 22:47:24 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:47:24 INFO - 'branch': 'Pine', 22:47:24 INFO - 'buildbot_json_path': 'buildprops.json', 22:47:24 INFO - 'config_files': ('talos/mac_config.py',), 22:47:24 INFO - 'default_actions': ('clobber', 22:47:24 INFO - 'read-buildbot-config', 22:47:24 INFO - 'download-and-extract', 22:47:24 INFO - 'populate-webroot', 22:47:24 INFO - 'create-virtualenv', 22:47:24 INFO - 'install', 22:47:24 INFO - 'run-tests'), 22:47:24 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:47:24 INFO - 'download_minidump_stackwalk': True, 22:47:24 INFO - 'download_symbols': 'ondemand', 22:47:24 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:47:24 INFO - 'tooltool.py': '/tools/tooltool.py', 22:47:24 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:47:24 INFO - '/tools/misc-python/virtualenv.py')}, 22:47:24 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:47:24 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:47:24 INFO - 'gecko_profile': False, 22:47:24 INFO - 'gecko_profile_interval': 0, 22:47:24 INFO - 'installer_path': 'installer.exe', 22:47:24 INFO - 'log_level': 'info', 22:47:24 INFO - 'log_name': 'talos', 22:47:24 INFO - 'log_to_console': True, 22:47:24 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 22:47:24 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 22:47:24 INFO - 'opt_config_files': (), 22:47:24 INFO - 'pip_index': False, 22:47:24 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:47:24 INFO - 'cmd': ('bash', 22:47:24 INFO - '-c', 22:47:24 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:47:24 INFO - 'enabled': True, 22:47:24 INFO - 'halt_on_failure': False, 22:47:24 INFO - 'name': 'check_screen_resolution'},), 22:47:24 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:47:24 INFO - 'cmd': ('bash', 22:47:24 INFO - '-c', 22:47:24 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:47:24 INFO - 'enabled': True, 22:47:24 INFO - 'halt_on_failure': False, 22:47:24 INFO - 'name': 'check_screen_resolution'},), 22:47:24 INFO - 'run_cmd_checks_enabled': True, 22:47:24 INFO - 'suite': 'dromaeojs', 22:47:24 INFO - 'system_bits': '32', 22:47:24 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:47:24 INFO - 'title': 't-yosemite-r7-0099', 22:47:24 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:47:24 INFO - 'use_talos_json': True, 22:47:24 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:47:24 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:47:24 INFO - 'work_dir': 'build'} 22:47:24 INFO - [mozharness: 2017-04-27 05:47:24.838932Z] Running clobber step. 22:47:24 INFO - Running pre-action listener: _resource_record_pre_action 22:47:24 INFO - Running main action method: clobber 22:47:24 INFO - rmtree: /builds/slave/test/build 22:47:24 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:47:27 INFO - Running post-action listener: _resource_record_post_action 22:47:27 INFO - [mozharness: 2017-04-27 05:47:27.492062Z] Finished clobber step (success) 22:47:27 INFO - [mozharness: 2017-04-27 05:47:27.492190Z] Running read-buildbot-config step. 22:47:27 INFO - Running pre-action listener: _resource_record_pre_action 22:47:27 INFO - Running main action method: read_buildbot_config 22:47:27 INFO - Using buildbot properties: 22:47:27 INFO - { 22:47:27 INFO - "project": "", 22:47:27 INFO - "product": "firefox", 22:47:27 INFO - "script_repo_revision": "default", 22:47:27 INFO - "builddir": "pine_yosemite_r7_test-dromaeojs", 22:47:27 INFO - "repository": "", 22:47:27 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 pine talos dromaeojs", 22:47:27 INFO - "buildid": "20170426090525", 22:47:27 INFO - "pgo_build": "False", 22:47:27 INFO - "basedir": "/builds/slave/test", 22:47:27 INFO - "buildnumber": 9, 22:47:27 INFO - "slavename": "t-yosemite-r7-0099", 22:47:27 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 22:47:27 INFO - "platform": "yosemite_r7", 22:47:27 INFO - "scheduler": "tests-pine-macosx64-talos", 22:47:27 INFO - "branch": "pine", 22:47:27 INFO - "repo_path": "projects/pine", 22:47:27 INFO - "revision": "ba912b0f3ea88b1ee2a06d2e26cf77bfcf5a3f3e", 22:47:27 INFO - "stage_platform": "macosx64", 22:47:27 INFO - "builduid": "5a5b915244ad42ba8f6a9b27ba7a58db", 22:47:27 INFO - "slavebuilddir": "test" 22:47:27 INFO - } 22:47:27 INFO - Found installer url https://queue.taskcluster.net/v1/task/e4rBHDOHTvuWDsOSvKffsw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 22:47:27 INFO - Running post-action listener: _resource_record_post_action 22:47:27 INFO - [mozharness: 2017-04-27 05:47:27.495405Z] Finished read-buildbot-config step (success) 22:47:27 INFO - [mozharness: 2017-04-27 05:47:27.495541Z] Running download-and-extract step. 22:47:27 INFO - Running pre-action listener: _resource_record_pre_action 22:47:27 INFO - Running main action method: download_and_extract 22:47:27 INFO - mkdir: /builds/slave/test/build/tests 22:47:27 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')]} 22:47:27 INFO - trying https://queue.taskcluster.net/v1/task/e4rBHDOHTvuWDsOSvKffsw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 22:47:27 INFO - Downloading https://queue.taskcluster.net/v1/task/e4rBHDOHTvuWDsOSvKffsw/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 22:47:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/e4rBHDOHTvuWDsOSvKffsw/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 22:47:28 INFO - Downloaded 1325 bytes. 22:47:28 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 22:47:28 INFO - Using the following test package requirements: 22:47:28 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:47:28 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 22:47:28 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 22:47:28 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:47:28 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 22:47:28 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:47:28 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 22:47:28 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:47:28 INFO - u'jsshell-mac.zip'], 22:47:28 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:47:28 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 22:47:28 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 22:47:28 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:47:28 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 22:47:28 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:47:28 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 22:47:28 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:47:28 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 22:47:28 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:47:28 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 22:47:28 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 22:47:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/e4rBHDOHTvuWDsOSvKffsw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 22:47:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/e4rBHDOHTvuWDsOSvKffsw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:47:28 INFO - Fetch https://queue.taskcluster.net/v1/task/e4rBHDOHTvuWDsOSvKffsw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 22:47:29 INFO - Content-Length response header: 52529807 22:47:29 INFO - Bytes received: 52529807 22:47:46 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 22:47:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/e4rBHDOHTvuWDsOSvKffsw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 22:47:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/e4rBHDOHTvuWDsOSvKffsw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:47:46 INFO - Fetch https://queue.taskcluster.net/v1/task/e4rBHDOHTvuWDsOSvKffsw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 22:47:48 INFO - Content-Length response header: 52529807 22:47:48 INFO - Bytes received: 52529807 22:48:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/e4rBHDOHTvuWDsOSvKffsw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 22:48:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/e4rBHDOHTvuWDsOSvKffsw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 22:48:03 INFO - Fetch https://queue.taskcluster.net/v1/task/e4rBHDOHTvuWDsOSvKffsw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 22:48:04 INFO - Content-Length response header: 14408809 22:48:04 INFO - Bytes received: 14408809 22:48:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:48:05 INFO - trying https://queue.taskcluster.net/v1/task/e4rBHDOHTvuWDsOSvKffsw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 22:48:05 INFO - Downloading https://queue.taskcluster.net/v1/task/e4rBHDOHTvuWDsOSvKffsw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 22:48:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/e4rBHDOHTvuWDsOSvKffsw/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 22:48:07 INFO - Downloaded 71195220 bytes. 22:48:07 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/e4rBHDOHTvuWDsOSvKffsw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 22:48:07 INFO - mkdir: /builds/slave/test/properties 22:48:07 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:48:07 INFO - Writing to file /builds/slave/test/properties/build_url 22:48:07 INFO - Contents: 22:48:07 INFO - build_url:https://queue.taskcluster.net/v1/task/e4rBHDOHTvuWDsOSvKffsw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 22:48:07 INFO - Running post-action listener: _resource_record_post_action 22:48:07 INFO - Running post-action listener: set_extra_try_arguments 22:48:07 INFO - [mozharness: 2017-04-27 05:48:07.896062Z] Finished download-and-extract step (success) 22:48:07 INFO - [mozharness: 2017-04-27 05:48:07.896185Z] Running populate-webroot step. 22:48:07 INFO - Running pre-action listener: _resource_record_pre_action 22:48:07 INFO - Running main action method: populate_webroot 22:48:07 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:48:07 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:48:07 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:48:07 INFO - '--tppagecycles', 22:48:07 INFO - '7'], 22:48:07 INFO - 'tests': ['tsvgm']}}, 22:48:07 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:48:07 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:48:07 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:48:07 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:48:07 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:48:07 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:48:07 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 22:48:07 INFO - 'talos_options': ['--disable-e10s'], 22:48:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:48:07 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 22:48:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:48:07 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 22:48:07 INFO - 'talos_options': ['--disable-e10s'], 22:48:07 INFO - 'tests': ['damp', 'tps']}, 22:48:07 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 22:48:07 INFO - 'tests': ['damp', 'tps']}, 22:48:07 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:48:07 INFO - 'tests': ['dromaeo_dom']}, 22:48:07 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:48:07 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:48:07 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 22:48:07 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 22:48:07 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:48:07 INFO - 'tests': ['a11yr', 22:48:07 INFO - 'ts_paint', 22:48:07 INFO - 'tpaint', 22:48:07 INFO - 'sessionrestore', 22:48:07 INFO - 'sessionrestore_no_auto_restore', 22:48:07 INFO - 'tabpaint']}, 22:48:07 INFO - 'other-e10s': {'tests': ['a11yr', 22:48:07 INFO - 'ts_paint', 22:48:07 INFO - 'tpaint', 22:48:07 INFO - 'sessionrestore', 22:48:07 INFO - 'sessionrestore_no_auto_restore', 22:48:07 INFO - 'tabpaint']}, 22:48:07 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:48:07 INFO - 'ts_paint', 22:48:07 INFO - 'tpaint', 22:48:07 INFO - 'sessionrestore', 22:48:07 INFO - 'sessionrestore_no_auto_restore', 22:48:07 INFO - 'tabpaint']}, 22:48:07 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:48:07 INFO - 'ts_paint', 22:48:07 INFO - 'tpaint', 22:48:07 INFO - 'sessionrestore', 22:48:07 INFO - 'sessionrestore_no_auto_restore', 22:48:07 INFO - 'tabpaint']}, 22:48:07 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:48:07 INFO - 'tests': ['a11yr', 22:48:07 INFO - 'ts_paint', 22:48:07 INFO - 'tpaint', 22:48:07 INFO - 'sessionrestore', 22:48:07 INFO - 'sessionrestore_no_auto_restore', 22:48:07 INFO - 'tabpaint']}, 22:48:07 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:48:07 INFO - 'tests': ['a11yr', 22:48:07 INFO - 'ts_paint', 22:48:07 INFO - 'tpaint', 22:48:07 INFO - 'sessionrestore', 22:48:07 INFO - 'sessionrestore_no_auto_restore', 22:48:07 INFO - 'tabpaint']}, 22:48:07 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 22:48:07 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 22:48:07 INFO - 'bloom_basic_ref']}, 22:48:07 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:48:07 INFO - 'tests': ['tsvgx', 22:48:07 INFO - 'tsvgr_opacity', 22:48:07 INFO - 'tart', 22:48:07 INFO - 'tscrollx', 22:48:07 INFO - 'cart', 22:48:07 INFO - 'tsvg_static']}, 22:48:07 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:48:07 INFO - 'tsvgr_opacity', 22:48:07 INFO - 'tart', 22:48:07 INFO - 'tscrollx', 22:48:07 INFO - 'cart', 22:48:07 INFO - 'tsvg_static']}, 22:48:07 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 22:48:07 INFO - 'talos_options': ['--disable-e10s'], 22:48:07 INFO - 'tests': ['tp5o']}, 22:48:07 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 22:48:07 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 22:48:07 INFO - 'talos_options': ['--disable-e10s', 22:48:07 INFO - '--xperf_path', 22:48:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:48:07 INFO - 'tests': ['tp5n']}, 22:48:07 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 22:48:07 INFO - 'talos_options': ['--xperf_path', 22:48:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:48:07 INFO - 'tests': ['tp5n']}}, 22:48:07 INFO - 'talos.zip': {'path': '', 22:48:07 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:48:07 INFO - Running post-action listener: _resource_record_post_action 22:48:07 INFO - [mozharness: 2017-04-27 05:48:07.914683Z] Finished populate-webroot step (success) 22:48:07 INFO - [mozharness: 2017-04-27 05:48:07.914824Z] Running create-virtualenv step. 22:48:07 INFO - Running pre-action listener: _resource_record_pre_action 22:48:07 INFO - Running main action method: create_virtualenv 22:48:07 INFO - Creating virtualenv /builds/slave/test/build/venv 22:48:07 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 22:48:07 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:48:08 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:48:08 INFO - Using real prefix '/tools/python27' 22:48:08 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:48:08 INFO - Installing distribute.............................................................................................................................................................................................done. 22:48:11 INFO - Installing pip.................done. 22:48:11 INFO - Return code: 0 22:48:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 22:48:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 22:48:11 INFO - Reading from file tmpfile_stdout 22:48:11 INFO - Output received: 22:48:11 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) 22:48:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:48:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:48:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:48:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:48:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:48:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:11 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 0x103392b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103395768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa7615a6960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103170d30>, '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 0x103096db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103165be8>, '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.4xVuKgeWXB/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.7T9L0PGXHs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:48:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 22:48:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 22:48:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4xVuKgeWXB/Render', 22:48:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:48:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:48:11 INFO - 'HOME': '/Users/cltbld', 22:48:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:48:11 INFO - 'LOGNAME': 'cltbld', 22:48:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:48:11 INFO - 'MOZ_NO_REMOTE': '1', 22:48:11 INFO - 'NO_EM_RESTART': '1', 22:48:11 INFO - 'PAGER': '/bin/cat', 22:48:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:48:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:48:11 INFO - 'PWD': '/builds/slave/test', 22:48:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:48:11 INFO - 'SHELL': '/bin/bash', 22:48:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7T9L0PGXHs/Listeners', 22:48:11 INFO - 'TMPDIR': '/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/', 22:48:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:48:11 INFO - 'USER': 'cltbld', 22:48:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:48:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:48:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:48:11 INFO - 'XPC_FLAGS': '0x0', 22:48:11 INFO - 'XPC_SERVICE_NAME': '0', 22:48:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:48:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:48:12 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 22:48:12 INFO - Cleaning up... 22:48:12 INFO - Return code: 0 22:48:12 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:48:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:48:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:48:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:48:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:48:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:12 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 0x103392b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103395768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa7615a6960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103170d30>, '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 0x103096db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103165be8>, '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.4xVuKgeWXB/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.7T9L0PGXHs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:48:12 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 22:48:12 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 22:48:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4xVuKgeWXB/Render', 22:48:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:48:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:48:12 INFO - 'HOME': '/Users/cltbld', 22:48:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:48:12 INFO - 'LOGNAME': 'cltbld', 22:48:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:48:12 INFO - 'MOZ_NO_REMOTE': '1', 22:48:12 INFO - 'NO_EM_RESTART': '1', 22:48:12 INFO - 'PAGER': '/bin/cat', 22:48:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:48:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:48:12 INFO - 'PWD': '/builds/slave/test', 22:48:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:48:12 INFO - 'SHELL': '/bin/bash', 22:48:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7T9L0PGXHs/Listeners', 22:48:12 INFO - 'TMPDIR': '/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/', 22:48:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:48:12 INFO - 'USER': 'cltbld', 22:48:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:48:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:48:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:48:12 INFO - 'XPC_FLAGS': '0x0', 22:48:12 INFO - 'XPC_SERVICE_NAME': '0', 22:48:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:48:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:48:13 INFO - Downloading/unpacking psutil>=3.1.1 22:48:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:48:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:48:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:48:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:48:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:48:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:48:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:48:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:48:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:48:17 INFO - Installing collected packages: psutil 22:48:17 INFO - Running setup.py install for psutil 22:48:17 INFO - building 'psutil._psutil_osx' extension 22:48:17 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 22:48:17 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 22:48:17 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 22:48:17 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 22:48:18 INFO - building 'psutil._psutil_posix' extension 22:48:18 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 22:48:18 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 22:48:18 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 22:48:18 INFO - ^ 22:48:18 INFO - 1 warning generated. 22:48:18 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 22:48:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:48:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:48:18 INFO - Successfully installed psutil 22:48:18 INFO - Cleaning up... 22:48:18 INFO - Return code: 0 22:48:18 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:48:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:48:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:48:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:48:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:48:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103392b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103395768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa7615a6960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103170d30>, '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 0x103096db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103165be8>, '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.4xVuKgeWXB/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.7T9L0PGXHs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:48:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 22:48:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 22:48:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4xVuKgeWXB/Render', 22:48:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:48:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:48:18 INFO - 'HOME': '/Users/cltbld', 22:48:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:48:18 INFO - 'LOGNAME': 'cltbld', 22:48:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:48:18 INFO - 'MOZ_NO_REMOTE': '1', 22:48:18 INFO - 'NO_EM_RESTART': '1', 22:48:18 INFO - 'PAGER': '/bin/cat', 22:48:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:48:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:48:18 INFO - 'PWD': '/builds/slave/test', 22:48:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:48:18 INFO - 'SHELL': '/bin/bash', 22:48:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7T9L0PGXHs/Listeners', 22:48:18 INFO - 'TMPDIR': '/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/', 22:48:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:48:18 INFO - 'USER': 'cltbld', 22:48:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:48:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:48:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:48:18 INFO - 'XPC_FLAGS': '0x0', 22:48:18 INFO - 'XPC_SERVICE_NAME': '0', 22:48:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:48:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:48:19 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:48:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:48:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:48:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:48:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:48:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:48:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:48:21 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:48:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:48:21 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:48:21 INFO - Installing collected packages: mozsystemmonitor 22:48:21 INFO - Running setup.py install for mozsystemmonitor 22:48:21 INFO - Successfully installed mozsystemmonitor 22:48:21 INFO - Cleaning up... 22:48:22 INFO - Return code: 0 22:48:22 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:48:22 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')]} 22:48:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:48:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:22 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')]} 22:48:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:48:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:22 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 0x103392b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103395768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa7615a6960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103170d30>, '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 0x103096db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103165be8>, '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.4xVuKgeWXB/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.7T9L0PGXHs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:48:22 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 22:48:22 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 22:48:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4xVuKgeWXB/Render', 22:48:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:48:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:48:22 INFO - 'HOME': '/Users/cltbld', 22:48:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:48:22 INFO - 'LOGNAME': 'cltbld', 22:48:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:48:22 INFO - 'MOZ_NO_REMOTE': '1', 22:48:22 INFO - 'NO_EM_RESTART': '1', 22:48:22 INFO - 'PAGER': '/bin/cat', 22:48:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:48:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:48:22 INFO - 'PWD': '/builds/slave/test', 22:48:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:48:22 INFO - 'SHELL': '/bin/bash', 22:48:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7T9L0PGXHs/Listeners', 22:48:22 INFO - 'TMPDIR': '/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/', 22:48:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:48:22 INFO - 'USER': 'cltbld', 22:48:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:48:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:48:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:48:22 INFO - 'XPC_FLAGS': '0x0', 22:48:22 INFO - 'XPC_SERVICE_NAME': '0', 22:48:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:48:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:48:22 INFO - Downloading/unpacking jsonschema==2.5.1 22:48:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:48:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:48:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:48:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:48:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:48:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:48:24 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:48:24 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:48:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:48:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:48:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:48:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:48:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:48:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:48:25 INFO - Downloading functools32-3.2.3-2.tar.gz 22:48:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:48:25 INFO - warning: no files found matching '*.txt' 22:48:25 INFO - no previously-included directories found matching 'build' 22:48:25 INFO - no previously-included directories found matching 'dist' 22:48:25 INFO - no previously-included directories found matching '.git*' 22:48:25 INFO - Installing collected packages: jsonschema, functools32 22:48:25 INFO - Running setup.py install for functools32 22:48:25 INFO - warning: no files found matching '*.txt' 22:48:25 INFO - no previously-included directories found matching 'build' 22:48:25 INFO - no previously-included directories found matching 'dist' 22:48:25 INFO - no previously-included directories found matching '.git*' 22:48:25 INFO - Successfully installed jsonschema functools32 22:48:25 INFO - Cleaning up... 22:48:25 INFO - Return code: 0 22:48:25 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:48:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:48:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:48:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:48:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:48:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:25 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 0x103392b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103395768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa7615a6960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103170d30>, '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 0x103096db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103165be8>, '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.4xVuKgeWXB/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.7T9L0PGXHs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:48:25 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 22:48:25 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 22:48:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4xVuKgeWXB/Render', 22:48:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:48:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:48:25 INFO - 'HOME': '/Users/cltbld', 22:48:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:48:25 INFO - 'LOGNAME': 'cltbld', 22:48:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:48:25 INFO - 'MOZ_NO_REMOTE': '1', 22:48:25 INFO - 'NO_EM_RESTART': '1', 22:48:25 INFO - 'PAGER': '/bin/cat', 22:48:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:48:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:48:25 INFO - 'PWD': '/builds/slave/test', 22:48:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:48:25 INFO - 'SHELL': '/bin/bash', 22:48:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7T9L0PGXHs/Listeners', 22:48:25 INFO - 'TMPDIR': '/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/', 22:48:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:48:25 INFO - 'USER': 'cltbld', 22:48:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:48:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:48:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:48:25 INFO - 'XPC_FLAGS': '0x0', 22:48:25 INFO - 'XPC_SERVICE_NAME': '0', 22:48:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:48:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:48:25 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:48:25 INFO - Cleaning up... 22:48:26 INFO - Return code: 0 22:48:26 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:48: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')]} 22:48:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:48:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48: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')]} 22:48:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:48:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103392b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103395768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa7615a6960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103170d30>, '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 0x103096db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103165be8>, '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.4xVuKgeWXB/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.7T9L0PGXHs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:48:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 22:48:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 22:48:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4xVuKgeWXB/Render', 22:48:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:48:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:48:26 INFO - 'HOME': '/Users/cltbld', 22:48:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:48:26 INFO - 'LOGNAME': 'cltbld', 22:48:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:48:26 INFO - 'MOZ_NO_REMOTE': '1', 22:48:26 INFO - 'NO_EM_RESTART': '1', 22:48:26 INFO - 'PAGER': '/bin/cat', 22:48:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:48:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:48:26 INFO - 'PWD': '/builds/slave/test', 22:48:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:48:26 INFO - 'SHELL': '/bin/bash', 22:48:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7T9L0PGXHs/Listeners', 22:48:26 INFO - 'TMPDIR': '/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/', 22:48:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:48:26 INFO - 'USER': 'cltbld', 22:48:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:48:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:48:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:48:26 INFO - 'XPC_FLAGS': '0x0', 22:48:26 INFO - 'XPC_SERVICE_NAME': '0', 22:48:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:48:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:48:26 INFO - Downloading/unpacking blobuploader==1.2.4 22:48:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:48:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:48:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:48:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:48:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:48:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:48:28 INFO - Downloading blobuploader-1.2.4.tar.gz 22:48:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:48:29 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:48:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:48:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:48:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:48:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:48:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:48:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:48:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:48:29 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:48:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:48:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:48:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:48:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:48:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:48:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:48:30 INFO - Downloading docopt-0.6.1.tar.gz 22:48:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:48:30 INFO - Installing collected packages: blobuploader, requests, docopt 22:48:30 INFO - Running setup.py install for blobuploader 22:48:30 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:48:30 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:48:30 INFO - Running setup.py install for requests 22:48:30 INFO - Running setup.py install for docopt 22:48:31 INFO - Successfully installed blobuploader requests docopt 22:48:31 INFO - Cleaning up... 22:48:31 INFO - Return code: 0 22:48:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:48:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:48:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:48:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:48:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:48:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103392b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103395768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa7615a6960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103170d30>, '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 0x103096db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103165be8>, '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.4xVuKgeWXB/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.7T9L0PGXHs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:48:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 22:48:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:48:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4xVuKgeWXB/Render', 22:48:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:48:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:48:31 INFO - 'HOME': '/Users/cltbld', 22:48:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:48:31 INFO - 'LOGNAME': 'cltbld', 22:48:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:48:31 INFO - 'MOZ_NO_REMOTE': '1', 22:48:31 INFO - 'NO_EM_RESTART': '1', 22:48:31 INFO - 'PAGER': '/bin/cat', 22:48:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:48:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:48:31 INFO - 'PWD': '/builds/slave/test', 22:48:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:48:31 INFO - 'SHELL': '/bin/bash', 22:48:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7T9L0PGXHs/Listeners', 22:48:31 INFO - 'TMPDIR': '/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/', 22:48:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:48:31 INFO - 'USER': 'cltbld', 22:48:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:48:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:48:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:48:31 INFO - 'XPC_FLAGS': '0x0', 22:48:31 INFO - 'XPC_SERVICE_NAME': '0', 22:48:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:48:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:48:31 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-IohcnB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:48:31 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-OfuEYd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:48:31 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-GaHDN8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:48:31 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-xa17Me-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:48:31 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-F1hYPB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:48:31 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-OHGQc3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:48:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:48:32 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-fq99If-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:48:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:48:32 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-AlTQDF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:48:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:48:32 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-DxR9ko-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:48:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:48:32 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-O8qUx2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:48:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:48:32 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-yX2rPl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:48:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:48:32 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-d57tV0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:48:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:48:32 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-mTGQcz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:48:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:48:32 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-Lwjm92-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:48:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:48:32 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-H2Bt0x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:48:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:48:33 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-MpUYun-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:48:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:48:33 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-DOoACY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:48:33 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:48:33 INFO - Running setup.py install for manifestparser 22:48:33 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:48:33 INFO - Running setup.py install for mozcrash 22:48:33 INFO - Running setup.py install for mozdebug 22:48:33 INFO - Running setup.py install for mozdevice 22:48:33 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:48:33 INFO - Running setup.py install for mozfile 22:48:33 INFO - Running setup.py install for mozhttpd 22:48:34 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:48:34 INFO - Running setup.py install for mozinfo 22:48:34 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:48:34 INFO - Running setup.py install for mozInstall 22:48:34 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:48:34 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:48:34 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:48:34 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:48:34 INFO - Running setup.py install for mozleak 22:48:34 INFO - Running setup.py install for mozlog 22:48:34 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:48:34 INFO - Running setup.py install for moznetwork 22:48:34 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:48:34 INFO - Running setup.py install for mozprocess 22:48:35 INFO - Running setup.py install for mozprofile 22:48:35 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:48:35 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:48:35 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:48:35 INFO - Running setup.py install for mozrunner 22:48:35 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:48:35 INFO - Running setup.py install for mozscreenshot 22:48:35 INFO - Running setup.py install for moztest 22:48:35 INFO - Running setup.py install for mozversion 22:48:35 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:48:35 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:48:35 INFO - Cleaning up... 22:48:35 INFO - Return code: 0 22:48:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:48:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:48:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:48:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:48:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:48:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103392b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103395768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa7615a6960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103170d30>, '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 0x103096db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103165be8>, '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.4xVuKgeWXB/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.7T9L0PGXHs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:48:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 22:48:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:48:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4xVuKgeWXB/Render', 22:48:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:48:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:48:35 INFO - 'HOME': '/Users/cltbld', 22:48:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:48:35 INFO - 'LOGNAME': 'cltbld', 22:48:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:48:35 INFO - 'MOZ_NO_REMOTE': '1', 22:48:35 INFO - 'NO_EM_RESTART': '1', 22:48:35 INFO - 'PAGER': '/bin/cat', 22:48:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:48:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:48:35 INFO - 'PWD': '/builds/slave/test', 22:48:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:48:35 INFO - 'SHELL': '/bin/bash', 22:48:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7T9L0PGXHs/Listeners', 22:48:35 INFO - 'TMPDIR': '/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/', 22:48:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:48:35 INFO - 'USER': 'cltbld', 22:48:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:48:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:48:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:48:35 INFO - 'XPC_FLAGS': '0x0', 22:48:35 INFO - 'XPC_SERVICE_NAME': '0', 22:48:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:48:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:48:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:48:36 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-_a_CzV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:48:36 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 22:48:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:48:36 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-KJVdej-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:48:36 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)) 22:48:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:48:36 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-bBgaja-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:48:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 22:48:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:48:36 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-01wNj6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:48:36 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)) 22:48:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:48:36 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-eOmMlG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:48:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 22:48:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:48:36 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-AoShiV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:48:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 22:48:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:48:36 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-szt4PI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:48:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 22:48:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:48:36 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-EMV96J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:48:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 22:48:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:48:36 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-YMoPwm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:48:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 22:48:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:48:37 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-AUKrd7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:48:37 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)) 22:48:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:48:37 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-XKvvJX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:48:37 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 22:48:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:48:37 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-jpJsOe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:48:37 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)) 22:48:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:48:37 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-NwF6wP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:48:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 22:48:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:48:37 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-3JUsOA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:48:37 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)) 22:48:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:48:37 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-wPKXAK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:48:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 22:48:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:48:37 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-tvSDbi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:48:37 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)) 22:48:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:48:37 INFO - Running setup.py (path:/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/pip-1DXLvZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:48:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 22:48:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:48:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:48:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 22:48:37 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:48:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:48:37 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:48:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:48:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:48:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:48:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:48:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:48:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:48:40 INFO - Downloading blessings-1.6.tar.gz 22:48:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:48:40 INFO - Installing collected packages: blessings 22:48:40 INFO - Running setup.py install for blessings 22:48:40 INFO - Successfully installed blessings 22:48:40 INFO - Cleaning up... 22:48:40 INFO - Return code: 0 22:48:40 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:48:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:48:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:48:41 INFO - Reading from file tmpfile_stdout 22:48:41 INFO - Current package versions: 22:48:41 INFO - blessings == 1.6 22:48:41 INFO - blobuploader == 1.2.4 22:48:41 INFO - docopt == 0.6.1 22:48:41 INFO - functools32 == 3.2.3-2 22:48:41 INFO - jsonschema == 2.5.1 22:48:41 INFO - manifestparser == 1.1 22:48:41 INFO - mozInstall == 1.12 22:48:41 INFO - mozcrash == 1.0 22:48:41 INFO - mozdebug == 0.1 22:48:41 INFO - mozdevice == 0.50 22:48:41 INFO - mozfile == 1.2 22:48:41 INFO - mozhttpd == 0.7 22:48:41 INFO - mozinfo == 0.9 22:48:41 INFO - mozleak == 0.1 22:48:41 INFO - mozlog == 3.4 22:48:41 INFO - moznetwork == 0.27 22:48:41 INFO - mozprocess == 0.25 22:48:41 INFO - mozprofile == 0.28 22:48:41 INFO - mozrunner == 6.13 22:48:41 INFO - mozscreenshot == 0.1 22:48:41 INFO - mozsystemmonitor == 0.3 22:48:41 INFO - moztest == 0.8 22:48:41 INFO - mozversion == 1.4 22:48:41 INFO - psutil == 3.1.1 22:48:41 INFO - requests == 1.2.3 22:48:41 INFO - wsgiref == 0.1.2 22:48:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:48:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:48:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:48:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:48:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:48:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:41 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 0x103392b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103395768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa7615a6960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103170d30>, '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 0x103096db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103165be8>, '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.4xVuKgeWXB/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.7T9L0PGXHs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:48:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 22:48:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:48:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4xVuKgeWXB/Render', 22:48:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:48:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:48:41 INFO - 'HOME': '/Users/cltbld', 22:48:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:48:41 INFO - 'LOGNAME': 'cltbld', 22:48:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:48:41 INFO - 'MOZ_NO_REMOTE': '1', 22:48:41 INFO - 'NO_EM_RESTART': '1', 22:48:41 INFO - 'PAGER': '/bin/cat', 22:48:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:48:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:48:41 INFO - 'PWD': '/builds/slave/test', 22:48:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:48:41 INFO - 'SHELL': '/bin/bash', 22:48:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7T9L0PGXHs/Listeners', 22:48:41 INFO - 'TMPDIR': '/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/', 22:48:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:48:41 INFO - 'USER': 'cltbld', 22:48:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:48:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:48:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:48:41 INFO - 'XPC_FLAGS': '0x0', 22:48:41 INFO - 'XPC_SERVICE_NAME': '0', 22:48:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:48:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:48:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 22:48:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 22:48:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 22:48:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 22:48:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 22:48:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 22:48:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 22:48:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 22:48:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 22:48:41 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 22:48:41 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 22:48:41 INFO - Cleaning up... 22:48:41 INFO - Return code: 0 22:48:41 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:48:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:48:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:48:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:48:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:48:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103392b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103395768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa7615a6960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103170d30>, '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 0x103096db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103165be8>, '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.4xVuKgeWXB/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.7T9L0PGXHs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:48:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 22:48:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 22:48:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4xVuKgeWXB/Render', 22:48:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:48:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:48:41 INFO - 'HOME': '/Users/cltbld', 22:48:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:48:41 INFO - 'LOGNAME': 'cltbld', 22:48:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:48:41 INFO - 'MOZ_NO_REMOTE': '1', 22:48:41 INFO - 'NO_EM_RESTART': '1', 22:48:41 INFO - 'PAGER': '/bin/cat', 22:48:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:48:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:48:41 INFO - 'PWD': '/builds/slave/test', 22:48:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:48:41 INFO - 'SHELL': '/bin/bash', 22:48:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7T9L0PGXHs/Listeners', 22:48:41 INFO - 'TMPDIR': '/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/', 22:48:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:48:41 INFO - 'USER': 'cltbld', 22:48:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:48:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:48:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:48:41 INFO - 'XPC_FLAGS': '0x0', 22:48:41 INFO - 'XPC_SERVICE_NAME': '0', 22:48:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:48:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:48:41 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:48:41 INFO - Cleaning up... 22:48:41 INFO - Return code: 0 22:48:41 INFO - Running post-action listener: _resource_record_post_action 22:48:41 INFO - Running post-action listener: _start_resource_monitoring 22:48:41 INFO - Starting resource monitoring. 22:48:41 INFO - [mozharness: 2017-04-27 05:48:41.707368Z] Finished create-virtualenv step (success) 22:48:41 INFO - [mozharness: 2017-04-27 05:48:41.708322Z] Running install step. 22:48:41 INFO - Running pre-action listener: _resource_record_pre_action 22:48:41 INFO - Running main action method: install 22:48:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:48:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:48:41 INFO - Reading from file tmpfile_stdout 22:48:41 INFO - Detecting whether we're running mozinstall >=1.0... 22:48:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:48:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:48:42 INFO - Reading from file tmpfile_stdout 22:48:42 INFO - Output received: 22:48:42 INFO - Usage: mozinstall [options] installer 22:48:42 INFO - Options: 22:48:42 INFO - -h, --help show this help message and exit 22:48:42 INFO - -d DEST, --destination=DEST 22:48:42 INFO - Directory to install application into. [default: 22:48:42 INFO - "/builds/slave/test"] 22:48:42 INFO - --app=APP Application being installed. [default: firefox] 22:48:42 INFO - mkdir: /builds/slave/test/build/application 22:48:42 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'] 22:48:42 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 22:49:01 INFO - Reading from file tmpfile_stdout 22:49:01 INFO - Output received: 22:49:01 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 22:49:01 INFO - Running post-action listener: _resource_record_post_action 22:49:01 INFO - [mozharness: 2017-04-27 05:49:01.838834Z] Finished install step (success) 22:49:01 INFO - [mozharness: 2017-04-27 05:49:01.838966Z] Running run-tests step. 22:49:01 INFO - Running pre-action listener: _resource_record_pre_action 22:49:01 INFO - Running main action method: run_tests 22:49:01 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:49:01 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:49:01 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:49:01 INFO - 2017-04-26 22:49:01.853 screenresolution[1622:7438] starting screenresolution argv=screenresolution get 22:49:01 INFO - 2017-04-26 22:49:01.856 screenresolution[1622:7438] Display 0: 1600x1200x32@60 22:49:01 INFO - 2017-04-26 22:49:01.861 screenresolution[1623:7442] starting screenresolution argv=screenresolution list 22:49:01 INFO - Available Modes on Display 0 22:49:01 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:49:01 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:49:01 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:49:01 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:49:02 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:49:02 INFO - Intel Iris: 22:49:02 INFO - Chipset Model: Intel Iris 22:49:02 INFO - Type: GPU 22:49:02 INFO - Bus: Built-In 22:49:02 INFO - VRAM (Dynamic, Max): 1536 MB 22:49:02 INFO - Vendor: Intel (0x8086) 22:49:02 INFO - Device ID: 0x0a2e 22:49:02 INFO - Revision ID: 0x0009 22:49:02 INFO - Displays: 22:49:02 INFO - Display: 22:49:02 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:49:02 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:49:02 INFO - Display Serial Number: 1600x1200 60 22:49:02 INFO - Main Display: Yes 22:49:02 INFO - Mirror: Off 22:49:02 INFO - Online: Yes 22:49:02 INFO - Rotation: Supported 22:49:02 INFO - Return code: 0 22:49:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:49:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:49:02 INFO - Python 2.7.3 22:49:02 INFO - Return code: 0 22:49:02 INFO - grabbing minidump binary from tooltool 22:49:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:49:02 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 0x103170d30>, '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 0x103096db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103165be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:49:02 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 22:49:02 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 22:49:02 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 22:49:02 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:49:02 INFO - Return code: 0 22:49:02 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 22:49:02 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:49:02 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:49:02 INFO - ENV: RUST_BACKTRACE is now 1 22:49:02 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 22:49:02 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:49:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Pine', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/e4rBHDOHTvuWDsOSvKffsw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0099', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 22:49:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Pine --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/e4rBHDOHTvuWDsOSvKffsw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0099 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 22:49:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4xVuKgeWXB/Render', 22:49:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:49:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:49:02 INFO - 'HOME': '/Users/cltbld', 22:49:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:49:02 INFO - 'LOGNAME': 'cltbld', 22:49:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:49:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 22:49:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:49:02 INFO - 'MOZ_NO_REMOTE': '1', 22:49:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:49:02 INFO - 'NO_EM_RESTART': '1', 22:49:02 INFO - 'PAGER': '/bin/cat', 22:49:02 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:49:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:49:02 INFO - 'PWD': '/builds/slave/test', 22:49:02 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:49:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:49:02 INFO - 'RUST_BACKTRACE': '1', 22:49:02 INFO - 'SHELL': '/bin/bash', 22:49:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7T9L0PGXHs/Listeners', 22:49:02 INFO - 'TMPDIR': '/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/', 22:49:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:49:02 INFO - 'USER': 'cltbld', 22:49:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:49:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:49:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:49:02 INFO - 'XPC_FLAGS': '0x0', 22:49:02 INFO - 'XPC_SERVICE_NAME': '0', 22:49:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:49:02 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Pine', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/e4rBHDOHTvuWDsOSvKffsw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0099', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 22:49:02 INFO - mozversion application_buildid: 20170426090525 22:49:02 INFO - mozversion application_changeset: ba912b0f3ea88b1ee2a06d2e26cf77bfcf5a3f3e 22:49:02 INFO - mozversion application_display_name: Nightly 22:49:02 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:49:02 INFO - mozversion application_name: Firefox 22:49:02 INFO - mozversion application_remotingname: firefox 22:49:02 INFO - mozversion application_repository: https://hg.mozilla.org/projects/pine 22:49:02 INFO - mozversion application_vendor: Mozilla 22:49:02 INFO - mozversion application_version: 55.0a1 22:49:02 INFO - mozversion platform_buildid: 20170426090525 22:49:02 INFO - mozversion platform_changeset: ba912b0f3ea88b1ee2a06d2e26cf77bfcf5a3f3e 22:49:02 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/pine 22:49:02 INFO - mozversion platform_version: 55.0a1 22:49:02 INFO - using testdate: 1493272142 22:49:02 INFO - actual date: 1493272142 22:49:02 INFO - starting webserver on 'localhost:49246' 22:49:02 INFO - SUITE-START | Running 2 tests 22:49:02 INFO - TEST-START | dromaeo_css 22:49:02 INFO - operating with platform_type : mac_ 22:49:02 INFO - Initialising browser for dromaeo_css test... 22:49:02 INFO - TEST-INFO | started process 1633 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/tmp8YALtt/profile http://localhost:49246/getInfo.html) 22:49:04 INFO - PID 1633 | __metrics Screen width/height:1600/1200 22:49:04 INFO - PID 1633 | colorDepth:24 22:49:04 INFO - PID 1633 | Browser inner width/height: 1024/689 22:49:05 INFO - PID 1633 | __metrics 22:49:05 INFO - PID 1633 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 22:49:05 INFO - PID 1633 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 334: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:49:05 INFO - TEST-INFO | 1633: exit 0 22:49:05 INFO - Browser initialized. 22:49:05 INFO - Running cycle 1/1 for dromaeo_css test... 22:49:05 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/tmp8YALtt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:49:18 INFO - PID 1635 | RSS: Main: 244842496 22:49:18 INFO - PID 1635 | 22:52:23 INFO - PID 1635 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49246/tests/dromaeo/cssquery-ext.html) 22:52:25 INFO - PID 1635 | RSS: Main: 442580992 22:52:25 INFO - PID 1635 | 22:54:07 INFO - PID 1635 | ************************* 22:54:07 INFO - PID 1635 | A coding exception was thrown and uncaught in a Task. 22:54:07 INFO - PID 1635 | 22:54:07 ERROR - PID 1635 | Full message: TypeError: NetworkError when attempting to fetch resource. 22:54:07 INFO - PID 1635 | Full stack: 22:54:07 INFO - PID 1635 | ************************* 22:54:07 INFO - PID 1635 | ************************* 22:54:07 INFO - PID 1635 | A coding exception was thrown and uncaught in a Task. 22:54:07 INFO - PID 1635 | 22:54:07 ERROR - PID 1635 | Full message: TypeError: NetworkError when attempting to fetch resource. 22:54:07 INFO - PID 1635 | Full stack: 22:54:07 INFO - PID 1635 | ************************* 22:54:07 INFO - PID 1635 | ************************* 22:54:07 INFO - PID 1635 | A coding exception was thrown and uncaught in a Task. 22:54:07 INFO - PID 1635 | 22:54:07 ERROR - PID 1635 | Full message: TypeError: NetworkError when attempting to fetch resource. 22:54:07 INFO - PID 1635 | Full stack: 22:54:07 INFO - PID 1635 | ************************* 22:54:07 INFO - PID 1635 | ************************* 22:54:07 INFO - PID 1635 | A coding exception was thrown and uncaught in a Task. 22:54:07 INFO - PID 1635 | 22:54:07 ERROR - PID 1635 | Full message: TypeError: NetworkError when attempting to fetch resource. 22:54:07 INFO - PID 1635 | Full stack: 22:54:07 INFO - PID 1635 | ************************* 22:55:27 INFO - PID 1635 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-ext.html (next: http://localhost:49246/tests/dromaeo/cssquery-jquery.html) 22:55:28 INFO - PID 1635 | RSS: Main: 372977664 22:55:28 INFO - PID 1635 | 22:58:30 INFO - PID 1635 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49246/tests/dromaeo/cssquery-mootools.html) 22:58:30 INFO - PID 1635 | RSS: Main: 333336576 22:58:30 INFO - PID 1635 | 23:01:33 INFO - PID 1635 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49246/tests/dromaeo/cssquery-prototype.html) 23:01:33 INFO - PID 1635 | RSS: Main: 340852736 23:01:33 INFO - PID 1635 | 23:04:37 INFO - PID 1635 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49246/tests/dromaeo/cssquery-yui.html) 23:04:37 INFO - PID 1635 | RSS: Main: 337321984 23:04:37 INFO - PID 1635 | 23:07:40 INFO - PID 1635 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-yui.html (next: http://localhost:49246/tests/dromaeo/cssquery-dojo.html) 23:07:40 INFO - PID 1635 | RSS: Main: 376614912 23:07:40 INFO - PID 1635 | 23:07:40 INFO - PID 1635 | __start_tp_report 23:07:40 INFO - PID 1635 | _x_x_mozilla_page_load 23:07:40 INFO - PID 1635 | _x_x_mozilla_page_load_details 23:07:40 INFO - PID 1635 | |i|pagename|runs| 23:07:40 INFO - PID 1635 | |0;dojo.html;2961.8074825136373;3530.1704099536605;3538.486919396687;3555.093451169951;3593.1915319053214;5920.703964801764;6089.147519347295;6105.60313579617;6144.5698801083945;6150.630962142274;5067.569256613183;5872.119182122684;6126.334466362969;6133.172021777059;6140.969295153529;4864;5237;5239.266502689627;5244.55421289189;5265.420803711589;5182.041322355376;5310.36275646921;5315.415304316522;5371.543418809386;5384.461553844604;5250.842474725745;5251.763670634792;5284.630075894689;5337.572994160458;5342.2787923630585;5282.313299271109;5299.6025298102795;5309.3894202166475;5361;5377.596680248997;4355.455568053993;5245.108331583602;5275.86810329741;5312.07038768214;5319.7340132993195;5902.144189092557;5972.70136493178;5976.133460648181;6108.450239478424;6131.632102073845;6246.6252024878195;6305.621662700207;6307.96846015767;6326.114343991845;6335.746570137189;6346;6358.968205158991;6362.981922892315;6369.490440764774;6492.3183065777885;5232.92150617741;6167.660778657172;6243.37566243372;6244.344343843922;6253.093301471261;6500.317466666026;6729.730810767514;6742.797716068526;6751.527393082437;6771.289014653489;6329.398707122816;6338.873222535523;6345.07996340528;6345.873082538324;6364.045393191059;5390.380106287748;6364.649944253018;6376.7130479128555;6548.8362790930605;6588.967055164693;6274.560780745304;6330.651814150266;6332.746690132343;6411.743530258737;6515.381038801306;6406.935930640727;6492.35076492347;6499.317571655003;6510;6519.967400163064;5184.5593124584075;5211.530962213419;5221.582273418117;5225.817096401684;5248.291480650139;4532.388127602731;4632;4646.97676511622;4656.557627025427;4691.7888695008805;6112.077967281658;6120.030276471984;6192.504599632019;6270.153529273581;6284.465820405317;6539.182602174728;6541.771038013647;6589;6599.076129341896;6618.900716489256;6212.0371342441995;6238.781642642486;6249.437550620466;6257.561970662101;6272.498200143978;5605.327360716658;5686.976344257991;5688.459596338341;5761.452661997104;5766.336871259775;6595.637239951753;6605.504587156079;6621;6624.768133115318;6644.867102658017;6329.715162817776;6385.553011289351;6389.808305750835;6400.07198956158;6404.967975160094;6604.603723776589;6607.339266073354;6616.139901812733;6695.363940425652;6788.59268443895;6424.357564243538;6435.06691529735;6449.903251451326;6457.354264573505;6476.125723027331;6301;6441.516886233645;6567.277599464151;6576.177977752781;6605.207375114825;5489.149181876815;5699.173619825019;6223.813285601439;6329.5569310149685;6376.553641245068;6332;6342.080398342307;6360.109584658244;6370.426661600478;6415.518836087219;6262;6280.654563998841;6293;6293.118963345227;6309.3690630936535;6227.439530442282;6297.622142671455;6304.306526282014;6353.078803573363;6377.043443483514;6228;6261.686915654108;6275.843103922439;6290.339514350927;6297.496200304056 23:07:40 INFO - PID 1635 | |1;ext.html;624.1075262374806;787.8148635070759;801.014751855218;1102.39919244012;1105.8838821923698;26068.478630427402;26816.32959176019;26847.32881677959;36024.45963310552;36600;38156;38565;38595.421068683994;38598.807005965;38610;8244.628991695374;8362.080171181165;8417.410781245315;8426.78933026673;8429.95785021074;5928.644281343111;6040.818775436744;8191;8197.508149511019;8376.958115209445;8334.374921880853;8436.282915952152;8441.831163376728;8449.57752112395;8449.873251901226;8380.287675547586;8399.790005249917;8418.410711250215;8420.200080992296;8449.493030418163;8249.463784853966;8319.500829950222;8330.291925186366;8337.166283371615;8342.457740246926;36462.27075458476;36562.72030478947;36685.81657091724;36692.81653591715;36719;3238.935221295561;3305.652906444837;3321.618013928405;3328.6504916983854;3337.916552086193;30100.8494957526;30128.397432051235;30152.69847301543;30179.396412071637;30322.696773032207;3074.2314421394653;3147.5750773645495;3166.6358368787655;3167.144870884871;3183.8726450941936;216842;293833;294328;294792.2603869798;296422;47558.28662570064;47795;47832;47972.7601361991;47997.8000549982;48957.265641015416;48977.75511122493;48999;49110.508894911305;49133.75433122811;13740.10689305192;13845.376958036912;13863.653408664864;13873.86126138746;13921.930390347983;11270.323780573191;11273.323600583828;11274.154438417152;11284.605038823602;11336.319820810777;9716.61133554664;10414.323069000491;10489.370637761758;10498.842517362245;11924.642260732191;4428.490723566767;5231.764570594332;5262.500062494057;5267.130923397596;5276.815311464157;890.9664788844909;965.584798536636;991.6727479931606;1024.6311327921942;1035.4305132177271;22875.771242287698;24145.879270603535;24172.516549668908;24246;24481.63277550838;1083.685731137961;1094.2340361746808;1120.5209123956463;1144.1590431033185;1146.4554336690005;12283.385830708428;12288;12350.567730129402;12366.258024518558;12368.567100151451;312529;313443.86556134146;313639;313742.4312878421;314008;50328.74835625799;50340.49659503358;50413.24380134301;50492.73768155752;50633.74683126635;1508.7284288827898;1517.347540557571;1560.6722588256594;1560.9063456192437;1565.3973220310036;23806.88096559506;23830.523389531765;23920.40198995039;23930.52138957246;23963.640545391485;37400.06499837417;37461.43807842939;37558.68544600926;37564.62435375611;37598.8120059398;294093;294811;295044;295510;305645;32517.02448926536;33472.66527334695;33475.330493389505;33526.66473335236;33561;35839.64160358363;36075.27849442944;36091.45862812112;36117.458238125924;36153;22896.656550151427;24519;27538;29910.953116640812;29978.25054373658;29033.12900612985;29524;29934.101976940725;29980.85009574938;30257.848710756305;34463.310733785685;34894.476582850766;35604.64395356013;35651.10872228215;35678.464823027156;25295;28751;28893.855530722212;29827.701722982492;30205.714000050342;22224.666630000887;22722.545549088594;24135.396615084763;24283.39291517726;24626.384340390916 23:07:40 INFO - PID 1635 | |2;jquery.html;384.45599476740415;423.18262519152654;431.2496256513667;433.9023719663076;440.3504830375196;19400.902995485038;19956.401307960747;21068.367948961535;21657;21686.24098156565;22189.556208875852;23090.422739431524;23208.651870221962;23866.164684236057;23877.522449550997;6012.128241404983;6295.0872123542285;6298.905516417258;6301.054841773747;6321.589096708699;5896.97051514744;6168.75324987;6223.657698826562;6257.749690012394;6258.123862659231;5247.422783493813;5998.730057147417;6199.504039676815;6200.379962003786;6213.782517611885;6154.323024467305;6292.2134733158355;6293.370662933716;6305.432511074003;6317.1156038154695;5366.436524164946;5504.587155963319;5568.86077848053;5589.189567512729;5622.353429355595;27212.863935680394;27367;27973.440531189262;28099.85950070257;28340.716592834015;2172.1528603844513;2254.763249858774;2256;2258.367657056027;2259.548090381914;17185;17204.827951720574;17229.39697110595;17367;17560.297588096462;2120.5652841167525;2133.5306232628795;2177.56448710257;2179.6294629912954;2182.4980254541388;127930;128431;128640.3567982154;130947.34526327498;133049;12129.393530323448;12445.377731113407;12468.43892024861;12469.75060498785;12477.688057798447;16696.165191740365;16708.24812883423;16732.749008764877;16743.246553905105;16787.91606041962;7342.669579868919;7567.583782891884;8631.48211107323;8833.690820821368;8996.775080623036;8230.547319897343;8324.542150181676;8351.916480835236;8501.319894408432;8528.65885364591;7531.171571127172;7609.15704992148;7654.464187506821;7704.345130663956;7742.6902923883545;2112.0812446585783;2114.6299397605358;2117.5447278834818;2120.6394912864544;2288.6681431192383;914.5198770645331;948.3219498058783;962.1965658675085;993.7316924430363;994.9005099489993;17770.91114544419;19707.50731231702;20167;20799.96000199984;21249.787502124782;962.41292811385;967.3663750243602;996.2826764729383;997.7705127820642;999.7702825524646;8294.709685160991;10225.335353202017;10776.168051431276;10856.78286434267;10955.287578780337;479016.60491698014;536613;539524;548466.2576687091;550695;90630.54684726665;91174;114469;130113;135064;958.3387462650749;1138.6128716236353;1185.8814118588245;1187.0503597122267;1188.5721140389453;20785.272515461886;20919.477013074797;20999.790002100395;21239.831809250638;21308.57382852365;55405;56601.43398565962;58778.11832822426;58953;59077.70461147667;66942;67978.64042719018;68144;68273;69249.30750692429;7252.419806415369;8515.957420212859;8561.529115898686;8815.735527934172;8848.71693604411;13653.658658533615;13823.377947992163;14279.214643194724;14646.560603181922;14742.11547307165;10026;11770.411479426166;12264.632061037826;12280.078994075659;12331.630051098482;11127.554897803997;11476.655700329004;11550.884491155317;11755.353455560027;11886.702832428913;11661.883381166079;12087.637370878536;12108.515659373526;12127.968007998;12190.390480475764;8727.781805454915;8791.604377802887;8957.372983891066;8991.28069754431;9224.584893679666;9956.701298961043;10616.84074738895;10995.945020275167;11088.944555277172;11925.284482931052 23:07:40 INFO - PID 1635 | |3;mootools.html;1243.4218088588807;1427.0296198584965;1443.6102252391854;1443.7256921184967;1450.1226757811523;9965.950170249147;11234.325940443568;11352.205345625809;11814.409279536032;11818.7636247275;11060.336379817216;11706.590269340575;11743.706407339821;11754.177207595472;11784.29294242346;2529.974700252997;2572;2576.188500622298;2577.30412788547;2581.8579978101197;2106.4944413340763;2418.443757935676;2546.4525127097672;2547.923562293125;2552.923412297634;2164.588728141656;2184.6286131357706;2463.310273123528;2541.923742287725;2542.8982840686444;2472.295395812185;2497.300755788382;2505.5615267328326;2509.284853816672;2511.937201569957;2497.825152239344;2511.309389917769;2511.635812807133;2513.673222481084;2515.38373098592;11453.656390308304;11682.123840711896;11724.29654220741;11727.17909746318;11731.002864756507;6521.119648847392;6742.561733487308;6750.8312292192595;6755.628440435774;6767.221769496522;3902.2195560887935;4103.589641035873;4355.085432059272;4359.84740534083;4433.689641725048;1030.108955753268;1036.5180191211048;1222.8960538354252;1272.2684456437585;1307.7972914198315;16357.918210408872;16476.505704828753;16572.92276002056;16585.751213731804;16613.252403641865;5408.513233808976;5467.535259502907;5568.415316391802;5679.091345384719;5711.3146422428745;12004.879951200552;12025.338606376557;12037.157398982163;12038.277703337828;12051.216670916363;6601.108850305147;6609.834754131089;6611.107500487469;6612.305707900698;6618;5971.253593300838;6167.136600875881;6221.4089661482085;6232.158658581124;6254.405831446096;3714.46140309659;4186.1418409226035;4313.568643135662;4341.413909122291;4361.389405483234;1268.2200446725199;1268.5366276334862;1284.6081945006677;1293.321006471591;1293.5020017293223;623.234438788483;786.3119770201074;796.1640277708383;802.9839403211903;849.4563479373206;6331.525135614847;6626.271110177877;6739.359760822714;6912.827179320558;6964.895526567106;556.858001209689;581.8952588534105;602.5300265792689;605.4429924469499;712.3659942651045;4146.730462519927;4551.567601077959;4586.128635559233;4685.2035154023215;4720.716756994591;15096.698066038618;15384;15396.846031539766;15410.691786164438;15485.845141548665;14899.925500372428;15310.770338444709;15334.846651533566;15493.690126197638;15507.379704811907;8536.231739143506;8797.592385218335;8813.779655508664;8818.29453643707;8819.559022049;6707.664616769044;6715.4291885190305;6820.249772524919;7017.719291228498;7138.428925686037;12093.334866582423;13122.934385328012;13167.67080822987;13601.047926645435;13842.100263482842;13954.511592094228;13972.231527266098;13985.860141398864;14020.439182432588;14365.712685746435;9253.583588738658;9547.904520954702;9554.713358599252;9559.713208603753;9575.186109180797;10729.302595331279;10970.232083754061;10993.725156871142;11009.669709908716;11079.113670906469;9264.72205833826;9268.397554158959;9303.906960930575;9343;9368.484733339732;8944.731658050268;9164.587593558172;9200.493972831558;9299.442033477742;9371.765705857408;8168.795780105307;8251.133630968901;8333.583320834055;8340.833183336177;8344.457610255315;5069.670471419494;5938.109283607493;6103.359147289649;6144.53915956314;6191.628502289877;5997.970010149921;6144.41628045335;6182.969085154726;6274.996000639876;6342.936570634419 23:07:40 INFO - PID 1635 | |4;prototype.html;389.2759467390654;395.5273448229365;400.7475290566943;404.28643444320795;405.99188016239697;5673.631213971089;5748.683822389772;5768.134779783035;5776.191333213343;5799.564183043121;5036.244563315505;5169.715665638389;5391.78432862686;5639.577031722617;5664.518515926141;3444.9311013779648;4344.956550434503;4367.279398899178;4389.517153113155;4396.934045989313;4351.934720979188;4382.97808510957;4391.626711729491;4397.516273209944;4400;4208.242516347056;4311.598730412613;4321.891952701192;4345.587169218919;4371.431713877208;4321.82712691492;4328.3074708046715;4334.913301733979;4336.197803406349;4350.564943505656;3860.82626281818;3871.322518559269;3871.632194941476;3873.682947797734;3878.4957955465893;5301;5421.403645598981;5597.3412629001305;5604.495595396393;5614.887702245973;4183.456150700389;4298.3565107459;4919.508049195052;4947.653664243504;4948.925766113512;5987.850303742398;6121.846953826145;6122.510199184055;6131.662758548278;6160.383961603804;3879.670228030621;4038.2125485530332;4073.8370465181356;4080.3879418087226;4085.775282359469;5178.741062946838;6142.1093941378695;6187.504999600066;6448.516361272877;6491.7092569556135;3559.911002224965;4116.444280022219;4118.602153223543;4133.42132101506;4140.0270936329935;3682.889513314551;4017.6384125428854;4119.176164767058;4214.578542145761;4215.494140703136;4038.293298672779;4555.658325625525;4612.192866248393;4821;4903.53416425439;4761.595264402496;5101.5408613224245;6080.209572755513;6088.086786981989;6088.817335479943;1405.5572494664148;1418.8084608577917;1419.8438171801095;1427.8143841300773;1441.6592568911014;800.4476910931438;918.4168053286211;931.9813603727888;944.8393773058597;947.6114792934864;5047.697138171721;5753.223314852441;5810;5842.415758424124;5842.503387212049;4873.172560289891;6310.43206111443;6358.491320694333;6368.458681012074;6374.904376434357;5655.688937108499;5784.71076446176;5846.479915222073;5852.912206316909;5864;3239.6598357172156;3285.6221534523356;3286.6220384655594;3297.6702329767313;3300.1914530939594;5954.37479064692;6278.372162783685;6306.243250809932;6433.871322573522;6563.540552161303;5733.455321744427;5973.894829456564;6099.786507472217;6534.9285835657165;6622.741679080959;5738.8278351649515;6310.927142385805;6542.247641521286;6548.6725663716625;6573.388860592543;5945.346011938596;5983.550479652686;5996.580239383201;6327.778527751507;6340.619562826245;5944;6125.387461253839;6227.626342419288;6255.1851666749235;6283.717232724447;5686.43135686428;5686.800961966312;6500.869982600227;6547.869042619026;6549.115869357575;4089.26393249218;4117.547069822377;4129.525104613008;4131.07050913542;4131.132462182975;5023.34696489454;5528.612997090326;6029;6097.451229389376;6338.968305158629;5613.46672066153;5840.737166827418;5855.59010869235;6095.738779752811;6116.602420842809;5223.660462069954;5549.639273447213;5725.513331366714;5869.442402971882;6118.847028824315;5250.0549901018185;5928.407159284037;5962.493188079062;5970.373110823475;5972.223610930551;5733.226014487991;5734.559264441302;5824.737886795189;6085.965385884322;6100.359462256399;4751.786169622306;5976.62537616342;6010.789622363196;6073.5748497604745;6119.418655227746 23:07:40 INFO - PID 1635 | |5;yui.html;717.1215261304899;863.7581477186386;865.2429124516049;874.6545114679701;883.6288758721337;13344.199348039125;17517.82482175178;17800.910995445003;18489.44531664049;18561.628767424645;17342.046187459684;17491.125443727826;17926.82073179268;17940;17941.641167176647;963.6097380560884;978.8972157923422;979.0992287095889;979.7207795778205;981.3964411886674;663.5023732200849;685.455063224735;970.7039352997333;1002.2332915319786;1003.0120331473493;1047.0681093826502;1047.350642601588;1047.8166320893852;1050.9159267258601;1053.7365658585354;1040.6617849198965;1045.419792015431;1045.46158728255;1046.4506134279488;1052.8947105289449;770.4991755359006;771.155584634824;771.4021633234232;772.2239149654617;776.7863837444692;16717.582060448465;16996.150192490328;17211.139443027925;17407.129643517772;17593.824061759475;16619.50141495745;16803.243854026598;17040.48878533646;17051.317947282092;17302.480925572254;13794.517191898236;13828.239446830421;13908.51320203788;13980.650483737887;14208.076475029193;3421.3841508528453;3442.5524681791458;3443.3113377324385;3449.810260435675;3449.862005519776;124653.37673311576;125358;126058;127319.72680273264;127793.72206277911;9363.672271470603;11457.14071444645;11849.170558060852;12037.458314375874;12172;19296.80703192978;19307.90346048261;19710.014499274977;20392.490187745425;24737;7693.615319234016;7855.214478552099;7871.724489642835;7874.803129921798;7883.84232315362;3904.1439330377957;3994.4407782910416;4003.819828107742;4008.5390180129075;4016.879493615196;4283.957160428418;4368.104538569585;4368.213721530092;4369.453818272716;4376.474823021258;849.3969281809984;899.67161985876;903.7108125399941;904.0597778310483;908.0329449136648;609.5184804004875;634.6445990245477;650.778735230024;654.2508827392704;663.4128796015465;10920.235583509237;11504.482298296596;11764.352960587079;11800.232984855957;11855.110866685032;644.7453255963868;660.197859600578;660.4419265720459;673.6328700858044;683.402023229674;4331.306990881444;5225.242339860699;5370.9731451342495;5501.587380946445;5627.380988091307;103658;104523;105634.9436505625;106140;107818.46090769653;91591.54204228937;92748.53625731828;93564;94142.52928735313;94272;81148.18851811525;81359.5932020336;81477.59261203774;81988;83112;8932.955335223282;10565.20760942917;11154.665360039211;11294.60468883585;11407.144464165054;38656.84848303073;40931.795341023106;41373.37939930963;41487.792561037;41686.95782605459;53877;54520.72739636277;54858.72570637121;54975;55472.167917482075;14273.500427484989;16436.178191090636;17084.23120959535;17113.91443042777;17118.48644540666;17817.287308507515;17902.91048544749;18444.446666600023;18498.630027399107;18757.71863422077;16040.839591604401;16134.435294764628;16830.915845420695;17011.659766804347;17039.659206815548;16286.429974951294;16815.495535133967;17023.744643830105;17074.914625426794;17145.485635430956;15768.526944191693;15787.605309867346;16215.837841621433;16660.16699165061;16849.663006739553;11565.132615054074;11607.6517704469;12185.634430967086;12224.327661978676;12288.87711122913;12024.098192635762;12695;12712;12729.363531823186;13013.284269364896 23:07:40 INFO - PID 1635 | __end_tp_report 23:07:40 INFO - PID 1635 | __start_cc_report 23:07:40 INFO - PID 1635 | _x_x_mozilla_cycle_collect,1863 23:07:40 INFO - PID 1635 | __end_cc_report 23:07:40 INFO - PID 1635 | __startTimestamp1493273260505__endTimestamp 23:07:40 INFO - PID 1635 | 23:07:40 INFO - PID 1635 | ------- Summary: start ------- 23:07:40 INFO - PID 1635 | Number of tests: 6 23:07:40 INFO - PID 1635 | 23:07:40 INFO - PID 1635 | [#0] dojo.html Cycles:165 Average:5967.33 Median:6272.50 stddev:710.10 (11.3%) stddev-sans-first:672.00 23:07:40 INFO - PID 1635 | Values: 2961.8 3530.2 3538.5 3555.1 3593.2 5920.7 6089.1 6105.6 6144.6 6150.6 5067.6 5872.1 6126.3 6133.2 6141.0 4864.0 5237.0 5239.3 5244.6 5265.4 5182.0 5310.4 5315.4 5371.5 5384.5 5250.8 5251.8 5284.6 5337.6 5342.3 5282.3 5299.6 5309.4 5361.0 5377.6 4355.5 5245.1 5275.9 5312.1 5319.7 5902.1 5972.7 5976.1 6108.5 6131.6 6246.6 6305.6 6308.0 6326.1 6335.7 6346.0 6359.0 6363.0 6369.5 6492.3 5232.9 6167.7 6243.4 6244.3 6253.1 6500.3 6729.7 6742.8 6751.5 6771.3 6329.4 6338.9 6345.1 6345.9 6364.0 5390.4 6364.6 6376.7 6548.8 6589.0 6274.6 6330.7 6332.7 6411.7 6515.4 6406.9 6492.4 6499.3 6510.0 6520.0 5184.6 5211.5 5221.6 5225.8 5248.3 4532.4 4632.0 4647.0 4656.6 4691.8 6112.1 6120.0 6192.5 6270.2 6284.5 6539.2 6541.8 6589.0 6599.1 6618.9 6212.0 6238.8 6249.4 6257.6 6272.5 5605.3 5687.0 5688.5 5761.5 5766.3 6595.6 6605.5 6621.0 6624.8 6644.9 6329.7 6385.6 6389.8 6400.1 6405.0 6604.6 6607.3 6616.1 6695.4 6788.6 6424.4 6435.1 6449.9 6457.4 6476.1 6301.0 6441.5 6567.3 6576.2 6605.2 5489.1 5699.2 6223.8 6329.6 6376.6 6332.0 6342.1 6360.1 6370.4 6415.5 6262.0 6280.7 6293.0 6293.1 6309.4 6227.4 6297.6 6304.3 6353.1 6377.0 6228.0 6261.7 6275.8 6290.3 6297.5 23:07:40 INFO - PID 1635 | 23:07:40 INFO - PID 1635 | [#1] ext.html Cycles:180 Average:43799.23 Median:3211.40 stddev:78123.34 (2432.7%) stddev-sans-first:78275.24 23:07:40 INFO - PID 1635 | Values: 624.1 787.8 801.0 1102.4 1105.9 26068.5 26816.3 26847.3 36024.5 36600.0 38156.0 38565.0 38595.4 38598.8 38610.0 8244.6 8362.1 8417.4 8426.8 8430.0 5928.6 6040.8 8191.0 8197.5 8377.0 8334.4 8436.3 8441.8 8449.6 8449.9 8380.3 8399.8 8418.4 8420.2 8449.5 8249.5 8319.5 8330.3 8337.2 8342.5 36462.3 36562.7 36685.8 36692.8 36719.0 3238.9 3305.7 3321.6 3328.7 3337.9 30100.8 30128.4 30152.7 30179.4 30322.7 3074.2 3147.6 3166.6 3167.1 3183.9 216842.0 293833.0 294328.0 294792.3 296422.0 47558.3 47795.0 47832.0 47972.8 47997.8 48957.3 48977.8 48999.0 49110.5 49133.8 13740.1 13845.4 13863.7 13873.9 13921.9 11270.3 11273.3 11274.2 11284.6 11336.3 9716.6 10414.3 10489.4 10498.8 11924.6 4428.5 5231.8 5262.5 5267.1 5276.8 891.0 965.6 991.7 1024.6 1035.4 22875.8 24145.9 24172.5 24246.0 24481.6 1083.7 1094.2 1120.5 1144.2 1146.5 12283.4 12288.0 12350.6 12366.3 12368.6 312529.0 313443.9 313639.0 313742.4 314008.0 50328.7 50340.5 50413.2 50492.7 50633.7 1508.7 1517.3 1560.7 1560.9 1565.4 23806.9 23830.5 23920.4 23930.5 23963.6 37400.1 37461.4 37558.7 37564.6 37598.8 294093.0 294811.0 295044.0 295510.0 305645.0 32517.0 33472.7 33475.3 33526.7 33561.0 35839.6 36075.3 36091.5 36117.5 36153.0 22896.7 24519.0 27538.0 29911.0 29978.3 29033.1 29524.0 29934.1 29980.9 30257.8 34463.3 34894.5 35604.6 35651.1 35678.5 25295.0 28751.0 28893.9 29827.7 30205.7 22224.7 22722.5 24135.4 24283.4 24626.4 23:07:40 INFO - PID 1635 | 23:07:40 INFO - PID 1635 | [#2] jquery.html Cycles:180 Average:33628.89 Median:27289.93 stddev:89115.58 (326.6%) stddev-sans-first:89330.61 23:07:40 INFO - PID 1635 | Values: 384.5 423.2 431.2 433.9 440.4 19400.9 19956.4 21068.4 21657.0 21686.2 22189.6 23090.4 23208.7 23866.2 23877.5 6012.1 6295.1 6298.9 6301.1 6321.6 5897.0 6168.8 6223.7 6257.7 6258.1 5247.4 5998.7 6199.5 6200.4 6213.8 6154.3 6292.2 6293.4 6305.4 6317.1 5366.4 5504.6 5568.9 5589.2 5622.4 27212.9 27367.0 27973.4 28099.9 28340.7 2172.2 2254.8 2256.0 2258.4 2259.5 17185.0 17204.8 17229.4 17367.0 17560.3 2120.6 2133.5 2177.6 2179.6 2182.5 127930.0 128431.0 128640.4 130947.3 133049.0 12129.4 12445.4 12468.4 12469.8 12477.7 16696.2 16708.2 16732.7 16743.2 16787.9 7342.7 7567.6 8631.5 8833.7 8996.8 8230.5 8324.5 8351.9 8501.3 8528.7 7531.2 7609.2 7654.5 7704.3 7742.7 2112.1 2114.6 2117.5 2120.6 2288.7 914.5 948.3 962.2 993.7 994.9 17770.9 19707.5 20167.0 20800.0 21249.8 962.4 967.4 996.3 997.8 999.8 8294.7 10225.3 10776.2 10856.8 10955.3 479016.6 536613.0 539524.0 548466.3 550695.0 90630.5 91174.0 114469.0 130113.0 135064.0 958.3 1138.6 1185.9 1187.1 1188.6 20785.3 20919.5 20999.8 21239.8 21308.6 55405.0 56601.4 58778.1 58953.0 59077.7 66942.0 67978.6 68144.0 68273.0 69249.3 7252.4 8516.0 8561.5 8815.7 8848.7 13653.7 13823.4 14279.2 14646.6 14742.1 10026.0 11770.4 12264.6 12280.1 12331.6 11127.6 11476.7 11550.9 11755.4 11886.7 11661.9 12087.6 12108.5 12128.0 12190.4 8727.8 8791.6 8957.4 8991.3 9224.6 9956.7 10616.8 10995.9 11088.9 11925.3 23:07:40 INFO - PID 1635 | 23:07:40 INFO - PID 1635 | [#3] mootools.html Cycles:180 Average:7174.13 Median:4002.90 stddev:4569.18 (114.1%) stddev-sans-first:4560.26 23:07:40 INFO - PID 1635 | Values: 1243.4 1427.0 1443.6 1443.7 1450.1 9966.0 11234.3 11352.2 11814.4 11818.8 11060.3 11706.6 11743.7 11754.2 11784.3 2530.0 2572.0 2576.2 2577.3 2581.9 2106.5 2418.4 2546.5 2547.9 2552.9 2164.6 2184.6 2463.3 2541.9 2542.9 2472.3 2497.3 2505.6 2509.3 2511.9 2497.8 2511.3 2511.6 2513.7 2515.4 11453.7 11682.1 11724.3 11727.2 11731.0 6521.1 6742.6 6750.8 6755.6 6767.2 3902.2 4103.6 4355.1 4359.8 4433.7 1030.1 1036.5 1222.9 1272.3 1307.8 16357.9 16476.5 16572.9 16585.8 16613.3 5408.5 5467.5 5568.4 5679.1 5711.3 12004.9 12025.3 12037.2 12038.3 12051.2 6601.1 6609.8 6611.1 6612.3 6618.0 5971.3 6167.1 6221.4 6232.2 6254.4 3714.5 4186.1 4313.6 4341.4 4361.4 1268.2 1268.5 1284.6 1293.3 1293.5 623.2 786.3 796.2 803.0 849.5 6331.5 6626.3 6739.4 6912.8 6964.9 556.9 581.9 602.5 605.4 712.4 4146.7 4551.6 4586.1 4685.2 4720.7 15096.7 15384.0 15396.8 15410.7 15485.8 14899.9 15310.8 15334.8 15493.7 15507.4 8536.2 8797.6 8813.8 8818.3 8819.6 6707.7 6715.4 6820.2 7017.7 7138.4 12093.3 13122.9 13167.7 13601.0 13842.1 13954.5 13972.2 13985.9 14020.4 14365.7 9253.6 9547.9 9554.7 9559.7 9575.2 10729.3 10970.2 10993.7 11009.7 11079.1 9264.7 9268.4 9303.9 9343.0 9368.5 8944.7 9164.6 9200.5 9299.4 9371.8 8168.8 8251.1 8333.6 8340.8 8344.5 5069.7 5938.1 6103.4 6144.5 6191.6 5998.0 6144.4 6183.0 6275.0 6342.9 23:07:40 INFO - PID 1635 | 23:07:40 INFO - PID 1635 | [#4] prototype.html Cycles:180 Average:4910.24 Median:5647.63 stddev:1515.16 (26.8%) stddev-sans-first:1480.92 23:07:40 INFO - PID 1635 | Values: 389.3 395.5 400.7 404.3 406.0 5673.6 5748.7 5768.1 5776.2 5799.6 5036.2 5169.7 5391.8 5639.6 5664.5 3444.9 4345.0 4367.3 4389.5 4396.9 4351.9 4383.0 4391.6 4397.5 4400.0 4208.2 4311.6 4321.9 4345.6 4371.4 4321.8 4328.3 4334.9 4336.2 4350.6 3860.8 3871.3 3871.6 3873.7 3878.5 5301.0 5421.4 5597.3 5604.5 5614.9 4183.5 4298.4 4919.5 4947.7 4948.9 5987.9 6121.8 6122.5 6131.7 6160.4 3879.7 4038.2 4073.8 4080.4 4085.8 5178.7 6142.1 6187.5 6448.5 6491.7 3559.9 4116.4 4118.6 4133.4 4140.0 3682.9 4017.6 4119.2 4214.6 4215.5 4038.3 4555.7 4612.2 4821.0 4903.5 4761.6 5101.5 6080.2 6088.1 6088.8 1405.6 1418.8 1419.8 1427.8 1441.7 800.4 918.4 932.0 944.8 947.6 5047.7 5753.2 5810.0 5842.4 5842.5 4873.2 6310.4 6358.5 6368.5 6374.9 5655.7 5784.7 5846.5 5852.9 5864.0 3239.7 3285.6 3286.6 3297.7 3300.2 5954.4 6278.4 6306.2 6433.9 6563.5 5733.5 5973.9 6099.8 6534.9 6622.7 5738.8 6310.9 6542.2 6548.7 6573.4 5945.3 5983.6 5996.6 6327.8 6340.6 5944.0 6125.4 6227.6 6255.2 6283.7 5686.4 5686.8 6500.9 6547.9 6549.1 4089.3 4117.5 4129.5 4131.1 4131.1 5023.3 5528.6 6029.0 6097.5 6339.0 5613.5 5840.7 5855.6 6095.7 6116.6 5223.7 5549.6 5725.5 5869.4 6118.8 5250.1 5928.4 5962.5 5970.4 5972.2 5733.2 5734.6 5824.7 6086.0 6100.4 4751.8 5976.6 6010.8 6073.6 6119.4 23:07:40 INFO - PID 1635 | 23:07:40 INFO - PID 1635 | [#5] yui.html Cycles:180 Average:21703.38 Median:18466.95 stddev:30934.65 (167.5%) stddev-sans-first:30981.29 23:07:40 INFO - PID 1635 | Values: 717.1 863.8 865.2 874.7 883.6 13344.2 17517.8 17800.9 18489.4 18561.6 17342.0 17491.1 17926.8 17940.0 17941.6 963.6 978.9 979.1 979.7 981.4 663.5 685.5 970.7 1002.2 1003.0 1047.1 1047.4 1047.8 1050.9 1053.7 1040.7 1045.4 1045.5 1046.5 1052.9 770.5 771.2 771.4 772.2 776.8 16717.6 16996.2 17211.1 17407.1 17593.8 16619.5 16803.2 17040.5 17051.3 17302.5 13794.5 13828.2 13908.5 13980.7 14208.1 3421.4 3442.6 3443.3 3449.8 3449.9 124653.4 125358.0 126058.0 127319.7 127793.7 9363.7 11457.1 11849.2 12037.5 12172.0 19296.8 19307.9 19710.0 20392.5 24737.0 7693.6 7855.2 7871.7 7874.8 7883.8 3904.1 3994.4 4003.8 4008.5 4016.9 4284.0 4368.1 4368.2 4369.5 4376.5 849.4 899.7 903.7 904.1 908.0 609.5 634.6 650.8 654.3 663.4 10920.2 11504.5 11764.4 11800.2 11855.1 644.7 660.2 660.4 673.6 683.4 4331.3 5225.2 5371.0 5501.6 5627.4 103658.0 104523.0 105634.9 106140.0 107818.5 91591.5 92748.5 93564.0 94142.5 94272.0 81148.2 81359.6 81477.6 81988.0 83112.0 8933.0 10565.2 11154.7 11294.6 11407.1 38656.8 40931.8 41373.4 41487.8 41687.0 53877.0 54520.7 54858.7 54975.0 55472.2 14273.5 16436.2 17084.2 17113.9 17118.5 17817.3 17902.9 18444.4 18498.6 18757.7 16040.8 16134.4 16830.9 17011.7 17039.7 16286.4 16815.5 17023.7 17074.9 17145.5 15768.5 15787.6 16215.8 16660.2 16849.7 11565.1 11607.7 12185.6 12224.3 12288.9 12024.1 12695.0 12712.0 12729.4 13013.3 23:07:40 INFO - PID 1635 | -------- Summary: end -------- 23:07:40 INFO - PID 1635 | 23:07:41 INFO - TEST-INFO | 1635: exit 0 23:07:41 INFO - TEST-OK | dromaeo_css | took 1118750ms 23:07:41 INFO - TEST-START | kraken 23:07:41 INFO - operating with platform_type : mac_ 23:07:41 INFO - Initialising browser for kraken test... 23:07:41 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/tmpAwTU8b/profile http://localhost:49246/getInfo.html) 23:07:43 INFO - PID 1646 | __metrics Screen width/height:1600/1200 23:07:43 INFO - PID 1646 | colorDepth:24 23:07:43 INFO - PID 1646 | Browser inner width/height: 1024/689 23:07:43 INFO - PID 1646 | __metrics 23:07:43 INFO - PID 1646 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 23:07:43 INFO - PID 1646 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 334: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:07:44 INFO - TEST-INFO | 1646: exit 0 23:07:44 INFO - Browser initialized. 23:07:44 INFO - Running cycle 1/1 for kraken test... 23:07:44 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/tmpAwTU8b/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:07:57 INFO - PID 1647 | RSS: Main: 243318784 23:07:57 INFO - PID 1647 | 23:07:59 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-beat-detection) 23:08:00 INFO - PID 1647 | RSS: Main: 313053184 23:08:00 INFO - PID 1647 | 23:08:03 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-dft) 23:08:03 INFO - PID 1647 | RSS: Main: 297857024 23:08:03 INFO - PID 1647 | 23:08:07 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-fft) 23:08:07 INFO - PID 1647 | RSS: Main: 385904640 23:08:07 INFO - PID 1647 | 23:08:09 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-oscillator) 23:08:10 INFO - PID 1647 | RSS: Main: 290000896 23:08:10 INFO - PID 1647 | 23:08:12 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49246/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:08:13 INFO - PID 1647 | RSS: Main: 292958208 23:08:13 INFO - PID 1647 | 23:08:15 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49246/tests/kraken/driver.html?testName=imaging-darkroom) 23:08:16 INFO - PID 1647 | RSS: Main: 354533376 23:08:16 INFO - PID 1647 | 23:08:19 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49246/tests/kraken/driver.html?testName=imaging-desaturate) 23:08:19 INFO - PID 1647 | RSS: Main: 369532928 23:08:19 INFO - PID 1647 | 23:08:22 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49246/tests/kraken/driver.html?testName=json-parse-financial) 23:08:23 INFO - PID 1647 | RSS: Main: 374067200 23:08:23 INFO - PID 1647 | 23:08:24 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49246/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:08:25 INFO - PID 1647 | RSS: Main: 330883072 23:08:25 INFO - PID 1647 | 23:08:27 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:08:27 INFO - PID 1647 | RSS: Main: 332660736 23:08:27 INFO - PID 1647 | 23:08:29 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:08:30 INFO - PID 1647 | RSS: Main: 334467072 23:08:30 INFO - PID 1647 | 23:08:33 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:08:34 INFO - PID 1647 | RSS: Main: 355786752 23:08:34 INFO - PID 1647 | 23:08:37 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:08:37 INFO - PID 1647 | RSS: Main: 347566080 23:08:37 INFO - PID 1647 | 23:08:39 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49246/tests/kraken/driver.html?testName=ai-astar) 23:08:39 INFO - PID 1647 | RSS: Main: 389861376 23:08:39 INFO - PID 1647 | 23:08:39 INFO - PID 1647 | __start_tp_report 23:08:39 INFO - PID 1647 | _x_x_mozilla_page_load 23:08:39 INFO - PID 1647 | _x_x_mozilla_page_load_details 23:08:39 INFO - PID 1647 | |i|pagename|runs| 23:08:39 INFO - PID 1647 | |0;ai-astar;109;108;109;109;108;111;110;110;110;108 23:08:39 INFO - PID 1647 | |1;audio-beat-detection;140;109;108;171;139;112;109;105;136;108 23:08:39 INFO - PID 1647 | |2;audio-dft;185;182;184;187;186;183;185;181;188;179 23:08:39 INFO - PID 1647 | |3;audio-fft;80;60;57;89;61;60;81;66;77;59 23:08:39 INFO - PID 1647 | |4;audio-oscillator;91;71;84;68;68;67;89;68;67;95 23:08:39 INFO - PID 1647 | |5;imaging-gaussian-blur;88;94;87;86;87;87;86;88;88;87 23:08:39 INFO - PID 1647 | |6;imaging-darkroom;95;95;94;95;95;96;95;96;93;95 23:08:39 INFO - PID 1647 | |7;imaging-desaturate;81;85;85;85;83;84;86;85;86;85 23:08:39 INFO - PID 1647 | |8;json-parse-financial;49;47;45;48;48;48;49;49;48;50 23:08:39 INFO - PID 1647 | |9;json-stringify-tinderbox;51;59;53;59;64;58;57;56;55;57 23:08:39 INFO - PID 1647 | |10;stanford-crypto-aes;81;82;82;82;82;81;81;81;80;81 23:08:39 INFO - PID 1647 | |11;stanford-crypto-ccm;141;151;137;136;144;136;136;139;192;153 23:08:39 INFO - PID 1647 | |12;stanford-crypto-pbkdf2;184;187;183;186;186;177;178;181;184;179 23:08:39 INFO - PID 1647 | |13;stanford-crypto-sha256-iterative;67;67;67;67;68;68;67;67;67;66 23:08:39 INFO - PID 1647 | __end_tp_report 23:08:39 INFO - PID 1647 | __start_cc_report 23:08:39 INFO - PID 1647 | _x_x_mozilla_cycle_collect,2148 23:08:39 INFO - PID 1647 | __end_cc_report 23:08:39 INFO - PID 1647 | __startTimestamp1493273319802__endTimestamp 23:08:39 INFO - PID 1647 | 23:08:39 INFO - PID 1647 | ------- Summary: start ------- 23:08:39 INFO - PID 1647 | Number of tests: 14 23:08:39 INFO - PID 1647 | 23:08:39 INFO - PID 1647 | [#0] ai-astar Cycles:10 Average:109.20 Median:109.50 stddev:1.03 (0.9%) stddev-sans-first:1.09 23:08:39 INFO - PID 1647 | Values: 109.0 108.0 109.0 109.0 108.0 111.0 110.0 110.0 110.0 108.0 23:08:39 INFO - PID 1647 | 23:08:39 INFO - PID 1647 | [#1] audio-beat-detection Cycles:10 Average:123.70 Median:124.00 stddev:21.86 (17.6%) stddev-sans-first:22.37 23:08:39 INFO - PID 1647 | Values: 140.0 109.0 108.0 171.0 139.0 112.0 109.0 105.0 136.0 108.0 23:08:39 INFO - PID 1647 | 23:08:39 INFO - PID 1647 | [#2] audio-dft Cycles:10 Average:184.00 Median:185.00 stddev:2.79 (1.5%) stddev-sans-first:2.93 23:08:39 INFO - PID 1647 | Values: 185.0 182.0 184.0 187.0 186.0 183.0 185.0 181.0 188.0 179.0 23:08:39 INFO - PID 1647 | 23:08:39 INFO - PID 1647 | [#3] audio-fft Cycles:10 Average:69.00 Median:71.50 stddev:11.59 (16.2%) stddev-sans-first:11.58 23:08:39 INFO - PID 1647 | Values: 80.0 60.0 57.0 89.0 61.0 60.0 81.0 66.0 77.0 59.0 23:08:39 INFO - PID 1647 | 23:08:39 INFO - PID 1647 | [#4] audio-oscillator Cycles:10 Average:76.80 Median:77.50 stddev:11.51 (14.8%) stddev-sans-first:11.00 23:08:39 INFO - PID 1647 | Values: 91.0 71.0 84.0 68.0 68.0 67.0 89.0 68.0 67.0 95.0 23:08:39 INFO - PID 1647 | 23:08:39 INFO - PID 1647 | [#5] imaging-gaussian-blur Cycles:10 Average:87.80 Median:87.50 stddev:2.30 (2.6%) stddev-sans-first:2.44 23:08:39 INFO - PID 1647 | Values: 88.0 94.0 87.0 86.0 87.0 87.0 86.0 88.0 88.0 87.0 23:08:39 INFO - PID 1647 | 23:08:39 INFO - PID 1647 | [#6] imaging-darkroom Cycles:10 Average:94.90 Median:95.00 stddev:0.88 (0.9%) stddev-sans-first:0.93 23:08:39 INFO - PID 1647 | Values: 95.0 95.0 94.0 95.0 95.0 96.0 95.0 96.0 93.0 95.0 23:08:39 INFO - PID 1647 | 23:08:39 INFO - PID 1647 | [#7] imaging-desaturate Cycles:10 Average:84.50 Median:85.00 stddev:1.51 (1.8%) stddev-sans-first:0.93 23:08:39 INFO - PID 1647 | Values: 81.0 85.0 85.0 85.0 83.0 84.0 86.0 85.0 86.0 85.0 23:08:39 INFO - PID 1647 | 23:08:39 INFO - PID 1647 | [#8] json-parse-financial Cycles:10 Average:48.10 Median:48.50 stddev:1.37 (2.8%) stddev-sans-first:1.41 23:08:39 INFO - PID 1647 | Values: 49.0 47.0 45.0 48.0 48.0 48.0 49.0 49.0 48.0 50.0 23:08:39 INFO - PID 1647 | 23:08:39 INFO - PID 1647 | [#9] json-stringify-tinderbox Cycles:10 Average:56.90 Median:57.50 stddev:3.57 (6.2%) stddev-sans-first:3.09 23:08:39 INFO - PID 1647 | Values: 51.0 59.0 53.0 59.0 64.0 58.0 57.0 56.0 55.0 57.0 23:08:39 INFO - PID 1647 | 23:08:39 INFO - PID 1647 | [#10] stanford-crypto-aes Cycles:10 Average:81.30 Median:81.50 stddev:0.67 (0.8%) stddev-sans-first:0.71 23:08:39 INFO - PID 1647 | Values: 81.0 82.0 82.0 82.0 82.0 81.0 81.0 81.0 80.0 81.0 23:08:39 INFO - PID 1647 | 23:08:39 INFO - PID 1647 | [#11] stanford-crypto-ccm Cycles:10 Average:146.50 Median:142.50 stddev:17.15 (12.0%) stddev-sans-first:18.07 23:08:39 INFO - PID 1647 | Values: 141.0 151.0 137.0 136.0 144.0 136.0 136.0 139.0 192.0 153.0 23:08:39 INFO - PID 1647 | 23:08:39 INFO - PID 1647 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:182.50 Median:184.00 stddev:3.57 (1.9%) stddev-sans-first:3.74 23:08:39 INFO - PID 1647 | Values: 184.0 187.0 183.0 186.0 186.0 177.0 178.0 181.0 184.0 179.0 23:08:39 INFO - PID 1647 | 23:08:39 INFO - PID 1647 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.10 Median:67.00 stddev:0.57 (0.8%) stddev-sans-first:0.60 23:08:39 INFO - PID 1647 | Values: 67.0 67.0 67.0 67.0 68.0 68.0 67.0 67.0 67.0 66.0 23:08:39 INFO - PID 1647 | -------- Summary: end -------- 23:08:39 INFO - PID 1647 | 23:08:40 INFO - TEST-INFO | 1647: exit 0 23:08:40 INFO - TEST-OK | kraken | took 59680ms 23:08:40 INFO - SUITE-END | took 1178s 23:08:40 INFO - Completed test suite (00:19:38) 23:08:40 INFO - javascript benchmark 23:08:41 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7640.86569891052, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2961.8074825136373, 3530.1704099536605, 3538.486919396687, 3555.093451169951, 3593.1915319053214, 5920.703964801764, 6089.147519347295, 6105.60313579617, 6144.5698801083945, 6150.630962142274, 5067.569256613183, 5872.119182122684, 6126.334466362969, 6133.172021777059, 6140.969295153529, 4864.0, 5237.0, 5239.266502689627, 5244.55421289189, 5265.420803711589, 5182.041322355376, 5310.36275646921, 5315.415304316522, 5371.543418809386, 5384.461553844604, 5250.842474725745, 5251.763670634792, 5284.630075894689, 5337.572994160458, 5342.2787923630585, 5282.313299271109, 5299.6025298102795, 5309.3894202166475, 5361.0, 5377.596680248997, 4355.455568053993, 5245.108331583602, 5275.86810329741, 5312.07038768214, 5319.7340132993195, 5902.144189092557, 5972.70136493178, 5976.133460648181, 6108.450239478424, 6131.632102073845, 6246.6252024878195, 6305.621662700207, 6307.96846015767, 6326.114343991845, 6335.746570137189, 6346.0, 6358.968205158991, 6362.981922892315, 6369.490440764774, 6492.3183065777885, 5232.92150617741, 6167.660778657172, 6243.37566243372, 6244.344343843922, 6253.093301471261, 6500.317466666026, 6729.730810767514, 6742.797716068526, 6751.527393082437, 6771.289014653489, 6329.398707122816, 6338.873222535523, 6345.07996340528, 6345.873082538324, 6364.045393191059, 5390.380106287748, 6364.649944253018, 6376.7130479128555, 6548.8362790930605, 6588.967055164693, 6274.560780745304, 6330.651814150266, 6332.746690132343, 6411.743530258737, 6515.381038801306, 6406.935930640727, 6492.35076492347, 6499.317571655003, 6510.0, 6519.967400163064, 5184.5593124584075, 5211.530962213419, 5221.582273418117, 5225.817096401684, 5248.291480650139, 4532.388127602731, 4632.0, 4646.97676511622, 4656.557627025427, 4691.7888695008805, 6112.077967281658, 6120.030276471984, 6192.504599632019, 6270.153529273581, 6284.465820405317, 6539.182602174728, 6541.771038013647, 6589.0, 6599.076129341896, 6618.900716489256, 6212.0371342441995, 6238.781642642486, 6249.437550620466, 6257.561970662101, 6272.498200143978, 5605.327360716658, 5686.976344257991, 5688.459596338341, 5761.452661997104, 5766.336871259775, 6595.637239951753, 6605.504587156079, 6621.0, 6624.768133115318, 6644.867102658017, 6329.715162817776, 6385.553011289351, 6389.808305750835, 6400.07198956158, 6404.967975160094, 6604.603723776589, 6607.339266073354, 6616.139901812733, 6695.363940425652, 6788.59268443895, 6424.357564243538, 6435.06691529735, 6449.903251451326, 6457.354264573505, 6476.125723027331, 6301.0, 6441.516886233645, 6567.277599464151, 6576.177977752781, 6605.207375114825, 5489.149181876815, 5699.173619825019, 6223.813285601439, 6329.5569310149685, 6376.553641245068, 6332.0, 6342.080398342307, 6360.109584658244, 6370.426661600478, 6415.518836087219, 6262.0, 6280.654563998841, 6293.0, 6293.118963345227, 6309.3690630936535, 6227.439530442282, 6297.622142671455, 6304.306526282014, 6353.078803573363, 6377.043443483514, 6228.0, 6261.686915654108, 6275.843103922439, 6290.339514350927, 6297.496200304056], "value": 5920.1144860846925, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [624.1075262374806, 787.8148635070759, 801.014751855218, 1102.39919244012, 1105.8838821923698, 26068.478630427402, 26816.32959176019, 26847.32881677959, 36024.45963310552, 36600.0, 38156.0, 38565.0, 38595.421068683994, 38598.807005965, 38610.0, 8244.628991695374, 8362.080171181165, 8417.410781245315, 8426.78933026673, 8429.95785021074, 5928.644281343111, 6040.818775436744, 8191.0, 8197.508149511019, 8376.958115209445, 8334.374921880853, 8436.282915952152, 8441.831163376728, 8449.57752112395, 8449.873251901226, 8380.287675547586, 8399.790005249917, 8418.410711250215, 8420.200080992296, 8449.493030418163, 8249.463784853966, 8319.500829950222, 8330.291925186366, 8337.166283371615, 8342.457740246926, 36462.27075458476, 36562.72030478947, 36685.81657091724, 36692.81653591715, 36719.0, 3238.935221295561, 3305.652906444837, 3321.618013928405, 3328.6504916983854, 3337.916552086193, 30100.8494957526, 30128.397432051235, 30152.69847301543, 30179.396412071637, 30322.696773032207, 3074.2314421394653, 3147.5750773645495, 3166.6358368787655, 3167.144870884871, 3183.8726450941936, 216842.0, 293833.0, 294328.0, 294792.2603869798, 296422.0, 47558.28662570064, 47795.0, 47832.0, 47972.7601361991, 47997.8000549982, 48957.265641015416, 48977.75511122493, 48999.0, 49110.508894911305, 49133.75433122811, 13740.10689305192, 13845.376958036912, 13863.653408664864, 13873.86126138746, 13921.930390347983, 11270.323780573191, 11273.323600583828, 11274.154438417152, 11284.605038823602, 11336.319820810777, 9716.61133554664, 10414.323069000491, 10489.370637761758, 10498.842517362245, 11924.642260732191, 4428.490723566767, 5231.764570594332, 5262.500062494057, 5267.130923397596, 5276.815311464157, 890.9664788844909, 965.584798536636, 991.6727479931606, 1024.6311327921942, 1035.4305132177271, 22875.771242287698, 24145.879270603535, 24172.516549668908, 24246.0, 24481.63277550838, 1083.685731137961, 1094.2340361746808, 1120.5209123956463, 1144.1590431033185, 1146.4554336690005, 12283.385830708428, 12288.0, 12350.567730129402, 12366.258024518558, 12368.567100151451, 312529.0, 313443.86556134146, 313639.0, 313742.4312878421, 314008.0, 50328.74835625799, 50340.49659503358, 50413.24380134301, 50492.73768155752, 50633.74683126635, 1508.7284288827898, 1517.347540557571, 1560.6722588256594, 1560.9063456192437, 1565.3973220310036, 23806.88096559506, 23830.523389531765, 23920.40198995039, 23930.52138957246, 23963.640545391485, 37400.06499837417, 37461.43807842939, 37558.68544600926, 37564.62435375611, 37598.8120059398, 294093.0, 294811.0, 295044.0, 295510.0, 305645.0, 32517.02448926536, 33472.66527334695, 33475.330493389505, 33526.66473335236, 33561.0, 35839.64160358363, 36075.27849442944, 36091.45862812112, 36117.458238125924, 36153.0, 22896.656550151427, 24519.0, 27538.0, 29910.953116640812, 29978.25054373658, 29033.12900612985, 29524.0, 29934.101976940725, 29980.85009574938, 30257.848710756305, 34463.310733785685, 34894.476582850766, 35604.64395356013, 35651.10872228215, 35678.464823027156, 25295.0, 28751.0, 28893.855530722212, 29827.701722982492, 30205.714000050342, 22224.666630000887, 22722.545549088594, 24135.396615084763, 24283.39291517726, 24626.384340390916], "value": 16750.37473633917, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [384.45599476740415, 423.18262519152654, 431.2496256513667, 433.9023719663076, 440.3504830375196, 19400.902995485038, 19956.401307960747, 21068.367948961535, 21657.0, 21686.24098156565, 22189.556208875852, 23090.422739431524, 23208.651870221962, 23866.164684236057, 23877.522449550997, 6012.128241404983, 6295.0872123542285, 6298.905516417258, 6301.054841773747, 6321.589096708699, 5896.97051514744, 6168.75324987, 6223.657698826562, 6257.749690012394, 6258.123862659231, 5247.422783493813, 5998.730057147417, 6199.504039676815, 6200.379962003786, 6213.782517611885, 6154.323024467305, 6292.2134733158355, 6293.370662933716, 6305.432511074003, 6317.1156038154695, 5366.436524164946, 5504.587155963319, 5568.86077848053, 5589.189567512729, 5622.353429355595, 27212.863935680394, 27367.0, 27973.440531189262, 28099.85950070257, 28340.716592834015, 2172.1528603844513, 2254.763249858774, 2256.0, 2258.367657056027, 2259.548090381914, 17185.0, 17204.827951720574, 17229.39697110595, 17367.0, 17560.297588096462, 2120.5652841167525, 2133.5306232628795, 2177.56448710257, 2179.6294629912954, 2182.4980254541388, 127930.0, 128431.0, 128640.3567982154, 130947.34526327498, 133049.0, 12129.393530323448, 12445.377731113407, 12468.43892024861, 12469.75060498785, 12477.688057798447, 16696.165191740365, 16708.24812883423, 16732.749008764877, 16743.246553905105, 16787.91606041962, 7342.669579868919, 7567.583782891884, 8631.48211107323, 8833.690820821368, 8996.775080623036, 8230.547319897343, 8324.542150181676, 8351.916480835236, 8501.319894408432, 8528.65885364591, 7531.171571127172, 7609.15704992148, 7654.464187506821, 7704.345130663956, 7742.6902923883545, 2112.0812446585783, 2114.6299397605358, 2117.5447278834818, 2120.6394912864544, 2288.6681431192383, 914.5198770645331, 948.3219498058783, 962.1965658675085, 993.7316924430363, 994.9005099489993, 17770.91114544419, 19707.50731231702, 20167.0, 20799.96000199984, 21249.787502124782, 962.41292811385, 967.3663750243602, 996.2826764729383, 997.7705127820642, 999.7702825524646, 8294.709685160991, 10225.335353202017, 10776.168051431276, 10856.78286434267, 10955.287578780337, 479016.60491698014, 536613.0, 539524.0, 548466.2576687091, 550695.0, 90630.54684726665, 91174.0, 114469.0, 130113.0, 135064.0, 958.3387462650749, 1138.6128716236353, 1185.8814118588245, 1187.0503597122267, 1188.5721140389453, 20785.272515461886, 20919.477013074797, 20999.790002100395, 21239.831809250638, 21308.57382852365, 55405.0, 56601.43398565962, 58778.11832822426, 58953.0, 59077.70461147667, 66942.0, 67978.64042719018, 68144.0, 68273.0, 69249.30750692429, 7252.419806415369, 8515.957420212859, 8561.529115898686, 8815.735527934172, 8848.71693604411, 13653.658658533615, 13823.377947992163, 14279.214643194724, 14646.560603181922, 14742.11547307165, 10026.0, 11770.411479426166, 12264.632061037826, 12280.078994075659, 12331.630051098482, 11127.554897803997, 11476.655700329004, 11550.884491155317, 11755.353455560027, 11886.702832428913, 11661.883381166079, 12087.637370878536, 12108.515659373526, 12127.968007998, 12190.390480475764, 8727.781805454915, 8791.604377802887, 8957.372983891066, 8991.28069754431, 9224.584893679666, 9956.701298961043, 10616.84074738895, 10995.945020275167, 11088.944555277172, 11925.284482931052], "value": 10246.545635566668, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1243.4218088588807, 1427.0296198584965, 1443.6102252391854, 1443.7256921184967, 1450.1226757811523, 9965.950170249147, 11234.325940443568, 11352.205345625809, 11814.409279536032, 11818.7636247275, 11060.336379817216, 11706.590269340575, 11743.706407339821, 11754.177207595472, 11784.29294242346, 2529.974700252997, 2572.0, 2576.188500622298, 2577.30412788547, 2581.8579978101197, 2106.4944413340763, 2418.443757935676, 2546.4525127097672, 2547.923562293125, 2552.923412297634, 2164.588728141656, 2184.6286131357706, 2463.310273123528, 2541.923742287725, 2542.8982840686444, 2472.295395812185, 2497.300755788382, 2505.5615267328326, 2509.284853816672, 2511.937201569957, 2497.825152239344, 2511.309389917769, 2511.635812807133, 2513.673222481084, 2515.38373098592, 11453.656390308304, 11682.123840711896, 11724.29654220741, 11727.17909746318, 11731.002864756507, 6521.119648847392, 6742.561733487308, 6750.8312292192595, 6755.628440435774, 6767.221769496522, 3902.2195560887935, 4103.589641035873, 4355.085432059272, 4359.84740534083, 4433.689641725048, 1030.108955753268, 1036.5180191211048, 1222.8960538354252, 1272.2684456437585, 1307.7972914198315, 16357.918210408872, 16476.505704828753, 16572.92276002056, 16585.751213731804, 16613.252403641865, 5408.513233808976, 5467.535259502907, 5568.415316391802, 5679.091345384719, 5711.3146422428745, 12004.879951200552, 12025.338606376557, 12037.157398982163, 12038.277703337828, 12051.216670916363, 6601.108850305147, 6609.834754131089, 6611.107500487469, 6612.305707900698, 6618.0, 5971.253593300838, 6167.136600875881, 6221.4089661482085, 6232.158658581124, 6254.405831446096, 3714.46140309659, 4186.1418409226035, 4313.568643135662, 4341.413909122291, 4361.389405483234, 1268.2200446725199, 1268.5366276334862, 1284.6081945006677, 1293.321006471591, 1293.5020017293223, 623.234438788483, 786.3119770201074, 796.1640277708383, 802.9839403211903, 849.4563479373206, 6331.525135614847, 6626.271110177877, 6739.359760822714, 6912.827179320558, 6964.895526567106, 556.858001209689, 581.8952588534105, 602.5300265792689, 605.4429924469499, 712.3659942651045, 4146.730462519927, 4551.567601077959, 4586.128635559233, 4685.2035154023215, 4720.716756994591, 15096.698066038618, 15384.0, 15396.846031539766, 15410.691786164438, 15485.845141548665, 14899.925500372428, 15310.770338444709, 15334.846651533566, 15493.690126197638, 15507.379704811907, 8536.231739143506, 8797.592385218335, 8813.779655508664, 8818.29453643707, 8819.559022049, 6707.664616769044, 6715.4291885190305, 6820.249772524919, 7017.719291228498, 7138.428925686037, 12093.334866582423, 13122.934385328012, 13167.67080822987, 13601.047926645435, 13842.100263482842, 13954.511592094228, 13972.231527266098, 13985.860141398864, 14020.439182432588, 14365.712685746435, 9253.583588738658, 9547.904520954702, 9554.713358599252, 9559.713208603753, 9575.186109180797, 10729.302595331279, 10970.232083754061, 10993.725156871142, 11009.669709908716, 11079.113670906469, 9264.72205833826, 9268.397554158959, 9303.906960930575, 9343.0, 9368.484733339732, 8944.731658050268, 9164.587593558172, 9200.493972831558, 9299.442033477742, 9371.765705857408, 8168.795780105307, 8251.133630968901, 8333.583320834055, 8340.833183336177, 8344.457610255315, 5069.670471419494, 5938.109283607493, 6103.359147289649, 6144.53915956314, 6191.628502289877, 5997.970010149921, 6144.41628045335, 6182.969085154726, 6274.996000639876, 6342.936570634419], "value": 5347.458391727176, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [389.2759467390654, 395.5273448229365, 400.7475290566943, 404.28643444320795, 405.99188016239697, 5673.631213971089, 5748.683822389772, 5768.134779783035, 5776.191333213343, 5799.564183043121, 5036.244563315505, 5169.715665638389, 5391.78432862686, 5639.577031722617, 5664.518515926141, 3444.9311013779648, 4344.956550434503, 4367.279398899178, 4389.517153113155, 4396.934045989313, 4351.934720979188, 4382.97808510957, 4391.626711729491, 4397.516273209944, 4400.0, 4208.242516347056, 4311.598730412613, 4321.891952701192, 4345.587169218919, 4371.431713877208, 4321.82712691492, 4328.3074708046715, 4334.913301733979, 4336.197803406349, 4350.564943505656, 3860.82626281818, 3871.322518559269, 3871.632194941476, 3873.682947797734, 3878.4957955465893, 5301.0, 5421.403645598981, 5597.3412629001305, 5604.495595396393, 5614.887702245973, 4183.456150700389, 4298.3565107459, 4919.508049195052, 4947.653664243504, 4948.925766113512, 5987.850303742398, 6121.846953826145, 6122.510199184055, 6131.662758548278, 6160.383961603804, 3879.670228030621, 4038.2125485530332, 4073.8370465181356, 4080.3879418087226, 4085.775282359469, 5178.741062946838, 6142.1093941378695, 6187.504999600066, 6448.516361272877, 6491.7092569556135, 3559.911002224965, 4116.444280022219, 4118.602153223543, 4133.42132101506, 4140.0270936329935, 3682.889513314551, 4017.6384125428854, 4119.176164767058, 4214.578542145761, 4215.494140703136, 4038.293298672779, 4555.658325625525, 4612.192866248393, 4821.0, 4903.53416425439, 4761.595264402496, 5101.5408613224245, 6080.209572755513, 6088.086786981989, 6088.817335479943, 1405.5572494664148, 1418.8084608577917, 1419.8438171801095, 1427.8143841300773, 1441.6592568911014, 800.4476910931438, 918.4168053286211, 931.9813603727888, 944.8393773058597, 947.6114792934864, 5047.697138171721, 5753.223314852441, 5810.0, 5842.415758424124, 5842.503387212049, 4873.172560289891, 6310.43206111443, 6358.491320694333, 6368.458681012074, 6374.904376434357, 5655.688937108499, 5784.71076446176, 5846.479915222073, 5852.912206316909, 5864.0, 3239.6598357172156, 3285.6221534523356, 3286.6220384655594, 3297.6702329767313, 3300.1914530939594, 5954.37479064692, 6278.372162783685, 6306.243250809932, 6433.871322573522, 6563.540552161303, 5733.455321744427, 5973.894829456564, 6099.786507472217, 6534.9285835657165, 6622.741679080959, 5738.8278351649515, 6310.927142385805, 6542.247641521286, 6548.6725663716625, 6573.388860592543, 5945.346011938596, 5983.550479652686, 5996.580239383201, 6327.778527751507, 6340.619562826245, 5944.0, 6125.387461253839, 6227.626342419288, 6255.1851666749235, 6283.717232724447, 5686.43135686428, 5686.800961966312, 6500.869982600227, 6547.869042619026, 6549.115869357575, 4089.26393249218, 4117.547069822377, 4129.525104613008, 4131.07050913542, 4131.132462182975, 5023.34696489454, 5528.612997090326, 6029.0, 6097.451229389376, 6338.968305158629, 5613.46672066153, 5840.737166827418, 5855.59010869235, 6095.738779752811, 6116.602420842809, 5223.660462069954, 5549.639273447213, 5725.513331366714, 5869.442402971882, 6118.847028824315, 5250.0549901018185, 5928.407159284037, 5962.493188079062, 5970.373110823475, 5972.223610930551, 5733.226014487991, 5734.559264441302, 5824.737886795189, 6085.965385884322, 6100.359462256399, 4751.786169622306, 5976.62537616342, 6010.789622363196, 6073.5748497604745, 6119.418655227746], "value": 4442.906161366259, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [717.1215261304899, 863.7581477186386, 865.2429124516049, 874.6545114679701, 883.6288758721337, 13344.199348039125, 17517.82482175178, 17800.910995445003, 18489.44531664049, 18561.628767424645, 17342.046187459684, 17491.125443727826, 17926.82073179268, 17940.0, 17941.641167176647, 963.6097380560884, 978.8972157923422, 979.0992287095889, 979.7207795778205, 981.3964411886674, 663.5023732200849, 685.455063224735, 970.7039352997333, 1002.2332915319786, 1003.0120331473493, 1047.0681093826502, 1047.350642601588, 1047.8166320893852, 1050.9159267258601, 1053.7365658585354, 1040.6617849198965, 1045.419792015431, 1045.46158728255, 1046.4506134279488, 1052.8947105289449, 770.4991755359006, 771.155584634824, 771.4021633234232, 772.2239149654617, 776.7863837444692, 16717.582060448465, 16996.150192490328, 17211.139443027925, 17407.129643517772, 17593.824061759475, 16619.50141495745, 16803.243854026598, 17040.48878533646, 17051.317947282092, 17302.480925572254, 13794.517191898236, 13828.239446830421, 13908.51320203788, 13980.650483737887, 14208.076475029193, 3421.3841508528453, 3442.5524681791458, 3443.3113377324385, 3449.810260435675, 3449.862005519776, 124653.37673311576, 125358.0, 126058.0, 127319.72680273264, 127793.72206277911, 9363.672271470603, 11457.14071444645, 11849.170558060852, 12037.458314375874, 12172.0, 19296.80703192978, 19307.90346048261, 19710.014499274977, 20392.490187745425, 24737.0, 7693.615319234016, 7855.214478552099, 7871.724489642835, 7874.803129921798, 7883.84232315362, 3904.1439330377957, 3994.4407782910416, 4003.819828107742, 4008.5390180129075, 4016.879493615196, 4283.957160428418, 4368.104538569585, 4368.213721530092, 4369.453818272716, 4376.474823021258, 849.3969281809984, 899.67161985876, 903.7108125399941, 904.0597778310483, 908.0329449136648, 609.5184804004875, 634.6445990245477, 650.778735230024, 654.2508827392704, 663.4128796015465, 10920.235583509237, 11504.482298296596, 11764.352960587079, 11800.232984855957, 11855.110866685032, 644.7453255963868, 660.197859600578, 660.4419265720459, 673.6328700858044, 683.402023229674, 4331.306990881444, 5225.242339860699, 5370.9731451342495, 5501.587380946445, 5627.380988091307, 103658.0, 104523.0, 105634.9436505625, 106140.0, 107818.46090769653, 91591.54204228937, 92748.53625731828, 93564.0, 94142.52928735313, 94272.0, 81148.18851811525, 81359.5932020336, 81477.59261203774, 81988.0, 83112.0, 8932.955335223282, 10565.20760942917, 11154.665360039211, 11294.60468883585, 11407.144464165054, 38656.84848303073, 40931.795341023106, 41373.37939930963, 41487.792561037, 41686.95782605459, 53877.0, 54520.72739636277, 54858.72570637121, 54975.0, 55472.167917482075, 14273.500427484989, 16436.178191090636, 17084.23120959535, 17113.91443042777, 17118.48644540666, 17817.287308507515, 17902.91048544749, 18444.446666600023, 18498.630027399107, 18757.71863422077, 16040.839591604401, 16134.435294764628, 16830.915845420695, 17011.659766804347, 17039.659206815548, 16286.429974951294, 16815.495535133967, 17023.744643830105, 17074.914625426794, 17145.485635430956, 15768.526944191693, 15787.605309867346, 16215.837841621433, 16660.16699165061, 16849.663006739553, 11565.132615054074, 11607.6517704469, 12185.634430967086, 12224.327661978676, 12288.87711122913, 12024.098192635762, 12695.0, 12712.0, 12729.363531823186, 13013.284269364896], "value": 8242.831966581536, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1412.2999999999997, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 108.0, 109.0, 109.0, 108.0, 111.0, 110.0, 110.0, 110.0, 108.0], "value": 109.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 109.0, 108.0, 171.0, 139.0, 112.0, 109.0, 105.0, 136.0, 108.0], "value": 123.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 182.0, 184.0, 187.0, 186.0, 183.0, 185.0, 181.0, 188.0, 179.0], "value": 184.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 60.0, 57.0, 89.0, 61.0, 60.0, 81.0, 66.0, 77.0, 59.0], "value": 69.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 71.0, 84.0, 68.0, 68.0, 67.0, 89.0, 68.0, 67.0, 95.0], "value": 76.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 94.0, 87.0, 86.0, 87.0, 87.0, 86.0, 88.0, 88.0, 87.0], "value": 87.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 95.0, 95.0, 96.0, 95.0, 96.0, 93.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 85.0, 85.0, 85.0, 83.0, 84.0, 86.0, 85.0, 86.0, 85.0], "value": 84.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 47.0, 45.0, 48.0, 48.0, 48.0, 49.0, 49.0, 48.0, 50.0], "value": 48.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 59.0, 53.0, 59.0, 64.0, 58.0, 57.0, 56.0, 55.0, 57.0], "value": 56.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 82.0, 82.0, 82.0, 82.0, 81.0, 81.0, 81.0, 80.0, 81.0], "value": 81.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 151.0, 137.0, 136.0, 144.0, 136.0, 136.0, 139.0, 192.0, 153.0], "value": 146.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 187.0, 183.0, 186.0, 186.0, 177.0, 178.0, 181.0, 184.0, 179.0], "value": 182.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 67.0, 67.0, 68.0, 68.0, 67.0, 67.0, 67.0, 66.0], "value": 67.1, "unit": "score"}]}]} 23:08:41 INFO - Return code: 0 23:08:41 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 23:08:41 ERROR - # TBPL SUCCESS # 23:08:41 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:08:41 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:08:41 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:08:41 INFO - 2017-04-26 23:08:41.133 screenresolution[1651:9878] starting screenresolution argv=screenresolution get 23:08:41 INFO - 2017-04-26 23:08:41.137 screenresolution[1651:9878] Display 0: 1600x1200x32@60 23:08:41 INFO - 2017-04-26 23:08:41.143 screenresolution[1652:9881] starting screenresolution argv=screenresolution list 23:08:41 INFO - Available Modes on Display 0 23:08:41 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:08:41 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:08:41 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:08:41 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:08:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:08:41 INFO - Intel Iris: 23:08:41 INFO - Chipset Model: Intel Iris 23:08:41 INFO - Type: GPU 23:08:41 INFO - Bus: Built-In 23:08:41 INFO - VRAM (Dynamic, Max): 1536 MB 23:08:41 INFO - Vendor: Intel (0x8086) 23:08:41 INFO - Device ID: 0x0a2e 23:08:41 INFO - Revision ID: 0x0009 23:08:41 INFO - Displays: 23:08:41 INFO - Display: 23:08:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:08:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:08:41 INFO - Display Serial Number: 1600x1200 60 23:08:41 INFO - Main Display: Yes 23:08:41 INFO - Mirror: Off 23:08:41 INFO - Online: Yes 23:08:41 INFO - Rotation: Supported 23:08:41 INFO - Return code: 0 23:08:41 INFO - Running post-action listener: _resource_record_post_action 23:08:41 INFO - [mozharness: 2017-04-27 06:08:41.392561Z] Finished run-tests step (success) 23:08:41 INFO - Running post-run listener: _resource_record_post_run 23:08:41 INFO - Total resource usage - Wall time: 1199s; CPU: 54.0%; Read bytes: 38846464; Write bytes: 510044160; Read time: 403; Write time: 1654 23:08:41 INFO - TinderboxPrint: CPU usage
53.5% 23:08:41 INFO - TinderboxPrint: I/O read bytes / time
38,846,464 / 403 23:08:41 INFO - TinderboxPrint: I/O write bytes / time
510,044,160 / 1,654 23:08:41 INFO - TinderboxPrint: CPU idle
2,228.6 (46.5%) 23:08:41 INFO - TinderboxPrint: CPU system
957.7 (20.0%) 23:08:41 INFO - TinderboxPrint: CPU user
1,611.5 (33.6%) 23:08:41 INFO - TinderboxPrint: Swap in / out
247,803,904 / 0 23:08:41 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 209676800; Write bytes: 209946624; Read time: 16188; Write time: 452 23:08:41 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 33738752; Write bytes: 298106880; Read time: 339; Write time: 1184 23:08:41 INFO - Running post-run listener: _upload_blobber_files 23:08:41 INFO - Blob upload gear active. 23:08:41 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:08:41 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 23:08:41 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'] 23:08:41 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 23:08:42 INFO - (blobuploader) - INFO - Open directory for files ... 23:08:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 23:08:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:08:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:08:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:08:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:08:44 INFO - (blobuploader) - INFO - Done attempting. 23:08:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 23:08:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:08:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:08:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:08:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:08:45 INFO - (blobuploader) - INFO - Done attempting. 23:08:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 23:08:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:08:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:08:47 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 23:08:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:08:47 INFO - (blobuploader) - INFO - Done attempting. 23:08:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:08:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:08:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:08:49 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:08:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:08:49 INFO - (blobuploader) - INFO - Done attempting. 23:08:49 INFO - (blobuploader) - INFO - Iteration through files over. 23:08:49 INFO - Return code: 0 23:08:49 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:08:49 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:08:49 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/62d467eeef46620349bfc5227783aab10ae921d069e8ac44f861c61df657f82e714e62d37c133881828255799e1712e9b10f9face25bc718ea140660e10741a4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/edc973ee1739c4ea60f404bb37ca89c3ebc27eb1cb74a4ef5835781a14632c0514dca4317209b689da40f08afeb5913514132ad17548a3897bb2a58ff828b9b7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/69cc9015caf2be476c73b29827dc67e2803d0cd1190bfb0e087f5fabc06aa74ee3a4e417b9f5fb6acffc802d647b117905dd0c5783e8a16c15cae5a6ac08850a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/edc55da3e52fb0ba1004839c53440dc12ed12763f2a151092c2119758396bdca887fc15f769d2169de598c9f98d033463e6f348cf2c2c9dd2b507e4d416e20d5"} 23:08:49 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:08:49 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:08:49 INFO - Contents: 23:08:49 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/62d467eeef46620349bfc5227783aab10ae921d069e8ac44f861c61df657f82e714e62d37c133881828255799e1712e9b10f9face25bc718ea140660e10741a4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/edc973ee1739c4ea60f404bb37ca89c3ebc27eb1cb74a4ef5835781a14632c0514dca4317209b689da40f08afeb5913514132ad17548a3897bb2a58ff828b9b7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/69cc9015caf2be476c73b29827dc67e2803d0cd1190bfb0e087f5fabc06aa74ee3a4e417b9f5fb6acffc802d647b117905dd0c5783e8a16c15cae5a6ac08850a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/edc55da3e52fb0ba1004839c53440dc12ed12763f2a151092c2119758396bdca887fc15f769d2169de598c9f98d033463e6f348cf2c2c9dd2b507e4d416e20d5"} 23:08:49 INFO - Running post-run listener: copy_logs_to_upload_dir 23:08:49 INFO - Copying logs to upload dir... 23:08:49 INFO - mkdir: /builds/slave/test/build/upload/logs 23:08:49 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1284.487761 ========= master_lag: 0.15 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2017-04-26 23:08:49.261905) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-26 23:08:49.265176) ========= 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.4xVuKgeWXB/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.7T9L0PGXHs/Listeners TMPDIR=/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/62d467eeef46620349bfc5227783aab10ae921d069e8ac44f861c61df657f82e714e62d37c133881828255799e1712e9b10f9face25bc718ea140660e10741a4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/edc973ee1739c4ea60f404bb37ca89c3ebc27eb1cb74a4ef5835781a14632c0514dca4317209b689da40f08afeb5913514132ad17548a3897bb2a58ff828b9b7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/69cc9015caf2be476c73b29827dc67e2803d0cd1190bfb0e087f5fabc06aa74ee3a4e417b9f5fb6acffc802d647b117905dd0c5783e8a16c15cae5a6ac08850a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/edc55da3e52fb0ba1004839c53440dc12ed12763f2a151092c2119758396bdca887fc15f769d2169de598c9f98d033463e6f348cf2c2c9dd2b507e4d416e20d5"} build_url:https://queue.taskcluster.net/v1/task/e4rBHDOHTvuWDsOSvKffsw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011144 build_url: 'https://queue.taskcluster.net/v1/task/e4rBHDOHTvuWDsOSvKffsw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/62d467eeef46620349bfc5227783aab10ae921d069e8ac44f861c61df657f82e714e62d37c133881828255799e1712e9b10f9face25bc718ea140660e10741a4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/edc973ee1739c4ea60f404bb37ca89c3ebc27eb1cb74a4ef5835781a14632c0514dca4317209b689da40f08afeb5913514132ad17548a3897bb2a58ff828b9b7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/69cc9015caf2be476c73b29827dc67e2803d0cd1190bfb0e087f5fabc06aa74ee3a4e417b9f5fb6acffc802d647b117905dd0c5783e8a16c15cae5a6ac08850a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/edc55da3e52fb0ba1004839c53440dc12ed12763f2a151092c2119758396bdca887fc15f769d2169de598c9f98d033463e6f348cf2c2c9dd2b507e4d416e20d5"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-26 23:08:49.300892) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-26 23:08:49.301331) ========= 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.4xVuKgeWXB/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.7T9L0PGXHs/Listeners TMPDIR=/var/folders/6d/dwmd8vqs4mj6zk_cht8x51b800000w/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.005039 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-26 23:08:49.353815) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-26 23:08:49.354165) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-26 23:08:49.354549) ========= ========= Total master_lag: 0.30 =========