builder: pine_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0249 starttime: 1492720214.42 results: success (0) buildid: 20170420115229 builduid: f8075253126b4866a51d0f9e83a91734 revision: 5d6e88c784ca72dd35b6f0a15701d867f8bd0061 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-20 13:30:14.415658) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-20 13:30:14.416106) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-20 13:30:14.417194) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-20 13:30:14.444452) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-20 13:30:14.444848) ========= 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.yF94n2Rvlo/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.O85HvhwTiN/Listeners TMPDIR=/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-04-20 13:30:14-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 58.4M=0s 2017-04-20 13:30:14 (58.4 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.149721 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-20 13:30:14.619080) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-20 13:30:14.619399) ========= 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.yF94n2Rvlo/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.O85HvhwTiN/Listeners TMPDIR=/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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.027281 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-20 13:30:14.668826) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-20 13:30:14.669226) ========= bash -c 'python archiver_client.py mozharness --repo projects/pine --rev 5d6e88c784ca72dd35b6f0a15701d867f8bd0061 --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 5d6e88c784ca72dd35b6f0a15701d867f8bd0061 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.yF94n2Rvlo/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.O85HvhwTiN/Listeners TMPDIR=/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-04-20 13:30:14,776 truncating revision to first 12 chars 2017-04-20 13:30:14,776 Setting DEBUG logging. 2017-04-20 13:30:14,776 attempt 1/10 2017-04-20 13:30:14,776 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/pine/5d6e88c784ca?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-20 13:30:15,751 unpacking tar archive at: pine-5d6e88c784ca/testing/mozharness/ program finished with exit code 0 elapsedTime=1.255226 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-20 13:30:15.948159) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-20 13:30:15.948480) ========= script_repo_revision: 5d6e88c784ca72dd35b6f0a15701d867f8bd0061 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-20 13:30:15.948888) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-20 13:30:15.949153) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-20 13:30:15.968731) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 20 secs) (at 2017-04-20 13:30:15.969090) ========= /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.yF94n2Rvlo/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.O85HvhwTiN/Listeners TMPDIR=/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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 13:30:16 INFO - MultiFileLogger online at 20170420 13:30:16 in /builds/slave/test 13:30:16 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 13:30:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:30:16 INFO - {'append_to_log': False, 13:30:16 INFO - 'base_work_dir': '/builds/slave/test', 13:30:16 INFO - 'blob_upload_branch': 'Pine', 13:30:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:30:16 INFO - 'branch': 'Pine', 13:30:16 INFO - 'buildbot_json_path': 'buildprops.json', 13:30:16 INFO - 'config_files': ('talos/mac_config.py',), 13:30:16 INFO - 'default_actions': ('clobber', 13:30:16 INFO - 'read-buildbot-config', 13:30:16 INFO - 'download-and-extract', 13:30:16 INFO - 'populate-webroot', 13:30:16 INFO - 'create-virtualenv', 13:30:16 INFO - 'install', 13:30:16 INFO - 'run-tests'), 13:30:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:30:16 INFO - 'download_minidump_stackwalk': True, 13:30:16 INFO - 'download_symbols': 'ondemand', 13:30:16 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:30:16 INFO - 'tooltool.py': '/tools/tooltool.py', 13:30:16 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:30:16 INFO - '/tools/misc-python/virtualenv.py')}, 13:30:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:30:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:30:16 INFO - 'gecko_profile': False, 13:30:16 INFO - 'gecko_profile_interval': 0, 13:30:16 INFO - 'installer_path': 'installer.exe', 13:30:16 INFO - 'log_level': 'info', 13:30:16 INFO - 'log_name': 'talos', 13:30:16 INFO - 'log_to_console': True, 13:30:16 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 13:30:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 13:30:16 INFO - 'opt_config_files': (), 13:30:16 INFO - 'pip_index': False, 13:30:16 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:30:16 INFO - 'cmd': ('bash', 13:30:16 INFO - '-c', 13:30:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:30:16 INFO - 'enabled': True, 13:30:16 INFO - 'halt_on_failure': False, 13:30:16 INFO - 'name': 'check_screen_resolution'},), 13:30:16 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:30:16 INFO - 'cmd': ('bash', 13:30:16 INFO - '-c', 13:30:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:30:16 INFO - 'enabled': True, 13:30:16 INFO - 'halt_on_failure': False, 13:30:16 INFO - 'name': 'check_screen_resolution'},), 13:30:16 INFO - 'run_cmd_checks_enabled': True, 13:30:16 INFO - 'suite': 'dromaeojs', 13:30:16 INFO - 'system_bits': '32', 13:30:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:30:16 INFO - 'title': 't-yosemite-r7-0249', 13:30:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:30:16 INFO - 'use_talos_json': True, 13:30:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:30:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:30:16 INFO - 'work_dir': 'build'} 13:30:16 INFO - [mozharness: 2017-04-20 20:30:16.184402Z] Running clobber step. 13:30:16 INFO - Running pre-action listener: _resource_record_pre_action 13:30:16 INFO - Running main action method: clobber 13:30:16 INFO - rmtree: /builds/slave/test/build 13:30:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:30:18 INFO - Running post-action listener: _resource_record_post_action 13:30:18 INFO - [mozharness: 2017-04-20 20:30:18.624713Z] Finished clobber step (success) 13:30:18 INFO - [mozharness: 2017-04-20 20:30:18.624844Z] Running read-buildbot-config step. 13:30:18 INFO - Running pre-action listener: _resource_record_pre_action 13:30:18 INFO - Running main action method: read_buildbot_config 13:30:18 INFO - Using buildbot properties: 13:30:18 INFO - { 13:30:18 INFO - "project": "", 13:30:18 INFO - "product": "firefox", 13:30:18 INFO - "script_repo_revision": "default", 13:30:18 INFO - "builddir": "pine_yosemite_r7_test-dromaeojs", 13:30:18 INFO - "repository": "", 13:30:18 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 pine talos dromaeojs", 13:30:18 INFO - "buildid": "20170420115229", 13:30:18 INFO - "pgo_build": "False", 13:30:18 INFO - "basedir": "/builds/slave/test", 13:30:18 INFO - "buildnumber": 1, 13:30:18 INFO - "slavename": "t-yosemite-r7-0249", 13:30:18 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 13:30:18 INFO - "platform": "yosemite_r7", 13:30:18 INFO - "scheduler": "tests-pine-macosx64-talos", 13:30:18 INFO - "branch": "pine", 13:30:18 INFO - "repo_path": "projects/pine", 13:30:18 INFO - "revision": "5d6e88c784ca72dd35b6f0a15701d867f8bd0061", 13:30:18 INFO - "stage_platform": "macosx64", 13:30:18 INFO - "builduid": "f8075253126b4866a51d0f9e83a91734", 13:30:18 INFO - "slavebuilddir": "test" 13:30:18 INFO - } 13:30:18 INFO - Found installer url https://queue.taskcluster.net/v1/task/BrJzbkJSSd-UcMXKoTmvoQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 13:30:18 INFO - Running post-action listener: _resource_record_post_action 13:30:18 INFO - [mozharness: 2017-04-20 20:30:18.628054Z] Finished read-buildbot-config step (success) 13:30:18 INFO - [mozharness: 2017-04-20 20:30:18.628196Z] Running download-and-extract step. 13:30:18 INFO - Running pre-action listener: _resource_record_pre_action 13:30:18 INFO - Running main action method: download_and_extract 13:30:18 INFO - mkdir: /builds/slave/test/build/tests 13:30:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:30:18 INFO - trying https://queue.taskcluster.net/v1/task/BrJzbkJSSd-UcMXKoTmvoQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 13:30:18 INFO - Downloading https://queue.taskcluster.net/v1/task/BrJzbkJSSd-UcMXKoTmvoQ/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 13:30:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BrJzbkJSSd-UcMXKoTmvoQ/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 13:30:19 INFO - Downloaded 1325 bytes. 13:30:19 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 13:30:19 INFO - Using the following test package requirements: 13:30:19 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 13:30:19 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 13:30:19 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 13:30:19 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 13:30:19 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 13:30:19 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 13:30:19 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 13:30:19 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 13:30:19 INFO - u'jsshell-mac.zip'], 13:30:19 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 13:30:19 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 13:30:19 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 13:30:19 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 13:30:19 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 13:30:19 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 13:30:19 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 13:30:19 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 13:30:19 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 13:30:19 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 13:30:19 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 13:30:19 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 13:30:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BrJzbkJSSd-UcMXKoTmvoQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 13:30:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BrJzbkJSSd-UcMXKoTmvoQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 13:30:19 INFO - Fetch https://queue.taskcluster.net/v1/task/BrJzbkJSSd-UcMXKoTmvoQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 13:30:21 INFO - Content-Length response header: 52521679 13:30:21 INFO - Bytes received: 52521679 13:30:37 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 13:30:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BrJzbkJSSd-UcMXKoTmvoQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 13:30:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BrJzbkJSSd-UcMXKoTmvoQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 13:30:37 INFO - Fetch https://queue.taskcluster.net/v1/task/BrJzbkJSSd-UcMXKoTmvoQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 13:30:39 INFO - Content-Length response header: 52521679 13:30:39 INFO - Bytes received: 52521679 13:30:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BrJzbkJSSd-UcMXKoTmvoQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 13:30:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BrJzbkJSSd-UcMXKoTmvoQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 13:30:54 INFO - Fetch https://queue.taskcluster.net/v1/task/BrJzbkJSSd-UcMXKoTmvoQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 13:30:55 INFO - Content-Length response header: 14408811 13:30:55 INFO - Bytes received: 14408811 13:30:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:30:55 INFO - trying https://queue.taskcluster.net/v1/task/BrJzbkJSSd-UcMXKoTmvoQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 13:30:55 INFO - Downloading https://queue.taskcluster.net/v1/task/BrJzbkJSSd-UcMXKoTmvoQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 13:30:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BrJzbkJSSd-UcMXKoTmvoQ/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 13:30:57 INFO - Downloaded 71093256 bytes. 13:30:57 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/BrJzbkJSSd-UcMXKoTmvoQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 13:30:57 INFO - mkdir: /builds/slave/test/properties 13:30:57 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:30:57 INFO - Writing to file /builds/slave/test/properties/build_url 13:30:57 INFO - Contents: 13:30:57 INFO - build_url:https://queue.taskcluster.net/v1/task/BrJzbkJSSd-UcMXKoTmvoQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 13:30:58 INFO - Running post-action listener: _resource_record_post_action 13:30:58 INFO - Running post-action listener: set_extra_try_arguments 13:30:58 INFO - [mozharness: 2017-04-20 20:30:58.478553Z] Finished download-and-extract step (success) 13:30:58 INFO - [mozharness: 2017-04-20 20:30:58.478695Z] Running populate-webroot step. 13:30:58 INFO - Running pre-action listener: _resource_record_pre_action 13:30:58 INFO - Running main action method: populate_webroot 13:30:58 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:30:58 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:30:58 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:30:58 INFO - '--tppagecycles', 13:30:58 INFO - '7'], 13:30:58 INFO - 'tests': ['tsvgm']}}, 13:30:58 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:30:58 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:30:58 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:30:58 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:30:58 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:30:58 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:30:58 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 13:30:58 INFO - 'talos_options': ['--disable-e10s'], 13:30:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:30:58 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 13:30:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:30:58 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 13:30:58 INFO - 'talos_options': ['--disable-e10s'], 13:30:58 INFO - 'tests': ['damp', 'tps']}, 13:30:58 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 13:30:58 INFO - 'tests': ['damp', 'tps']}, 13:30:58 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:30:58 INFO - 'tests': ['dromaeo_dom']}, 13:30:58 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:30:58 INFO - 'g4': {'talos_options': ['--disable-e10s'], 13:30:58 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 13:30:58 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 13:30:58 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:30:58 INFO - 'tests': ['a11yr', 13:30:58 INFO - 'ts_paint', 13:30:58 INFO - 'tpaint', 13:30:58 INFO - 'sessionrestore', 13:30:58 INFO - 'sessionrestore_no_auto_restore', 13:30:58 INFO - 'tabpaint']}, 13:30:58 INFO - 'other-e10s': {'tests': ['a11yr', 13:30:58 INFO - 'ts_paint', 13:30:58 INFO - 'tpaint', 13:30:58 INFO - 'sessionrestore', 13:30:58 INFO - 'sessionrestore_no_auto_restore', 13:30:58 INFO - 'tabpaint']}, 13:30:58 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:30:58 INFO - 'ts_paint', 13:30:58 INFO - 'tpaint', 13:30:58 INFO - 'sessionrestore', 13:30:58 INFO - 'sessionrestore_no_auto_restore', 13:30:58 INFO - 'tabpaint']}, 13:30:58 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:30:58 INFO - 'ts_paint', 13:30:58 INFO - 'tpaint', 13:30:58 INFO - 'sessionrestore', 13:30:58 INFO - 'sessionrestore_no_auto_restore', 13:30:58 INFO - 'tabpaint']}, 13:30:58 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:30:58 INFO - 'tests': ['a11yr', 13:30:58 INFO - 'ts_paint', 13:30:58 INFO - 'tpaint', 13:30:58 INFO - 'sessionrestore', 13:30:58 INFO - 'sessionrestore_no_auto_restore', 13:30:58 INFO - 'tabpaint']}, 13:30:58 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:30:58 INFO - 'tests': ['a11yr', 13:30:58 INFO - 'ts_paint', 13:30:58 INFO - 'tpaint', 13:30:58 INFO - 'sessionrestore', 13:30:58 INFO - 'sessionrestore_no_auto_restore', 13:30:58 INFO - 'tabpaint']}, 13:30:58 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 13:30:58 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 13:30:58 INFO - 'bloom_basic_ref']}, 13:30:58 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:30:58 INFO - 'tests': ['tsvgx', 13:30:58 INFO - 'tsvgr_opacity', 13:30:58 INFO - 'tart', 13:30:58 INFO - 'tscrollx', 13:30:58 INFO - 'cart', 13:30:58 INFO - 'tsvg_static']}, 13:30:58 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:30:58 INFO - 'tsvgr_opacity', 13:30:58 INFO - 'tart', 13:30:58 INFO - 'tscrollx', 13:30:58 INFO - 'cart', 13:30:58 INFO - 'tsvg_static']}, 13:30:58 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 13:30:58 INFO - 'talos_options': ['--disable-e10s'], 13:30:58 INFO - 'tests': ['tp5o']}, 13:30:58 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 13:30:58 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 13:30:58 INFO - 'talos_options': ['--disable-e10s', 13:30:58 INFO - '--xperf_path', 13:30:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:30:58 INFO - 'tests': ['tp5n']}, 13:30:58 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 13:30:58 INFO - 'talos_options': ['--xperf_path', 13:30:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:30:58 INFO - 'tests': ['tp5n']}}, 13:30:58 INFO - 'talos.zip': {'path': '', 13:30:58 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:30:58 INFO - Running post-action listener: _resource_record_post_action 13:30:58 INFO - [mozharness: 2017-04-20 20:30:58.497696Z] Finished populate-webroot step (success) 13:30:58 INFO - [mozharness: 2017-04-20 20:30:58.497830Z] Running create-virtualenv step. 13:30:58 INFO - Running pre-action listener: _resource_record_pre_action 13:30:58 INFO - Running main action method: create_virtualenv 13:30:58 INFO - Creating virtualenv /builds/slave/test/build/venv 13:30:58 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 13:30:58 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:30:58 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:30:58 INFO - Using real prefix '/tools/python27' 13:30:58 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:30:59 INFO - Installing distribute.............................................................................................................................................................................................done. 13:31:02 INFO - Installing pip.................done. 13:31:02 INFO - Return code: 0 13:31:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 13:31:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 13:31:02 INFO - Reading from file tmpfile_stdout 13:31:02 INFO - Output received: 13:31:02 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) 13:31:02 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:31: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')]} 13:31:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:31:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:31: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')]} 13:31:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:31:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:31: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109889b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10988c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf90574ff0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109667d30>, '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 0x10958ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10965cbe8>, '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.yF94n2Rvlo/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.O85HvhwTiN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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 13:31: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', 'pip>=1.5'] in /builds/slave/test/build 13:31: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 pip>=1.5 13:31:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yF94n2Rvlo/Render', 13:31:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:31:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:31:02 INFO - 'HOME': '/Users/cltbld', 13:31:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:31:02 INFO - 'LOGNAME': 'cltbld', 13:31:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:31:02 INFO - 'MOZ_NO_REMOTE': '1', 13:31:02 INFO - 'NO_EM_RESTART': '1', 13:31:02 INFO - 'PAGER': '/bin/cat', 13:31:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:31:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:31:02 INFO - 'PWD': '/builds/slave/test', 13:31:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:31:02 INFO - 'SHELL': '/bin/bash', 13:31:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O85HvhwTiN/Listeners', 13:31:02 INFO - 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', 13:31:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:31:02 INFO - 'USER': 'cltbld', 13:31:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:31:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:31:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:31:02 INFO - 'XPC_FLAGS': '0x0', 13:31:02 INFO - 'XPC_SERVICE_NAME': '0', 13:31:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:31:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:31:02 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 13:31:02 INFO - Cleaning up... 13:31:02 INFO - Return code: 0 13:31:02 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 13:31: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')]} 13:31:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:31:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:31: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')]} 13:31:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:31:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:31: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', '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 0x109889b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10988c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf90574ff0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109667d30>, '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 0x10958ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10965cbe8>, '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.yF94n2Rvlo/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.O85HvhwTiN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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 13:31: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', 'psutil>=3.1.1'] in /builds/slave/test/build 13:31: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 psutil>=3.1.1 13:31:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yF94n2Rvlo/Render', 13:31:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:31:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:31:02 INFO - 'HOME': '/Users/cltbld', 13:31:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:31:02 INFO - 'LOGNAME': 'cltbld', 13:31:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:31:02 INFO - 'MOZ_NO_REMOTE': '1', 13:31:02 INFO - 'NO_EM_RESTART': '1', 13:31:02 INFO - 'PAGER': '/bin/cat', 13:31:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:31:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:31:02 INFO - 'PWD': '/builds/slave/test', 13:31:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:31:02 INFO - 'SHELL': '/bin/bash', 13:31:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O85HvhwTiN/Listeners', 13:31:02 INFO - 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', 13:31:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:31:02 INFO - 'USER': 'cltbld', 13:31:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:31:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:31:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:31:02 INFO - 'XPC_FLAGS': '0x0', 13:31:02 INFO - 'XPC_SERVICE_NAME': '0', 13:31:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:31:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:31:02 INFO - Downloading/unpacking psutil>=3.1.1 13:31: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 13:31: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 13:31: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 13:31: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 13:31:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:31:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:31:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:31:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:31:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:31:06 INFO - Installing collected packages: psutil 13:31:06 INFO - Running setup.py install for psutil 13:31:06 INFO - building 'psutil._psutil_osx' extension 13:31:06 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 13:31:07 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 13:31:07 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 13:31:07 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 13:31:07 INFO - building 'psutil._psutil_posix' extension 13:31:07 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 13:31:07 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 13:31:07 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 13:31:07 INFO - ^ 13:31:07 INFO - 1 warning generated. 13:31:07 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 13:31:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:31:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:31:07 INFO - Successfully installed psutil 13:31:07 INFO - Cleaning up... 13:31:07 INFO - Return code: 0 13:31:07 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 13:31:07 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')]} 13:31:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:31:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:31:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:31:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:31:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:31:08 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 0x109889b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10988c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf90574ff0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109667d30>, '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 0x10958ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10965cbe8>, '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.yF94n2Rvlo/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.O85HvhwTiN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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 13:31:08 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 13:31:08 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 13:31:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yF94n2Rvlo/Render', 13:31:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:31:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:31:08 INFO - 'HOME': '/Users/cltbld', 13:31:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:31:08 INFO - 'LOGNAME': 'cltbld', 13:31:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:31:08 INFO - 'MOZ_NO_REMOTE': '1', 13:31:08 INFO - 'NO_EM_RESTART': '1', 13:31:08 INFO - 'PAGER': '/bin/cat', 13:31:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:31:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:31:08 INFO - 'PWD': '/builds/slave/test', 13:31:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:31:08 INFO - 'SHELL': '/bin/bash', 13:31:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O85HvhwTiN/Listeners', 13:31:08 INFO - 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', 13:31:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:31:08 INFO - 'USER': 'cltbld', 13:31:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:31:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:31:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:31:08 INFO - 'XPC_FLAGS': '0x0', 13:31:08 INFO - 'XPC_SERVICE_NAME': '0', 13:31:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:31:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:31:08 INFO - Downloading/unpacking mozsystemmonitor==0.3 13:31:08 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 13:31:08 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 13:31:08 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 13:31:08 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 13:31:08 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 13:31:08 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 13:31:11 INFO - Downloading mozsystemmonitor-0.3.tar.gz 13:31:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:31:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 13:31:11 INFO - Installing collected packages: mozsystemmonitor 13:31:11 INFO - Running setup.py install for mozsystemmonitor 13:31:11 INFO - Successfully installed mozsystemmonitor 13:31:11 INFO - Cleaning up... 13:31:11 INFO - Return code: 0 13:31:11 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 13:31:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:31:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:31:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:31:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:31:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:31:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:31:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x109889b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10988c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf90574ff0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109667d30>, '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 0x10958ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10965cbe8>, '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.yF94n2Rvlo/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.O85HvhwTiN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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 13:31:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 13:31:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 13:31:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yF94n2Rvlo/Render', 13:31:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:31:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:31:11 INFO - 'HOME': '/Users/cltbld', 13:31:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:31:11 INFO - 'LOGNAME': 'cltbld', 13:31:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:31:11 INFO - 'MOZ_NO_REMOTE': '1', 13:31:11 INFO - 'NO_EM_RESTART': '1', 13:31:11 INFO - 'PAGER': '/bin/cat', 13:31:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:31:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:31:11 INFO - 'PWD': '/builds/slave/test', 13:31:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:31:11 INFO - 'SHELL': '/bin/bash', 13:31:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O85HvhwTiN/Listeners', 13:31:11 INFO - 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', 13:31:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:31:11 INFO - 'USER': 'cltbld', 13:31:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:31:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:31:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:31:11 INFO - 'XPC_FLAGS': '0x0', 13:31:11 INFO - 'XPC_SERVICE_NAME': '0', 13:31:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:31:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:31:12 INFO - Downloading/unpacking jsonschema==2.5.1 13:31:12 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 13:31:12 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 13:31:12 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 13:31:12 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 13:31:12 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 13:31:12 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 13:31:14 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:31:14 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 13:31:14 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 13:31:14 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 13:31:14 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 13:31:14 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 13:31:14 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 13:31:14 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 13:31:15 INFO - Downloading functools32-3.2.3-2.tar.gz 13:31:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:31:15 INFO - warning: no files found matching '*.txt' 13:31:15 INFO - no previously-included directories found matching 'build' 13:31:15 INFO - no previously-included directories found matching 'dist' 13:31:15 INFO - no previously-included directories found matching '.git*' 13:31:15 INFO - Installing collected packages: jsonschema, functools32 13:31:15 INFO - Running setup.py install for functools32 13:31:15 INFO - warning: no files found matching '*.txt' 13:31:15 INFO - no previously-included directories found matching 'build' 13:31:15 INFO - no previously-included directories found matching 'dist' 13:31:15 INFO - no previously-included directories found matching '.git*' 13:31:15 INFO - Successfully installed jsonschema functools32 13:31:15 INFO - Cleaning up... 13:31:15 INFO - Return code: 0 13:31:15 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 13:31: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')]} 13:31:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:31:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:31: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')]} 13:31:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:31:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:31:15 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 0x109889b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10988c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf90574ff0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109667d30>, '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 0x10958ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10965cbe8>, '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.yF94n2Rvlo/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.O85HvhwTiN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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 13:31:15 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 13:31:15 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 13:31:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yF94n2Rvlo/Render', 13:31:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:31:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:31:15 INFO - 'HOME': '/Users/cltbld', 13:31:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:31:15 INFO - 'LOGNAME': 'cltbld', 13:31:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:31:15 INFO - 'MOZ_NO_REMOTE': '1', 13:31:15 INFO - 'NO_EM_RESTART': '1', 13:31:15 INFO - 'PAGER': '/bin/cat', 13:31:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:31:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:31:15 INFO - 'PWD': '/builds/slave/test', 13:31:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:31:15 INFO - 'SHELL': '/bin/bash', 13:31:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O85HvhwTiN/Listeners', 13:31:15 INFO - 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', 13:31:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:31:15 INFO - 'USER': 'cltbld', 13:31:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:31:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:31:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:31:15 INFO - 'XPC_FLAGS': '0x0', 13:31:15 INFO - 'XPC_SERVICE_NAME': '0', 13:31:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:31:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:31:15 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 13:31:15 INFO - Cleaning up... 13:31:15 INFO - Return code: 0 13:31:15 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:31: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')]} 13:31:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:31:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:31: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')]} 13:31:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:31:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:31:15 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 0x109889b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10988c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf90574ff0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109667d30>, '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 0x10958ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10965cbe8>, '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.yF94n2Rvlo/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.O85HvhwTiN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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 13:31:15 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 13:31:15 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 13:31:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yF94n2Rvlo/Render', 13:31:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:31:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:31:15 INFO - 'HOME': '/Users/cltbld', 13:31:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:31:15 INFO - 'LOGNAME': 'cltbld', 13:31:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:31:15 INFO - 'MOZ_NO_REMOTE': '1', 13:31:15 INFO - 'NO_EM_RESTART': '1', 13:31:15 INFO - 'PAGER': '/bin/cat', 13:31:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:31:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:31:15 INFO - 'PWD': '/builds/slave/test', 13:31:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:31:15 INFO - 'SHELL': '/bin/bash', 13:31:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O85HvhwTiN/Listeners', 13:31:15 INFO - 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', 13:31:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:31:15 INFO - 'USER': 'cltbld', 13:31:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:31:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:31:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:31:15 INFO - 'XPC_FLAGS': '0x0', 13:31:15 INFO - 'XPC_SERVICE_NAME': '0', 13:31:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:31:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:31:16 INFO - Downloading/unpacking blobuploader==1.2.4 13:31:16 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 13:31:16 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 13:31:16 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 13:31:16 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 13:31:16 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 13:31:16 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 13:31:18 INFO - Downloading blobuploader-1.2.4.tar.gz 13:31:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:31:18 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:31:18 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 13:31:18 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 13:31:18 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 13:31:18 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 13:31:18 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 13:31:18 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 13:31:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:31:19 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:31:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:31:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:31:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:31:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:31:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:31:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:31:19 INFO - Downloading docopt-0.6.1.tar.gz 13:31:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:31:20 INFO - Installing collected packages: blobuploader, requests, docopt 13:31:20 INFO - Running setup.py install for blobuploader 13:31:20 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:31:20 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:31:20 INFO - Running setup.py install for requests 13:31:20 INFO - Running setup.py install for docopt 13:31:20 INFO - Successfully installed blobuploader requests docopt 13:31:20 INFO - Cleaning up... 13:31:20 INFO - Return code: 0 13:31:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:31:20 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')]} 13:31:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:31:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:31:20 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')]} 13:31:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:31:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:31:20 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 0x109889b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10988c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf90574ff0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109667d30>, '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 0x10958ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10965cbe8>, '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.yF94n2Rvlo/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.O85HvhwTiN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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 13:31:20 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 13:31:20 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 13:31:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yF94n2Rvlo/Render', 13:31:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:31:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:31:20 INFO - 'HOME': '/Users/cltbld', 13:31:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:31:20 INFO - 'LOGNAME': 'cltbld', 13:31:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:31:20 INFO - 'MOZ_NO_REMOTE': '1', 13:31:20 INFO - 'NO_EM_RESTART': '1', 13:31:20 INFO - 'PAGER': '/bin/cat', 13:31:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:31:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:31:20 INFO - 'PWD': '/builds/slave/test', 13:31:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:31:20 INFO - 'SHELL': '/bin/bash', 13:31:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O85HvhwTiN/Listeners', 13:31:20 INFO - 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', 13:31:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:31:20 INFO - 'USER': 'cltbld', 13:31:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:31:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:31:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:31:20 INFO - 'XPC_FLAGS': '0x0', 13:31:20 INFO - 'XPC_SERVICE_NAME': '0', 13:31:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:31:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:31:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:31:21 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-h2yrUO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:31:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:31:21 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-t3R_bP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:31:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:31:21 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-smsSAR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:31:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:31:21 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-HqYa3x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:31:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:31:21 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-9y0l56-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:31:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:31:21 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-ICAsRC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:31:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:31:21 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-lmP_DC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:31:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:31:21 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-cY57vU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:31:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:31:22 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-Qe8KTo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:31:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:31:22 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-jqIZeQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:31:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:31:22 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-lmHn5D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:31:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:31:22 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-CtYInV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:31:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:31:22 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-xr4yID-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:31:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:31:22 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-_lNCA_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:31:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:31:22 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-UJSHaw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:31:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:31:22 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-UYAAid-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:31:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:31:22 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-Jq4UwH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:31:22 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:31:22 INFO - Running setup.py install for manifestparser 13:31:23 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:31:23 INFO - Running setup.py install for mozcrash 13:31:23 INFO - Running setup.py install for mozdebug 13:31:23 INFO - Running setup.py install for mozdevice 13:31:23 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:31:23 INFO - Running setup.py install for mozfile 13:31:23 INFO - Running setup.py install for mozhttpd 13:31:23 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:31:23 INFO - Running setup.py install for mozinfo 13:31:23 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:31:23 INFO - Running setup.py install for mozInstall 13:31:24 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:31:24 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:31:24 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:31:24 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:31:24 INFO - Running setup.py install for mozleak 13:31:24 INFO - Running setup.py install for mozlog 13:31:24 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:31:24 INFO - Running setup.py install for moznetwork 13:31:24 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:31:24 INFO - Running setup.py install for mozprocess 13:31:24 INFO - Running setup.py install for mozprofile 13:31:24 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:31:24 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:31:24 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:31:24 INFO - Running setup.py install for mozrunner 13:31:25 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:31:25 INFO - Running setup.py install for mozscreenshot 13:31:25 INFO - Running setup.py install for moztest 13:31:25 INFO - Running setup.py install for mozversion 13:31:25 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:31:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:31:25 INFO - Cleaning up... 13:31:25 INFO - Return code: 0 13:31:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:31: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')]} 13:31:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:31:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:31: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')]} 13:31:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:31:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:31:25 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 0x109889b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10988c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf90574ff0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109667d30>, '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 0x10958ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10965cbe8>, '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.yF94n2Rvlo/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.O85HvhwTiN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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 13:31:25 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 13:31:25 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 13:31:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yF94n2Rvlo/Render', 13:31:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:31:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:31:25 INFO - 'HOME': '/Users/cltbld', 13:31:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:31:25 INFO - 'LOGNAME': 'cltbld', 13:31:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:31:25 INFO - 'MOZ_NO_REMOTE': '1', 13:31:25 INFO - 'NO_EM_RESTART': '1', 13:31:25 INFO - 'PAGER': '/bin/cat', 13:31:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:31:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:31:25 INFO - 'PWD': '/builds/slave/test', 13:31:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:31:25 INFO - 'SHELL': '/bin/bash', 13:31:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O85HvhwTiN/Listeners', 13:31:25 INFO - 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', 13:31:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:31:25 INFO - 'USER': 'cltbld', 13:31:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:31:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:31:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:31:25 INFO - 'XPC_FLAGS': '0x0', 13:31:25 INFO - 'XPC_SERVICE_NAME': '0', 13:31:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:31:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:31:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:31:25 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-Ow2D91-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:31:25 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)) 13:31:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:31:25 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-gG3y27-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:31:25 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)) 13:31:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:31:25 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-qOQ_tn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:31:26 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)) 13:31:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:31:26 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-1TGmjK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:31:26 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)) 13:31:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:31:26 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-uwicgI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:31:26 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)) 13:31:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:31:26 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-vmlzcQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:31:26 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)) 13:31:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:31:26 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-O9z5q6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:31:26 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)) 13:31:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:31:26 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-pBp8uw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:31:26 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)) 13:31:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:31:26 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-00nnUI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:31:26 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)) 13:31:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:31:26 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-L2KxU8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:31:26 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)) 13:31:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:31:26 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-NknFan-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:31:26 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)) 13:31:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:31:26 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-ThBYHf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:31:27 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)) 13:31:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:31:27 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-ea1kIi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:31:27 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)) 13:31:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:31:27 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-CC5vih-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:31:27 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)) 13:31:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:31:27 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-o1HPLx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:31:27 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)) 13:31:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:31:27 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-EvSXpC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:31:27 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)) 13:31:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:31:27 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-Xy8rqH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:31:27 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)) 13:31:27 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)) 13:31:27 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)) 13:31:27 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)) 13:31:27 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)) 13:31:27 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)) 13:31:27 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:31:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:31:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:31:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:31:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:31:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:31:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:31:30 INFO - Downloading blessings-1.6.tar.gz 13:31:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:31:30 INFO - Installing collected packages: blessings 13:31:30 INFO - Running setup.py install for blessings 13:31:30 INFO - Successfully installed blessings 13:31:30 INFO - Cleaning up... 13:31:30 INFO - Return code: 0 13:31:30 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:31:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:31:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:31:30 INFO - Reading from file tmpfile_stdout 13:31:30 INFO - Current package versions: 13:31:30 INFO - blessings == 1.6 13:31:30 INFO - blobuploader == 1.2.4 13:31:30 INFO - docopt == 0.6.1 13:31:30 INFO - functools32 == 3.2.3-2 13:31:30 INFO - jsonschema == 2.5.1 13:31:30 INFO - manifestparser == 1.1 13:31:30 INFO - mozInstall == 1.12 13:31:30 INFO - mozcrash == 1.0 13:31:30 INFO - mozdebug == 0.1 13:31:30 INFO - mozdevice == 0.50 13:31:30 INFO - mozfile == 1.2 13:31:30 INFO - mozhttpd == 0.7 13:31:30 INFO - mozinfo == 0.9 13:31:30 INFO - mozleak == 0.1 13:31:30 INFO - mozlog == 3.4 13:31:30 INFO - moznetwork == 0.27 13:31:30 INFO - mozprocess == 0.25 13:31:30 INFO - mozprofile == 0.28 13:31:30 INFO - mozrunner == 6.13 13:31:30 INFO - mozscreenshot == 0.1 13:31:30 INFO - mozsystemmonitor == 0.3 13:31:30 INFO - moztest == 0.8 13:31:30 INFO - mozversion == 1.4 13:31:30 INFO - psutil == 3.1.1 13:31:30 INFO - requests == 1.2.3 13:31:30 INFO - wsgiref == 0.1.2 13:31:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:31:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:31:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:31:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:31:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:31:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:31:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:31:30 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 0x109889b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10988c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf90574ff0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109667d30>, '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 0x10958ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10965cbe8>, '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.yF94n2Rvlo/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.O85HvhwTiN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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 13:31:30 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 13:31:30 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 13:31:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yF94n2Rvlo/Render', 13:31:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:31:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:31:30 INFO - 'HOME': '/Users/cltbld', 13:31:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:31:30 INFO - 'LOGNAME': 'cltbld', 13:31:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:31:30 INFO - 'MOZ_NO_REMOTE': '1', 13:31:30 INFO - 'NO_EM_RESTART': '1', 13:31:30 INFO - 'PAGER': '/bin/cat', 13:31:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:31:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:31:30 INFO - 'PWD': '/builds/slave/test', 13:31:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:31:30 INFO - 'SHELL': '/bin/bash', 13:31:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O85HvhwTiN/Listeners', 13:31:30 INFO - 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', 13:31:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:31:30 INFO - 'USER': 'cltbld', 13:31:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:31:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:31:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:31:30 INFO - 'XPC_FLAGS': '0x0', 13:31:30 INFO - 'XPC_SERVICE_NAME': '0', 13:31:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:31:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:31:31 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)) 13:31:31 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)) 13:31:31 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)) 13:31:31 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)) 13:31:31 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)) 13:31:31 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)) 13:31:31 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)) 13:31:31 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)) 13:31:31 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)) 13:31:31 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)) 13:31:31 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)) 13:31:31 INFO - Cleaning up... 13:31:31 INFO - Return code: 0 13:31:31 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:31:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:31:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:31:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:31:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:31:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:31:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:31:31 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 0x109889b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10988c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf90574ff0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109667d30>, '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 0x10958ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10965cbe8>, '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.yF94n2Rvlo/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.O85HvhwTiN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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 13:31:31 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 13:31:31 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 13:31:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yF94n2Rvlo/Render', 13:31:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:31:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:31:31 INFO - 'HOME': '/Users/cltbld', 13:31:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:31:31 INFO - 'LOGNAME': 'cltbld', 13:31:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:31:31 INFO - 'MOZ_NO_REMOTE': '1', 13:31:31 INFO - 'NO_EM_RESTART': '1', 13:31:31 INFO - 'PAGER': '/bin/cat', 13:31:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:31:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:31:31 INFO - 'PWD': '/builds/slave/test', 13:31:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:31:31 INFO - 'SHELL': '/bin/bash', 13:31:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O85HvhwTiN/Listeners', 13:31:31 INFO - 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', 13:31:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:31:31 INFO - 'USER': 'cltbld', 13:31:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:31:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:31:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:31:31 INFO - 'XPC_FLAGS': '0x0', 13:31:31 INFO - 'XPC_SERVICE_NAME': '0', 13:31:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:31:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:31:31 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 13:31:31 INFO - Cleaning up... 13:31:31 INFO - Return code: 0 13:31:31 INFO - Running post-action listener: _resource_record_post_action 13:31:31 INFO - Running post-action listener: _start_resource_monitoring 13:31:31 INFO - Starting resource monitoring. 13:31:31 INFO - [mozharness: 2017-04-20 20:31:31.352392Z] Finished create-virtualenv step (success) 13:31:31 INFO - [mozharness: 2017-04-20 20:31:31.353419Z] Running install step. 13:31:31 INFO - Running pre-action listener: _resource_record_pre_action 13:31:31 INFO - Running main action method: install 13:31:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:31:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:31:31 INFO - Reading from file tmpfile_stdout 13:31:31 INFO - Detecting whether we're running mozinstall >=1.0... 13:31:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:31:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:31:31 INFO - Reading from file tmpfile_stdout 13:31:31 INFO - Output received: 13:31:31 INFO - Usage: mozinstall [options] installer 13:31:31 INFO - Options: 13:31:31 INFO - -h, --help show this help message and exit 13:31:31 INFO - -d DEST, --destination=DEST 13:31:31 INFO - Directory to install application into. [default: 13:31:31 INFO - "/builds/slave/test"] 13:31:31 INFO - --app=APP Application being installed. [default: firefox] 13:31:31 INFO - mkdir: /builds/slave/test/build/application 13:31:31 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'] 13:31:31 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 13:31:51 INFO - Reading from file tmpfile_stdout 13:31:51 INFO - Output received: 13:31:51 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 13:31:51 INFO - Running post-action listener: _resource_record_post_action 13:31:51 INFO - [mozharness: 2017-04-20 20:31:51.195123Z] Finished install step (success) 13:31:51 INFO - [mozharness: 2017-04-20 20:31:51.195283Z] Running run-tests step. 13:31:51 INFO - Running pre-action listener: _resource_record_pre_action 13:31:51 INFO - Running main action method: run_tests 13:31:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:31:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:31:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:31:51 INFO - 2017-04-20 13:31:51.209 screenresolution[1625:7379] starting screenresolution argv=screenresolution get 13:31:51 INFO - 2017-04-20 13:31:51.212 screenresolution[1625:7379] Display 0: 1600x1200x32@60 13:31:51 INFO - 2017-04-20 13:31:51.218 screenresolution[1626:7381] starting screenresolution argv=screenresolution list 13:31:51 INFO - Available Modes on Display 0 13:31:51 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:31:51 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:31:51 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:31:51 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:31:51 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:31:51 INFO - Intel Iris: 13:31:51 INFO - Chipset Model: Intel Iris 13:31:51 INFO - Type: GPU 13:31:51 INFO - Bus: Built-In 13:31:51 INFO - VRAM (Dynamic, Max): 1536 MB 13:31:51 INFO - Vendor: Intel (0x8086) 13:31:51 INFO - Device ID: 0x0a2e 13:31:51 INFO - Revision ID: 0x0009 13:31:51 INFO - Displays: 13:31:51 INFO - Display: 13:31:51 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:31:51 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:31:51 INFO - Display Serial Number: 1600x1200 60 13:31:51 INFO - Main Display: Yes 13:31:51 INFO - Mirror: Off 13:31:51 INFO - Online: Yes 13:31:51 INFO - Rotation: Supported 13:31:51 INFO - Return code: 0 13:31:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:31:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:31:51 INFO - Python 2.7.3 13:31:51 INFO - Return code: 0 13:31:51 INFO - grabbing minidump binary from tooltool 13:31:51 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')]} 13:31:51 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 0x109667d30>, '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 0x10958ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10965cbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:31:51 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 13:31:51 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 13:31:51 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 13:31:51 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:31:51 INFO - Return code: 0 13:31:51 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 13:31:51 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:31:51 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:31:51 INFO - ENV: RUST_BACKTRACE is now 1 13:31:51 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 13:31:51 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:31:51 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/BrJzbkJSSd-UcMXKoTmvoQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0249', '--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 13:31:51 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/BrJzbkJSSd-UcMXKoTmvoQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0249 --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 13:31:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yF94n2Rvlo/Render', 13:31:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:31:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:31:51 INFO - 'HOME': '/Users/cltbld', 13:31:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:31:51 INFO - 'LOGNAME': 'cltbld', 13:31:51 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:31:51 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 13:31:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:31:51 INFO - 'MOZ_NO_REMOTE': '1', 13:31:51 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:31:51 INFO - 'NO_EM_RESTART': '1', 13:31:51 INFO - 'PAGER': '/bin/cat', 13:31:51 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:31:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:31:51 INFO - 'PWD': '/builds/slave/test', 13:31:51 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:31:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:31:51 INFO - 'RUST_BACKTRACE': '1', 13:31:51 INFO - 'SHELL': '/bin/bash', 13:31:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O85HvhwTiN/Listeners', 13:31:51 INFO - 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', 13:31:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:31:51 INFO - 'USER': 'cltbld', 13:31:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:31:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:31:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:31:51 INFO - 'XPC_FLAGS': '0x0', 13:31:51 INFO - 'XPC_SERVICE_NAME': '0', 13:31:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:31:51 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/BrJzbkJSSd-UcMXKoTmvoQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0249', '--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 13:31:51 INFO - mozversion application_buildid: 20170420115229 13:31:51 INFO - mozversion application_changeset: 5d6e88c784ca72dd35b6f0a15701d867f8bd0061 13:31:51 INFO - mozversion application_display_name: Nightly 13:31:51 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:31:51 INFO - mozversion application_name: Firefox 13:31:51 INFO - mozversion application_remotingname: firefox 13:31:51 INFO - mozversion application_repository: https://hg.mozilla.org/projects/pine 13:31:51 INFO - mozversion application_vendor: Mozilla 13:31:51 INFO - mozversion application_version: 55.0a1 13:31:51 INFO - mozversion platform_buildid: 20170420115229 13:31:51 INFO - mozversion platform_changeset: 5d6e88c784ca72dd35b6f0a15701d867f8bd0061 13:31:51 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/pine 13:31:51 INFO - mozversion platform_version: 55.0a1 13:31:51 INFO - using testdate: 1492720311 13:31:51 INFO - actual date: 1492720311 13:31:51 INFO - starting webserver on 'localhost:49248' 13:31:51 INFO - SUITE-START | Running 2 tests 13:31:51 INFO - TEST-START | dromaeo_css 13:31:51 INFO - operating with platform_type : mac_ 13:31:51 INFO - Initialising browser for dromaeo_css test... 13:31:51 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/tmpCFM4K8/profile http://localhost:49248/getInfo.html) 13:31:54 INFO - PID 1636 | __metrics Screen width/height:1600/1200 13:31:54 INFO - PID 1636 | colorDepth:24 13:31:54 INFO - PID 1636 | Browser inner width/height: 1024/689 13:31:54 INFO - PID 1636 | __metrics 13:31:54 INFO - PID 1636 | 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" 13:31:54 INFO - PID 1636 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 334: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:31:55 INFO - TEST-INFO | 1636: exit 0 13:31:55 INFO - Browser initialized. 13:31:55 INFO - Running cycle 1/1 for dromaeo_css test... 13:31:55 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/tmpCFM4K8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:32:07 INFO - PID 1638 | RSS: Main: 243093504 13:32:07 INFO - PID 1638 | 13:35:12 INFO - PID 1638 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49248/tests/dromaeo/cssquery-ext.html) 13:35:14 INFO - PID 1638 | RSS: Main: 385945600 13:35:14 INFO - PID 1638 | 13:36:57 INFO - PID 1638 | ************************* 13:36:57 INFO - PID 1638 | A coding exception was thrown and uncaught in a Task. 13:36:57 INFO - PID 1638 | 13:36:57 ERROR - PID 1638 | Full message: TypeError: NetworkError when attempting to fetch resource. 13:36:57 INFO - PID 1638 | Full stack: 13:36:57 INFO - PID 1638 | ************************* 13:36:57 INFO - PID 1638 | ************************* 13:36:57 INFO - PID 1638 | A coding exception was thrown and uncaught in a Task. 13:36:57 INFO - PID 1638 | 13:36:57 ERROR - PID 1638 | Full message: TypeError: NetworkError when attempting to fetch resource. 13:36:57 INFO - PID 1638 | Full stack: 13:36:57 INFO - PID 1638 | ************************* 13:36:57 INFO - PID 1638 | ************************* 13:36:57 INFO - PID 1638 | A coding exception was thrown and uncaught in a Task. 13:36:57 INFO - PID 1638 | 13:36:57 ERROR - PID 1638 | Full message: TypeError: NetworkError when attempting to fetch resource. 13:36:57 INFO - PID 1638 | Full stack: 13:36:57 INFO - PID 1638 | ************************* 13:36:57 INFO - PID 1638 | ************************* 13:36:57 INFO - PID 1638 | A coding exception was thrown and uncaught in a Task. 13:36:57 INFO - PID 1638 | 13:36:57 ERROR - PID 1638 | Full message: TypeError: NetworkError when attempting to fetch resource. 13:36:57 INFO - PID 1638 | Full stack: 13:36:57 INFO - PID 1638 | ************************* 13:38:16 INFO - PID 1638 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-ext.html (next: http://localhost:49248/tests/dromaeo/cssquery-jquery.html) 13:38:17 INFO - PID 1638 | RSS: Main: 367083520 13:38:17 INFO - PID 1638 | 13:41:19 INFO - PID 1638 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49248/tests/dromaeo/cssquery-mootools.html) 13:41:20 INFO - PID 1638 | RSS: Main: 322854912 13:41:20 INFO - PID 1638 | 13:44:22 INFO - PID 1638 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49248/tests/dromaeo/cssquery-prototype.html) 13:44:23 INFO - PID 1638 | RSS: Main: 360009728 13:44:23 INFO - PID 1638 | 13:47:26 INFO - PID 1638 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49248/tests/dromaeo/cssquery-yui.html) 13:47:27 INFO - PID 1638 | RSS: Main: 329379840 13:47:27 INFO - PID 1638 | 13:50:29 INFO - PID 1638 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-yui.html (next: http://localhost:49248/tests/dromaeo/cssquery-dojo.html) 13:50:29 INFO - PID 1638 | RSS: Main: 375492608 13:50:29 INFO - PID 1638 | 13:50:29 INFO - PID 1638 | __start_tp_report 13:50:29 INFO - PID 1638 | _x_x_mozilla_page_load 13:50:29 INFO - PID 1638 | _x_x_mozilla_page_load_details 13:50:29 INFO - PID 1638 | |i|pagename|runs| 13:50:29 INFO - PID 1638 | |0;dojo.html;3411.7782344147613;3453.2230248194173;3461.203923097687;3494.545709057822;3512.1746389598457;5826;6045.33501314855;6071.058985857194;6072.271327440702;6076.7569297228165;5939.316978547468;5996.940030599703;6011.669358185309;6024.789132380368;6025.12635667829;4306.375575541545;4323.437953066097;5112.233165025248;5204.401493828202;5239.79040838366;4437.622802061828;5313.946860531403;5368.516833484986;5374.7043912584795;5448.318960129984;5364.946350536503;5365.704886231256;5371.919421208685;5383.623146379755;5396.757145928423;5078.7968481260705;5286.947130528684;5362.597805164609;5380.7309634518115;5430.51125398716;5164.251183578398;5253.6322457428;5303.681779093266;5310.575153987672;5341.706206158659;5951.58338916276;6066.059760737048;6076.179715738363;6084.817455476343;6089.695515224265;6259.593126446812;6427.550071494998;6432;6432.839179020515;6481.351864813528;6347.936520634781;6355.904661430036;6501.934980650228;6552.443042341407;6574.342565743435;6182.721777520067;6187.628742275478;6193.4425901669065;6197.96901015501;6198.039303907902;6673.599584024974;6678.6326752028135;6685.164354455693;6690.364415380531;6702.798916032527;6224.813255602339;6262.5616206866;6307.4954003679595;6313.273973493016;6321.683915804283;6306.432421082033;6357.936420635827;6364.936350636527;6367.0449432584555;6384.07430922523;6348.428641422294;6352.523560732963;6447.194100737408;6450.193725784277;6459.935400645933;6437.388448097423;6452.612843229421;6459.966405375118;6461.8061458156335;6487.026945958144;4052.311107111739;5198.714070726146;5203.765830537635;5213.6089793265655;5216.1393370094265;4519.457665080211;4557.6581756368405;4568.497465278817;4607;4663.230566956414;6132.508481341068;6166.1983942087245;6177.351378105324;6195.19462469876;6204.13142160098;6555.180602424697;6559.770408035696;6569.67151642416;6569.981652843817;6572.277049524549;5976.223090998143;6052.243469566304;6075.27096748393;6187.659678717714;6205.22434695663;5752.13717942312;5790.855228619318;5818;5825.155352473867;5841.620294680833;6720.7647732330115;6749.763758268437;6761.560498567577;6772.322767723188;6777.728890844311;6291.245050593958;6500.804975850732;6511.251206111168;6551.082848401133;6566.6388348641285;6525.314841941527;6540.117084193573;6544.149260596092;6545.0182472629485;6557.409833115043;6313.1792866928845;6322.715477803602;6333.986562150034;6334.809955701337;6346.365363463617;5747.453991870766;6529.053287273414;6567.376099270562;6585.539012269095;6598.90101648466;6325.146105275729;6325.810225693236;6342.175517182736;6357.173567436389;6369.8726025480155;6234.096056071935;6338.049292606146;6341.49268058545;6346.746130154742;6368.777092801915;5297.8145764898045;6202.875942481035;6202.906956395748;6229.345918678655;6281.497480201481;6332.683365831782;6365.077063825626;6373.107764913008;6373.6494492801485;6374.426301632875;6276.780312689034;6280.7487700491465;6296.401841825019;6297.4017468340435;6298.7480500781285 13:50:29 INFO - PID 1638 | |1;ext.html;760.1828034862524;795.9920400795992;811.387402511104;1108.5011744714875;1119.748056687245;27242;27720.58419123712;37163.25673486532;37548;37549.55531447938;39221.41167882484;39587;39626.40560391596;39656.80171599145;39733;8351.123132071136;8516.659333626647;8532.36007299452;8544.273736732384;8544.401891867572;6004.3395226525045;6158.076288556707;8348.791280218013;8530.445521041112;8555.358348123884;8545.487270763773;8600.05399406068;8618.525981071038;8641.95679021604;8642.956785216065;8530.23227909488;8594.69918552854;8631.266342360906;8631.784205394853;8656.523891185981;8308.418410711252;8356.582170891432;8356.832863342699;8369.288610468118;8375.706850260212;37144.25711485755;37340;37374;37453.438198426775;37496.87509374723;3546.6985306249;3585.2650206707563;3586.246888153491;3589.425691889311;3593.1376469647357;30682.386352272828;30768.53847192294;30793.538096928565;30815.69184308173;30849.0745277642;3136.827474488902;3169.0968074099;3179.189306726793;3183.9522407163913;3201.295714942732;290753;295206;295674;296225;296334;46464;48230;48306;48407.433145188756;48648.51351486511;51357.45927622102;51412;51453.48546514562;51490;51511.48488515142;13722.519711810039;13819.8944084473;13828.170309781446;13829.861701382857;13848.86151138496;9794.608215671436;11251.549938002388;11252.212345135762;11252.718682033015;11283.266587671775;10895;12402.5659101931;12551.874481255254;12558.87441125577;12589.24464532131;5230.0585894539345;5249.448807875195;5258.842234732964;5275.472452754694;5290.285811415487;840.3781201910664;974.4007435427161;983.3460748602099;995.1590905684685;1044.7179261599326;23228.883855580614;24525.01899923983;24655.87672061664;24762.752372476403;24780.628290575656;926.6756635177634;1083.6423980086552;1084.1489430796944;1121.9775604487866;1144.66804626659;12539.31033793132;12574.748505030031;12592.307423091635;12592.370381480889;12603.873961260453;310239;310273;312053;312530;313161;52436;52443;52524;52605.2109218362;52714;1452.8329242137079;1455.323274677259;1497.8127734033246;1501.6846462242834;1508.1855797869027;21885.890570547046;22399.664005040264;23730.49120667068;23983.760162398852;24040.63939040951;38026.85919422422;38060.42909356306;38063.61936380601;38125;38149.427758583086;210209;256814.43185568653;257217.71391142925;258093;258623;33698.66301336955;34253.972380828614;34371.828140859136;34401.31197376089;34424.48363274603;38733.612663874126;38863.41704874373;39259;39659.801700992466;39691.60308396879;28968.565471517522;29913.401731965674;30159.246018849703;30847;30930.84534577403;29616.22302661847;30083;30140.232470941133;30275;30305.69694303029;36334.454983175805;36350.63649363472;36789;36876.81561592175;37012.259754805295;30158;30527.847360763055;30934.845325773225;31037.068887933397;31178;22313.261909870605;23767;24204.910779014634;24290;24586.631200532363 13:50:29 INFO - PID 1638 | |2;jquery.html;387.2545350200866;419.89140081497106;432.32773037926034;436.4435344935207;451.8192722910835;17115.657686846273;20576.588468230628;21955;22166.556668866655;22363.552728945433;22237.777622223774;22410;22671.886640566816;22687;22958.885205573948;5931.970340148293;6019.428154325332;6194.380561943792;6247.281562620288;6279.309275979638;3634.654707802768;6067.484263837579;6155.138280640714;6183.690815459231;6192.752289908398;3719.1817800083936;5898.1447690084915;6163.106349579309;6199.380061993809;6214.875702485948;5810.796622118206;6032.4570788629235;6169.290531588857;6181.320054793969;6189.473894718954;5174.197999310113;5389.029974604569;5391.433899440581;5394.6493477924205;5403;26577.46845063088;27763.583546246824;27933.88264469419;28024;28202.717972820214;2189.8357623178326;2273.7612550682106;2277.4534111813045;2278.646809744476;2278.7493375728654;17012.319507219698;18040.27838886443;18139;18153.45539633813;18285.451436456926;2093.8324934005395;2106.872823039682;2160.8595441296266;2162.9351119466282;2164.2425151197112;145664.271678642;154270.22864885605;156479;157231;158354.62468063104;11309.151813614011;11758.470868810924;12309.815352769716;12359.567415140607;12394.81407778884;15215.695686086216;15216.391344346102;15232;15235.619109522351;15250.923745381202;7299.854002919912;8491.278241349555;8544.957275213585;8696.869546956801;8728.766548224106;7770.572618505923;7843.882341764878;8019.43863929531;8023.919760802317;8026.919730802734;6507.772227971998;7675.692972281046;7772.533647981026;7796.257796257828;7807.843843123106;1926.49911023135;2078.532330225687;2093.98953005234;2106.231225602644;2107.5363420047565;857.5026484638895;902.228594551662;935.780091678455;950.1401231885155;982.2731178927543;20673.58652826965;21385.037673304738;21425.89287053586;21440.571188576454;21450.892745536174;983.1593987141034;995.7809281957958;1007.3250921882357;1015.9644412445529;1016.6949915025463;10318.469432020469;10961.780764384668;11004.944975275072;11012.176258279414;11070.501827417786;512572.4371378119;527367;531086;531939;553545;124088;124179;124334.29659923719;126056.73943260634;128356;1119.160629527854;1134.869490008643;1146.094585277638;1153.4751687981948;1154.7979103656826;18106.728399074287;18141.546461338574;18177;18292.908535457238;18536.629267414308;55909.043183488386;56969.715151423974;59674.403255966885;60589;60601;77280.2271977273;77310.6134469324;77687.22312777027;79803.60098199703;80038.39923201386;8588.441751286146;8780.868286975829;9078;9215.723528294162;9223.44659320443;10270.383776973405;13996.930015349859;14063.507777227747;14580;14668.70662586721;9185.908140918506;11810.527578896748;11842.28946263227;12128.696782580153;12233.571824986082;11395.145364097893;11424.543018279508;11650.242734222249;11717.648470545897;11909.928106470114;9900.752481188028;12039.879601203877;12318.815217771562;12354.44405001795;12544.435500402686;8650.697225597074;8823.205911467741;8928.642854286016;9116.954415227881;9193.77015574616;10921;11042.33745975244;11927.701807454883;12219.75560488803;12344.444499997342 13:50:29 INFO - PID 1638 | |3;mootools.html;1314.835645544307;1337.6789570503083;1349.9470413077797;1442.4446588063593;1444.3716983112347;11235.325880447168;11751.941240293809;11772.7645447091;11775.941120294408;11777.882221177786;11362.715932101702;11812.114091443133;11870.82193767092;11874.643760687171;11904.404779760998;2540.2379286214154;2582.3673200065914;2589.2102908612865;2589.4173810892494;2589.443269697015;2109.2617583845686;2176.205684924999;2406.651035599837;2515.5723527000455;2559.808014398918;2152.924647637333;2180.705604743363;2466.8766561671846;2552.1833013435707;2557.6930768307734;2100.8214301784365;2490.651308816767;2514.660520829692;2524.5582023146058;2532.1643857527065;2083.541620843412;2489.0168383488603;2492.2056119593863;2502.8373155744816;2507.761762632547;11364.318140911571;11616.883831161666;11637.650870473899;11648.941755291255;11701.94149029258;5706.629069110494;6516.0877477153235;6765.661716914184;6777.288384719632;6784.8303792405095;4021.1354558769713;4245.511766146902;4342.848000320006;4409.867703968886;4414.514403415622;1266.0567876931652;1268.839012303741;1298.1302527306632;1317.6376496463454;1319.195290872567;13678;16496.587585310463;16770.832291677172;16777.832221677872;16812.83187168125;5551.916721249185;5608.513743857881;5761.88476230473;5863.824085277449;5882.588218824727;12383.69040773988;12419.627411177678;12430.316332601793;12448.688782780322;12461.875381246253;6693.698783554752;6698.8995165072565;6699.6315202663345;6701.530892837454;6715.4627629790475;5983.910241346383;6182.505399568024;6258.405451482102;6285.402886725754;6288.842778930563;4245.448091748053;4380.058287468146;4403.757793321334;4455.309427038814;4460.487043989918;1265.6203139058246;1280.6606249343931;1291.0768800307826;1303.139927647748;1312.3175948506914;668.1314291421133;692.3872372949875;785.6346798738614;801.1147681811631;807.9111297757242;6559.704813283413;6687.428454313233;6687.866242675216;6814.386705196556;6867.313268673092;584.1238142785821;596.63307066154;596.9641821490637;606.7785258380659;711.3810984443567;3599.866042196752;3859.671927886161;3924.136689928211;4178.101708132766;4277.700560960765;14501;15342.693146137015;15353.002054866398;15364.846351536342;15365;15182;15186.633203011783;15189.696206075816;15199.544013679386;15234;7541.924580754232;8655.697050603325;8693.347998900108;8886.066962968924;8888.022317545194;6841.863162736618;6869.690863911036;7031.156261248683;7034.894476582957;7052.753153639598;13487.797683034567;13583.049186556846;13625.659358516117;13964.720705585629;13973.301334933416;13901.58295251144;13904.652383690489;14040.578782636538;14150.575482735534;14269.357878895682;9526.761830954005;9602.615895364386;9728.464934428392;9788.559514821993;9804.803903921738;10595.788084238118;10601.522931468257;10709.143268538342;10714.892851071701;10718.89281107179;8998.640054398013;9259.166674999282;9307.72076837696;9335.113164249386;9494.572744226389;8969.192772650493;8995.865062023944;9077.364584479286;9184.816303674022;9322.627094916128;6293.842653933688;7922.524648521107;8128.552929588693;8129.552874591955;8153.470024448392;5942.702864856827;6012.496875781055;6045.425684559959;6138.294096178996;6171.47542458896;5851.0053290939795;6031.939680603314;6131.386861313833;6181.84545386369;6271.874562508816 13:50:29 INFO - PID 1638 | |4;prototype.html;382.9674477669398;393.7499687698312;408.1510458246847;408.61794222402057;408.86132122040124;5905.734241959111;5948.613340132893;5967.462928336449;5973.4623883850445;5983.371745966676;4811.639127065475;5624.325080990277;5674.914876276859;5739.885202295952;5864.120381942711;3885.825137868803;4176.895577610554;4263.275243208662;4300.688290071527;4382.517923028472;4353.303471444554;4358.237308471021;4364.9781751091205;4367.410399596061;4412.205802955466;4223.155368926212;4256.1913236485125;4304.504981927087;4349.565043495641;4351.521332653406;3499.3001399720156;3532.964670353315;4019.216252830699;4240.893977650568;4269.082147338306;3739;3743.850245990157;3749.606291339425;3752.8311225994894;3777.867774627875;4739.834105806315;5015.473375295578;5416.91874621881;5458.4814442628285;5713.742881570298;4464.263396539567;5055.51972562606;5072.2391641253735;5074.538848658398;5080.1363768159135;5139.665921715076;5430.945690543083;5799.130130480419;6195.969020154916;6206.224221972254;3282.638909719929;3992.740471869319;4205.684573656958;4213.726107803013;4225;6233.937660623381;6435.839104022437;6437.903431448532;6521.673916304166;6627.734890604418;3709.517762690833;3944.1322908959983;4026.6174713402766;4163.666906647461;4181.498220213533;3596.770002598406;3640.9453858192146;3913.178232571192;4353.477582690098;4355;3555.466679998021;4344.6089851913475;4953.90092198154;5051.823186188466;5080.59355251579;5812.070068788974;6206.937930620727;6269.43264183954;6286.371362863769;6335.68321583919;1475.4098360655823;1494.6039299585618;1497.9850201498064;1500.7673810559381;1505.2323315109372;937.7561833923229;990.2127808392345;995.6813819577666;998.1964518562481;1011.1708399112657;5984.581079324405;5988.760449581968;5996.640201587918;6008.549358798107;6020.909686354708;5377.650452720561;5575.386707462176;6337.493000559944;6494.480441564663;6513.837154071091;5105.668131571436;5241.9213711794355;5506.201600767866;5579.414161513064;5873.148393482921;3234.6765323467935;3329.816860072671;3364.7812892161933;3372.494125881088;3376.5272861798876;6332.778352757631;6632.701528431232;6666.100076489709;6681;6723.872694943376;6079.848003799941;6641.302663220389;6648.235452922879;6649.204714726997;6657.70443630406;6354.872902542016;6610.96694516534;6667.399934005963;6704.195496540447;6707.887015590073;5288.365396152486;6391.872162556816;6430.228372595318;6456.579552498443;6485.870282594227;5276.630635855607;6129.908051379144;6153.800008998382;6376;6378.681065946591;5473.397926228191;6106.358832322542;6221.875562488815;6625.271220165874;6671;3380.814055226986;4207.97896010518;4249.32010878258;4276.636485898609;4285.2286588413135;5694.800681976277;5760.481556659921;5864.67744274069;6242.968785156045;6470.450011749054;5106.948930510647;5664.603477756517;6206.906896396648;6224.968875155595;6243.411092846844;6094.939050609437;6201.379862013762;6208.441240288395;6242.563020588515;6253.624782513064;5785.884282314414;5987.820365389045;6113.908291375544;6125.571210015256;6131.63210207389;5703.657780533182;6222.315545289924;6247.781327653511;6249.968750156402;6255.624662520263;5190.97404512975;5289.841304760864;5795.333536643171;6091.573589848668;6099.298580663103 13:50:29 INFO - PID 1638 | |5;yui.html;705.2136867392859;846.9280111190549;848.4230723108284;853.7268074216252;873.2882700599014;13265.867341326584;18065.36771213008;18178.4546463606;18695.53261168468;18700.62598748026;16782.496525104238;16963.74554381685;17229.483115506522;17383.13084345784;17389.304427822903;923.5197697197453;947.4930911962101;953.8521529162991;954.813811306792;957.1242313283337;650.4373716735014;664.3622122762154;923.1507013547518;961.480800367804;965.3483898368609;984.5520288268856;987.669130841169;989.3865803201988;989.6189966862773;992.2111921022769;966.6906589891203;969.0938972061075;970.2819913264204;970.7136394763492;992.5136683025338;723.8371366442561;728.6393235348536;729.7628270811954;737.3327138939269;747.312472525278;15629.765553516707;16027;16048.75926861098;16283.837161628351;17295.567610809703;15667.921660391625;16127.83872161275;16187.83812161875;16235.83764162367;16372.754408683879;12446.004319654407;15497.5350739478;15593.532194034196;15682.529524114294;15757.054576725433;5259.76331065103;5281.102212623863;5284.894302113975;5286.418493965661;5290.68255904647;116557.4172129134;118764;121823;121858;121939.68361898315;13363.53227637028;13607.795883061763;13639.727205455836;13883.305834708224;13894.305284735723;25316.367090822878;25409.364765881;25659.74340256611;25883.74116258851;26134.34664133412;7639.618019099023;7762.262585054524;7796.337311328487;7814.140444551095;7821.7262395817015;3691.873978436549;3873.283442563135;3925.430812532224;3927.8821635350987;3938.5076865391825;4028.8388464461095;4214.7471151731;4284.871453856327;4324.8486302979245;4399;936.5270538378076;947.8483442649143;955.1308309438516;958.1903291718487;958.3818437108026;646.6863571167979;661.6592454885738;673.4847841401332;676.6853413162903;685.4653370371118;9415.728876601708;11337.943310283395;11834.940825295991;11909.166358355005;11963.40182990847;629.122374287875;639.2999665366416;661.6063442251949;664.6709878610119;667.9398854103154;4963.8014479421145;5413.377461591967;5489.1217405214975;5501.862453438696;5543.805966791224;99359.50320248352;100197.49901250447;100549;100677;107086;64770.38074048092;68662;79071.41857162825;87876.56061719779;89311.55344223368;59725;76468.61765691247;78514;78642.4271514555;78989;11145.944270278596;11346.773064538827;11362.54549817998;11397.145214108808;11400.429978501208;39794;40297.79106626806;40447;40626;40757.796211019944;47363.76318118387;47748.522514774406;48275.517244827104;48621.5137848617;48929;14059;14279.64300892486;14569.053011554215;14572.635684107981;14597.051191672508;15445.382184712935;15513.84486155124;15547.922260388626;15691.686166276382;15723.449679261172;12091.214071085351;14607.415703371746;14662.63343416423;14775.039622424509;14803.333849976561;14438;14479;14633.268336583342;14704.191269479854;14717.043392179474;14293.64265893361;14391.28043597837;14467.204303763394;14472.421103155755;14542.636434089232;10357.896421035693;10488;10685.679429617125;10750.35497870099;10968.17738669587;11811.409429528318;11868.584599538975;11931.880681193077;12063.165156188114;12875.935620321838 13:50:29 INFO - PID 1638 | __end_tp_report 13:50:29 INFO - PID 1638 | __start_cc_report 13:50:29 INFO - PID 1638 | _x_x_mozilla_cycle_collect,1565 13:50:29 INFO - PID 1638 | __end_cc_report 13:50:29 INFO - PID 1638 | __startTimestamp1492721429716__endTimestamp 13:50:29 INFO - PID 1638 | 13:50:29 INFO - PID 1638 | ------- Summary: start ------- 13:50:29 INFO - PID 1638 | Number of tests: 6 13:50:29 INFO - PID 1638 | 13:50:29 INFO - PID 1638 | [#0] dojo.html Cycles:165 Average:5966.03 Median:6259.59 stddev:728.42 (11.6%) stddev-sans-first:702.56 13:50:29 INFO - PID 1638 | Values: 3411.8 3453.2 3461.2 3494.5 3512.2 5826.0 6045.3 6071.1 6072.3 6076.8 5939.3 5996.9 6011.7 6024.8 6025.1 4306.4 4323.4 5112.2 5204.4 5239.8 4437.6 5313.9 5368.5 5374.7 5448.3 5364.9 5365.7 5371.9 5383.6 5396.8 5078.8 5286.9 5362.6 5380.7 5430.5 5164.3 5253.6 5303.7 5310.6 5341.7 5951.6 6066.1 6076.2 6084.8 6089.7 6259.6 6427.6 6432.0 6432.8 6481.4 6347.9 6355.9 6501.9 6552.4 6574.3 6182.7 6187.6 6193.4 6198.0 6198.0 6673.6 6678.6 6685.2 6690.4 6702.8 6224.8 6262.6 6307.5 6313.3 6321.7 6306.4 6357.9 6364.9 6367.0 6384.1 6348.4 6352.5 6447.2 6450.2 6459.9 6437.4 6452.6 6460.0 6461.8 6487.0 4052.3 5198.7 5203.8 5213.6 5216.1 4519.5 4557.7 4568.5 4607.0 4663.2 6132.5 6166.2 6177.4 6195.2 6204.1 6555.2 6559.8 6569.7 6570.0 6572.3 5976.2 6052.2 6075.3 6187.7 6205.2 5752.1 5790.9 5818.0 5825.2 5841.6 6720.8 6749.8 6761.6 6772.3 6777.7 6291.2 6500.8 6511.3 6551.1 6566.6 6525.3 6540.1 6544.1 6545.0 6557.4 6313.2 6322.7 6334.0 6334.8 6346.4 5747.5 6529.1 6567.4 6585.5 6598.9 6325.1 6325.8 6342.2 6357.2 6369.9 6234.1 6338.0 6341.5 6346.7 6368.8 5297.8 6202.9 6202.9 6229.3 6281.5 6332.7 6365.1 6373.1 6373.6 6374.4 6276.8 6280.7 6296.4 6297.4 6298.7 13:50:29 INFO - PID 1638 | 13:50:29 INFO - PID 1638 | [#1] ext.html Cycles:180 Average:43503.15 Median:34386.57 stddev:75051.38 (218.3%) stddev-sans-first:75193.30 13:50:29 INFO - PID 1638 | Values: 760.2 796.0 811.4 1108.5 1119.7 27242.0 27720.6 37163.3 37548.0 37549.6 39221.4 39587.0 39626.4 39656.8 39733.0 8351.1 8516.7 8532.4 8544.3 8544.4 6004.3 6158.1 8348.8 8530.4 8555.4 8545.5 8600.1 8618.5 8642.0 8643.0 8530.2 8594.7 8631.3 8631.8 8656.5 8308.4 8356.6 8356.8 8369.3 8375.7 37144.3 37340.0 37374.0 37453.4 37496.9 3546.7 3585.3 3586.2 3589.4 3593.1 30682.4 30768.5 30793.5 30815.7 30849.1 3136.8 3169.1 3179.2 3184.0 3201.3 290753.0 295206.0 295674.0 296225.0 296334.0 46464.0 48230.0 48306.0 48407.4 48648.5 51357.5 51412.0 51453.5 51490.0 51511.5 13722.5 13819.9 13828.2 13829.9 13848.9 9794.6 11251.5 11252.2 11252.7 11283.3 10895.0 12402.6 12551.9 12558.9 12589.2 5230.1 5249.4 5258.8 5275.5 5290.3 840.4 974.4 983.3 995.2 1044.7 23228.9 24525.0 24655.9 24762.8 24780.6 926.7 1083.6 1084.1 1122.0 1144.7 12539.3 12574.7 12592.3 12592.4 12603.9 310239.0 310273.0 312053.0 312530.0 313161.0 52436.0 52443.0 52524.0 52605.2 52714.0 1452.8 1455.3 1497.8 1501.7 1508.2 21885.9 22399.7 23730.5 23983.8 24040.6 38026.9 38060.4 38063.6 38125.0 38149.4 210209.0 256814.4 257217.7 258093.0 258623.0 33698.7 34254.0 34371.8 34401.3 34424.5 38733.6 38863.4 39259.0 39659.8 39691.6 28968.6 29913.4 30159.2 30847.0 30930.8 29616.2 30083.0 30140.2 30275.0 30305.7 36334.5 36350.6 36789.0 36876.8 37012.3 30158.0 30527.8 30934.8 31037.1 31178.0 22313.3 23767.0 24204.9 24290.0 24586.6 13:50:29 INFO - PID 1638 | 13:50:29 INFO - PID 1638 | [#2] jquery.html Cycles:180 Average:34878.11 Median:12480.38 stddev:90381.28 (724.2%) stddev-sans-first:90597.72 13:50:29 INFO - PID 1638 | Values: 387.3 419.9 432.3 436.4 451.8 17115.7 20576.6 21955.0 22166.6 22363.6 22237.8 22410.0 22671.9 22687.0 22958.9 5932.0 6019.4 6194.4 6247.3 6279.3 3634.7 6067.5 6155.1 6183.7 6192.8 3719.2 5898.1 6163.1 6199.4 6214.9 5810.8 6032.5 6169.3 6181.3 6189.5 5174.2 5389.0 5391.4 5394.6 5403.0 26577.5 27763.6 27933.9 28024.0 28202.7 2189.8 2273.8 2277.5 2278.6 2278.7 17012.3 18040.3 18139.0 18153.5 18285.5 2093.8 2106.9 2160.9 2162.9 2164.2 145664.3 154270.2 156479.0 157231.0 158354.6 11309.2 11758.5 12309.8 12359.6 12394.8 15215.7 15216.4 15232.0 15235.6 15250.9 7299.9 8491.3 8545.0 8696.9 8728.8 7770.6 7843.9 8019.4 8023.9 8026.9 6507.8 7675.7 7772.5 7796.3 7807.8 1926.5 2078.5 2094.0 2106.2 2107.5 857.5 902.2 935.8 950.1 982.3 20673.6 21385.0 21425.9 21440.6 21450.9 983.2 995.8 1007.3 1016.0 1016.7 10318.5 10961.8 11004.9 11012.2 11070.5 512572.4 527367.0 531086.0 531939.0 553545.0 124088.0 124179.0 124334.3 126056.7 128356.0 1119.2 1134.9 1146.1 1153.5 1154.8 18106.7 18141.5 18177.0 18292.9 18536.6 55909.0 56969.7 59674.4 60589.0 60601.0 77280.2 77310.6 77687.2 79803.6 80038.4 8588.4 8780.9 9078.0 9215.7 9223.4 10270.4 13996.9 14063.5 14580.0 14668.7 9185.9 11810.5 11842.3 12128.7 12233.6 11395.1 11424.5 11650.2 11717.6 11909.9 9900.8 12039.9 12318.8 12354.4 12544.4 8650.7 8823.2 8928.6 9117.0 9193.8 10921.0 11042.3 11927.7 12219.8 12344.4 13:50:29 INFO - PID 1638 | 13:50:29 INFO - PID 1638 | [#3] mootools.html Cycles:180 Average:7162.66 Median:3729.77 stddev:4580.47 (122.8%) stddev-sans-first:4572.24 13:50:29 INFO - PID 1638 | Values: 1314.8 1337.7 1349.9 1442.4 1444.4 11235.3 11751.9 11772.8 11775.9 11777.9 11362.7 11812.1 11870.8 11874.6 11904.4 2540.2 2582.4 2589.2 2589.4 2589.4 2109.3 2176.2 2406.7 2515.6 2559.8 2152.9 2180.7 2466.9 2552.2 2557.7 2100.8 2490.7 2514.7 2524.6 2532.2 2083.5 2489.0 2492.2 2502.8 2507.8 11364.3 11616.9 11637.7 11648.9 11701.9 5706.6 6516.1 6765.7 6777.3 6784.8 4021.1 4245.5 4342.8 4409.9 4414.5 1266.1 1268.8 1298.1 1317.6 1319.2 13678.0 16496.6 16770.8 16777.8 16812.8 5551.9 5608.5 5761.9 5863.8 5882.6 12383.7 12419.6 12430.3 12448.7 12461.9 6693.7 6698.9 6699.6 6701.5 6715.5 5983.9 6182.5 6258.4 6285.4 6288.8 4245.4 4380.1 4403.8 4455.3 4460.5 1265.6 1280.7 1291.1 1303.1 1312.3 668.1 692.4 785.6 801.1 807.9 6559.7 6687.4 6687.9 6814.4 6867.3 584.1 596.6 597.0 606.8 711.4 3599.9 3859.7 3924.1 4178.1 4277.7 14501.0 15342.7 15353.0 15364.8 15365.0 15182.0 15186.6 15189.7 15199.5 15234.0 7541.9 8655.7 8693.3 8886.1 8888.0 6841.9 6869.7 7031.2 7034.9 7052.8 13487.8 13583.0 13625.7 13964.7 13973.3 13901.6 13904.7 14040.6 14150.6 14269.4 9526.8 9602.6 9728.5 9788.6 9804.8 10595.8 10601.5 10709.1 10714.9 10718.9 8998.6 9259.2 9307.7 9335.1 9494.6 8969.2 8995.9 9077.4 9184.8 9322.6 6293.8 7922.5 8128.6 8129.6 8153.5 5942.7 6012.5 6045.4 6138.3 6171.5 5851.0 6031.9 6131.4 6181.8 6271.9 13:50:29 INFO - PID 1638 | 13:50:29 INFO - PID 1638 | [#4] prototype.html Cycles:180 Average:4978.21 Median:5644.46 stddev:1576.16 (27.9%) stddev-sans-first:1542.39 13:50:29 INFO - PID 1638 | Values: 383.0 393.7 408.2 408.6 408.9 5905.7 5948.6 5967.5 5973.5 5983.4 4811.6 5624.3 5674.9 5739.9 5864.1 3885.8 4176.9 4263.3 4300.7 4382.5 4353.3 4358.2 4365.0 4367.4 4412.2 4223.2 4256.2 4304.5 4349.6 4351.5 3499.3 3533.0 4019.2 4240.9 4269.1 3739.0 3743.9 3749.6 3752.8 3777.9 4739.8 5015.5 5416.9 5458.5 5713.7 4464.3 5055.5 5072.2 5074.5 5080.1 5139.7 5430.9 5799.1 6196.0 6206.2 3282.6 3992.7 4205.7 4213.7 4225.0 6233.9 6435.8 6437.9 6521.7 6627.7 3709.5 3944.1 4026.6 4163.7 4181.5 3596.8 3640.9 3913.2 4353.5 4355.0 3555.5 4344.6 4953.9 5051.8 5080.6 5812.1 6206.9 6269.4 6286.4 6335.7 1475.4 1494.6 1498.0 1500.8 1505.2 937.8 990.2 995.7 998.2 1011.2 5984.6 5988.8 5996.6 6008.5 6020.9 5377.7 5575.4 6337.5 6494.5 6513.8 5105.7 5241.9 5506.2 5579.4 5873.1 3234.7 3329.8 3364.8 3372.5 3376.5 6332.8 6632.7 6666.1 6681.0 6723.9 6079.8 6641.3 6648.2 6649.2 6657.7 6354.9 6611.0 6667.4 6704.2 6707.9 5288.4 6391.9 6430.2 6456.6 6485.9 5276.6 6129.9 6153.8 6376.0 6378.7 5473.4 6106.4 6221.9 6625.3 6671.0 3380.8 4208.0 4249.3 4276.6 4285.2 5694.8 5760.5 5864.7 6243.0 6470.5 5106.9 5664.6 6206.9 6225.0 6243.4 6094.9 6201.4 6208.4 6242.6 6253.6 5785.9 5987.8 6113.9 6125.6 6131.6 5703.7 6222.3 6247.8 6250.0 6255.6 5191.0 5289.8 5795.3 6091.6 6099.3 13:50:29 INFO - PID 1638 | 13:50:29 INFO - PID 1638 | [#5] yui.html Cycles:180 Average:20464.59 Median:3926.66 stddev:28643.00 (729.4%) stddev-sans-first:28684.92 13:50:29 INFO - PID 1638 | Values: 705.2 846.9 848.4 853.7 873.3 13265.9 18065.4 18178.5 18695.5 18700.6 16782.5 16963.7 17229.5 17383.1 17389.3 923.5 947.5 953.9 954.8 957.1 650.4 664.4 923.2 961.5 965.3 984.6 987.7 989.4 989.6 992.2 966.7 969.1 970.3 970.7 992.5 723.8 728.6 729.8 737.3 747.3 15629.8 16027.0 16048.8 16283.8 17295.6 15667.9 16127.8 16187.8 16235.8 16372.8 12446.0 15497.5 15593.5 15682.5 15757.1 5259.8 5281.1 5284.9 5286.4 5290.7 116557.4 118764.0 121823.0 121858.0 121939.7 13363.5 13607.8 13639.7 13883.3 13894.3 25316.4 25409.4 25659.7 25883.7 26134.3 7639.6 7762.3 7796.3 7814.1 7821.7 3691.9 3873.3 3925.4 3927.9 3938.5 4028.8 4214.7 4284.9 4324.8 4399.0 936.5 947.8 955.1 958.2 958.4 646.7 661.7 673.5 676.7 685.5 9415.7 11337.9 11834.9 11909.2 11963.4 629.1 639.3 661.6 664.7 667.9 4963.8 5413.4 5489.1 5501.9 5543.8 99359.5 100197.5 100549.0 100677.0 107086.0 64770.4 68662.0 79071.4 87876.6 89311.6 59725.0 76468.6 78514.0 78642.4 78989.0 11145.9 11346.8 11362.5 11397.1 11400.4 39794.0 40297.8 40447.0 40626.0 40757.8 47363.8 47748.5 48275.5 48621.5 48929.0 14059.0 14279.6 14569.1 14572.6 14597.1 15445.4 15513.8 15547.9 15691.7 15723.4 12091.2 14607.4 14662.6 14775.0 14803.3 14438.0 14479.0 14633.3 14704.2 14717.0 14293.6 14391.3 14467.2 14472.4 14542.6 10357.9 10488.0 10685.7 10750.4 10968.2 11811.4 11868.6 11931.9 12063.2 12875.9 13:50:29 INFO - PID 1638 | -------- Summary: end -------- 13:50:29 INFO - PID 1638 | 13:50:30 INFO - TEST-INFO | 1638: exit 0 13:50:30 INFO - TEST-OK | dromaeo_css | took 1118563ms 13:50:30 INFO - TEST-START | kraken 13:50:30 INFO - operating with platform_type : mac_ 13:50:30 INFO - Initialising browser for kraken test... 13:50:30 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/tmpNTAOXh/profile http://localhost:49248/getInfo.html) 13:50:33 INFO - PID 1648 | __metrics Screen width/height:1600/1200 13:50:33 INFO - PID 1648 | colorDepth:24 13:50:33 INFO - PID 1648 | Browser inner width/height: 1024/689 13:50:33 INFO - PID 1648 | __metrics 13:50:33 INFO - PID 1648 | 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" 13:50:33 INFO - PID 1648 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 334: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:50:34 INFO - TEST-INFO | 1648: exit 0 13:50:34 INFO - Browser initialized. 13:50:34 INFO - Running cycle 1/1 for kraken test... 13:50:34 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/tmpNTAOXh/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:50:46 INFO - PID 1649 | RSS: Main: 242741248 13:50:46 INFO - PID 1649 | 13:50:49 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-beat-detection) 13:50:50 INFO - PID 1649 | RSS: Main: 312197120 13:50:50 INFO - PID 1649 | 13:50:52 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-dft) 13:50:53 INFO - PID 1649 | RSS: Main: 292421632 13:50:53 INFO - PID 1649 | 13:50:56 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-fft) 13:50:57 INFO - PID 1649 | RSS: Main: 385228800 13:50:57 INFO - PID 1649 | 13:50:58 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-oscillator) 13:50:59 INFO - PID 1649 | RSS: Main: 289406976 13:50:59 INFO - PID 1649 | 13:51:01 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49248/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:51:02 INFO - PID 1649 | RSS: Main: 291799040 13:51:02 INFO - PID 1649 | 13:51:04 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49248/tests/kraken/driver.html?testName=imaging-darkroom) 13:51:05 INFO - PID 1649 | RSS: Main: 355377152 13:51:05 INFO - PID 1649 | 13:51:08 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49248/tests/kraken/driver.html?testName=imaging-desaturate) 13:51:09 INFO - PID 1649 | RSS: Main: 372383744 13:51:09 INFO - PID 1649 | 13:51:11 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49248/tests/kraken/driver.html?testName=json-parse-financial) 13:51:12 INFO - PID 1649 | RSS: Main: 380030976 13:51:12 INFO - PID 1649 | 13:51:13 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49248/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:51:14 INFO - PID 1649 | RSS: Main: 333045760 13:51:14 INFO - PID 1649 | 13:51:16 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:51:16 INFO - PID 1649 | RSS: Main: 332648448 13:51:16 INFO - PID 1649 | 13:51:18 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:51:19 INFO - PID 1649 | RSS: Main: 336195584 13:51:19 INFO - PID 1649 | 13:51:22 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:51:23 INFO - PID 1649 | RSS: Main: 349958144 13:51:23 INFO - PID 1649 | 13:51:26 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:51:27 INFO - PID 1649 | RSS: Main: 342728704 13:51:27 INFO - PID 1649 | 13:51:28 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49248/tests/kraken/driver.html?testName=ai-astar) 13:51:28 INFO - PID 1649 | RSS: Main: 385437696 13:51:28 INFO - PID 1649 | 13:51:28 INFO - PID 1649 | __start_tp_report 13:51:28 INFO - PID 1649 | _x_x_mozilla_page_load 13:51:28 INFO - PID 1649 | _x_x_mozilla_page_load_details 13:51:28 INFO - PID 1649 | |i|pagename|runs| 13:51:28 INFO - PID 1649 | |0;ai-astar;110;110;110;110;111;110;110;111;111;111 13:51:28 INFO - PID 1649 | |1;audio-beat-detection;136;110;109;145;109;108;108;105;134;108 13:51:28 INFO - PID 1649 | |2;audio-dft;182;185;181;184;185;186;181;184;183;182 13:51:28 INFO - PID 1649 | |3;audio-fft;83;59;59;89;59;57;59;57;79;61 13:51:28 INFO - PID 1649 | |4;audio-oscillator;88;71;85;69;67;67;88;68;68;91 13:51:28 INFO - PID 1649 | |5;imaging-gaussian-blur;89;87;87;86;87;86;86;87;87;87 13:51:28 INFO - PID 1649 | |6;imaging-darkroom;94;93;93;95;93;93;94;93;93;94 13:51:28 INFO - PID 1649 | |7;imaging-desaturate;80;83;83;84;85;85;83;85;86;83 13:51:28 INFO - PID 1649 | |8;json-parse-financial;51;48;47;47;47;47;48;48;48;49 13:51:28 INFO - PID 1649 | |9;json-stringify-tinderbox;54;52;62;56;63;58;55;56;56;56 13:51:28 INFO - PID 1649 | |10;stanford-crypto-aes;82;82;83;81;83;83;81;82;83;85 13:51:28 INFO - PID 1649 | |11;stanford-crypto-ccm;138;137;136;133;139;133;137;147;192;135 13:51:28 INFO - PID 1649 | |12;stanford-crypto-pbkdf2;185;189;182;184;184;223;239;188;183;186 13:51:28 INFO - PID 1649 | |13;stanford-crypto-sha256-iterative;65;66;69;67;66;67;68;68;68;66 13:51:28 INFO - PID 1649 | __end_tp_report 13:51:28 INFO - PID 1649 | __start_cc_report 13:51:28 INFO - PID 1649 | _x_x_mozilla_cycle_collect,2201 13:51:28 INFO - PID 1649 | __end_cc_report 13:51:28 INFO - PID 1649 | __startTimestamp1492721488985__endTimestamp 13:51:28 INFO - PID 1649 | 13:51:28 INFO - PID 1649 | ------- Summary: start ------- 13:51:28 INFO - PID 1649 | Number of tests: 14 13:51:28 INFO - PID 1649 | 13:51:28 INFO - PID 1649 | [#0] ai-astar Cycles:10 Average:110.40 Median:110.50 stddev:0.52 (0.5%) stddev-sans-first:0.53 13:51:28 INFO - PID 1649 | Values: 110.0 110.0 110.0 110.0 111.0 110.0 110.0 111.0 111.0 111.0 13:51:28 INFO - PID 1649 | 13:51:28 INFO - PID 1649 | [#1] audio-beat-detection Cycles:10 Average:117.20 Median:109.50 stddev:14.90 (13.6%) stddev-sans-first:14.16 13:51:28 INFO - PID 1649 | Values: 136.0 110.0 109.0 145.0 109.0 108.0 108.0 105.0 134.0 108.0 13:51:28 INFO - PID 1649 | 13:51:28 INFO - PID 1649 | [#2] audio-dft Cycles:10 Average:183.30 Median:184.00 stddev:1.77 (1.0%) stddev-sans-first:1.81 13:51:28 INFO - PID 1649 | Values: 182.0 185.0 181.0 184.0 185.0 186.0 181.0 184.0 183.0 182.0 13:51:28 INFO - PID 1649 | 13:51:28 INFO - PID 1649 | [#3] audio-fft Cycles:10 Average:66.20 Median:60.00 stddev:12.34 (20.6%) stddev-sans-first:11.49 13:51:28 INFO - PID 1649 | Values: 83.0 59.0 59.0 89.0 59.0 57.0 59.0 57.0 79.0 61.0 13:51:28 INFO - PID 1649 | 13:51:28 INFO - PID 1649 | [#4] audio-oscillator Cycles:10 Average:76.20 Median:78.00 stddev:10.32 (13.2%) stddev-sans-first:10.02 13:51:28 INFO - PID 1649 | Values: 88.0 71.0 85.0 69.0 67.0 67.0 88.0 68.0 68.0 91.0 13:51:28 INFO - PID 1649 | 13:51:28 INFO - PID 1649 | [#5] imaging-gaussian-blur Cycles:10 Average:86.90 Median:87.00 stddev:0.88 (1.0%) stddev-sans-first:0.50 13:51:28 INFO - PID 1649 | Values: 89.0 87.0 87.0 86.0 87.0 86.0 86.0 87.0 87.0 87.0 13:51:28 INFO - PID 1649 | 13:51:28 INFO - PID 1649 | [#6] imaging-darkroom Cycles:10 Average:93.50 Median:93.50 stddev:0.71 (0.8%) stddev-sans-first:0.73 13:51:28 INFO - PID 1649 | Values: 94.0 93.0 93.0 95.0 93.0 93.0 94.0 93.0 93.0 94.0 13:51:28 INFO - PID 1649 | 13:51:28 INFO - PID 1649 | [#7] imaging-desaturate Cycles:10 Average:83.70 Median:84.50 stddev:1.70 (2.0%) stddev-sans-first:1.17 13:51:28 INFO - PID 1649 | Values: 80.0 83.0 83.0 84.0 85.0 85.0 83.0 85.0 86.0 83.0 13:51:28 INFO - PID 1649 | 13:51:28 INFO - PID 1649 | [#8] json-parse-financial Cycles:10 Average:48.00 Median:48.00 stddev:1.25 (2.6%) stddev-sans-first:0.71 13:51:28 INFO - PID 1649 | Values: 51.0 48.0 47.0 47.0 47.0 47.0 48.0 48.0 48.0 49.0 13:51:28 INFO - PID 1649 | 13:51:28 INFO - PID 1649 | [#9] json-stringify-tinderbox Cycles:10 Average:56.80 Median:56.00 stddev:3.39 (6.1%) stddev-sans-first:3.44 13:51:28 INFO - PID 1649 | Values: 54.0 52.0 62.0 56.0 63.0 58.0 55.0 56.0 56.0 56.0 13:51:28 INFO - PID 1649 | 13:51:28 INFO - PID 1649 | [#10] stanford-crypto-aes Cycles:10 Average:82.50 Median:83.00 stddev:1.18 (1.4%) stddev-sans-first:1.24 13:51:28 INFO - PID 1649 | Values: 82.0 82.0 83.0 81.0 83.0 83.0 81.0 82.0 83.0 85.0 13:51:28 INFO - PID 1649 | 13:51:28 INFO - PID 1649 | [#11] stanford-crypto-ccm Cycles:10 Average:142.70 Median:137.50 stddev:17.77 (12.9%) stddev-sans-first:18.77 13:51:28 INFO - PID 1649 | Values: 138.0 137.0 136.0 133.0 139.0 133.0 137.0 147.0 192.0 135.0 13:51:28 INFO - PID 1649 | 13:51:28 INFO - PID 1649 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:194.30 Median:187.00 stddev:19.82 (10.6%) stddev-sans-first:20.74 13:51:28 INFO - PID 1649 | Values: 185.0 189.0 182.0 184.0 184.0 223.0 239.0 188.0 183.0 186.0 13:51:28 INFO - PID 1649 | 13:51:28 INFO - PID 1649 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.00 Median:67.50 stddev:1.25 (1.8%) stddev-sans-first:1.09 13:51:28 INFO - PID 1649 | Values: 65.0 66.0 69.0 67.0 66.0 67.0 68.0 68.0 68.0 66.0 13:51:28 INFO - PID 1649 | -------- Summary: end -------- 13:51:28 INFO - PID 1649 | 13:51:30 INFO - TEST-INFO | 1649: exit 0 13:51:30 INFO - TEST-OK | kraken | took 59704ms 13:51:30 INFO - SUITE-END | took 1178s 13:51:30 INFO - Completed test suite (00:19:38) 13:51:30 INFO - javascript benchmark 13:51:30 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7653.452065886929, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3411.7782344147613, 3453.2230248194173, 3461.203923097687, 3494.545709057822, 3512.1746389598457, 5826.0, 6045.33501314855, 6071.058985857194, 6072.271327440702, 6076.7569297228165, 5939.316978547468, 5996.940030599703, 6011.669358185309, 6024.789132380368, 6025.12635667829, 4306.375575541545, 4323.437953066097, 5112.233165025248, 5204.401493828202, 5239.79040838366, 4437.622802061828, 5313.946860531403, 5368.516833484986, 5374.7043912584795, 5448.318960129984, 5364.946350536503, 5365.704886231256, 5371.919421208685, 5383.623146379755, 5396.757145928423, 5078.7968481260705, 5286.947130528684, 5362.597805164609, 5380.7309634518115, 5430.51125398716, 5164.251183578398, 5253.6322457428, 5303.681779093266, 5310.575153987672, 5341.706206158659, 5951.58338916276, 6066.059760737048, 6076.179715738363, 6084.817455476343, 6089.695515224265, 6259.593126446812, 6427.550071494998, 6432.0, 6432.839179020515, 6481.351864813528, 6347.936520634781, 6355.904661430036, 6501.934980650228, 6552.443042341407, 6574.342565743435, 6182.721777520067, 6187.628742275478, 6193.4425901669065, 6197.96901015501, 6198.039303907902, 6673.599584024974, 6678.6326752028135, 6685.164354455693, 6690.364415380531, 6702.798916032527, 6224.813255602339, 6262.5616206866, 6307.4954003679595, 6313.273973493016, 6321.683915804283, 6306.432421082033, 6357.936420635827, 6364.936350636527, 6367.0449432584555, 6384.07430922523, 6348.428641422294, 6352.523560732963, 6447.194100737408, 6450.193725784277, 6459.935400645933, 6437.388448097423, 6452.612843229421, 6459.966405375118, 6461.8061458156335, 6487.026945958144, 4052.311107111739, 5198.714070726146, 5203.765830537635, 5213.6089793265655, 5216.1393370094265, 4519.457665080211, 4557.6581756368405, 4568.497465278817, 4607.0, 4663.230566956414, 6132.508481341068, 6166.1983942087245, 6177.351378105324, 6195.19462469876, 6204.13142160098, 6555.180602424697, 6559.770408035696, 6569.67151642416, 6569.981652843817, 6572.277049524549, 5976.223090998143, 6052.243469566304, 6075.27096748393, 6187.659678717714, 6205.22434695663, 5752.13717942312, 5790.855228619318, 5818.0, 5825.155352473867, 5841.620294680833, 6720.7647732330115, 6749.763758268437, 6761.560498567577, 6772.322767723188, 6777.728890844311, 6291.245050593958, 6500.804975850732, 6511.251206111168, 6551.082848401133, 6566.6388348641285, 6525.314841941527, 6540.117084193573, 6544.149260596092, 6545.0182472629485, 6557.409833115043, 6313.1792866928845, 6322.715477803602, 6333.986562150034, 6334.809955701337, 6346.365363463617, 5747.453991870766, 6529.053287273414, 6567.376099270562, 6585.539012269095, 6598.90101648466, 6325.146105275729, 6325.810225693236, 6342.175517182736, 6357.173567436389, 6369.8726025480155, 6234.096056071935, 6338.049292606146, 6341.49268058545, 6346.746130154742, 6368.777092801915, 5297.8145764898045, 6202.875942481035, 6202.906956395748, 6229.345918678655, 6281.497480201481, 6332.683365831782, 6365.077063825626, 6373.107764913008, 6373.6494492801485, 6374.426301632875, 6276.780312689034, 6280.7487700491465, 6296.401841825019, 6297.4017468340435, 6298.7480500781285], "value": 5916.201141451612, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [760.1828034862524, 795.9920400795992, 811.387402511104, 1108.5011744714875, 1119.748056687245, 27242.0, 27720.58419123712, 37163.25673486532, 37548.0, 37549.55531447938, 39221.41167882484, 39587.0, 39626.40560391596, 39656.80171599145, 39733.0, 8351.123132071136, 8516.659333626647, 8532.36007299452, 8544.273736732384, 8544.401891867572, 6004.3395226525045, 6158.076288556707, 8348.791280218013, 8530.445521041112, 8555.358348123884, 8545.487270763773, 8600.05399406068, 8618.525981071038, 8641.95679021604, 8642.956785216065, 8530.23227909488, 8594.69918552854, 8631.266342360906, 8631.784205394853, 8656.523891185981, 8308.418410711252, 8356.582170891432, 8356.832863342699, 8369.288610468118, 8375.706850260212, 37144.25711485755, 37340.0, 37374.0, 37453.438198426775, 37496.87509374723, 3546.6985306249, 3585.2650206707563, 3586.246888153491, 3589.425691889311, 3593.1376469647357, 30682.386352272828, 30768.53847192294, 30793.538096928565, 30815.69184308173, 30849.0745277642, 3136.827474488902, 3169.0968074099, 3179.189306726793, 3183.9522407163913, 3201.295714942732, 290753.0, 295206.0, 295674.0, 296225.0, 296334.0, 46464.0, 48230.0, 48306.0, 48407.433145188756, 48648.51351486511, 51357.45927622102, 51412.0, 51453.48546514562, 51490.0, 51511.48488515142, 13722.519711810039, 13819.8944084473, 13828.170309781446, 13829.861701382857, 13848.86151138496, 9794.608215671436, 11251.549938002388, 11252.212345135762, 11252.718682033015, 11283.266587671775, 10895.0, 12402.5659101931, 12551.874481255254, 12558.87441125577, 12589.24464532131, 5230.0585894539345, 5249.448807875195, 5258.842234732964, 5275.472452754694, 5290.285811415487, 840.3781201910664, 974.4007435427161, 983.3460748602099, 995.1590905684685, 1044.7179261599326, 23228.883855580614, 24525.01899923983, 24655.87672061664, 24762.752372476403, 24780.628290575656, 926.6756635177634, 1083.6423980086552, 1084.1489430796944, 1121.9775604487866, 1144.66804626659, 12539.31033793132, 12574.748505030031, 12592.307423091635, 12592.370381480889, 12603.873961260453, 310239.0, 310273.0, 312053.0, 312530.0, 313161.0, 52436.0, 52443.0, 52524.0, 52605.2109218362, 52714.0, 1452.8329242137079, 1455.323274677259, 1497.8127734033246, 1501.6846462242834, 1508.1855797869027, 21885.890570547046, 22399.664005040264, 23730.49120667068, 23983.760162398852, 24040.63939040951, 38026.85919422422, 38060.42909356306, 38063.61936380601, 38125.0, 38149.427758583086, 210209.0, 256814.43185568653, 257217.71391142925, 258093.0, 258623.0, 33698.66301336955, 34253.972380828614, 34371.828140859136, 34401.31197376089, 34424.48363274603, 38733.612663874126, 38863.41704874373, 39259.0, 39659.801700992466, 39691.60308396879, 28968.565471517522, 29913.401731965674, 30159.246018849703, 30847.0, 30930.84534577403, 29616.22302661847, 30083.0, 30140.232470941133, 30275.0, 30305.69694303029, 36334.454983175805, 36350.63649363472, 36789.0, 36876.81561592175, 37012.259754805295, 30158.0, 30527.847360763055, 30934.845325773225, 31037.068887933397, 31178.0, 22313.261909870605, 23767.0, 24204.910779014634, 24290.0, 24586.631200532363], "value": 17108.041382234696, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [387.2545350200866, 419.89140081497106, 432.32773037926034, 436.4435344935207, 451.8192722910835, 17115.657686846273, 20576.588468230628, 21955.0, 22166.556668866655, 22363.552728945433, 22237.777622223774, 22410.0, 22671.886640566816, 22687.0, 22958.885205573948, 5931.970340148293, 6019.428154325332, 6194.380561943792, 6247.281562620288, 6279.309275979638, 3634.654707802768, 6067.484263837579, 6155.138280640714, 6183.690815459231, 6192.752289908398, 3719.1817800083936, 5898.1447690084915, 6163.106349579309, 6199.380061993809, 6214.875702485948, 5810.796622118206, 6032.4570788629235, 6169.290531588857, 6181.320054793969, 6189.473894718954, 5174.197999310113, 5389.029974604569, 5391.433899440581, 5394.6493477924205, 5403.0, 26577.46845063088, 27763.583546246824, 27933.88264469419, 28024.0, 28202.717972820214, 2189.8357623178326, 2273.7612550682106, 2277.4534111813045, 2278.646809744476, 2278.7493375728654, 17012.319507219698, 18040.27838886443, 18139.0, 18153.45539633813, 18285.451436456926, 2093.8324934005395, 2106.872823039682, 2160.8595441296266, 2162.9351119466282, 2164.2425151197112, 145664.271678642, 154270.22864885605, 156479.0, 157231.0, 158354.62468063104, 11309.151813614011, 11758.470868810924, 12309.815352769716, 12359.567415140607, 12394.81407778884, 15215.695686086216, 15216.391344346102, 15232.0, 15235.619109522351, 15250.923745381202, 7299.854002919912, 8491.278241349555, 8544.957275213585, 8696.869546956801, 8728.766548224106, 7770.572618505923, 7843.882341764878, 8019.43863929531, 8023.919760802317, 8026.919730802734, 6507.772227971998, 7675.692972281046, 7772.533647981026, 7796.257796257828, 7807.843843123106, 1926.49911023135, 2078.532330225687, 2093.98953005234, 2106.231225602644, 2107.5363420047565, 857.5026484638895, 902.228594551662, 935.780091678455, 950.1401231885155, 982.2731178927543, 20673.58652826965, 21385.037673304738, 21425.89287053586, 21440.571188576454, 21450.892745536174, 983.1593987141034, 995.7809281957958, 1007.3250921882357, 1015.9644412445529, 1016.6949915025463, 10318.469432020469, 10961.780764384668, 11004.944975275072, 11012.176258279414, 11070.501827417786, 512572.4371378119, 527367.0, 531086.0, 531939.0, 553545.0, 124088.0, 124179.0, 124334.29659923719, 126056.73943260634, 128356.0, 1119.160629527854, 1134.869490008643, 1146.094585277638, 1153.4751687981948, 1154.7979103656826, 18106.728399074287, 18141.546461338574, 18177.0, 18292.908535457238, 18536.629267414308, 55909.043183488386, 56969.715151423974, 59674.403255966885, 60589.0, 60601.0, 77280.2271977273, 77310.6134469324, 77687.22312777027, 79803.60098199703, 80038.39923201386, 8588.441751286146, 8780.868286975829, 9078.0, 9215.723528294162, 9223.44659320443, 10270.383776973405, 13996.930015349859, 14063.507777227747, 14580.0, 14668.70662586721, 9185.908140918506, 11810.527578896748, 11842.28946263227, 12128.696782580153, 12233.571824986082, 11395.145364097893, 11424.543018279508, 11650.242734222249, 11717.648470545897, 11909.928106470114, 9900.752481188028, 12039.879601203877, 12318.815217771562, 12354.44405001795, 12544.435500402686, 8650.697225597074, 8823.205911467741, 8928.642854286016, 9116.954415227881, 9193.77015574616, 10921.0, 11042.33745975244, 11927.701807454883, 12219.75560488803, 12344.444499997342], "value": 10251.468288898152, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1314.835645544307, 1337.6789570503083, 1349.9470413077797, 1442.4446588063593, 1444.3716983112347, 11235.325880447168, 11751.941240293809, 11772.7645447091, 11775.941120294408, 11777.882221177786, 11362.715932101702, 11812.114091443133, 11870.82193767092, 11874.643760687171, 11904.404779760998, 2540.2379286214154, 2582.3673200065914, 2589.2102908612865, 2589.4173810892494, 2589.443269697015, 2109.2617583845686, 2176.205684924999, 2406.651035599837, 2515.5723527000455, 2559.808014398918, 2152.924647637333, 2180.705604743363, 2466.8766561671846, 2552.1833013435707, 2557.6930768307734, 2100.8214301784365, 2490.651308816767, 2514.660520829692, 2524.5582023146058, 2532.1643857527065, 2083.541620843412, 2489.0168383488603, 2492.2056119593863, 2502.8373155744816, 2507.761762632547, 11364.318140911571, 11616.883831161666, 11637.650870473899, 11648.941755291255, 11701.94149029258, 5706.629069110494, 6516.0877477153235, 6765.661716914184, 6777.288384719632, 6784.8303792405095, 4021.1354558769713, 4245.511766146902, 4342.848000320006, 4409.867703968886, 4414.514403415622, 1266.0567876931652, 1268.839012303741, 1298.1302527306632, 1317.6376496463454, 1319.195290872567, 13678.0, 16496.587585310463, 16770.832291677172, 16777.832221677872, 16812.83187168125, 5551.916721249185, 5608.513743857881, 5761.88476230473, 5863.824085277449, 5882.588218824727, 12383.69040773988, 12419.627411177678, 12430.316332601793, 12448.688782780322, 12461.875381246253, 6693.698783554752, 6698.8995165072565, 6699.6315202663345, 6701.530892837454, 6715.4627629790475, 5983.910241346383, 6182.505399568024, 6258.405451482102, 6285.402886725754, 6288.842778930563, 4245.448091748053, 4380.058287468146, 4403.757793321334, 4455.309427038814, 4460.487043989918, 1265.6203139058246, 1280.6606249343931, 1291.0768800307826, 1303.139927647748, 1312.3175948506914, 668.1314291421133, 692.3872372949875, 785.6346798738614, 801.1147681811631, 807.9111297757242, 6559.704813283413, 6687.428454313233, 6687.866242675216, 6814.386705196556, 6867.313268673092, 584.1238142785821, 596.63307066154, 596.9641821490637, 606.7785258380659, 711.3810984443567, 3599.866042196752, 3859.671927886161, 3924.136689928211, 4178.101708132766, 4277.700560960765, 14501.0, 15342.693146137015, 15353.002054866398, 15364.846351536342, 15365.0, 15182.0, 15186.633203011783, 15189.696206075816, 15199.544013679386, 15234.0, 7541.924580754232, 8655.697050603325, 8693.347998900108, 8886.066962968924, 8888.022317545194, 6841.863162736618, 6869.690863911036, 7031.156261248683, 7034.894476582957, 7052.753153639598, 13487.797683034567, 13583.049186556846, 13625.659358516117, 13964.720705585629, 13973.301334933416, 13901.58295251144, 13904.652383690489, 14040.578782636538, 14150.575482735534, 14269.357878895682, 9526.761830954005, 9602.615895364386, 9728.464934428392, 9788.559514821993, 9804.803903921738, 10595.788084238118, 10601.522931468257, 10709.143268538342, 10714.892851071701, 10718.89281107179, 8998.640054398013, 9259.166674999282, 9307.72076837696, 9335.113164249386, 9494.572744226389, 8969.192772650493, 8995.865062023944, 9077.364584479286, 9184.816303674022, 9322.627094916128, 6293.842653933688, 7922.524648521107, 8128.552929588693, 8129.552874591955, 8153.470024448392, 5942.702864856827, 6012.496875781055, 6045.425684559959, 6138.294096178996, 6171.47542458896, 5851.0053290939795, 6031.939680603314, 6131.386861313833, 6181.84545386369, 6271.874562508816], "value": 5333.985625822744, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [382.9674477669398, 393.7499687698312, 408.1510458246847, 408.61794222402057, 408.86132122040124, 5905.734241959111, 5948.613340132893, 5967.462928336449, 5973.4623883850445, 5983.371745966676, 4811.639127065475, 5624.325080990277, 5674.914876276859, 5739.885202295952, 5864.120381942711, 3885.825137868803, 4176.895577610554, 4263.275243208662, 4300.688290071527, 4382.517923028472, 4353.303471444554, 4358.237308471021, 4364.9781751091205, 4367.410399596061, 4412.205802955466, 4223.155368926212, 4256.1913236485125, 4304.504981927087, 4349.565043495641, 4351.521332653406, 3499.3001399720156, 3532.964670353315, 4019.216252830699, 4240.893977650568, 4269.082147338306, 3739.0, 3743.850245990157, 3749.606291339425, 3752.8311225994894, 3777.867774627875, 4739.834105806315, 5015.473375295578, 5416.91874621881, 5458.4814442628285, 5713.742881570298, 4464.263396539567, 5055.51972562606, 5072.2391641253735, 5074.538848658398, 5080.1363768159135, 5139.665921715076, 5430.945690543083, 5799.130130480419, 6195.969020154916, 6206.224221972254, 3282.638909719929, 3992.740471869319, 4205.684573656958, 4213.726107803013, 4225.0, 6233.937660623381, 6435.839104022437, 6437.903431448532, 6521.673916304166, 6627.734890604418, 3709.517762690833, 3944.1322908959983, 4026.6174713402766, 4163.666906647461, 4181.498220213533, 3596.770002598406, 3640.9453858192146, 3913.178232571192, 4353.477582690098, 4355.0, 3555.466679998021, 4344.6089851913475, 4953.90092198154, 5051.823186188466, 5080.59355251579, 5812.070068788974, 6206.937930620727, 6269.43264183954, 6286.371362863769, 6335.68321583919, 1475.4098360655823, 1494.6039299585618, 1497.9850201498064, 1500.7673810559381, 1505.2323315109372, 937.7561833923229, 990.2127808392345, 995.6813819577666, 998.1964518562481, 1011.1708399112657, 5984.581079324405, 5988.760449581968, 5996.640201587918, 6008.549358798107, 6020.909686354708, 5377.650452720561, 5575.386707462176, 6337.493000559944, 6494.480441564663, 6513.837154071091, 5105.668131571436, 5241.9213711794355, 5506.201600767866, 5579.414161513064, 5873.148393482921, 3234.6765323467935, 3329.816860072671, 3364.7812892161933, 3372.494125881088, 3376.5272861798876, 6332.778352757631, 6632.701528431232, 6666.100076489709, 6681.0, 6723.872694943376, 6079.848003799941, 6641.302663220389, 6648.235452922879, 6649.204714726997, 6657.70443630406, 6354.872902542016, 6610.96694516534, 6667.399934005963, 6704.195496540447, 6707.887015590073, 5288.365396152486, 6391.872162556816, 6430.228372595318, 6456.579552498443, 6485.870282594227, 5276.630635855607, 6129.908051379144, 6153.800008998382, 6376.0, 6378.681065946591, 5473.397926228191, 6106.358832322542, 6221.875562488815, 6625.271220165874, 6671.0, 3380.814055226986, 4207.97896010518, 4249.32010878258, 4276.636485898609, 4285.2286588413135, 5694.800681976277, 5760.481556659921, 5864.67744274069, 6242.968785156045, 6470.450011749054, 5106.948930510647, 5664.603477756517, 6206.906896396648, 6224.968875155595, 6243.411092846844, 6094.939050609437, 6201.379862013762, 6208.441240288395, 6242.563020588515, 6253.624782513064, 5785.884282314414, 5987.820365389045, 6113.908291375544, 6125.571210015256, 6131.63210207389, 5703.657780533182, 6222.315545289924, 6247.781327653511, 6249.968750156402, 6255.624662520263, 5190.97404512975, 5289.841304760864, 5795.333536643171, 6091.573589848668, 6099.298580663103], "value": 4500.646931909011, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [705.2136867392859, 846.9280111190549, 848.4230723108284, 853.7268074216252, 873.2882700599014, 13265.867341326584, 18065.36771213008, 18178.4546463606, 18695.53261168468, 18700.62598748026, 16782.496525104238, 16963.74554381685, 17229.483115506522, 17383.13084345784, 17389.304427822903, 923.5197697197453, 947.4930911962101, 953.8521529162991, 954.813811306792, 957.1242313283337, 650.4373716735014, 664.3622122762154, 923.1507013547518, 961.480800367804, 965.3483898368609, 984.5520288268856, 987.669130841169, 989.3865803201988, 989.6189966862773, 992.2111921022769, 966.6906589891203, 969.0938972061075, 970.2819913264204, 970.7136394763492, 992.5136683025338, 723.8371366442561, 728.6393235348536, 729.7628270811954, 737.3327138939269, 747.312472525278, 15629.765553516707, 16027.0, 16048.75926861098, 16283.837161628351, 17295.567610809703, 15667.921660391625, 16127.83872161275, 16187.83812161875, 16235.83764162367, 16372.754408683879, 12446.004319654407, 15497.5350739478, 15593.532194034196, 15682.529524114294, 15757.054576725433, 5259.76331065103, 5281.102212623863, 5284.894302113975, 5286.418493965661, 5290.68255904647, 116557.4172129134, 118764.0, 121823.0, 121858.0, 121939.68361898315, 13363.53227637028, 13607.795883061763, 13639.727205455836, 13883.305834708224, 13894.305284735723, 25316.367090822878, 25409.364765881, 25659.74340256611, 25883.74116258851, 26134.34664133412, 7639.618019099023, 7762.262585054524, 7796.337311328487, 7814.140444551095, 7821.7262395817015, 3691.873978436549, 3873.283442563135, 3925.430812532224, 3927.8821635350987, 3938.5076865391825, 4028.8388464461095, 4214.7471151731, 4284.871453856327, 4324.8486302979245, 4399.0, 936.5270538378076, 947.8483442649143, 955.1308309438516, 958.1903291718487, 958.3818437108026, 646.6863571167979, 661.6592454885738, 673.4847841401332, 676.6853413162903, 685.4653370371118, 9415.728876601708, 11337.943310283395, 11834.940825295991, 11909.166358355005, 11963.40182990847, 629.122374287875, 639.2999665366416, 661.6063442251949, 664.6709878610119, 667.9398854103154, 4963.8014479421145, 5413.377461591967, 5489.1217405214975, 5501.862453438696, 5543.805966791224, 99359.50320248352, 100197.49901250447, 100549.0, 100677.0, 107086.0, 64770.38074048092, 68662.0, 79071.41857162825, 87876.56061719779, 89311.55344223368, 59725.0, 76468.61765691247, 78514.0, 78642.4271514555, 78989.0, 11145.944270278596, 11346.773064538827, 11362.54549817998, 11397.145214108808, 11400.429978501208, 39794.0, 40297.79106626806, 40447.0, 40626.0, 40757.796211019944, 47363.76318118387, 47748.522514774406, 48275.517244827104, 48621.5137848617, 48929.0, 14059.0, 14279.64300892486, 14569.053011554215, 14572.635684107981, 14597.051191672508, 15445.382184712935, 15513.84486155124, 15547.922260388626, 15691.686166276382, 15723.449679261172, 12091.214071085351, 14607.415703371746, 14662.63343416423, 14775.039622424509, 14803.333849976561, 14438.0, 14479.0, 14633.268336583342, 14704.191269479854, 14717.043392179474, 14293.64265893361, 14391.28043597837, 14467.204303763394, 14472.421103155755, 14542.636434089232, 10357.896421035693, 10488.0, 10685.679429617125, 10750.35497870099, 10968.17738669587, 11811.409429528318, 11868.584599538975, 11931.880681193077, 12063.165156188114, 12875.935620321838], "value": 8067.810557956192, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1408.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 110.0, 110.0, 110.0, 111.0, 110.0, 110.0, 111.0, 111.0, 111.0], "value": 110.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 110.0, 109.0, 145.0, 109.0, 108.0, 108.0, 105.0, 134.0, 108.0], "value": 117.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 185.0, 181.0, 184.0, 185.0, 186.0, 181.0, 184.0, 183.0, 182.0], "value": 183.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 59.0, 59.0, 89.0, 59.0, 57.0, 59.0, 57.0, 79.0, 61.0], "value": 66.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 71.0, 85.0, 69.0, 67.0, 67.0, 88.0, 68.0, 68.0, 91.0], "value": 76.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 87.0, 86.0, 87.0, 86.0, 86.0, 87.0, 87.0, 87.0], "value": 86.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 93.0, 95.0, 93.0, 93.0, 94.0, 93.0, 93.0, 94.0], "value": 93.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 83.0, 83.0, 84.0, 85.0, 85.0, 83.0, 85.0, 86.0, 83.0], "value": 83.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 48.0, 47.0, 47.0, 47.0, 47.0, 48.0, 48.0, 48.0, 49.0], "value": 48.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 52.0, 62.0, 56.0, 63.0, 58.0, 55.0, 56.0, 56.0, 56.0], "value": 56.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 83.0, 81.0, 83.0, 83.0, 81.0, 82.0, 83.0, 85.0], "value": 82.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 137.0, 136.0, 133.0, 139.0, 133.0, 137.0, 147.0, 192.0, 135.0], "value": 142.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 189.0, 182.0, 184.0, 184.0, 223.0, 239.0, 188.0, 183.0, 186.0], "value": 194.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 69.0, 67.0, 66.0, 67.0, 68.0, 68.0, 68.0, 66.0], "value": 67.0, "unit": "score"}]}]} 13:51:30 INFO - Return code: 0 13:51:30 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 13:51:30 ERROR - # TBPL SUCCESS # 13:51:30 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:51:30 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:51:30 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:51:30 INFO - 2017-04-20 13:51:30.343 screenresolution[1653:9838] starting screenresolution argv=screenresolution get 13:51:30 INFO - 2017-04-20 13:51:30.345 screenresolution[1653:9838] Display 0: 1600x1200x32@60 13:51:30 INFO - 2017-04-20 13:51:30.351 screenresolution[1654:9841] starting screenresolution argv=screenresolution list 13:51:30 INFO - Available Modes on Display 0 13:51:30 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:51:30 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:51:30 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:51:30 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:51:30 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:51:30 INFO - Intel Iris: 13:51:30 INFO - Chipset Model: Intel Iris 13:51:30 INFO - Type: GPU 13:51:30 INFO - Bus: Built-In 13:51:30 INFO - VRAM (Dynamic, Max): 1536 MB 13:51:30 INFO - Vendor: Intel (0x8086) 13:51:30 INFO - Device ID: 0x0a2e 13:51:30 INFO - Revision ID: 0x0009 13:51:30 INFO - Displays: 13:51:30 INFO - Display: 13:51:30 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:51:30 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:51:30 INFO - Display Serial Number: 1600x1200 60 13:51:30 INFO - Main Display: Yes 13:51:30 INFO - Mirror: Off 13:51:30 INFO - Online: Yes 13:51:30 INFO - Rotation: Supported 13:51:30 INFO - Return code: 0 13:51:30 INFO - Running post-action listener: _resource_record_post_action 13:51:30 INFO - [mozharness: 2017-04-20 20:51:30.597093Z] Finished run-tests step (success) 13:51:30 INFO - Running post-run listener: _resource_record_post_run 13:51:30 INFO - Total resource usage - Wall time: 1198s; CPU: 53.0%; Read bytes: 38155264; Write bytes: 513648128; Read time: 244; Write time: 1605 13:51:30 INFO - TinderboxPrint: CPU usage
53.2% 13:51:30 INFO - TinderboxPrint: I/O read bytes / time
38,155,264 / 244 13:51:30 INFO - TinderboxPrint: I/O write bytes / time
513,648,128 / 1,605 13:51:30 INFO - TinderboxPrint: CPU idle
2,241.9 (46.8%) 13:51:30 INFO - TinderboxPrint: CPU system
951.5 (19.8%) 13:51:30 INFO - TinderboxPrint: CPU user
1,600.2 (33.4%) 13:51:30 INFO - TinderboxPrint: Swap in / out
246,022,144 / 0 13:51:30 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 194605568; Write bytes: 195290624; Read time: 14792; Write time: 417 13:51:30 INFO - run-tests - Wall time: 1179s; CPU: 53.0%; Read bytes: 37168128; Write bytes: 301531136; Read time: 231; Write time: 1136 13:51:31 INFO - Running post-run listener: _upload_blobber_files 13:51:31 INFO - Blob upload gear active. 13:51:31 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:51:31 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 13:51:31 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'] 13:51:31 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 13:51:31 INFO - (blobuploader) - INFO - Open directory for files ... 13:51:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 13:51:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:51:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:51:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 13:51:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:51:32 INFO - (blobuploader) - INFO - Done attempting. 13:51:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 13:51:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:51:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:51:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 13:51:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:51:33 INFO - (blobuploader) - INFO - Done attempting. 13:51:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 13:51:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:51:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:51:34 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 13:51:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:51:34 INFO - (blobuploader) - INFO - Done attempting. 13:51:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:51:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:51:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:51:36 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:51:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:51:36 INFO - (blobuploader) - INFO - Done attempting. 13:51:36 INFO - (blobuploader) - INFO - Iteration through files over. 13:51:36 INFO - Return code: 0 13:51:36 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:51:36 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:51:36 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/7ff2f227ed60d0d5c451c5a4347e2af2cb0313eb6853b24581ded8529027ed18fe1c6d479466ee65a47956961ced07de2fb9398c3381b758ef393463598d95f4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/c3162ed0dc4d5685e8c27e97f929e17bd2075114e7c49cf0ce7141dbb335a90df914fb46c25dab0cea158cde9ad5193435c15d28bc8e8cf29c665654a025cdbe", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/e2e8963b9755a85052feada0a95ce609e7ae52ff4083f1e8060aae473368af3596d8561e3d44752cf53fc6778139f3f04ecbef83d5e3ae98ebd2f149753befbb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/1fad92b073eec710aa47266d845f8b7a31fc26f6e0cfca8fbe86840543b7f4d7cb44165d330f44ecc41b4f9014aea01ee78b3e22014b1cb9a47be6e07d825c90"} 13:51:36 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:51:36 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:51:36 INFO - Contents: 13:51:36 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/7ff2f227ed60d0d5c451c5a4347e2af2cb0313eb6853b24581ded8529027ed18fe1c6d479466ee65a47956961ced07de2fb9398c3381b758ef393463598d95f4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/c3162ed0dc4d5685e8c27e97f929e17bd2075114e7c49cf0ce7141dbb335a90df914fb46c25dab0cea158cde9ad5193435c15d28bc8e8cf29c665654a025cdbe", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/e2e8963b9755a85052feada0a95ce609e7ae52ff4083f1e8060aae473368af3596d8561e3d44752cf53fc6778139f3f04ecbef83d5e3ae98ebd2f149753befbb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/1fad92b073eec710aa47266d845f8b7a31fc26f6e0cfca8fbe86840543b7f4d7cb44165d330f44ecc41b4f9014aea01ee78b3e22014b1cb9a47be6e07d825c90"} 13:51:36 INFO - Running post-run listener: copy_logs_to_upload_dir 13:51:36 INFO - Copying logs to upload dir... 13:51:36 INFO - mkdir: /builds/slave/test/build/upload/logs 13:51:36 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1280.589932 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 20 secs) (at 2017-04-20 13:51:36.602718) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-20 13:51:36.609353) ========= 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.yF94n2Rvlo/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.O85HvhwTiN/Listeners TMPDIR=/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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/7ff2f227ed60d0d5c451c5a4347e2af2cb0313eb6853b24581ded8529027ed18fe1c6d479466ee65a47956961ced07de2fb9398c3381b758ef393463598d95f4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/c3162ed0dc4d5685e8c27e97f929e17bd2075114e7c49cf0ce7141dbb335a90df914fb46c25dab0cea158cde9ad5193435c15d28bc8e8cf29c665654a025cdbe", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/e2e8963b9755a85052feada0a95ce609e7ae52ff4083f1e8060aae473368af3596d8561e3d44752cf53fc6778139f3f04ecbef83d5e3ae98ebd2f149753befbb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/1fad92b073eec710aa47266d845f8b7a31fc26f6e0cfca8fbe86840543b7f4d7cb44165d330f44ecc41b4f9014aea01ee78b3e22014b1cb9a47be6e07d825c90"} build_url:https://queue.taskcluster.net/v1/task/BrJzbkJSSd-UcMXKoTmvoQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010480 build_url: 'https://queue.taskcluster.net/v1/task/BrJzbkJSSd-UcMXKoTmvoQ/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/7ff2f227ed60d0d5c451c5a4347e2af2cb0313eb6853b24581ded8529027ed18fe1c6d479466ee65a47956961ced07de2fb9398c3381b758ef393463598d95f4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/c3162ed0dc4d5685e8c27e97f929e17bd2075114e7c49cf0ce7141dbb335a90df914fb46c25dab0cea158cde9ad5193435c15d28bc8e8cf29c665654a025cdbe", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/e2e8963b9755a85052feada0a95ce609e7ae52ff4083f1e8060aae473368af3596d8561e3d44752cf53fc6778139f3f04ecbef83d5e3ae98ebd2f149753befbb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/1fad92b073eec710aa47266d845f8b7a31fc26f6e0cfca8fbe86840543b7f4d7cb44165d330f44ecc41b4f9014aea01ee78b3e22014b1cb9a47be6e07d825c90"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-20 13:51:36.644960) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-20 13:51:36.645321) ========= 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.yF94n2Rvlo/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.O85HvhwTiN/Listeners TMPDIR=/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005039 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-20 13:51:36.693868) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-20 13:51:36.694180) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-20 13:51:36.694512) ========= ========= Total master_lag: 0.18 =========