builder: pine_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0176 starttime: 1493675288.2 results: success (0) buildid: 20170501111719 builduid: 7cf6c96fe3994190bcfc5c047fd50b33 revision: a7501c0dd13e1163ff65a83d30143303101ca313 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-01 14:48:08.202933) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-01 14:48:08.203351) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-01 14:48:08.203619) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-01 14:48:08.232611) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-01 14:48:08.232926) ========= 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.HRXgfRXAjp/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.vbyL1zMO4g/Listeners TMPDIR=/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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-05-01 14:48:08-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 155M=0s 2017-05-01 14:48:08 (155 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.081575 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-01 14:48:08.339084) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-01 14:48:08.339389) ========= 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.HRXgfRXAjp/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.vbyL1zMO4g/Listeners TMPDIR=/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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.022275 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-01 14:48:08.387345) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-01 14:48:08.387668) ========= bash -c 'python archiver_client.py mozharness --repo projects/pine --rev a7501c0dd13e1163ff65a83d30143303101ca313 --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 a7501c0dd13e1163ff65a83d30143303101ca313 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.HRXgfRXAjp/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.vbyL1zMO4g/Listeners TMPDIR=/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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-05-01 14:48:08,464 truncating revision to first 12 chars 2017-05-01 14:48:08,464 Setting DEBUG logging. 2017-05-01 14:48:08,464 attempt 1/10 2017-05-01 14:48:08,464 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/pine/a7501c0dd13e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-01 14:48:09,533 unpacking tar archive at: pine-a7501c0dd13e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.316636 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-01 14:48:09.732177) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-01 14:48:09.732978) ========= script_repo_revision: a7501c0dd13e1163ff65a83d30143303101ca313 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-01 14:48:09.733976) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-01 14:48:09.734670) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-01 14:48:09.756594) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 23 secs) (at 2017-05-01 14:48:09.757307) ========= /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.HRXgfRXAjp/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.vbyL1zMO4g/Listeners TMPDIR=/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 14:48:09 INFO - MultiFileLogger online at 20170501 14:48:09 in /builds/slave/test 14:48:09 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 14:48:09 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:48:09 INFO - {'append_to_log': False, 14:48:09 INFO - 'base_work_dir': '/builds/slave/test', 14:48:09 INFO - 'blob_upload_branch': 'Pine', 14:48:09 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:48:09 INFO - 'branch': 'Pine', 14:48:09 INFO - 'buildbot_json_path': 'buildprops.json', 14:48:09 INFO - 'config_files': ('talos/mac_config.py',), 14:48:09 INFO - 'default_actions': ('clobber', 14:48:09 INFO - 'read-buildbot-config', 14:48:09 INFO - 'download-and-extract', 14:48:09 INFO - 'populate-webroot', 14:48:09 INFO - 'create-virtualenv', 14:48:09 INFO - 'install', 14:48:09 INFO - 'run-tests'), 14:48:09 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:48:09 INFO - 'download_minidump_stackwalk': True, 14:48:09 INFO - 'download_symbols': 'ondemand', 14:48:09 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:48:09 INFO - 'tooltool.py': '/tools/tooltool.py', 14:48:09 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:48:09 INFO - '/tools/misc-python/virtualenv.py')}, 14:48:09 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:48:09 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:48:09 INFO - 'gecko_profile': False, 14:48:09 INFO - 'gecko_profile_interval': 0, 14:48:09 INFO - 'installer_path': 'installer.exe', 14:48:09 INFO - 'log_level': 'info', 14:48:09 INFO - 'log_name': 'talos', 14:48:09 INFO - 'log_to_console': True, 14:48:09 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 14:48:09 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 14:48:09 INFO - 'opt_config_files': (), 14:48:09 INFO - 'pip_index': False, 14:48:09 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:48:09 INFO - 'cmd': ('bash', 14:48:09 INFO - '-c', 14:48:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:48:09 INFO - 'enabled': True, 14:48:09 INFO - 'halt_on_failure': False, 14:48:09 INFO - 'name': 'check_screen_resolution'},), 14:48:09 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:48:09 INFO - 'cmd': ('bash', 14:48:09 INFO - '-c', 14:48:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:48:09 INFO - 'enabled': True, 14:48:09 INFO - 'halt_on_failure': False, 14:48:09 INFO - 'name': 'check_screen_resolution'},), 14:48:09 INFO - 'run_cmd_checks_enabled': True, 14:48:09 INFO - 'suite': 'dromaeojs', 14:48:09 INFO - 'system_bits': '32', 14:48:09 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:48:09 INFO - 'title': 't-yosemite-r7-0176', 14:48:09 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:48:09 INFO - 'use_talos_json': True, 14:48:09 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:48:09 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:48:09 INFO - 'work_dir': 'build'} 14:48:09 INFO - [mozharness: 2017-05-01 21:48:09.959278Z] Running clobber step. 14:48:09 INFO - Running pre-action listener: _resource_record_pre_action 14:48:09 INFO - Running main action method: clobber 14:48:09 INFO - rmtree: /builds/slave/test/build 14:48:09 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:48:12 INFO - Running post-action listener: _resource_record_post_action 14:48:12 INFO - [mozharness: 2017-05-01 21:48:12.203272Z] Finished clobber step (success) 14:48:12 INFO - [mozharness: 2017-05-01 21:48:12.203498Z] Running read-buildbot-config step. 14:48:12 INFO - Running pre-action listener: _resource_record_pre_action 14:48:12 INFO - Running main action method: read_buildbot_config 14:48:12 INFO - Using buildbot properties: 14:48:12 INFO - { 14:48:12 INFO - "project": "", 14:48:12 INFO - "product": "firefox", 14:48:12 INFO - "script_repo_revision": "default", 14:48:12 INFO - "builddir": "pine_yosemite_r7_test-dromaeojs", 14:48:12 INFO - "repository": "", 14:48:12 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 pine talos dromaeojs", 14:48:12 INFO - "buildid": "20170501111719", 14:48:12 INFO - "pgo_build": "False", 14:48:12 INFO - "basedir": "/builds/slave/test", 14:48:12 INFO - "buildnumber": 0, 14:48:12 INFO - "slavename": "t-yosemite-r7-0176", 14:48:12 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 14:48:12 INFO - "platform": "yosemite_r7", 14:48:12 INFO - "scheduler": "tests-pine-macosx64-talos", 14:48:12 INFO - "branch": "pine", 14:48:12 INFO - "repo_path": "projects/pine", 14:48:12 INFO - "revision": "a7501c0dd13e1163ff65a83d30143303101ca313", 14:48:12 INFO - "stage_platform": "macosx64", 14:48:12 INFO - "builduid": "7cf6c96fe3994190bcfc5c047fd50b33", 14:48:12 INFO - "slavebuilddir": "test" 14:48:12 INFO - } 14:48:12 INFO - Found installer url https://queue.taskcluster.net/v1/task/ECfBHkqaSWanuC2p3cKhfA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 14:48:12 INFO - Running post-action listener: _resource_record_post_action 14:48:12 INFO - [mozharness: 2017-05-01 21:48:12.209134Z] Finished read-buildbot-config step (success) 14:48:12 INFO - [mozharness: 2017-05-01 21:48:12.209356Z] Running download-and-extract step. 14:48:12 INFO - Running pre-action listener: _resource_record_pre_action 14:48:12 INFO - Running main action method: download_and_extract 14:48:12 INFO - mkdir: /builds/slave/test/build/tests 14: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')]} 14:48:12 INFO - trying https://queue.taskcluster.net/v1/task/ECfBHkqaSWanuC2p3cKhfA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 14:48:12 INFO - Downloading https://queue.taskcluster.net/v1/task/ECfBHkqaSWanuC2p3cKhfA/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 14:48:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ECfBHkqaSWanuC2p3cKhfA/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 14:48:13 INFO - Downloaded 1325 bytes. 14:48:13 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 14:48:13 INFO - Using the following test package requirements: 14:48:13 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:48:13 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 14:48:13 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 14:48:13 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:48:13 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 14:48:13 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:48:13 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 14:48:13 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:48:13 INFO - u'jsshell-mac.zip'], 14:48:13 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:48:13 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 14:48:13 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 14:48:13 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:48:13 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 14:48:13 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:48:13 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 14:48:13 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:48:13 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 14:48:13 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:48:13 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 14:48:13 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 14:48:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ECfBHkqaSWanuC2p3cKhfA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 14:48:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ECfBHkqaSWanuC2p3cKhfA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:48:13 INFO - Fetch https://queue.taskcluster.net/v1/task/ECfBHkqaSWanuC2p3cKhfA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 14:48:15 INFO - Content-Length response header: 52701577 14:48:15 INFO - Bytes received: 52701577 14:48:31 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 14:48:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ECfBHkqaSWanuC2p3cKhfA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 14:48:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ECfBHkqaSWanuC2p3cKhfA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:48:31 INFO - Fetch https://queue.taskcluster.net/v1/task/ECfBHkqaSWanuC2p3cKhfA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 14:48:33 INFO - Content-Length response header: 52701577 14:48:33 INFO - Bytes received: 52701577 14:48:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ECfBHkqaSWanuC2p3cKhfA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 14:48:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ECfBHkqaSWanuC2p3cKhfA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 14:48:49 INFO - Fetch https://queue.taskcluster.net/v1/task/ECfBHkqaSWanuC2p3cKhfA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 14:48:50 INFO - Content-Length response header: 14408811 14:48:50 INFO - Bytes received: 14408811 14:48:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:48:50 INFO - trying https://queue.taskcluster.net/v1/task/ECfBHkqaSWanuC2p3cKhfA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 14:48:50 INFO - Downloading https://queue.taskcluster.net/v1/task/ECfBHkqaSWanuC2p3cKhfA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 14:48:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ECfBHkqaSWanuC2p3cKhfA/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 14:48:52 INFO - Downloaded 71263866 bytes. 14:48:52 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ECfBHkqaSWanuC2p3cKhfA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 14:48:52 INFO - mkdir: /builds/slave/test/properties 14:48:52 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:48:52 INFO - Writing to file /builds/slave/test/properties/build_url 14:48:52 INFO - Contents: 14:48:52 INFO - build_url:https://queue.taskcluster.net/v1/task/ECfBHkqaSWanuC2p3cKhfA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 14:48:53 INFO - Running post-action listener: _resource_record_post_action 14:48:53 INFO - Running post-action listener: set_extra_try_arguments 14:48:53 INFO - [mozharness: 2017-05-01 21:48:53.530685Z] Finished download-and-extract step (success) 14:48:53 INFO - [mozharness: 2017-05-01 21:48:53.530817Z] Running populate-webroot step. 14:48:53 INFO - Running pre-action listener: _resource_record_pre_action 14:48:53 INFO - Running main action method: populate_webroot 14:48:53 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:48:53 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:48:53 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:48:53 INFO - '--tppagecycles', 14:48:53 INFO - '7'], 14:48:53 INFO - 'tests': ['tsvgm']}}, 14:48:53 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:48:53 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:48:53 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:48:53 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:48:53 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:48:53 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:48:53 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 14:48:53 INFO - 'talos_options': ['--disable-e10s'], 14:48:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:48:53 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 14:48:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:48:53 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 14:48:53 INFO - 'talos_options': ['--disable-e10s'], 14:48:53 INFO - 'tests': ['damp', 'tps']}, 14:48:53 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 14:48:53 INFO - 'tests': ['damp', 'tps']}, 14:48:53 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:48:53 INFO - 'tests': ['dromaeo_dom']}, 14:48:53 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:48:53 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:48:53 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 14:48:53 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 14:48:53 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:48:53 INFO - 'tests': ['a11yr', 14:48:53 INFO - 'ts_paint', 14:48:53 INFO - 'tpaint', 14:48:53 INFO - 'sessionrestore', 14:48:53 INFO - 'sessionrestore_no_auto_restore', 14:48:53 INFO - 'tabpaint']}, 14:48:53 INFO - 'other-e10s': {'tests': ['a11yr', 14:48:53 INFO - 'ts_paint', 14:48:53 INFO - 'tpaint', 14:48:53 INFO - 'sessionrestore', 14:48:53 INFO - 'sessionrestore_no_auto_restore', 14:48:53 INFO - 'tabpaint']}, 14:48:53 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:48:53 INFO - 'ts_paint', 14:48:53 INFO - 'tpaint', 14:48:53 INFO - 'sessionrestore', 14:48:53 INFO - 'sessionrestore_no_auto_restore', 14:48:53 INFO - 'tabpaint']}, 14:48:53 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:48:53 INFO - 'ts_paint', 14:48:53 INFO - 'tpaint', 14:48:53 INFO - 'sessionrestore', 14:48:53 INFO - 'sessionrestore_no_auto_restore', 14:48:53 INFO - 'tabpaint']}, 14:48:53 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:48:53 INFO - 'tests': ['a11yr', 14:48:53 INFO - 'ts_paint', 14:48:53 INFO - 'tpaint', 14:48:53 INFO - 'sessionrestore', 14:48:53 INFO - 'sessionrestore_no_auto_restore', 14:48:53 INFO - 'tabpaint']}, 14:48:53 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:48:53 INFO - 'tests': ['a11yr', 14:48:53 INFO - 'ts_paint', 14:48:53 INFO - 'tpaint', 14:48:53 INFO - 'sessionrestore', 14:48:53 INFO - 'sessionrestore_no_auto_restore', 14:48:53 INFO - 'tabpaint']}, 14:48:53 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 14:48:53 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 14:48:53 INFO - 'bloom_basic_ref']}, 14:48:53 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:48:53 INFO - 'tests': ['tsvgx', 14:48:53 INFO - 'tsvgr_opacity', 14:48:53 INFO - 'tart', 14:48:53 INFO - 'tscrollx', 14:48:53 INFO - 'cart', 14:48:53 INFO - 'tsvg_static']}, 14:48:53 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:48:53 INFO - 'tsvgr_opacity', 14:48:53 INFO - 'tart', 14:48:53 INFO - 'tscrollx', 14:48:53 INFO - 'cart', 14:48:53 INFO - 'tsvg_static']}, 14:48:53 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 14:48:53 INFO - 'talos_options': ['--disable-e10s'], 14:48:53 INFO - 'tests': ['tp5o']}, 14:48:53 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 14:48:53 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 14:48:53 INFO - 'talos_options': ['--disable-e10s', 14:48:53 INFO - '--xperf_path', 14:48:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:48:53 INFO - 'tests': ['tp5n']}, 14:48:53 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 14:48:53 INFO - 'talos_options': ['--xperf_path', 14:48:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:48:53 INFO - 'tests': ['tp5n']}}, 14:48:53 INFO - 'talos.zip': {'path': '', 14:48:53 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:48:53 INFO - Running post-action listener: _resource_record_post_action 14:48:53 INFO - [mozharness: 2017-05-01 21:48:53.549549Z] Finished populate-webroot step (success) 14:48:53 INFO - [mozharness: 2017-05-01 21:48:53.549640Z] Running create-virtualenv step. 14:48:53 INFO - Running pre-action listener: _resource_record_pre_action 14:48:53 INFO - Running main action method: create_virtualenv 14:48:53 INFO - Creating virtualenv /builds/slave/test/build/venv 14:48:53 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 14:48:53 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:48:53 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:48:53 INFO - Using real prefix '/tools/python27' 14:48:53 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:48:54 INFO - Installing distribute.............................................................................................................................................................................................done. 14:48:57 INFO - Installing pip.................done. 14:48:57 INFO - Return code: 0 14:48:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 14:48:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 14:48:57 INFO - Reading from file tmpfile_stdout 14:48:57 INFO - Output received: 14:48:57 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 14:48:57 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:48:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:48:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:48:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:48:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:48:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:48:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:48:57 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 0x103e5fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e62768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb82a467510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c3dd30>, '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 0x103b63db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103c32be8>, '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.HRXgfRXAjp/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.vbyL1zMO4g/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:48:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 14:48:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 14:48:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HRXgfRXAjp/Render', 14:48:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:48:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:48:57 INFO - 'HOME': '/Users/cltbld', 14:48:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:48:57 INFO - 'LOGNAME': 'cltbld', 14:48:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:48:57 INFO - 'MOZ_NO_REMOTE': '1', 14:48:57 INFO - 'NO_EM_RESTART': '1', 14:48:57 INFO - 'PAGER': '/bin/cat', 14:48:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:48:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:48:57 INFO - 'PWD': '/builds/slave/test', 14:48:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:48:57 INFO - 'SHELL': '/bin/bash', 14:48:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vbyL1zMO4g/Listeners', 14:48:57 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 14:48:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:48:57 INFO - 'USER': 'cltbld', 14:48:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:48:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:48:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:48:57 INFO - 'XPC_FLAGS': '0x0', 14:48:57 INFO - 'XPC_SERVICE_NAME': '0', 14:48:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:48:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:48:57 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 14:48:57 INFO - Cleaning up... 14:48:57 INFO - Return code: 0 14:48:57 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:48:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:48:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:48:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:48:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:48:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:48:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:48:57 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 0x103e5fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e62768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb82a467510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c3dd30>, '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 0x103b63db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103c32be8>, '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.HRXgfRXAjp/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.vbyL1zMO4g/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:48:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 14:48:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 14:48:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HRXgfRXAjp/Render', 14:48:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:48:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:48:57 INFO - 'HOME': '/Users/cltbld', 14:48:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:48:57 INFO - 'LOGNAME': 'cltbld', 14:48:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:48:57 INFO - 'MOZ_NO_REMOTE': '1', 14:48:57 INFO - 'NO_EM_RESTART': '1', 14:48:57 INFO - 'PAGER': '/bin/cat', 14:48:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:48:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:48:57 INFO - 'PWD': '/builds/slave/test', 14:48:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:48:57 INFO - 'SHELL': '/bin/bash', 14:48:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vbyL1zMO4g/Listeners', 14:48:57 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 14:48:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:48:57 INFO - 'USER': 'cltbld', 14:48:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:48:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:48:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:48:57 INFO - 'XPC_FLAGS': '0x0', 14:48:57 INFO - 'XPC_SERVICE_NAME': '0', 14:48:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:48:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:48:58 INFO - Downloading/unpacking psutil>=3.1.1 14:48:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:48:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:48:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:48:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:48:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:48:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:49:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:49:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:49:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:49:01 INFO - Installing collected packages: psutil 14:49:01 INFO - Running setup.py install for psutil 14:49:01 INFO - building 'psutil._psutil_osx' extension 14:49:01 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 14:49:01 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 14:49:01 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 14:49:02 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 14:49:02 INFO - building 'psutil._psutil_posix' extension 14:49:02 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 14:49:02 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 14:49:02 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 14:49:02 INFO - ^ 14:49:02 INFO - 1 warning generated. 14:49:02 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 14:49:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:49:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:49:02 INFO - Successfully installed psutil 14:49:02 INFO - Cleaning up... 14:49:02 INFO - Return code: 0 14:49:02 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14: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')]} 14:49:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:49:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14: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')]} 14:49:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:49:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:49:02 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 0x103e5fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e62768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb82a467510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c3dd30>, '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 0x103b63db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103c32be8>, '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.HRXgfRXAjp/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.vbyL1zMO4g/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:49:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 14:49:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 14:49:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HRXgfRXAjp/Render', 14:49:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:49:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:49:02 INFO - 'HOME': '/Users/cltbld', 14:49:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:49:02 INFO - 'LOGNAME': 'cltbld', 14:49:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:49:02 INFO - 'MOZ_NO_REMOTE': '1', 14:49:02 INFO - 'NO_EM_RESTART': '1', 14:49:02 INFO - 'PAGER': '/bin/cat', 14:49:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:49:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:49:02 INFO - 'PWD': '/builds/slave/test', 14:49:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:49:02 INFO - 'SHELL': '/bin/bash', 14:49:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vbyL1zMO4g/Listeners', 14:49:02 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 14:49:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:49:02 INFO - 'USER': 'cltbld', 14:49:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:49:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:49:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:49:02 INFO - 'XPC_FLAGS': '0x0', 14:49:02 INFO - 'XPC_SERVICE_NAME': '0', 14:49:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:49:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:49:02 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:49:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:49:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:49:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:49:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:49:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:49:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:49:05 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:49:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:49:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:49:05 INFO - Installing collected packages: mozsystemmonitor 14:49:05 INFO - Running setup.py install for mozsystemmonitor 14:49:05 INFO - Successfully installed mozsystemmonitor 14:49:05 INFO - Cleaning up... 14:49:05 INFO - Return code: 0 14:49:05 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:49: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')]} 14:49:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:49:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:49: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')]} 14:49:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:49:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:49:06 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 0x103e5fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e62768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb82a467510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c3dd30>, '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 0x103b63db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103c32be8>, '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.HRXgfRXAjp/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.vbyL1zMO4g/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:49:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 14:49:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 14:49:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HRXgfRXAjp/Render', 14:49:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:49:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:49:06 INFO - 'HOME': '/Users/cltbld', 14:49:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:49:06 INFO - 'LOGNAME': 'cltbld', 14:49:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:49:06 INFO - 'MOZ_NO_REMOTE': '1', 14:49:06 INFO - 'NO_EM_RESTART': '1', 14:49:06 INFO - 'PAGER': '/bin/cat', 14:49:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:49:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:49:06 INFO - 'PWD': '/builds/slave/test', 14:49:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:49:06 INFO - 'SHELL': '/bin/bash', 14:49:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vbyL1zMO4g/Listeners', 14:49:06 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 14:49:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:49:06 INFO - 'USER': 'cltbld', 14:49:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:49:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:49:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:49:06 INFO - 'XPC_FLAGS': '0x0', 14:49:06 INFO - 'XPC_SERVICE_NAME': '0', 14:49:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:49:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:49:06 INFO - Downloading/unpacking jsonschema==2.5.1 14:49:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:49:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:49:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:49:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:49:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:49:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:49:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:49:09 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:49:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:49:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:49:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:49:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:49:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:49:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:49:09 INFO - Downloading functools32-3.2.3-2.tar.gz 14:49:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:49:09 INFO - warning: no files found matching '*.txt' 14:49:09 INFO - no previously-included directories found matching 'build' 14:49:09 INFO - no previously-included directories found matching 'dist' 14:49:09 INFO - no previously-included directories found matching '.git*' 14:49:09 INFO - Installing collected packages: jsonschema, functools32 14:49:09 INFO - Running setup.py install for functools32 14:49:09 INFO - warning: no files found matching '*.txt' 14:49:09 INFO - no previously-included directories found matching 'build' 14:49:09 INFO - no previously-included directories found matching 'dist' 14:49:09 INFO - no previously-included directories found matching '.git*' 14:49:09 INFO - Successfully installed jsonschema functools32 14:49:09 INFO - Cleaning up... 14:49:09 INFO - Return code: 0 14:49:09 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:49:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:49:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:49:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:49:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:49:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:49:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:49:09 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 0x103e5fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e62768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb82a467510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c3dd30>, '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 0x103b63db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103c32be8>, '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.HRXgfRXAjp/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.vbyL1zMO4g/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:49:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 14:49:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 14:49:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HRXgfRXAjp/Render', 14:49:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:49:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:49:09 INFO - 'HOME': '/Users/cltbld', 14:49:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:49:09 INFO - 'LOGNAME': 'cltbld', 14:49:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:49:09 INFO - 'MOZ_NO_REMOTE': '1', 14:49:09 INFO - 'NO_EM_RESTART': '1', 14:49:09 INFO - 'PAGER': '/bin/cat', 14:49:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:49:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:49:09 INFO - 'PWD': '/builds/slave/test', 14:49:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:49:09 INFO - 'SHELL': '/bin/bash', 14:49:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vbyL1zMO4g/Listeners', 14:49:09 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 14:49:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:49:09 INFO - 'USER': 'cltbld', 14:49:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:49:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:49:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:49:09 INFO - 'XPC_FLAGS': '0x0', 14:49:09 INFO - 'XPC_SERVICE_NAME': '0', 14:49:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:49:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:49:10 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:49:10 INFO - Cleaning up... 14:49:10 INFO - Return code: 0 14:49:10 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:49:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:49:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:49:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:49:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:49:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:49:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:49:10 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 0x103e5fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e62768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb82a467510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c3dd30>, '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 0x103b63db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103c32be8>, '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.HRXgfRXAjp/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.vbyL1zMO4g/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:49:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 14:49:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 14:49:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HRXgfRXAjp/Render', 14:49:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:49:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:49:10 INFO - 'HOME': '/Users/cltbld', 14:49:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:49:10 INFO - 'LOGNAME': 'cltbld', 14:49:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:49:10 INFO - 'MOZ_NO_REMOTE': '1', 14:49:10 INFO - 'NO_EM_RESTART': '1', 14:49:10 INFO - 'PAGER': '/bin/cat', 14:49:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:49:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:49:10 INFO - 'PWD': '/builds/slave/test', 14:49:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:49:10 INFO - 'SHELL': '/bin/bash', 14:49:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vbyL1zMO4g/Listeners', 14:49:10 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 14:49:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:49:10 INFO - 'USER': 'cltbld', 14:49:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:49:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:49:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:49:10 INFO - 'XPC_FLAGS': '0x0', 14:49:10 INFO - 'XPC_SERVICE_NAME': '0', 14:49:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:49:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:49:10 INFO - Downloading/unpacking blobuploader==1.2.4 14:49:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:49:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:49:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:49:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:49:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:49:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:49:13 INFO - Downloading blobuploader-1.2.4.tar.gz 14:49:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:49:13 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:49: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 14:49: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 14:49: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 14:49: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 14:49: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 14:49: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 14:49:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:49:13 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:49: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 14:49: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 14:49: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 14:49: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 14:49: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 14:49: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 14:49:14 INFO - Downloading docopt-0.6.1.tar.gz 14:49:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:49:14 INFO - Installing collected packages: blobuploader, requests, docopt 14:49:14 INFO - Running setup.py install for blobuploader 14:49:14 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:49:14 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:49:14 INFO - Running setup.py install for requests 14:49:14 INFO - Running setup.py install for docopt 14:49:15 INFO - Successfully installed blobuploader requests docopt 14:49:15 INFO - Cleaning up... 14:49:15 INFO - Return code: 0 14:49:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:49:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:49:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:49:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:49:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:49:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:49:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:49:15 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 0x103e5fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e62768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb82a467510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c3dd30>, '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 0x103b63db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103c32be8>, '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.HRXgfRXAjp/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.vbyL1zMO4g/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:49:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 14:49:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:49:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HRXgfRXAjp/Render', 14:49:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:49:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:49:15 INFO - 'HOME': '/Users/cltbld', 14:49:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:49:15 INFO - 'LOGNAME': 'cltbld', 14:49:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:49:15 INFO - 'MOZ_NO_REMOTE': '1', 14:49:15 INFO - 'NO_EM_RESTART': '1', 14:49:15 INFO - 'PAGER': '/bin/cat', 14:49:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:49:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:49:15 INFO - 'PWD': '/builds/slave/test', 14:49:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:49:15 INFO - 'SHELL': '/bin/bash', 14:49:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vbyL1zMO4g/Listeners', 14:49:15 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 14:49:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:49:15 INFO - 'USER': 'cltbld', 14:49:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:49:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:49:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:49:15 INFO - 'XPC_FLAGS': '0x0', 14:49:15 INFO - 'XPC_SERVICE_NAME': '0', 14:49:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:49:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:49:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:49:15 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-76FdbI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:49:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:49:15 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-P2F6Nn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:49:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:49:15 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-t5zFVQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:49:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:49:15 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-Ark7vb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:49:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:49:15 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-MW6WnB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:49:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:49:15 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-d69dMo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:49:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:49:16 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-_lzlp6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:49:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:49:16 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-iL4Sxg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:49:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:49:16 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-n4eGHm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:49:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:49:16 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-TE2b_M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:49:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:49:16 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-2ENJ6C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:49:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:49:16 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-iyerRb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:49:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:49:16 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-EwbolV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:49:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:49:16 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-SsKRev-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:49:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:49:16 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-Hul4tH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:49:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:49:17 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-HLIhB2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:49:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:49:17 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-PxXkus-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:49:17 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:49:17 INFO - Running setup.py install for manifestparser 14:49:17 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:49:17 INFO - Running setup.py install for mozcrash 14:49:17 INFO - Running setup.py install for mozdebug 14:49:17 INFO - Running setup.py install for mozdevice 14:49:17 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:49:17 INFO - Running setup.py install for mozfile 14:49:17 INFO - Running setup.py install for mozhttpd 14:49:18 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:49:18 INFO - Running setup.py install for mozinfo 14:49:18 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:49:18 INFO - Running setup.py install for mozInstall 14:49:18 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:49:18 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:49:18 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:49:18 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:49:18 INFO - Running setup.py install for mozleak 14:49:18 INFO - Running setup.py install for mozlog 14:49:18 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:49:18 INFO - Running setup.py install for moznetwork 14:49:18 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:49:18 INFO - Running setup.py install for mozprocess 14:49:19 INFO - Running setup.py install for mozprofile 14:49:19 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:49:19 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:49:19 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:49:19 INFO - Running setup.py install for mozrunner 14:49:19 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:49:19 INFO - Running setup.py install for mozscreenshot 14:49:19 INFO - Running setup.py install for moztest 14:49:19 INFO - Running setup.py install for mozversion 14:49:19 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:49:19 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:49:19 INFO - Cleaning up... 14:49:19 INFO - Return code: 0 14:49:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:49:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:49:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:49:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:49:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:49:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:49:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:49:19 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 0x103e5fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e62768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb82a467510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c3dd30>, '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 0x103b63db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103c32be8>, '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.HRXgfRXAjp/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.vbyL1zMO4g/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:49:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 14:49:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:49:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HRXgfRXAjp/Render', 14:49:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:49:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:49:19 INFO - 'HOME': '/Users/cltbld', 14:49:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:49:19 INFO - 'LOGNAME': 'cltbld', 14:49:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:49:19 INFO - 'MOZ_NO_REMOTE': '1', 14:49:19 INFO - 'NO_EM_RESTART': '1', 14:49:19 INFO - 'PAGER': '/bin/cat', 14:49:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:49:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:49:19 INFO - 'PWD': '/builds/slave/test', 14:49:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:49:19 INFO - 'SHELL': '/bin/bash', 14:49:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vbyL1zMO4g/Listeners', 14:49:19 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 14:49:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:49:19 INFO - 'USER': 'cltbld', 14:49:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:49:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:49:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:49:19 INFO - 'XPC_FLAGS': '0x0', 14:49:19 INFO - 'XPC_SERVICE_NAME': '0', 14:49:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:49:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:49:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:49:20 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-B3sCKI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:49:20 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 14:49:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:49:20 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-T5jdqZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:49:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:49:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:49:20 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-yPdEwB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:49:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 14:49:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:49:20 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-BGNbKJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:49:20 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)) 14:49:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:49:20 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-KZDJ2r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:49:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 14:49:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:49:20 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-_XQLhE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:49:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 14:49:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:49:20 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-79qG0_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:49:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 14:49:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:49:20 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-6yHigF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:49:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 14:49:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:49:20 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-Ejj9jo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:49:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 14:49:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:49:21 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-b87oyD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:49:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:49:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:49:21 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-F4olD7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:49:21 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 14:49:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:49:21 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-V2v2ng-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:49:21 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)) 14:49:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:49:21 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-nxNvHC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:49:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 14:49:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:49:21 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-c9jXmH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:49:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 14:49:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:49:21 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-amtm08-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:49:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 14:49:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:49:21 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-Tltk3J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:49:21 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 14:49:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:49:21 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-STwOLw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:49:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 14:49:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:49:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:49:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 14:49:21 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)) 14:49:21 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)) 14:49:21 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:49:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:49:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:49:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:49:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:49:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:49:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:49:24 INFO - Downloading blessings-1.6.tar.gz 14:49:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:49:24 INFO - Installing collected packages: blessings 14:49:24 INFO - Running setup.py install for blessings 14:49:24 INFO - Successfully installed blessings 14:49:24 INFO - Cleaning up... 14:49:24 INFO - Return code: 0 14:49:24 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:49:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:49:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:49:25 INFO - Reading from file tmpfile_stdout 14:49:25 INFO - Current package versions: 14:49:25 INFO - blessings == 1.6 14:49:25 INFO - blobuploader == 1.2.4 14:49:25 INFO - docopt == 0.6.1 14:49:25 INFO - functools32 == 3.2.3-2 14:49:25 INFO - jsonschema == 2.5.1 14:49:25 INFO - manifestparser == 1.1 14:49:25 INFO - mozInstall == 1.12 14:49:25 INFO - mozcrash == 1.0 14:49:25 INFO - mozdebug == 0.1 14:49:25 INFO - mozdevice == 0.50 14:49:25 INFO - mozfile == 1.2 14:49:25 INFO - mozhttpd == 0.7 14:49:25 INFO - mozinfo == 0.9 14:49:25 INFO - mozleak == 0.1 14:49:25 INFO - mozlog == 3.4 14:49:25 INFO - moznetwork == 0.27 14:49:25 INFO - mozprocess == 0.25 14:49:25 INFO - mozprofile == 0.28 14:49:25 INFO - mozrunner == 6.13 14:49:25 INFO - mozscreenshot == 0.1 14:49:25 INFO - mozsystemmonitor == 0.3 14:49:25 INFO - moztest == 0.8 14:49:25 INFO - mozversion == 1.4 14:49:25 INFO - psutil == 3.1.1 14:49:25 INFO - requests == 1.2.3 14:49:25 INFO - wsgiref == 0.1.2 14:49:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:49: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')]} 14:49:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:49:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:49: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')]} 14:49:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:49:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:49:25 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 0x103e5fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e62768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb82a467510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c3dd30>, '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 0x103b63db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103c32be8>, '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.HRXgfRXAjp/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.vbyL1zMO4g/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:49:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 14:49:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:49:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HRXgfRXAjp/Render', 14:49:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:49:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:49:25 INFO - 'HOME': '/Users/cltbld', 14:49:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:49:25 INFO - 'LOGNAME': 'cltbld', 14:49:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:49:25 INFO - 'MOZ_NO_REMOTE': '1', 14:49:25 INFO - 'NO_EM_RESTART': '1', 14:49:25 INFO - 'PAGER': '/bin/cat', 14:49:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:49:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:49:25 INFO - 'PWD': '/builds/slave/test', 14:49:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:49:25 INFO - 'SHELL': '/bin/bash', 14:49:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vbyL1zMO4g/Listeners', 14:49:25 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 14:49:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:49:25 INFO - 'USER': 'cltbld', 14:49:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:49:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:49:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:49:25 INFO - 'XPC_FLAGS': '0x0', 14:49:25 INFO - 'XPC_SERVICE_NAME': '0', 14:49:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:49:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:49:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 14:49:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 14:49:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 14:49:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 14:49:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 14:49:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 14:49:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 14:49:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 14:49:25 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 14:49:25 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 14:49:25 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 14:49:25 INFO - Cleaning up... 14:49:25 INFO - Return code: 0 14:49:25 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:49: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')]} 14:49:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:49:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:49: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')]} 14:49:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:49:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:49: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103e5fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e62768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb82a467510>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c3dd30>, '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 0x103b63db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103c32be8>, '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.HRXgfRXAjp/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.vbyL1zMO4g/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:49: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', 'jsonschema'] in /builds/slave/test/build 14:49: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 jsonschema 14:49:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HRXgfRXAjp/Render', 14:49:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:49:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:49:25 INFO - 'HOME': '/Users/cltbld', 14:49:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:49:25 INFO - 'LOGNAME': 'cltbld', 14:49:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:49:25 INFO - 'MOZ_NO_REMOTE': '1', 14:49:25 INFO - 'NO_EM_RESTART': '1', 14:49:25 INFO - 'PAGER': '/bin/cat', 14:49:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:49:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:49:25 INFO - 'PWD': '/builds/slave/test', 14:49:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:49:25 INFO - 'SHELL': '/bin/bash', 14:49:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vbyL1zMO4g/Listeners', 14:49:25 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 14:49:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:49:25 INFO - 'USER': 'cltbld', 14:49:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:49:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:49:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:49:25 INFO - 'XPC_FLAGS': '0x0', 14:49:25 INFO - 'XPC_SERVICE_NAME': '0', 14:49:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:49:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:49:25 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:49:25 INFO - Cleaning up... 14:49:25 INFO - Return code: 0 14:49:25 INFO - Running post-action listener: _resource_record_post_action 14:49:25 INFO - Running post-action listener: _start_resource_monitoring 14:49:25 INFO - Starting resource monitoring. 14:49:25 INFO - [mozharness: 2017-05-01 21:49:25.768636Z] Finished create-virtualenv step (success) 14:49:25 INFO - [mozharness: 2017-05-01 21:49:25.769559Z] Running install step. 14:49:25 INFO - Running pre-action listener: _resource_record_pre_action 14:49:25 INFO - Running main action method: install 14:49:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:49:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:49:26 INFO - Reading from file tmpfile_stdout 14:49:26 INFO - Detecting whether we're running mozinstall >=1.0... 14:49:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:49:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:49:26 INFO - Reading from file tmpfile_stdout 14:49:26 INFO - Output received: 14:49:26 INFO - Usage: mozinstall [options] installer 14:49:26 INFO - Options: 14:49:26 INFO - -h, --help show this help message and exit 14:49:26 INFO - -d DEST, --destination=DEST 14:49:26 INFO - Directory to install application into. [default: 14:49:26 INFO - "/builds/slave/test"] 14:49:26 INFO - --app=APP Application being installed. [default: firefox] 14:49:26 INFO - mkdir: /builds/slave/test/build/application 14:49:26 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'] 14:49:26 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 14:49:46 INFO - Reading from file tmpfile_stdout 14:49:46 INFO - Output received: 14:49:46 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 14:49:46 INFO - Running post-action listener: _resource_record_post_action 14:49:46 INFO - [mozharness: 2017-05-01 21:49:46.394443Z] Finished install step (success) 14:49:46 INFO - [mozharness: 2017-05-01 21:49:46.394563Z] Running run-tests step. 14:49:46 INFO - Running pre-action listener: _resource_record_pre_action 14:49:46 INFO - Running main action method: run_tests 14:49:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:49:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:49:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:49:46 INFO - 2017-05-01 14:49:46.409 screenresolution[2967:43974] starting screenresolution argv=screenresolution get 14:49:46 INFO - 2017-05-01 14:49:46.411 screenresolution[2967:43974] Display 0: 1600x1200x32@60 14:49:46 INFO - 2017-05-01 14:49:46.417 screenresolution[2968:43977] starting screenresolution argv=screenresolution list 14:49:46 INFO - Available Modes on Display 0 14:49:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:49:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:49:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:49:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:49:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:49:46 INFO - Intel Iris: 14:49:46 INFO - Chipset Model: Intel Iris 14:49:46 INFO - Type: GPU 14:49:46 INFO - Bus: Built-In 14:49:46 INFO - VRAM (Dynamic, Max): 1536 MB 14:49:46 INFO - Vendor: Intel (0x8086) 14:49:46 INFO - Device ID: 0x0a2e 14:49:46 INFO - Revision ID: 0x0009 14:49:46 INFO - Displays: 14:49:46 INFO - Display: 14:49:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:49:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:49:46 INFO - Display Serial Number: 1600x1200 60 14:49:46 INFO - Main Display: Yes 14:49:46 INFO - Mirror: Off 14:49:46 INFO - Online: Yes 14:49:46 INFO - Rotation: Supported 14:49:46 INFO - Return code: 0 14:49:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:49:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:49:46 INFO - Python 2.7.3 14:49:46 INFO - Return code: 0 14:49:46 INFO - grabbing minidump binary from tooltool 14:49:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:49:46 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 0x103c3dd30>, '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 0x103b63db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103c32be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:49:46 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 14:49:46 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 14:49:46 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 14:49:46 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:49:46 INFO - Return code: 0 14:49:46 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:49:46 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:49:46 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:49:46 INFO - ENV: RUST_BACKTRACE is now 1 14:49:46 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:49:46 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:49:46 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/ECfBHkqaSWanuC2p3cKhfA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0176', '--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 14:49:46 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/ECfBHkqaSWanuC2p3cKhfA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0176 --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 14:49:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HRXgfRXAjp/Render', 14:49:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:49:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:49:46 INFO - 'HOME': '/Users/cltbld', 14:49:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:49:46 INFO - 'LOGNAME': 'cltbld', 14:49:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:49:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:49:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:49:46 INFO - 'MOZ_NO_REMOTE': '1', 14:49:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:49:46 INFO - 'NO_EM_RESTART': '1', 14:49:46 INFO - 'PAGER': '/bin/cat', 14:49:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:49:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:49:46 INFO - 'PWD': '/builds/slave/test', 14:49:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:49:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:49:46 INFO - 'RUST_BACKTRACE': '1', 14:49:46 INFO - 'SHELL': '/bin/bash', 14:49:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vbyL1zMO4g/Listeners', 14:49:46 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 14:49:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:49:46 INFO - 'USER': 'cltbld', 14:49:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:49:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:49:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:49:46 INFO - 'XPC_FLAGS': '0x0', 14:49:46 INFO - 'XPC_SERVICE_NAME': '0', 14:49:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:49:46 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/ECfBHkqaSWanuC2p3cKhfA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0176', '--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 14:49:46 INFO - mozversion application_buildid: 20170501111719 14:49:46 INFO - mozversion application_changeset: a7501c0dd13e1163ff65a83d30143303101ca313 14:49:46 INFO - mozversion application_display_name: Nightly 14:49:46 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:49:46 INFO - mozversion application_name: Firefox 14:49:46 INFO - mozversion application_remotingname: firefox 14:49:46 INFO - mozversion application_repository: https://hg.mozilla.org/projects/pine 14:49:46 INFO - mozversion application_vendor: Mozilla 14:49:46 INFO - mozversion application_version: 55.0a1 14:49:46 INFO - mozversion platform_buildid: 20170501111719 14:49:46 INFO - mozversion platform_changeset: a7501c0dd13e1163ff65a83d30143303101ca313 14:49:46 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/pine 14:49:46 INFO - mozversion platform_version: 55.0a1 14:49:46 INFO - using testdate: 1493675386 14:49:46 INFO - actual date: 1493675386 14:49:46 INFO - starting webserver on 'localhost:57349' 14:49:46 INFO - SUITE-START | Running 2 tests 14:49:46 INFO - TEST-START | dromaeo_css 14:49:47 INFO - operating with platform_type : mac_ 14:49:47 INFO - Initialising browser for dromaeo_css test... 14:49:47 INFO - TEST-INFO | started process 2978 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/tmpqs0lDg/profile http://localhost:57349/getInfo.html) 14:49:49 INFO - PID 2978 | __metrics Screen width/height:1600/1200 14:49:49 INFO - PID 2978 | colorDepth:24 14:49:49 INFO - PID 2978 | Browser inner width/height: 1024/689 14:49:49 INFO - PID 2978 | __metrics 14:49:49 INFO - PID 2978 | 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" 14:49:49 INFO - PID 2978 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 334: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:49:50 INFO - TEST-INFO | 2978: exit 0 14:49:50 INFO - Browser initialized. 14:49:50 INFO - Running cycle 1/1 for dromaeo_css test... 14:49:50 INFO - TEST-INFO | started process 2980 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/tmpqs0lDg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:50:02 INFO - PID 2980 | RSS: Main: 240271360 14:50:02 INFO - PID 2980 | 14:53:07 INFO - PID 2980 | Cycle 1(1): loaded http://localhost:57349/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57349/tests/dromaeo/cssquery-ext.html) 14:53:09 INFO - PID 2980 | RSS: Main: 376999936 14:53:09 INFO - PID 2980 | 14:54:53 INFO - PID 2980 | ************************* 14:54:53 INFO - PID 2980 | A coding exception was thrown and uncaught in a Task. 14:54:53 INFO - PID 2980 | 14:54:53 ERROR - PID 2980 | Full message: TypeError: NetworkError when attempting to fetch resource. 14:54:53 INFO - PID 2980 | Full stack: 14:54:53 INFO - PID 2980 | ************************* 14:54:53 INFO - PID 2980 | ************************* 14:54:53 INFO - PID 2980 | A coding exception was thrown and uncaught in a Task. 14:54:53 INFO - PID 2980 | 14:54:53 ERROR - PID 2980 | Full message: TypeError: NetworkError when attempting to fetch resource. 14:54:53 INFO - PID 2980 | Full stack: 14:54:53 INFO - PID 2980 | ************************* 14:54:53 INFO - PID 2980 | ************************* 14:54:53 INFO - PID 2980 | A coding exception was thrown and uncaught in a Task. 14:54:53 INFO - PID 2980 | 14:54:53 ERROR - PID 2980 | Full message: TypeError: NetworkError when attempting to fetch resource. 14:54:53 INFO - PID 2980 | Full stack: 14:54:53 INFO - PID 2980 | ************************* 14:54:53 INFO - PID 2980 | ************************* 14:54:53 INFO - PID 2980 | A coding exception was thrown and uncaught in a Task. 14:54:53 INFO - PID 2980 | 14:54:53 ERROR - PID 2980 | Full message: TypeError: NetworkError when attempting to fetch resource. 14:54:53 INFO - PID 2980 | Full stack: 14:54:53 INFO - PID 2980 | ************************* 14:56:11 INFO - PID 2980 | Cycle 1(1): loaded http://localhost:57349/tests/dromaeo/cssquery-ext.html (next: http://localhost:57349/tests/dromaeo/cssquery-jquery.html) 14:56:12 INFO - PID 2980 | RSS: Main: 369184768 14:56:12 INFO - PID 2980 | 14:59:14 INFO - PID 2980 | Cycle 1(1): loaded http://localhost:57349/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57349/tests/dromaeo/cssquery-mootools.html) 14:59:15 INFO - PID 2980 | RSS: Main: 348418048 14:59:15 INFO - PID 2980 | 15:02:17 INFO - PID 2980 | Cycle 1(1): loaded http://localhost:57349/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57349/tests/dromaeo/cssquery-prototype.html) 15:02:18 INFO - PID 2980 | RSS: Main: 360210432 15:02:18 INFO - PID 2980 | 15:05:22 INFO - PID 2980 | Cycle 1(1): loaded http://localhost:57349/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57349/tests/dromaeo/cssquery-yui.html) 15:05:22 INFO - PID 2980 | RSS: Main: 334499840 15:05:22 INFO - PID 2980 | 15:08:25 INFO - PID 2980 | Cycle 1(1): loaded http://localhost:57349/tests/dromaeo/cssquery-yui.html (next: http://localhost:57349/tests/dromaeo/cssquery-dojo.html) 15:08:25 INFO - PID 2980 | RSS: Main: 375394304 15:08:25 INFO - PID 2980 | 15:08:25 INFO - PID 2980 | __start_tp_report 15:08:25 INFO - PID 2980 | _x_x_mozilla_page_load 15:08:25 INFO - PID 2980 | _x_x_mozilla_page_load_details 15:08:25 INFO - PID 2980 | |i|pagename|runs| 15:08:25 INFO - PID 2980 | |0;dojo.html;3041.4677431449495;3406.6593340665922;3430;3450.4306789379752;3458.67142621451;5715.371309155989;5853.268341457318;5906.970465147673;5920.555958303123;5938.376470470598;5785;5838;5842.88314233715;5850.531957443405;5874.060150375941;5092.949070509303;5114.130597798365;5123.871903202413;5143.12566863634;5147.922781158286;5268.710220937847;5271.65734227274;5279.70961597112;5292.550133238679;5297.761600727977;5252.553532949704;5263.078961181797;5275.657082289658;5275.657082289658;5281.5774738021055;5251.474852514756;5254.8686282842855;5255.947440525584;5268.104422248227;5268.473152684739;5063.924041139349;5191.247269145991;5198.454162312941;5198.844034678966;5202.609804264657;5936.109583562457;5996.790112346047;5998.550108741862;6035.366286539894;6048.637081775108;6288.434040936292;6317.810465686037;6327.367263273682;6346.238451385817;6352;5290.735463226823;6290.591111577733;6336.334684858116;6358.205224346957;6441.871162576722;6042.123892035718;6053.969730151365;6075.3924607539775;6077.604955677911;6110.511159107261;6507.089007538948;6544.476441884638;6556.24603170632;6568.146141001735;6587.374199451045;6134.142567655225;6246.656433896089;6271.62370257786;6281.623102613767;6315.179026726588;6307.180066591313;6330.968345158245;6341.524385671001;6373.649449280241;6429.903551446732;5222.712750798667;6277.466415354655;6285.120083188357;6320.336364681642;6322.146510221153;6247.094171345129;6310.747570097236;6313.779017734358;6314.463270622048;6319.178506794179;4362.411074504965;5003.5997120230295;5123;5128.974355128201;5148.124818780816;3960.0099975006246;4517.93828450314;4518.525554816762;4611.308303754397;4626.352310676508;6086.573939824169;6103.428585615297;6112.381899552462;6128.361498531508;6148.692565371803;6360.840978975562;6383.106365108889;6406.6476343800605;6411.551191416649;6436.227652681709;5723.513501352431;6098.817035488943;6100.633961962297;6109.541784366191;6119.816405507842;5468.781248750005;5549.250851135126;5553.222548843165;5555.805546805843;5558.805441809598;6442.130312407765;6477.125588045554;6482.67586620667;6487.22153341602;6497.090407342881;5880.647161170344;6356.173697419306;6427.775027874002;6430.324815894451;6435.2599451063725;6583.572067815577;6599.9010014848855;6601.075849381186;6601.9009714853355;6605.867882642224;6219.813405597839;6288.654124023223;6309.179806625109;6316.873662526632;6326;6245.437910588069;6252.624842509464;6340.397662222082;6364.172657554488;6374.171357723466;6251.499880009497;6255.53083518747;6282.3089460158435;6294.276158241678;6294.779682711083;6278.121063051085;6279.434850863444;6291.96854015727;6293.244810622755;6307.274663413767;6064.605800622945;6123.160610921377;6237.438630523275;6241.407066328691;6251.124842522141;6215.906761398673;6231.906521402092;6253.405926436982;6284.905726414016;6290.339514350927;6166.660833654192;6174.845628859314;6180.6600636965395;6195.132681424694;6195.566310358412 15:08:25 INFO - PID 2980 | |1;ext.html;781.2968328504345;790.5019837502374;1087.1085709718034;1106.6458733205377;1118.3457677258803;31043.068707938768;31133;34001.65998340019;34064.65935340646;34072.63709451625;29053.273668158254;35844.82077589615;35976.82011589938;36140.096497587576;36151.638483615156;8059.476134051297;8180.713675021376;8209.876851847228;8217.34261259098;8217.465864718804;5121.436641969399;5914.97042514789;7282.235365286648;8140.226678465537;8146.674133034701;8194.01671799382;8230.300424463927;8242.299404550591;8246.216609422096;8283.875741863876;8146.674133034671;8183.468074575193;8186.918130818675;8191.344692424592;8207.58962051898;5783.21926539916;5888.941110588882;7921.881171782428;8039.397045221632;8068.596570171468;34183.31633367319;34465;34600.134996625035;34605;34669.826650866584;3431.3995050866247;3438.1748380388526;3503.7372197085065;3507.140750516114;3543.9114022149392;28613.57079643807;28726.712732872613;28758.856205719047;28771;28802.855985720147;3038.4075105354464;3098.2873938994157;3130.3895740330645;3149.133988153253;3149.6220453545493;292589;293846;293962.5905611439;294125;295166;37777;46916.2962555562;47050;47132.293015604795;47177.76411117991;38325.808370957966;38339.80830095831;38342.23315533674;38366.424503632465;38398;12875.678108047186;12980.286084265455;13024.283664398548;13025.934870325587;13029.15310504814;10831.67504974852;10832.079273261703;10832.187585931088;10834;10846.403447810288;11551.88448115508;11556.653300401002;11699.415029248503;11809.940950295193;11868.050555955502;4908.975276254111;4913.926291105637;4946.356973593482;4970.2793095001025;4984.750762461862;789.9802504937422;903.7257466971539;922.5295099499169;982.6658935961807;1011.7925825205815;21220.575588488453;22756.772432275797;23142.884285578464;23168.65247021296;23266.185683501335;1073.9409332486632;1081.6646839479786;1112.4604566785104;1113.2763703592568;1126.1722633864103;10127.139193168663;10461.738456538495;10561.577536898592;11690;12132.393380330948;294293;294374;295187;295277;295307;49694;49769.75115124474;49791.25313120306;49799.75100124476;49940.50178494651;1432.734944035336;1441.8486058963658;1509.222750283605;1517.5143953934635;1527.5722797616686;16831.158442078093;21144.154233830475;21784.67322990188;22560.435989100402;22642.094316227165;36461;36603.450948235266;36614.08464788402;36618.63381366152;36730.26539469249;284473;285972;286565;286892;286899.5655021712;27752.306192344546;27795.722042779315;29170.124896253146;32619.510707339883;32770.52532635989;38684.41973370346;40762.79618601888;40827.1834563313;40863.16115774738;40872.59127408688;23010.309690709306;26725.331866703487;27483.17550473489;27591.172264832087;29003;28151.71848281491;28202.85898570494;29119;29277.414451711273;29305.560416594195;34193.829030854686;34323.828380857936;34340.14149646279;34358.82820585881;34447;28070.71929280681;28506.57240141358;29102.27244318824;29706.702932970395;29789.70210297956;20094.894780787195;20146.10494989206;21111.894440528315;21408;22935.65596516087 15:08:25 INFO - PID 2980 | |2;jquery.html;380.3780818361978;398.9980050099749;414.7159195950776;444.2425664242466;450.3155204089784;12683.682907927307;14607.269636518171;16142.869999100069;20904.686429703528;20973.68539471909;20545.486362840937;20783.480412989684;21520;22040;22442.214522491675;5798.884022319551;5967.940320596793;6105.725242364082;6186.56694031418;6192.628442293454;3628.383174860267;6105.847353816168;6160.537959653022;6167.814965551041;6168.938310616903;3636.5090712753836;3670.44943258512;3905.8437662493466;6086.695665216722;6102.664353460558;5938.6436813791315;6096.268447786249;6104.908426373608;6110.1140334651445;6118.418750218722;5373.704446255454;5467.58993075517;5468.453154684539;5475.835724928219;5478.506934375925;26804.19587412381;27345.86327068372;27409.862950685118;27578.5863212052;27604.171874843785;2134.4557137929883;2175.05385157457;2176.249194028074;2179.553191595719;2183.705199798023;17402.390916317996;17665;18154.90922545392;18183.727244091348;18272.269109235614;1845.7877344105332;2067.9040108742392;2090.696848956908;2140.283005193262;2141.828653707715;139845.3007734965;144446;156252;162883;166667;11402.184885708348;11439.313641181388;11876.584319548774;12508.437120329607;12550.246985180918;16619.6676066478;16626.418075367303;16637;16644.417445389354;16655.16724163811;8884.289256859436;9090.181883630505;9101.453912765255;9111.863322050174;9243.491607961492;8676.132386761274;8681.609327580274;8691.652333906573;8850.73447796567;8883.555822208864;7704.691812327556;7822;7825.647845846951;7830.530168189927;7850.603661949327;2125.242863548062;2129.902046484212;2134.8292136629034;2138.358492452258;2287.32523905445;909.1817364371985;916.3722849847742;938.4979036215592;943.834828904939;960.8627830134477;20507.58984820295;21095.68356474654;21147.577048458945;21178.894105529373;21416.143354265656;952.538019060755;969.9321047526746;979.2606582030521;981.3179840011123;991.0782971836118;9384.8592271116;10587.682369528926;10687.83968240477;10748.97884700952;10790.51442685081;507103;564369;573641;574039;576939;134147;134889;140361;142617.28691356478;145373;1138.9202755807014;1146.3866831245077;1195.7070517723039;1196.1985469735298;1199.9100067494974;16817.495475135765;17192.88246263989;19717.802821971596;21201.469963251042;21470;47222;52789.736051319494;55601.44398555963;57164;58182.7090864543;74598.62700686461;74652;75187.62406187934;75201.49597008139;77142.61428693045;8712.172343627346;8814.206721394849;8825.470471771714;9028.864567031631;9087.50056240713;13962.58112256634;14126.50557230492;14135.434582616992;14520.564383068102;14543.92728036353;11771.705707357041;11811.940940295244;11957.880421195676;12129.393530323272;12225.816612750637;9536.332456727963;10142.594296228363;11399.658010259705;11722.062235021007;11747.353895535827;9797.951010244904;9992.750181245527;11785.528578856749;11935.001774405671;12367.381630918238;8734.126587341469;8937;8941.642334306554;9021.90978090237;9036.63853445881;11752.70618234551;11795;12138.635840924786;12158.878411215775;12191.085668574715 15:08:25 INFO - PID 2980 | |3;mootools.html;1426.87158155766;1439.4349547258473;1464.3556834992603;1482.8961972661918;1485.628592851787;8941.50821704806;11635.825462618057;11646.475908584112;11648.650540483799;11660.300381977087;11300.434978251096;11328;11591.942040289809;11600.535978560869;11623.883761162386;2450.8039356851455;2494.189388448752;2496.488219914913;2500.1374525788574;2500.312414086132;2433;2479.1818699829105;2481.789047930928;2485.515324511721;2486;2428.368624157723;2447.951040979179;2486.8507889526595;2490.5018996200833;2492.9127480538186;2358.304300231427;2421.0315873650507;2445.303088619735;2449.6815413996064;2450.779429851313;2360.492494113774;2372.37132159976;2403.146882856578;2444.779969802726;2460.1512478194995;11338.206325557214;11384.487698053608;11466.31202127875;11488.88511114895;11500.942495287554;6405.263394709621;6608.504362172856;6626.304238055031;6628.072069910217;6633.2703402625675;3869.361555343365;4192.538820729717;4197.643200327976;4220.556841531657;4315.82736690532;1218.8049912014123;1254.91215614907;1289.8774616411426;1299.337337957639;1303.7262174943273;14964.326605302787;15317.23413829304;15336.156511391757;15950.840491595052;16114.919425402797;4910.926336104962;5025.422076461181;5033.6224783141415;5037.3955125384455;5321;9540.666076687283;11437.599684011186;11532.077433805276;11538.653840384633;11550.480228389742;6185;6390.45681117101;6403.423691867754;6404.807855764335;6411.647359395278;6064.848378790565;6237.313895471494;6240.469560087353;6253.218347706536;6254.15568898202;4176.415301857743;4297;4313.67647426438;4316.503602085738;4364.694471387036;1240.5658019693215;1245.2092920995053;1250.7498500299976;1257.251935098615;1263.9368031598383;712.6935417770409;783.2167832167833;790.3321693169263;800.1438460846838;803.7669075968021;6503.926852069356;6907.585544867324;6971.024056632075;7170.569765814068;7233.287517181057;786.2177133751953;910.1671970147373;924.6162842420455;926.749777560055;929.4702019848621;4253.404523366731;4263.786810659454;4306.440162778881;4407.162639098561;4424.469063712375;14683.48607798722;15026.023308484913;15174.393024279127;15203;15246.3901443941;14319.57041288763;15073.170975596448;15074.773878391832;15107;15179.089254644756;8391.286740627114;8580.270676992526;8593.613287402208;8603.784905377415;8623.887147648866;6323.715432805627;6674.365935236339;6750.8649827004165;6888.621125838127;6922.480813938874;12923.741525169631;13418.731625367243;13511.391987360395;13629;13659.317034148055;11508.59719909799;12196.146269761031;12523.812142818048;13333.266670333225;13634;8885.333599979898;9142.177204051668;9266.02706715812;9266.21237194846;9266.583003764981;10433.530491127765;10462.947685261524;10543.472826358498;10561.63034293796;10613.416262105658;9065.637374504946;9101.544922753968;9149.86275205859;9150.22223111043;9271.211946984313;8714.956425217833;8809.339299552432;8844.955775221082;8937.106289371011;8975.775605609912;8026.59867006659;8194.22154895284;8201.015878094668;8209.261166495044;8213.79465513367;5914.2311499504785;5965.940340596712;6096.969515152396;6130.693465326805;6175.104609831639;4744.240921452551;6029.8191054268445;6182.505399567934;6275;6355.983042713144 15:08:25 INFO - PID 2980 | |4;prototype.html;377.4037021506021;388.6463318380274;399.33510704676695;399.424828247324;401.1895970140317;4751.762411879406;5395.649282796616;5543.501084902358;5716.971415142919;5853.41465853415;5518.91721624175;5578.219049333096;5661.2923384576925;5689.203511508382;5718.7426565804535;3562.4656301554846;4145.875623731278;4359.215341238576;4425.889352766175;4429.889252768674;3466.73999450041;3914.2367238388524;4180.540140584533;4227.936580951288;4416;3259.4784834426496;4179.874603761892;4182.393552934823;4193.391958166064;4206.453161089054;4105.630493255607;4107.589241075883;4187;4210.200061988213;4210.621044106045;3603.1352475405974;3613.2773445311045;3614.518047600471;3619.4389869569995;3622.7645203061716;5485.588580856452;5561.5272701820395;5583.6928968906695;5589.568537686119;5612.494875461187;4650.953490465085;4839.540243676845;4878.634102442331;4901.338319326881;4924.2367433047575;4946.975265123688;5733.627314224562;5981.730822113015;6050.818475445743;6064.757409703607;3393.9490907636405;3555.9466608000903;4051.217464315721;4053.27041132596;4076.9184616307507;6187.350328215518;6306.432421082125;6378.234611846562;6499.707513161872;6527.412532872065;3409.761316707856;3916.8433262669173;4123.257813593522;4134.255833949918;4138.79306034697;4115.238680844053;4191.245575796325;4214.431051808028;4217;4221.282381995068;3733.4773131761576;4102.876913692594;4838.419389673261;4904.950950490449;4948.703077815343;5624.831255062355;5853.64878107315;5881.647221139162;5913.76344946206;6020.488258498077;1352.7970804379422;1402.621292251107;1426.9571912842632;1440.5030264558711;1444.0180677139647;800.6117033238875;817.9059408168018;912.1425859691868;950.8668786369914;952.8618350339162;5072.594192464594;5329.653572517774;5814.534837213013;5822.854428639318;5824.155497452845;5164.3802743671;5246.868828279324;6207.037909124093;6303.432691057735;6340.904886426707;5037.773300201499;5303.7348132593215;5631.014572449805;5795.594308398456;5813.982553053199;2823.3365159187574;3014.457397668397;3203.615566132079;3288.93422131556;3303.7687361884437;5561.916571251434;5959.1657167995645;6298.582818865811;6548.476121910236;6557.573757705733;5754.769809207585;6322.830276398881;6390.680465976682;6412.070249813751;6464.41820236181;5203.947960520422;6497.953144759385;6506.479481641457;6534;6534.640594767334;6059.21230240066;6239.251289845248;6255.40573645503;6284.937150628435;6379.393957574023;5949.851253718519;6184.5052395809125;6188.412100850412;6217.440430361289;6229.190205273467;5242.97378513105;5383.757730902041;6214.4407003369915;6446.548741588043;6482.643454610042;3379.9324013520086;3426.8286585671117;3627.1838836262687;3742.821011381406;4033.91932161361;5894.351621321737;6034;6352.36476352361;6358.745650174126;6378.45783108441;5253.317068781187;5574.721263936706;5884.793617308356;6169.827732730767;6192.225971753531;5028.647994640486;5377.704226267593;6129.233845769279;6134.294556126102;6164.352742962104;5803.535717142534;5939.2575928009;5963.582549221513;6046.334903160737;6058.878822423615;4655.86032419028;5059.316992206005;5936.367498937654;6096.329403765671;6111.633302001894;5073.365829271341;5115.4373018967135;5274.947250527599;5734.22587950638;5877.529797616095 15:08:25 INFO - PID 2980 | |5;yui.html;694.0560837261321;740.2079774641135;833.1834801894147;835.5195762436597;851.9318454523639;12621.55824546141;12913.418896149673;17368.65262694747;18519;18544.165512551936;16600.335986560523;16651.250693718783;16659.91670041648;16692.165391730425;16699.24853381598;928.2481190235931;929.530587053537;930.167500087423;930.860370944357;933.2347475070445;619.6932518403374;631.6241836107508;911.2436677557645;915.7756349694334;931.8089791592741;958.2190514730472;979.9853002204973;980;981.7349315684761;1001.774600714837;976.3507267666993;978.0073225675909;979.2704435195753;979.9951000245024;1000.1498726082845;486.91478991176757;492.82997365908705;698.2214830464047;708.7271400510789;709.9148102227766;15789.973651712602;15883.126428046453;15904.522864314089;15918.124503152321;15919.124448155348;15946.521604351889;16419.343226270936;16687.249073791587;16790.58023549409;16869.24088416024;13349.86650133496;13519.121257118255;13656.863431365658;13661.043726939119;13666.93166534161;3125.671804460522;3313.519539666759;3350.179206094498;3358.983205083983;3379.678930501598;126459.20375078729;126913;127091.36454317762;127377;129027.35486322602;10733.340307563634;11648.534058637559;11778.352190629426;11854.644360669194;11876.287422754662;16125.93924307389;19696.734875489215;19704.211831526864;25168.622470662955;25211.991520339347;7644.006279183782;7654.158042615308;7797.454178207585;7938.364930805522;7967.601619918981;3935.409688546684;4004.3192657248337;4011.7191796574543;4011.9799401002806;4012.879613611596;4229.450171477688;4295.8281668733525;4334.956650433518;4374.825006999748;4375.431193944767;866.1857853617579;888.813349196663;894.1773568317163;894.5348418822176;895.4985208283382;569.9202111704365;634.9206349206349;653.4413076819346;661.566673828643;676.4351766275117;10414.531346089445;10777.407242601575;10986.395748233925;11919.284842909454;12146.939265303618;603.3031848215319;612.5008118383503;614.9385061493904;624.5628060357768;643.7585905285518;5366;5576.944230557642;5588.916166257428;5602.859928501739;5630.211770352154;107888.46055769672;108093.4595327034;109726.45136774264;110779;111232;92951;92994.67513312087;93755;94071.05928940623;94734.5263273693;81381;82129.58935205406;83146.58426707948;83295.58352208321;84212;9752.36536228851;9969.950150249493;11681.357525336189;11835.940820295844;12151.756964860477;38015.239695206496;40137.19725605413;40669.79665101656;40874.97812554591;41720.74837754872;63538.04692929702;71530;72084.71025832096;72934.63532682516;73621.6318918402;15125.470608528649;15844.68310633804;16361.263743131349;16377.42679006229;16379.672406551565;17689.557761055563;17805.821941780934;17882.28470861151;18052.532144575114;18375.540611484284;14224.644383890072;16390.590235244214;16404.589885252964;16528.25622846951;17227;16348.346066157696;16592.2533485991;16652.58368540796;16673.333066677198;16808.243629036966;15065.548033559011;15604.375824966874;15792.842071579136;16305;16308.347666093223;10727.570897164025;10754;10894.564217431214;11069.944650276697;11093.549637670136;12096.516139354679;12370.44333005035;12510.749785004431;12546.12177147591;12731.363431828186 15:08:25 INFO - PID 2980 | __end_tp_report 15:08:25 INFO - PID 2980 | __start_cc_report 15:08:25 INFO - PID 2980 | _x_x_mozilla_cycle_collect,1884 15:08:25 INFO - PID 2980 | __end_cc_report 15:08:25 INFO - PID 2980 | __startTimestamp1493676505674__endTimestamp 15:08:25 INFO - PID 2980 | 15:08:25 INFO - PID 2980 | ------- Summary: start ------- 15:08:25 INFO - PID 2980 | Number of tests: 6 15:08:25 INFO - PID 2980 | 15:08:25 INFO - PID 2980 | [#0] dojo.html Cycles:165 Average:5876.35 Median:6174.85 stddev:698.50 (11.3%) stddev-sans-first:664.30 15:08:25 INFO - PID 2980 | Values: 3041.5 3406.7 3430.0 3450.4 3458.7 5715.4 5853.3 5907.0 5920.6 5938.4 5785.0 5838.0 5842.9 5850.5 5874.1 5092.9 5114.1 5123.9 5143.1 5147.9 5268.7 5271.7 5279.7 5292.6 5297.8 5252.6 5263.1 5275.7 5275.7 5281.6 5251.5 5254.9 5255.9 5268.1 5268.5 5063.9 5191.2 5198.5 5198.8 5202.6 5936.1 5996.8 5998.6 6035.4 6048.6 6288.4 6317.8 6327.4 6346.2 6352.0 5290.7 6290.6 6336.3 6358.2 6441.9 6042.1 6054.0 6075.4 6077.6 6110.5 6507.1 6544.5 6556.2 6568.1 6587.4 6134.1 6246.7 6271.6 6281.6 6315.2 6307.2 6331.0 6341.5 6373.6 6429.9 5222.7 6277.5 6285.1 6320.3 6322.1 6247.1 6310.7 6313.8 6314.5 6319.2 4362.4 5003.6 5123.0 5129.0 5148.1 3960.0 4517.9 4518.5 4611.3 4626.4 6086.6 6103.4 6112.4 6128.4 6148.7 6360.8 6383.1 6406.6 6411.6 6436.2 5723.5 6098.8 6100.6 6109.5 6119.8 5468.8 5549.3 5553.2 5555.8 5558.8 6442.1 6477.1 6482.7 6487.2 6497.1 5880.6 6356.2 6427.8 6430.3 6435.3 6583.6 6599.9 6601.1 6601.9 6605.9 6219.8 6288.7 6309.2 6316.9 6326.0 6245.4 6252.6 6340.4 6364.2 6374.2 6251.5 6255.5 6282.3 6294.3 6294.8 6278.1 6279.4 6292.0 6293.2 6307.3 6064.6 6123.2 6237.4 6241.4 6251.1 6215.9 6231.9 6253.4 6284.9 6290.3 6166.7 6174.8 6180.7 6195.1 6195.6 15:08:25 INFO - PID 2980 | 15:08:25 INFO - PID 2980 | [#1] ext.html Cycles:180 Average:42530.65 Median:31088.03 stddev:76626.53 (246.5%) stddev-sans-first:76777.38 15:08:25 INFO - PID 2980 | Values: 781.3 790.5 1087.1 1106.6 1118.3 31043.1 31133.0 34001.7 34064.7 34072.6 29053.3 35844.8 35976.8 36140.1 36151.6 8059.5 8180.7 8209.9 8217.3 8217.5 5121.4 5915.0 7282.2 8140.2 8146.7 8194.0 8230.3 8242.3 8246.2 8283.9 8146.7 8183.5 8186.9 8191.3 8207.6 5783.2 5888.9 7921.9 8039.4 8068.6 34183.3 34465.0 34600.1 34605.0 34669.8 3431.4 3438.2 3503.7 3507.1 3543.9 28613.6 28726.7 28758.9 28771.0 28802.9 3038.4 3098.3 3130.4 3149.1 3149.6 292589.0 293846.0 293962.6 294125.0 295166.0 37777.0 46916.3 47050.0 47132.3 47177.8 38325.8 38339.8 38342.2 38366.4 38398.0 12875.7 12980.3 13024.3 13025.9 13029.2 10831.7 10832.1 10832.2 10834.0 10846.4 11551.9 11556.7 11699.4 11809.9 11868.1 4909.0 4913.9 4946.4 4970.3 4984.8 790.0 903.7 922.5 982.7 1011.8 21220.6 22756.8 23142.9 23168.7 23266.2 1073.9 1081.7 1112.5 1113.3 1126.2 10127.1 10461.7 10561.6 11690.0 12132.4 294293.0 294374.0 295187.0 295277.0 295307.0 49694.0 49769.8 49791.3 49799.8 49940.5 1432.7 1441.8 1509.2 1517.5 1527.6 16831.2 21144.2 21784.7 22560.4 22642.1 36461.0 36603.5 36614.1 36618.6 36730.3 284473.0 285972.0 286565.0 286892.0 286899.6 27752.3 27795.7 29170.1 32619.5 32770.5 38684.4 40762.8 40827.2 40863.2 40872.6 23010.3 26725.3 27483.2 27591.2 29003.0 28151.7 28202.9 29119.0 29277.4 29305.6 34193.8 34323.8 34340.1 34358.8 34447.0 28070.7 28506.6 29102.3 29706.7 29789.7 20094.9 20146.1 21111.9 21408.0 22935.7 15:08:25 INFO - PID 2980 | 15:08:25 INFO - PID 2980 | [#2] jquery.html Cycles:180 Average:35751.65 Median:14545.76 stddev:95105.90 (653.8%) stddev-sans-first:95335.61 15:08:25 INFO - PID 2980 | Values: 380.4 399.0 414.7 444.2 450.3 12683.7 14607.3 16142.9 20904.7 20973.7 20545.5 20783.5 21520.0 22040.0 22442.2 5798.9 5967.9 6105.7 6186.6 6192.6 3628.4 6105.8 6160.5 6167.8 6168.9 3636.5 3670.4 3905.8 6086.7 6102.7 5938.6 6096.3 6104.9 6110.1 6118.4 5373.7 5467.6 5468.5 5475.8 5478.5 26804.2 27345.9 27409.9 27578.6 27604.2 2134.5 2175.1 2176.2 2179.6 2183.7 17402.4 17665.0 18154.9 18183.7 18272.3 1845.8 2067.9 2090.7 2140.3 2141.8 139845.3 144446.0 156252.0 162883.0 166667.0 11402.2 11439.3 11876.6 12508.4 12550.2 16619.7 16626.4 16637.0 16644.4 16655.2 8884.3 9090.2 9101.5 9111.9 9243.5 8676.1 8681.6 8691.7 8850.7 8883.6 7704.7 7822.0 7825.6 7830.5 7850.6 2125.2 2129.9 2134.8 2138.4 2287.3 909.2 916.4 938.5 943.8 960.9 20507.6 21095.7 21147.6 21178.9 21416.1 952.5 969.9 979.3 981.3 991.1 9384.9 10587.7 10687.8 10749.0 10790.5 507103.0 564369.0 573641.0 574039.0 576939.0 134147.0 134889.0 140361.0 142617.3 145373.0 1138.9 1146.4 1195.7 1196.2 1199.9 16817.5 17192.9 19717.8 21201.5 21470.0 47222.0 52789.7 55601.4 57164.0 58182.7 74598.6 74652.0 75187.6 75201.5 77142.6 8712.2 8814.2 8825.5 9028.9 9087.5 13962.6 14126.5 14135.4 14520.6 14543.9 11771.7 11811.9 11957.9 12129.4 12225.8 9536.3 10142.6 11399.7 11722.1 11747.4 9798.0 9992.8 11785.5 11935.0 12367.4 8734.1 8937.0 8941.6 9021.9 9036.6 11752.7 11795.0 12138.6 12158.9 12191.1 15:08:25 INFO - PID 2980 | 15:08:25 INFO - PID 2980 | [#3] mootools.html Cycles:180 Average:6993.26 Median:4195.09 stddev:4376.94 (104.3%) stddev-sans-first:4369.24 15:08:25 INFO - PID 2980 | Values: 1426.9 1439.4 1464.4 1482.9 1485.6 8941.5 11635.8 11646.5 11648.7 11660.3 11300.4 11328.0 11591.9 11600.5 11623.9 2450.8 2494.2 2496.5 2500.1 2500.3 2433.0 2479.2 2481.8 2485.5 2486.0 2428.4 2448.0 2486.9 2490.5 2492.9 2358.3 2421.0 2445.3 2449.7 2450.8 2360.5 2372.4 2403.1 2444.8 2460.2 11338.2 11384.5 11466.3 11488.9 11500.9 6405.3 6608.5 6626.3 6628.1 6633.3 3869.4 4192.5 4197.6 4220.6 4315.8 1218.8 1254.9 1289.9 1299.3 1303.7 14964.3 15317.2 15336.2 15950.8 16114.9 4910.9 5025.4 5033.6 5037.4 5321.0 9540.7 11437.6 11532.1 11538.7 11550.5 6185.0 6390.5 6403.4 6404.8 6411.6 6064.8 6237.3 6240.5 6253.2 6254.2 4176.4 4297.0 4313.7 4316.5 4364.7 1240.6 1245.2 1250.7 1257.3 1263.9 712.7 783.2 790.3 800.1 803.8 6503.9 6907.6 6971.0 7170.6 7233.3 786.2 910.2 924.6 926.7 929.5 4253.4 4263.8 4306.4 4407.2 4424.5 14683.5 15026.0 15174.4 15203.0 15246.4 14319.6 15073.2 15074.8 15107.0 15179.1 8391.3 8580.3 8593.6 8603.8 8623.9 6323.7 6674.4 6750.9 6888.6 6922.5 12923.7 13418.7 13511.4 13629.0 13659.3 11508.6 12196.1 12523.8 13333.3 13634.0 8885.3 9142.2 9266.0 9266.2 9266.6 10433.5 10462.9 10543.5 10561.6 10613.4 9065.6 9101.5 9149.9 9150.2 9271.2 8715.0 8809.3 8845.0 8937.1 8975.8 8026.6 8194.2 8201.0 8209.3 8213.8 5914.2 5965.9 6097.0 6130.7 6175.1 4744.2 6029.8 6182.5 6275.0 6356.0 15:08:25 INFO - PID 2980 | 15:08:25 INFO - PID 2980 | [#4] prototype.html Cycles:180 Average:4837.85 Median:5502.25 stddev:1544.11 (28.1%) stddev-sans-first:1511.71 15:08:25 INFO - PID 2980 | Values: 377.4 388.6 399.3 399.4 401.2 4751.8 5395.6 5543.5 5717.0 5853.4 5518.9 5578.2 5661.3 5689.2 5718.7 3562.5 4145.9 4359.2 4425.9 4429.9 3466.7 3914.2 4180.5 4227.9 4416.0 3259.5 4179.9 4182.4 4193.4 4206.5 4105.6 4107.6 4187.0 4210.2 4210.6 3603.1 3613.3 3614.5 3619.4 3622.8 5485.6 5561.5 5583.7 5589.6 5612.5 4651.0 4839.5 4878.6 4901.3 4924.2 4947.0 5733.6 5981.7 6050.8 6064.8 3393.9 3555.9 4051.2 4053.3 4076.9 6187.4 6306.4 6378.2 6499.7 6527.4 3409.8 3916.8 4123.3 4134.3 4138.8 4115.2 4191.2 4214.4 4217.0 4221.3 3733.5 4102.9 4838.4 4905.0 4948.7 5624.8 5853.6 5881.6 5913.8 6020.5 1352.8 1402.6 1427.0 1440.5 1444.0 800.6 817.9 912.1 950.9 952.9 5072.6 5329.7 5814.5 5822.9 5824.2 5164.4 5246.9 6207.0 6303.4 6340.9 5037.8 5303.7 5631.0 5795.6 5814.0 2823.3 3014.5 3203.6 3288.9 3303.8 5561.9 5959.2 6298.6 6548.5 6557.6 5754.8 6322.8 6390.7 6412.1 6464.4 5203.9 6498.0 6506.5 6534.0 6534.6 6059.2 6239.3 6255.4 6284.9 6379.4 5949.9 6184.5 6188.4 6217.4 6229.2 5243.0 5383.8 6214.4 6446.5 6482.6 3379.9 3426.8 3627.2 3742.8 4033.9 5894.4 6034.0 6352.4 6358.7 6378.5 5253.3 5574.7 5884.8 6169.8 6192.2 5028.6 5377.7 6129.2 6134.3 6164.4 5803.5 5939.3 5963.6 6046.3 6058.9 4655.9 5059.3 5936.4 6096.3 6111.6 5073.4 5115.4 5274.9 5734.2 5877.5 15:08:25 INFO - PID 2980 | 15:08:25 INFO - PID 2980 | [#5] yui.html Cycles:180 Average:22121.29 Median:3219.60 stddev:32051.28 (995.5%) stddev-sans-first:32100.81 15:08:25 INFO - PID 2980 | Values: 694.1 740.2 833.2 835.5 851.9 12621.6 12913.4 17368.7 18519.0 18544.2 16600.3 16651.3 16659.9 16692.2 16699.2 928.2 929.5 930.2 930.9 933.2 619.7 631.6 911.2 915.8 931.8 958.2 980.0 980.0 981.7 1001.8 976.4 978.0 979.3 980.0 1000.1 486.9 492.8 698.2 708.7 709.9 15790.0 15883.1 15904.5 15918.1 15919.1 15946.5 16419.3 16687.2 16790.6 16869.2 13349.9 13519.1 13656.9 13661.0 13666.9 3125.7 3313.5 3350.2 3359.0 3379.7 126459.2 126913.0 127091.4 127377.0 129027.4 10733.3 11648.5 11778.4 11854.6 11876.3 16125.9 19696.7 19704.2 25168.6 25212.0 7644.0 7654.2 7797.5 7938.4 7967.6 3935.4 4004.3 4011.7 4012.0 4012.9 4229.5 4295.8 4335.0 4374.8 4375.4 866.2 888.8 894.2 894.5 895.5 569.9 634.9 653.4 661.6 676.4 10414.5 10777.4 10986.4 11919.3 12146.9 603.3 612.5 614.9 624.6 643.8 5366.0 5576.9 5588.9 5602.9 5630.2 107888.5 108093.5 109726.5 110779.0 111232.0 92951.0 92994.7 93755.0 94071.1 94734.5 81381.0 82129.6 83146.6 83295.6 84212.0 9752.4 9970.0 11681.4 11835.9 12151.8 38015.2 40137.2 40669.8 40875.0 41720.7 63538.0 71530.0 72084.7 72934.6 73621.6 15125.5 15844.7 16361.3 16377.4 16379.7 17689.6 17805.8 17882.3 18052.5 18375.5 14224.6 16390.6 16404.6 16528.3 17227.0 16348.3 16592.3 16652.6 16673.3 16808.2 15065.5 15604.4 15792.8 16305.0 16308.3 10727.6 10754.0 10894.6 11069.9 11093.5 12096.5 12370.4 12510.7 12546.1 12731.4 15:08:25 INFO - PID 2980 | -------- Summary: end -------- 15:08:25 INFO - PID 2980 | 15:08:26 INFO - TEST-INFO | 2980: exit 0 15:08:26 INFO - TEST-OK | dromaeo_css | took 1119381ms 15:08:26 INFO - TEST-START | kraken 15:08:26 INFO - operating with platform_type : mac_ 15:08:26 INFO - Initialising browser for kraken test... 15:08:26 INFO - TEST-INFO | started process 2983 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/tmpqj2dkj/profile http://localhost:57349/getInfo.html) 15:08:28 INFO - PID 2983 | __metrics Screen width/height:1600/1200 15:08:28 INFO - PID 2983 | colorDepth:24 15:08:28 INFO - PID 2983 | Browser inner width/height: 1024/689 15:08:28 INFO - PID 2983 | __metrics 15:08:28 INFO - PID 2983 | 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" 15:08:28 INFO - PID 2983 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 334: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:08:29 INFO - TEST-INFO | 2983: exit 0 15:08:29 INFO - Browser initialized. 15:08:29 INFO - Running cycle 1/1 for kraken test... 15:08:29 INFO - TEST-INFO | started process 2984 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/tmpqj2dkj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:08:42 INFO - PID 2984 | RSS: Main: 242311168 15:08:42 INFO - PID 2984 | 15:08:44 INFO - PID 2984 | Cycle 1(1): loaded http://localhost:57349/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57349/tests/kraken/driver.html?testName=audio-beat-detection) 15:08:45 INFO - PID 2984 | RSS: Main: 313397248 15:08:45 INFO - PID 2984 | 15:08:48 INFO - PID 2984 | Cycle 1(1): loaded http://localhost:57349/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57349/tests/kraken/driver.html?testName=audio-dft) 15:08:49 INFO - PID 2984 | RSS: Main: 295542784 15:08:49 INFO - PID 2984 | 15:08:52 INFO - PID 2984 | Cycle 1(1): loaded http://localhost:57349/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57349/tests/kraken/driver.html?testName=audio-fft) 15:08:52 INFO - PID 2984 | RSS: Main: 388485120 15:08:52 INFO - PID 2984 | 15:08:54 INFO - PID 2984 | Cycle 1(1): loaded http://localhost:57349/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57349/tests/kraken/driver.html?testName=audio-oscillator) 15:08:55 INFO - PID 2984 | RSS: Main: 290627584 15:08:55 INFO - PID 2984 | 15:08:57 INFO - PID 2984 | Cycle 1(1): loaded http://localhost:57349/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57349/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:08:58 INFO - PID 2984 | RSS: Main: 292904960 15:08:58 INFO - PID 2984 | 15:09:00 INFO - PID 2984 | Cycle 1(1): loaded http://localhost:57349/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57349/tests/kraken/driver.html?testName=imaging-darkroom) 15:09:01 INFO - PID 2984 | RSS: Main: 357240832 15:09:01 INFO - PID 2984 | 15:09:04 INFO - PID 2984 | Cycle 1(1): loaded http://localhost:57349/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57349/tests/kraken/driver.html?testName=imaging-desaturate) 15:09:05 INFO - PID 2984 | RSS: Main: 373358592 15:09:05 INFO - PID 2984 | 15:09:07 INFO - PID 2984 | Cycle 1(1): loaded http://localhost:57349/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57349/tests/kraken/driver.html?testName=json-parse-financial) 15:09:08 INFO - PID 2984 | RSS: Main: 378294272 15:09:08 INFO - PID 2984 | 15:09:09 INFO - PID 2984 | Cycle 1(1): loaded http://localhost:57349/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57349/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:09:10 INFO - PID 2984 | RSS: Main: 333701120 15:09:10 INFO - PID 2984 | 15:09:12 INFO - PID 2984 | Cycle 1(1): loaded http://localhost:57349/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57349/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:09:13 INFO - PID 2984 | RSS: Main: 333549568 15:09:13 INFO - PID 2984 | 15:09:15 INFO - PID 2984 | Cycle 1(1): loaded http://localhost:57349/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57349/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:09:15 INFO - PID 2984 | RSS: Main: 336171008 15:09:15 INFO - PID 2984 | 15:09:18 INFO - PID 2984 | Cycle 1(1): loaded http://localhost:57349/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57349/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:09:19 INFO - PID 2984 | RSS: Main: 354844672 15:09:19 INFO - PID 2984 | 15:09:22 INFO - PID 2984 | Cycle 1(1): loaded http://localhost:57349/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57349/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:09:23 INFO - PID 2984 | RSS: Main: 347230208 15:09:23 INFO - PID 2984 | 15:09:24 INFO - PID 2984 | Cycle 1(1): loaded http://localhost:57349/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57349/tests/kraken/driver.html?testName=ai-astar) 15:09:25 INFO - PID 2984 | RSS: Main: 389971968 15:09:25 INFO - PID 2984 | 15:09:25 INFO - PID 2984 | __start_tp_report 15:09:25 INFO - PID 2984 | _x_x_mozilla_page_load 15:09:25 INFO - PID 2984 | _x_x_mozilla_page_load_details 15:09:25 INFO - PID 2984 | |i|pagename|runs| 15:09:25 INFO - PID 2984 | |0;ai-astar;108;110;110;109;109;111;108;108;108;110 15:09:25 INFO - PID 2984 | |1;audio-beat-detection;141;110;107;150;95;109;128;139;141;111 15:09:25 INFO - PID 2984 | |2;audio-dft;187;183;185;186;187;186;183;188;189;184 15:09:25 INFO - PID 2984 | |3;audio-fft;85;58;58;88;64;58;57;63;78;61 15:09:25 INFO - PID 2984 | |4;audio-oscillator;93;70;87;69;70;68;87;68;68;90 15:09:25 INFO - PID 2984 | |5;imaging-gaussian-blur;90;88;89;89;88;90;89;90;89;88 15:09:25 INFO - PID 2984 | |6;imaging-darkroom;96;95;96;95;96;95;96;97;96;95 15:09:25 INFO - PID 2984 | |7;imaging-desaturate;84;85;87;84;83;83;83;83;84;86 15:09:25 INFO - PID 2984 | |8;json-parse-financial;47;47;48;48;47;47;48;48;48;47 15:09:25 INFO - PID 2984 | |9;json-stringify-tinderbox;55;53;56;66;69;59;59;59;58;60 15:09:25 INFO - PID 2984 | |10;stanford-crypto-aes;82;83;81;83;81;81;82;85;82;82 15:09:25 INFO - PID 2984 | |11;stanford-crypto-ccm;137;135;136;135;133;140;141;123;210;150 15:09:25 INFO - PID 2984 | |12;stanford-crypto-pbkdf2;229;183;186;208;229;183;181;180;184;182 15:09:25 INFO - PID 2984 | |13;stanford-crypto-sha256-iterative;69;67;67;67;67;66;65;59;65;82 15:09:25 INFO - PID 2984 | __end_tp_report 15:09:25 INFO - PID 2984 | __start_cc_report 15:09:25 INFO - PID 2984 | _x_x_mozilla_cycle_collect,2235 15:09:25 INFO - PID 2984 | __end_cc_report 15:09:25 INFO - PID 2984 | __startTimestamp1493676565164__endTimestamp 15:09:25 INFO - PID 2984 | 15:09:25 INFO - PID 2984 | ------- Summary: start ------- 15:09:25 INFO - PID 2984 | Number of tests: 14 15:09:25 INFO - PID 2984 | 15:09:25 INFO - PID 2984 | [#0] ai-astar Cycles:10 Average:109.10 Median:109.50 stddev:1.10 (1.0%) stddev-sans-first:1.09 15:09:25 INFO - PID 2984 | Values: 108.0 110.0 110.0 109.0 109.0 111.0 108.0 108.0 108.0 110.0 15:09:25 INFO - PID 2984 | 15:09:25 INFO - PID 2984 | [#1] audio-beat-detection Cycles:10 Average:123.10 Median:140.00 stddev:18.88 (13.5%) stddev-sans-first:18.88 15:09:25 INFO - PID 2984 | Values: 141.0 110.0 107.0 150.0 95.0 109.0 128.0 139.0 141.0 111.0 15:09:25 INFO - PID 2984 | 15:09:25 INFO - PID 2984 | [#2] audio-dft Cycles:10 Average:185.80 Median:186.50 stddev:2.04 (1.1%) stddev-sans-first:2.12 15:09:25 INFO - PID 2984 | Values: 187.0 183.0 185.0 186.0 187.0 186.0 183.0 188.0 189.0 184.0 15:09:25 INFO - PID 2984 | 15:09:25 INFO - PID 2984 | [#3] audio-fft Cycles:10 Average:67.00 Median:63.50 stddev:11.97 (18.9%) stddev-sans-first:10.78 15:09:25 INFO - PID 2984 | Values: 85.0 58.0 58.0 88.0 64.0 58.0 57.0 63.0 78.0 61.0 15:09:25 INFO - PID 2984 | 15:09:25 INFO - PID 2984 | [#4] audio-oscillator Cycles:10 Average:77.00 Median:78.50 stddev:10.70 (13.6%) stddev-sans-first:9.65 15:09:25 INFO - PID 2984 | Values: 93.0 70.0 87.0 69.0 70.0 68.0 87.0 68.0 68.0 90.0 15:09:25 INFO - PID 2984 | 15:09:25 INFO - PID 2984 | [#5] imaging-gaussian-blur Cycles:10 Average:89.00 Median:89.00 stddev:0.82 (0.9%) stddev-sans-first:0.78 15:09:25 INFO - PID 2984 | Values: 90.0 88.0 89.0 89.0 88.0 90.0 89.0 90.0 89.0 88.0 15:09:25 INFO - PID 2984 | 15:09:25 INFO - PID 2984 | [#6] imaging-darkroom Cycles:10 Average:95.70 Median:96.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 15:09:25 INFO - PID 2984 | Values: 96.0 95.0 96.0 95.0 96.0 95.0 96.0 97.0 96.0 95.0 15:09:25 INFO - PID 2984 | 15:09:25 INFO - PID 2984 | [#7] imaging-desaturate Cycles:10 Average:84.20 Median:84.00 stddev:1.40 (1.7%) stddev-sans-first:1.48 15:09:25 INFO - PID 2984 | Values: 84.0 85.0 87.0 84.0 83.0 83.0 83.0 83.0 84.0 86.0 15:09:25 INFO - PID 2984 | 15:09:25 INFO - PID 2984 | [#8] json-parse-financial Cycles:10 Average:47.50 Median:48.00 stddev:0.53 (1.1%) stddev-sans-first:0.53 15:09:25 INFO - PID 2984 | Values: 47.0 47.0 48.0 48.0 47.0 47.0 48.0 48.0 48.0 47.0 15:09:25 INFO - PID 2984 | 15:09:25 INFO - PID 2984 | [#9] json-stringify-tinderbox Cycles:10 Average:59.40 Median:59.00 stddev:4.84 (8.2%) stddev-sans-first:4.86 15:09:25 INFO - PID 2984 | Values: 55.0 53.0 56.0 66.0 69.0 59.0 59.0 59.0 58.0 60.0 15:09:25 INFO - PID 2984 | 15:09:25 INFO - PID 2984 | [#10] stanford-crypto-aes Cycles:10 Average:82.20 Median:82.00 stddev:1.23 (1.5%) stddev-sans-first:1.30 15:09:25 INFO - PID 2984 | Values: 82.0 83.0 81.0 83.0 81.0 81.0 82.0 85.0 82.0 82.0 15:09:25 INFO - PID 2984 | 15:09:25 INFO - PID 2984 | [#11] stanford-crypto-ccm Cycles:10 Average:144.00 Median:138.50 stddev:24.16 (17.4%) stddev-sans-first:25.49 15:09:25 INFO - PID 2984 | Values: 137.0 135.0 136.0 135.0 133.0 140.0 141.0 123.0 210.0 150.0 15:09:25 INFO - PID 2984 | 15:09:25 INFO - PID 2984 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:194.50 Median:185.00 stddev:19.88 (10.7%) stddev-sans-first:16.72 15:09:25 INFO - PID 2984 | Values: 229.0 183.0 186.0 208.0 229.0 183.0 181.0 180.0 184.0 182.0 15:09:25 INFO - PID 2984 | 15:09:25 INFO - PID 2984 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.40 Median:67.00 stddev:5.78 (8.6%) stddev-sans-first:6.10 15:09:25 INFO - PID 2984 | Values: 69.0 67.0 67.0 67.0 67.0 66.0 65.0 59.0 65.0 82.0 15:09:25 INFO - PID 2984 | -------- Summary: end -------- 15:09:25 INFO - PID 2984 | 15:09:26 INFO - TEST-INFO | 2984: exit 0 15:09:26 INFO - TEST-OK | kraken | took 59931ms 15:09:26 INFO - SUITE-END | took 1179s 15:09:26 INFO - Completed test suite (00:19:39) 15:09:26 INFO - javascript benchmark 15:09:26 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7515.68875914076, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3041.4677431449495, 3406.6593340665922, 3430.0, 3450.4306789379752, 3458.67142621451, 5715.371309155989, 5853.268341457318, 5906.970465147673, 5920.555958303123, 5938.376470470598, 5785.0, 5838.0, 5842.88314233715, 5850.531957443405, 5874.060150375941, 5092.949070509303, 5114.130597798365, 5123.871903202413, 5143.12566863634, 5147.922781158286, 5268.710220937847, 5271.65734227274, 5279.70961597112, 5292.550133238679, 5297.761600727977, 5252.553532949704, 5263.078961181797, 5275.657082289658, 5275.657082289658, 5281.5774738021055, 5251.474852514756, 5254.8686282842855, 5255.947440525584, 5268.104422248227, 5268.473152684739, 5063.924041139349, 5191.247269145991, 5198.454162312941, 5198.844034678966, 5202.609804264657, 5936.109583562457, 5996.790112346047, 5998.550108741862, 6035.366286539894, 6048.637081775108, 6288.434040936292, 6317.810465686037, 6327.367263273682, 6346.238451385817, 6352.0, 5290.735463226823, 6290.591111577733, 6336.334684858116, 6358.205224346957, 6441.871162576722, 6042.123892035718, 6053.969730151365, 6075.3924607539775, 6077.604955677911, 6110.511159107261, 6507.089007538948, 6544.476441884638, 6556.24603170632, 6568.146141001735, 6587.374199451045, 6134.142567655225, 6246.656433896089, 6271.62370257786, 6281.623102613767, 6315.179026726588, 6307.180066591313, 6330.968345158245, 6341.524385671001, 6373.649449280241, 6429.903551446732, 5222.712750798667, 6277.466415354655, 6285.120083188357, 6320.336364681642, 6322.146510221153, 6247.094171345129, 6310.747570097236, 6313.779017734358, 6314.463270622048, 6319.178506794179, 4362.411074504965, 5003.5997120230295, 5123.0, 5128.974355128201, 5148.124818780816, 3960.0099975006246, 4517.93828450314, 4518.525554816762, 4611.308303754397, 4626.352310676508, 6086.573939824169, 6103.428585615297, 6112.381899552462, 6128.361498531508, 6148.692565371803, 6360.840978975562, 6383.106365108889, 6406.6476343800605, 6411.551191416649, 6436.227652681709, 5723.513501352431, 6098.817035488943, 6100.633961962297, 6109.541784366191, 6119.816405507842, 5468.781248750005, 5549.250851135126, 5553.222548843165, 5555.805546805843, 5558.805441809598, 6442.130312407765, 6477.125588045554, 6482.67586620667, 6487.22153341602, 6497.090407342881, 5880.647161170344, 6356.173697419306, 6427.775027874002, 6430.324815894451, 6435.2599451063725, 6583.572067815577, 6599.9010014848855, 6601.075849381186, 6601.9009714853355, 6605.867882642224, 6219.813405597839, 6288.654124023223, 6309.179806625109, 6316.873662526632, 6326.0, 6245.437910588069, 6252.624842509464, 6340.397662222082, 6364.172657554488, 6374.171357723466, 6251.499880009497, 6255.53083518747, 6282.3089460158435, 6294.276158241678, 6294.779682711083, 6278.121063051085, 6279.434850863444, 6291.96854015727, 6293.244810622755, 6307.274663413767, 6064.605800622945, 6123.160610921377, 6237.438630523275, 6241.407066328691, 6251.124842522141, 6215.906761398673, 6231.906521402092, 6253.405926436982, 6284.905726414016, 6290.339514350927, 6166.660833654192, 6174.845628859314, 6180.6600636965395, 6195.132681424694, 6195.566310358412], "value": 5828.729853901471, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [781.2968328504345, 790.5019837502374, 1087.1085709718034, 1106.6458733205377, 1118.3457677258803, 31043.068707938768, 31133.0, 34001.65998340019, 34064.65935340646, 34072.63709451625, 29053.273668158254, 35844.82077589615, 35976.82011589938, 36140.096497587576, 36151.638483615156, 8059.476134051297, 8180.713675021376, 8209.876851847228, 8217.34261259098, 8217.465864718804, 5121.436641969399, 5914.97042514789, 7282.235365286648, 8140.226678465537, 8146.674133034701, 8194.01671799382, 8230.300424463927, 8242.299404550591, 8246.216609422096, 8283.875741863876, 8146.674133034671, 8183.468074575193, 8186.918130818675, 8191.344692424592, 8207.58962051898, 5783.21926539916, 5888.941110588882, 7921.881171782428, 8039.397045221632, 8068.596570171468, 34183.31633367319, 34465.0, 34600.134996625035, 34605.0, 34669.826650866584, 3431.3995050866247, 3438.1748380388526, 3503.7372197085065, 3507.140750516114, 3543.9114022149392, 28613.57079643807, 28726.712732872613, 28758.856205719047, 28771.0, 28802.855985720147, 3038.4075105354464, 3098.2873938994157, 3130.3895740330645, 3149.133988153253, 3149.6220453545493, 292589.0, 293846.0, 293962.5905611439, 294125.0, 295166.0, 37777.0, 46916.2962555562, 47050.0, 47132.293015604795, 47177.76411117991, 38325.808370957966, 38339.80830095831, 38342.23315533674, 38366.424503632465, 38398.0, 12875.678108047186, 12980.286084265455, 13024.283664398548, 13025.934870325587, 13029.15310504814, 10831.67504974852, 10832.079273261703, 10832.187585931088, 10834.0, 10846.403447810288, 11551.88448115508, 11556.653300401002, 11699.415029248503, 11809.940950295193, 11868.050555955502, 4908.975276254111, 4913.926291105637, 4946.356973593482, 4970.2793095001025, 4984.750762461862, 789.9802504937422, 903.7257466971539, 922.5295099499169, 982.6658935961807, 1011.7925825205815, 21220.575588488453, 22756.772432275797, 23142.884285578464, 23168.65247021296, 23266.185683501335, 1073.9409332486632, 1081.6646839479786, 1112.4604566785104, 1113.2763703592568, 1126.1722633864103, 10127.139193168663, 10461.738456538495, 10561.577536898592, 11690.0, 12132.393380330948, 294293.0, 294374.0, 295187.0, 295277.0, 295307.0, 49694.0, 49769.75115124474, 49791.25313120306, 49799.75100124476, 49940.50178494651, 1432.734944035336, 1441.8486058963658, 1509.222750283605, 1517.5143953934635, 1527.5722797616686, 16831.158442078093, 21144.154233830475, 21784.67322990188, 22560.435989100402, 22642.094316227165, 36461.0, 36603.450948235266, 36614.08464788402, 36618.63381366152, 36730.26539469249, 284473.0, 285972.0, 286565.0, 286892.0, 286899.5655021712, 27752.306192344546, 27795.722042779315, 29170.124896253146, 32619.510707339883, 32770.52532635989, 38684.41973370346, 40762.79618601888, 40827.1834563313, 40863.16115774738, 40872.59127408688, 23010.309690709306, 26725.331866703487, 27483.17550473489, 27591.172264832087, 29003.0, 28151.71848281491, 28202.85898570494, 29119.0, 29277.414451711273, 29305.560416594195, 34193.829030854686, 34323.828380857936, 34340.14149646279, 34358.82820585881, 34447.0, 28070.71929280681, 28506.57240141358, 29102.27244318824, 29706.702932970395, 29789.70210297956, 20094.894780787195, 20146.10494989206, 21111.894440528315, 21408.0, 22935.65596516087], "value": 16168.840329395554, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [380.3780818361978, 398.9980050099749, 414.7159195950776, 444.2425664242466, 450.3155204089784, 12683.682907927307, 14607.269636518171, 16142.869999100069, 20904.686429703528, 20973.68539471909, 20545.486362840937, 20783.480412989684, 21520.0, 22040.0, 22442.214522491675, 5798.884022319551, 5967.940320596793, 6105.725242364082, 6186.56694031418, 6192.628442293454, 3628.383174860267, 6105.847353816168, 6160.537959653022, 6167.814965551041, 6168.938310616903, 3636.5090712753836, 3670.44943258512, 3905.8437662493466, 6086.695665216722, 6102.664353460558, 5938.6436813791315, 6096.268447786249, 6104.908426373608, 6110.1140334651445, 6118.418750218722, 5373.704446255454, 5467.58993075517, 5468.453154684539, 5475.835724928219, 5478.506934375925, 26804.19587412381, 27345.86327068372, 27409.862950685118, 27578.5863212052, 27604.171874843785, 2134.4557137929883, 2175.05385157457, 2176.249194028074, 2179.553191595719, 2183.705199798023, 17402.390916317996, 17665.0, 18154.90922545392, 18183.727244091348, 18272.269109235614, 1845.7877344105332, 2067.9040108742392, 2090.696848956908, 2140.283005193262, 2141.828653707715, 139845.3007734965, 144446.0, 156252.0, 162883.0, 166667.0, 11402.184885708348, 11439.313641181388, 11876.584319548774, 12508.437120329607, 12550.246985180918, 16619.6676066478, 16626.418075367303, 16637.0, 16644.417445389354, 16655.16724163811, 8884.289256859436, 9090.181883630505, 9101.453912765255, 9111.863322050174, 9243.491607961492, 8676.132386761274, 8681.609327580274, 8691.652333906573, 8850.73447796567, 8883.555822208864, 7704.691812327556, 7822.0, 7825.647845846951, 7830.530168189927, 7850.603661949327, 2125.242863548062, 2129.902046484212, 2134.8292136629034, 2138.358492452258, 2287.32523905445, 909.1817364371985, 916.3722849847742, 938.4979036215592, 943.834828904939, 960.8627830134477, 20507.58984820295, 21095.68356474654, 21147.577048458945, 21178.894105529373, 21416.143354265656, 952.538019060755, 969.9321047526746, 979.2606582030521, 981.3179840011123, 991.0782971836118, 9384.8592271116, 10587.682369528926, 10687.83968240477, 10748.97884700952, 10790.51442685081, 507103.0, 564369.0, 573641.0, 574039.0, 576939.0, 134147.0, 134889.0, 140361.0, 142617.28691356478, 145373.0, 1138.9202755807014, 1146.3866831245077, 1195.7070517723039, 1196.1985469735298, 1199.9100067494974, 16817.495475135765, 17192.88246263989, 19717.802821971596, 21201.469963251042, 21470.0, 47222.0, 52789.736051319494, 55601.44398555963, 57164.0, 58182.7090864543, 74598.62700686461, 74652.0, 75187.62406187934, 75201.49597008139, 77142.61428693045, 8712.172343627346, 8814.206721394849, 8825.470471771714, 9028.864567031631, 9087.50056240713, 13962.58112256634, 14126.50557230492, 14135.434582616992, 14520.564383068102, 14543.92728036353, 11771.705707357041, 11811.940940295244, 11957.880421195676, 12129.393530323272, 12225.816612750637, 9536.332456727963, 10142.594296228363, 11399.658010259705, 11722.062235021007, 11747.353895535827, 9797.951010244904, 9992.750181245527, 11785.528578856749, 11935.001774405671, 12367.381630918238, 8734.126587341469, 8937.0, 8941.642334306554, 9021.90978090237, 9036.63853445881, 11752.70618234551, 11795.0, 12138.635840924786, 12158.878411215775, 12191.085668574715], "value": 10209.405095167014, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1426.87158155766, 1439.4349547258473, 1464.3556834992603, 1482.8961972661918, 1485.628592851787, 8941.50821704806, 11635.825462618057, 11646.475908584112, 11648.650540483799, 11660.300381977087, 11300.434978251096, 11328.0, 11591.942040289809, 11600.535978560869, 11623.883761162386, 2450.8039356851455, 2494.189388448752, 2496.488219914913, 2500.1374525788574, 2500.312414086132, 2433.0, 2479.1818699829105, 2481.789047930928, 2485.515324511721, 2486.0, 2428.368624157723, 2447.951040979179, 2486.8507889526595, 2490.5018996200833, 2492.9127480538186, 2358.304300231427, 2421.0315873650507, 2445.303088619735, 2449.6815413996064, 2450.779429851313, 2360.492494113774, 2372.37132159976, 2403.146882856578, 2444.779969802726, 2460.1512478194995, 11338.206325557214, 11384.487698053608, 11466.31202127875, 11488.88511114895, 11500.942495287554, 6405.263394709621, 6608.504362172856, 6626.304238055031, 6628.072069910217, 6633.2703402625675, 3869.361555343365, 4192.538820729717, 4197.643200327976, 4220.556841531657, 4315.82736690532, 1218.8049912014123, 1254.91215614907, 1289.8774616411426, 1299.337337957639, 1303.7262174943273, 14964.326605302787, 15317.23413829304, 15336.156511391757, 15950.840491595052, 16114.919425402797, 4910.926336104962, 5025.422076461181, 5033.6224783141415, 5037.3955125384455, 5321.0, 9540.666076687283, 11437.599684011186, 11532.077433805276, 11538.653840384633, 11550.480228389742, 6185.0, 6390.45681117101, 6403.423691867754, 6404.807855764335, 6411.647359395278, 6064.848378790565, 6237.313895471494, 6240.469560087353, 6253.218347706536, 6254.15568898202, 4176.415301857743, 4297.0, 4313.67647426438, 4316.503602085738, 4364.694471387036, 1240.5658019693215, 1245.2092920995053, 1250.7498500299976, 1257.251935098615, 1263.9368031598383, 712.6935417770409, 783.2167832167833, 790.3321693169263, 800.1438460846838, 803.7669075968021, 6503.926852069356, 6907.585544867324, 6971.024056632075, 7170.569765814068, 7233.287517181057, 786.2177133751953, 910.1671970147373, 924.6162842420455, 926.749777560055, 929.4702019848621, 4253.404523366731, 4263.786810659454, 4306.440162778881, 4407.162639098561, 4424.469063712375, 14683.48607798722, 15026.023308484913, 15174.393024279127, 15203.0, 15246.3901443941, 14319.57041288763, 15073.170975596448, 15074.773878391832, 15107.0, 15179.089254644756, 8391.286740627114, 8580.270676992526, 8593.613287402208, 8603.784905377415, 8623.887147648866, 6323.715432805627, 6674.365935236339, 6750.8649827004165, 6888.621125838127, 6922.480813938874, 12923.741525169631, 13418.731625367243, 13511.391987360395, 13629.0, 13659.317034148055, 11508.59719909799, 12196.146269761031, 12523.812142818048, 13333.266670333225, 13634.0, 8885.333599979898, 9142.177204051668, 9266.02706715812, 9266.21237194846, 9266.583003764981, 10433.530491127765, 10462.947685261524, 10543.472826358498, 10561.63034293796, 10613.416262105658, 9065.637374504946, 9101.544922753968, 9149.86275205859, 9150.22223111043, 9271.211946984313, 8714.956425217833, 8809.339299552432, 8844.955775221082, 8937.106289371011, 8975.775605609912, 8026.59867006659, 8194.22154895284, 8201.015878094668, 8209.261166495044, 8213.79465513367, 5914.2311499504785, 5965.940340596712, 6096.969515152396, 6130.693465326805, 6175.104609831639, 4744.240921452551, 6029.8191054268445, 6182.505399567934, 6275.0, 6355.983042713144], "value": 5312.98488882712, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [377.4037021506021, 388.6463318380274, 399.33510704676695, 399.424828247324, 401.1895970140317, 4751.762411879406, 5395.649282796616, 5543.501084902358, 5716.971415142919, 5853.41465853415, 5518.91721624175, 5578.219049333096, 5661.2923384576925, 5689.203511508382, 5718.7426565804535, 3562.4656301554846, 4145.875623731278, 4359.215341238576, 4425.889352766175, 4429.889252768674, 3466.73999450041, 3914.2367238388524, 4180.540140584533, 4227.936580951288, 4416.0, 3259.4784834426496, 4179.874603761892, 4182.393552934823, 4193.391958166064, 4206.453161089054, 4105.630493255607, 4107.589241075883, 4187.0, 4210.200061988213, 4210.621044106045, 3603.1352475405974, 3613.2773445311045, 3614.518047600471, 3619.4389869569995, 3622.7645203061716, 5485.588580856452, 5561.5272701820395, 5583.6928968906695, 5589.568537686119, 5612.494875461187, 4650.953490465085, 4839.540243676845, 4878.634102442331, 4901.338319326881, 4924.2367433047575, 4946.975265123688, 5733.627314224562, 5981.730822113015, 6050.818475445743, 6064.757409703607, 3393.9490907636405, 3555.9466608000903, 4051.217464315721, 4053.27041132596, 4076.9184616307507, 6187.350328215518, 6306.432421082125, 6378.234611846562, 6499.707513161872, 6527.412532872065, 3409.761316707856, 3916.8433262669173, 4123.257813593522, 4134.255833949918, 4138.79306034697, 4115.238680844053, 4191.245575796325, 4214.431051808028, 4217.0, 4221.282381995068, 3733.4773131761576, 4102.876913692594, 4838.419389673261, 4904.950950490449, 4948.703077815343, 5624.831255062355, 5853.64878107315, 5881.647221139162, 5913.76344946206, 6020.488258498077, 1352.7970804379422, 1402.621292251107, 1426.9571912842632, 1440.5030264558711, 1444.0180677139647, 800.6117033238875, 817.9059408168018, 912.1425859691868, 950.8668786369914, 952.8618350339162, 5072.594192464594, 5329.653572517774, 5814.534837213013, 5822.854428639318, 5824.155497452845, 5164.3802743671, 5246.868828279324, 6207.037909124093, 6303.432691057735, 6340.904886426707, 5037.773300201499, 5303.7348132593215, 5631.014572449805, 5795.594308398456, 5813.982553053199, 2823.3365159187574, 3014.457397668397, 3203.615566132079, 3288.93422131556, 3303.7687361884437, 5561.916571251434, 5959.1657167995645, 6298.582818865811, 6548.476121910236, 6557.573757705733, 5754.769809207585, 6322.830276398881, 6390.680465976682, 6412.070249813751, 6464.41820236181, 5203.947960520422, 6497.953144759385, 6506.479481641457, 6534.0, 6534.640594767334, 6059.21230240066, 6239.251289845248, 6255.40573645503, 6284.937150628435, 6379.393957574023, 5949.851253718519, 6184.5052395809125, 6188.412100850412, 6217.440430361289, 6229.190205273467, 5242.97378513105, 5383.757730902041, 6214.4407003369915, 6446.548741588043, 6482.643454610042, 3379.9324013520086, 3426.8286585671117, 3627.1838836262687, 3742.821011381406, 4033.91932161361, 5894.351621321737, 6034.0, 6352.36476352361, 6358.745650174126, 6378.45783108441, 5253.317068781187, 5574.721263936706, 5884.793617308356, 6169.827732730767, 6192.225971753531, 5028.647994640486, 5377.704226267593, 6129.233845769279, 6134.294556126102, 6164.352742962104, 5803.535717142534, 5939.2575928009, 5963.582549221513, 6046.334903160737, 6058.878822423615, 4655.86032419028, 5059.316992206005, 5936.367498937654, 6096.329403765671, 6111.633302001894, 5073.365829271341, 5115.4373018967135, 5274.947250527599, 5734.22587950638, 5877.529797616095], "value": 4364.160080867317, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [694.0560837261321, 740.2079774641135, 833.1834801894147, 835.5195762436597, 851.9318454523639, 12621.55824546141, 12913.418896149673, 17368.65262694747, 18519.0, 18544.165512551936, 16600.335986560523, 16651.250693718783, 16659.91670041648, 16692.165391730425, 16699.24853381598, 928.2481190235931, 929.530587053537, 930.167500087423, 930.860370944357, 933.2347475070445, 619.6932518403374, 631.6241836107508, 911.2436677557645, 915.7756349694334, 931.8089791592741, 958.2190514730472, 979.9853002204973, 980.0, 981.7349315684761, 1001.774600714837, 976.3507267666993, 978.0073225675909, 979.2704435195753, 979.9951000245024, 1000.1498726082845, 486.91478991176757, 492.82997365908705, 698.2214830464047, 708.7271400510789, 709.9148102227766, 15789.973651712602, 15883.126428046453, 15904.522864314089, 15918.124503152321, 15919.124448155348, 15946.521604351889, 16419.343226270936, 16687.249073791587, 16790.58023549409, 16869.24088416024, 13349.86650133496, 13519.121257118255, 13656.863431365658, 13661.043726939119, 13666.93166534161, 3125.671804460522, 3313.519539666759, 3350.179206094498, 3358.983205083983, 3379.678930501598, 126459.20375078729, 126913.0, 127091.36454317762, 127377.0, 129027.35486322602, 10733.340307563634, 11648.534058637559, 11778.352190629426, 11854.644360669194, 11876.287422754662, 16125.93924307389, 19696.734875489215, 19704.211831526864, 25168.622470662955, 25211.991520339347, 7644.006279183782, 7654.158042615308, 7797.454178207585, 7938.364930805522, 7967.601619918981, 3935.409688546684, 4004.3192657248337, 4011.7191796574543, 4011.9799401002806, 4012.879613611596, 4229.450171477688, 4295.8281668733525, 4334.956650433518, 4374.825006999748, 4375.431193944767, 866.1857853617579, 888.813349196663, 894.1773568317163, 894.5348418822176, 895.4985208283382, 569.9202111704365, 634.9206349206349, 653.4413076819346, 661.566673828643, 676.4351766275117, 10414.531346089445, 10777.407242601575, 10986.395748233925, 11919.284842909454, 12146.939265303618, 603.3031848215319, 612.5008118383503, 614.9385061493904, 624.5628060357768, 643.7585905285518, 5366.0, 5576.944230557642, 5588.916166257428, 5602.859928501739, 5630.211770352154, 107888.46055769672, 108093.4595327034, 109726.45136774264, 110779.0, 111232.0, 92951.0, 92994.67513312087, 93755.0, 94071.05928940623, 94734.5263273693, 81381.0, 82129.58935205406, 83146.58426707948, 83295.58352208321, 84212.0, 9752.36536228851, 9969.950150249493, 11681.357525336189, 11835.940820295844, 12151.756964860477, 38015.239695206496, 40137.19725605413, 40669.79665101656, 40874.97812554591, 41720.74837754872, 63538.04692929702, 71530.0, 72084.71025832096, 72934.63532682516, 73621.6318918402, 15125.470608528649, 15844.68310633804, 16361.263743131349, 16377.42679006229, 16379.672406551565, 17689.557761055563, 17805.821941780934, 17882.28470861151, 18052.532144575114, 18375.540611484284, 14224.644383890072, 16390.590235244214, 16404.589885252964, 16528.25622846951, 17227.0, 16348.346066157696, 16592.2533485991, 16652.58368540796, 16673.333066677198, 16808.243629036966, 15065.548033559011, 15604.375824966874, 15792.842071579136, 16305.0, 16308.347666093223, 10727.570897164025, 10754.0, 10894.564217431214, 11069.944650276697, 11093.549637670136, 12096.516139354679, 12370.44333005035, 12510.749785004431, 12546.12177147591, 12731.363431828186], "value": 8077.7056156104245, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1425.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 110.0, 110.0, 109.0, 109.0, 111.0, 108.0, 108.0, 108.0, 110.0], "value": 109.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 110.0, 107.0, 150.0, 95.0, 109.0, 128.0, 139.0, 141.0, 111.0], "value": 123.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 183.0, 185.0, 186.0, 187.0, 186.0, 183.0, 188.0, 189.0, 184.0], "value": 185.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 58.0, 58.0, 88.0, 64.0, 58.0, 57.0, 63.0, 78.0, 61.0], "value": 67.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 70.0, 87.0, 69.0, 70.0, 68.0, 87.0, 68.0, 68.0, 90.0], "value": 77.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 88.0, 89.0, 89.0, 88.0, 90.0, 89.0, 90.0, 89.0, 88.0], "value": 89.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 96.0, 95.0, 96.0, 95.0, 96.0, 97.0, 96.0, 95.0], "value": 95.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 87.0, 84.0, 83.0, 83.0, 83.0, 83.0, 84.0, 86.0], "value": 84.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [47.0, 47.0, 48.0, 48.0, 47.0, 47.0, 48.0, 48.0, 48.0, 47.0], "value": 47.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 53.0, 56.0, 66.0, 69.0, 59.0, 59.0, 59.0, 58.0, 60.0], "value": 59.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 81.0, 83.0, 81.0, 81.0, 82.0, 85.0, 82.0, 82.0], "value": 82.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 135.0, 136.0, 135.0, 133.0, 140.0, 141.0, 123.0, 210.0, 150.0], "value": 144.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [229.0, 183.0, 186.0, 208.0, 229.0, 183.0, 181.0, 180.0, 184.0, 182.0], "value": 194.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 67.0, 67.0, 67.0, 67.0, 66.0, 65.0, 59.0, 65.0, 82.0], "value": 67.4, "unit": "score"}]}]} 15:09:26 INFO - Return code: 0 15:09:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:09:26 ERROR - # TBPL SUCCESS # 15:09:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:09:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:09:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:09:26 INFO - 2017-05-01 15:09:26.994 screenresolution[2989:45994] starting screenresolution argv=screenresolution get 15:09:26 INFO - 2017-05-01 15:09:26.997 screenresolution[2989:45994] Display 0: 1600x1200x32@60 15:09:27 INFO - 2017-05-01 15:09:27.003 screenresolution[2990:45997] starting screenresolution argv=screenresolution list 15:09:27 INFO - Available Modes on Display 0 15:09:27 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:09:27 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:09:27 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:09:27 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:09:27 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:09:27 INFO - Intel Iris: 15:09:27 INFO - Chipset Model: Intel Iris 15:09:27 INFO - Type: GPU 15:09:27 INFO - Bus: Built-In 15:09:27 INFO - VRAM (Dynamic, Max): 1536 MB 15:09:27 INFO - Vendor: Intel (0x8086) 15:09:27 INFO - Device ID: 0x0a2e 15:09:27 INFO - Revision ID: 0x0009 15:09:27 INFO - Displays: 15:09:27 INFO - Display: 15:09:27 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:09:27 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:09:27 INFO - Display Serial Number: 1600x1200 60 15:09:27 INFO - Main Display: Yes 15:09:27 INFO - Mirror: Off 15:09:27 INFO - Online: Yes 15:09:27 INFO - Rotation: Supported 15:09:27 INFO - Return code: 0 15:09:27 INFO - Running post-action listener: _resource_record_post_action 15:09:27 INFO - [mozharness: 2017-05-01 22:09:27.253879Z] Finished run-tests step (success) 15:09:27 INFO - Running post-run listener: _resource_record_post_run 15:09:27 INFO - Total resource usage - Wall time: 1201s; CPU: 53.0%; Read bytes: 57856; Write bytes: 509029888; Read time: 1; Write time: 1606 15:09:27 INFO - TinderboxPrint: CPU usage
53.0% 15:09:27 INFO - TinderboxPrint: I/O read bytes / time
57,856 / 1 15:09:27 INFO - TinderboxPrint: I/O write bytes / time
509,029,888 / 1,606 15:09:27 INFO - TinderboxPrint: CPU idle
2,257.0 (47.0%) 15:09:27 INFO - TinderboxPrint: CPU system
954.3 (19.9%) 15:09:27 INFO - TinderboxPrint: CPU user
1,594.4 (33.2%) 15:09:27 INFO - TinderboxPrint: Swap in / out
208,125,952 / 0 15:09:27 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 201081344; Write bytes: 196470784; Read time: 16382; Write time: 449 15:09:27 INFO - run-tests - Wall time: 1181s; CPU: 53.0%; Read bytes: 57856; Write bytes: 295900672; Read time: 1; Write time: 1107 15:09:27 INFO - Running post-run listener: _upload_blobber_files 15:09:27 INFO - Blob upload gear active. 15:09:27 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:09:27 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 15:09:27 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'] 15:09:27 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 15:09:28 INFO - (blobuploader) - INFO - Open directory for files ... 15:09:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:09:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:09:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:09:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:09:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:09:29 INFO - (blobuploader) - INFO - Done attempting. 15:09:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:09:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:09:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:09:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:09:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:09:30 INFO - (blobuploader) - INFO - Done attempting. 15:09:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 15:09:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:09:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:09:31 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 15:09:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:09:31 INFO - (blobuploader) - INFO - Done attempting. 15:09:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:09:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:09:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:09:33 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:09:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:09:33 INFO - (blobuploader) - INFO - Done attempting. 15:09:33 INFO - (blobuploader) - INFO - Iteration through files over. 15:09:33 INFO - Return code: 0 15:09:33 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:09:33 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:09:33 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/d16e01b82812c4d6b5cf1f126e9936c72fc6b68584640c47e713bb5e9f7f3d223259170877fb5c4c78478b1b72d021b1be58a97a1dbb1913f3552f72c8d6835c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/16f6d6a481d4cf82f2bf0bf60e0686d57470f3a9c8b25d974ddc50615838054b6a2fb801cc978458c038982edbc8a712e9184d5e937a95a527d0164c7e5a5850", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/3b470ac2517ee4d0cd44ee152fa31bec7951f5822bbeb6caca6d470ecf912519e725f656a7671c4a5f6551815639ba0ed61e223d3fefdf0aa7f28353f1c87249", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/a478cfc641d84039c6cb57b87afe7d13431f4d91173293c760d6644c419bb29ac0db59e6525cc856b88049c79e6818fd0dbead475b29887820b5a98162757412"} 15:09:33 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:09:33 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:09:33 INFO - Contents: 15:09:33 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/d16e01b82812c4d6b5cf1f126e9936c72fc6b68584640c47e713bb5e9f7f3d223259170877fb5c4c78478b1b72d021b1be58a97a1dbb1913f3552f72c8d6835c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/16f6d6a481d4cf82f2bf0bf60e0686d57470f3a9c8b25d974ddc50615838054b6a2fb801cc978458c038982edbc8a712e9184d5e937a95a527d0164c7e5a5850", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/3b470ac2517ee4d0cd44ee152fa31bec7951f5822bbeb6caca6d470ecf912519e725f656a7671c4a5f6551815639ba0ed61e223d3fefdf0aa7f28353f1c87249", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/a478cfc641d84039c6cb57b87afe7d13431f4d91173293c760d6644c419bb29ac0db59e6525cc856b88049c79e6818fd0dbead475b29887820b5a98162757412"} 15:09:33 INFO - Running post-run listener: copy_logs_to_upload_dir 15:09:33 INFO - Copying logs to upload dir... 15:09:33 INFO - mkdir: /builds/slave/test/build/upload/logs 15:09:33 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1283.762070 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 23 secs) (at 2017-05-01 15:09:33.559396) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-01 15:09:33.563203) ========= 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.HRXgfRXAjp/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.vbyL1zMO4g/Listeners TMPDIR=/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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/d16e01b82812c4d6b5cf1f126e9936c72fc6b68584640c47e713bb5e9f7f3d223259170877fb5c4c78478b1b72d021b1be58a97a1dbb1913f3552f72c8d6835c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/16f6d6a481d4cf82f2bf0bf60e0686d57470f3a9c8b25d974ddc50615838054b6a2fb801cc978458c038982edbc8a712e9184d5e937a95a527d0164c7e5a5850", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/3b470ac2517ee4d0cd44ee152fa31bec7951f5822bbeb6caca6d470ecf912519e725f656a7671c4a5f6551815639ba0ed61e223d3fefdf0aa7f28353f1c87249", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/a478cfc641d84039c6cb57b87afe7d13431f4d91173293c760d6644c419bb29ac0db59e6525cc856b88049c79e6818fd0dbead475b29887820b5a98162757412"} build_url:https://queue.taskcluster.net/v1/task/ECfBHkqaSWanuC2p3cKhfA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011040 build_url: 'https://queue.taskcluster.net/v1/task/ECfBHkqaSWanuC2p3cKhfA/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/d16e01b82812c4d6b5cf1f126e9936c72fc6b68584640c47e713bb5e9f7f3d223259170877fb5c4c78478b1b72d021b1be58a97a1dbb1913f3552f72c8d6835c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/16f6d6a481d4cf82f2bf0bf60e0686d57470f3a9c8b25d974ddc50615838054b6a2fb801cc978458c038982edbc8a712e9184d5e937a95a527d0164c7e5a5850", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/3b470ac2517ee4d0cd44ee152fa31bec7951f5822bbeb6caca6d470ecf912519e725f656a7671c4a5f6551815639ba0ed61e223d3fefdf0aa7f28353f1c87249", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/a478cfc641d84039c6cb57b87afe7d13431f4d91173293c760d6644c419bb29ac0db59e6525cc856b88049c79e6818fd0dbead475b29887820b5a98162757412"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-01 15:09:33.597292) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-01 15:09:33.597611) ========= 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.HRXgfRXAjp/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.vbyL1zMO4g/Listeners TMPDIR=/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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.005176 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-01 15:09:33.646488) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-01 15:09:33.646813) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-01 15:09:33.647122) ========= ========= Total master_lag: 0.18 =========