builder: graphics_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0098 starttime: 1479830040.51 results: success (0) buildid: 20161122065550 builduid: 3f13d961382e49f7b88477d6c3539938 revision: 1ff17ad606b1dc2cf1135337b52acd80579889b2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-22 07:54:00.510702) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-22 07:54:00.511401) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-22 07:54:00.511681) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-22 07:54:00.575559) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 07:54:00.575847) ========= 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.QjCB05Xsak/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.SmKiZYJBgQ/Listeners TMPDIR=/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/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 --2016-11-22 07:54:00-- 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% 10.2M=0.001s 2016-11-22 07:54:01 (10.2 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.869707 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 07:54:01.469386) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 07:54:01.469732) ========= 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.QjCB05Xsak/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.SmKiZYJBgQ/Listeners TMPDIR=/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/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.026496 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 07:54:01.518403) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 07:54:01.518810) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev 1ff17ad606b1dc2cf1135337b52acd80579889b2 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/graphics --rev 1ff17ad606b1dc2cf1135337b52acd80579889b2 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.QjCB05Xsak/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.SmKiZYJBgQ/Listeners TMPDIR=/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/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 2016-11-22 07:54:01,614 truncating revision to first 12 chars 2016-11-22 07:54:01,614 Setting DEBUG logging. 2016-11-22 07:54:01,615 attempt 1/10 2016-11-22 07:54:01,615 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/1ff17ad606b1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-22 07:54:02,296 unpacking tar archive at: graphics-1ff17ad606b1/testing/mozharness/ program finished with exit code 0 elapsedTime=0.947036 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 07:54:02.490715) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-22 07:54:02.491048) ========= script_repo_revision: 1ff17ad606b1dc2cf1135337b52acd80579889b2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-22 07:54:02.491471) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-22 07:54:02.491765) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-22 07:54:02.512200) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-11-22 07:54:02.512618) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Graphics --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Graphics', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Graphics'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.QjCB05Xsak/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.SmKiZYJBgQ/Listeners TMPDIR=/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/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 07:54:02 INFO - MultiFileLogger online at 20161122 07:54:02 in /builds/slave/test 07:54:02 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Graphics --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 07:54:02 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:54:02 INFO - {'append_to_log': False, 07:54:02 INFO - 'base_work_dir': '/builds/slave/test', 07:54:02 INFO - 'blob_upload_branch': 'Graphics', 07:54:02 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:54:02 INFO - 'branch': 'Graphics', 07:54:02 INFO - 'buildbot_json_path': 'buildprops.json', 07:54:02 INFO - 'config_files': ('talos/mac_config.py',), 07:54:02 INFO - 'default_actions': ('clobber', 07:54:02 INFO - 'read-buildbot-config', 07:54:02 INFO - 'download-and-extract', 07:54:02 INFO - 'populate-webroot', 07:54:02 INFO - 'create-virtualenv', 07:54:02 INFO - 'install', 07:54:02 INFO - 'run-tests'), 07:54:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:54:02 INFO - 'download_minidump_stackwalk': True, 07:54:02 INFO - 'download_symbols': 'ondemand', 07:54:02 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:54:02 INFO - 'tooltool.py': '/tools/tooltool.py', 07:54:02 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:54:02 INFO - '/tools/misc-python/virtualenv.py')}, 07:54:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:54:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:54:02 INFO - 'installer_path': 'installer.exe', 07:54:02 INFO - 'log_level': 'info', 07:54:02 INFO - 'log_name': 'talos', 07:54:02 INFO - 'log_to_console': True, 07:54:02 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:54:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:54:02 INFO - 'opt_config_files': (), 07:54:02 INFO - 'pip_index': False, 07:54:02 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:54:02 INFO - 'cmd': ('bash', 07:54:02 INFO - '-c', 07:54:02 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:54:02 INFO - 'enabled': True, 07:54:02 INFO - 'halt_on_failure': False, 07:54:02 INFO - 'name': 'check_screen_resolution'},), 07:54:02 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:54:02 INFO - 'cmd': ('bash', 07:54:02 INFO - '-c', 07:54:02 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:54:02 INFO - 'enabled': True, 07:54:02 INFO - 'halt_on_failure': False, 07:54:02 INFO - 'name': 'check_screen_resolution'},), 07:54:02 INFO - 'run_cmd_checks_enabled': True, 07:54:02 INFO - 'sps_profile': False, 07:54:02 INFO - 'sps_profile_interval': 0, 07:54:02 INFO - 'suite': 'dromaeojs-e10s', 07:54:02 INFO - 'system_bits': '32', 07:54:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:54:02 INFO - 'title': 't-yosemite-r7-0098', 07:54:02 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:54:02 INFO - 'use_talos_json': True, 07:54:02 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:54:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:54:02 INFO - 'work_dir': 'build'} 07:54:02 INFO - [mozharness: 2016-11-22 15:54:02.719849Z] Running clobber step. 07:54:02 INFO - Running pre-action listener: _resource_record_pre_action 07:54:02 INFO - Running main action method: clobber 07:54:02 INFO - rmtree: /builds/slave/test/build 07:54:02 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:54:05 INFO - Running post-action listener: _resource_record_post_action 07:54:05 INFO - [mozharness: 2016-11-22 15:54:05.158093Z] Finished clobber step (success) 07:54:05 INFO - [mozharness: 2016-11-22 15:54:05.158264Z] Running read-buildbot-config step. 07:54:05 INFO - Running pre-action listener: _resource_record_pre_action 07:54:05 INFO - Running main action method: read_buildbot_config 07:54:05 INFO - Using buildbot properties: 07:54:05 INFO - { 07:54:05 INFO - "project": "", 07:54:05 INFO - "product": "firefox", 07:54:05 INFO - "script_repo_revision": "default", 07:54:05 INFO - "builddir": "graphics_yosemite_r7_test-dromaeojs-e10s", 07:54:05 INFO - "repository": "", 07:54:05 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 graphics talos dromaeojs-e10s", 07:54:05 INFO - "buildid": "20161122065550", 07:54:05 INFO - "pgo_build": "False", 07:54:05 INFO - "basedir": "/builds/slave/test", 07:54:05 INFO - "buildnumber": 0, 07:54:05 INFO - "slavename": "t-yosemite-r7-0098", 07:54:05 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 07:54:05 INFO - "platform": "yosemite_r7", 07:54:05 INFO - "scheduler": "tests-graphics-macosx64-talos", 07:54:05 INFO - "branch": "graphics", 07:54:05 INFO - "repo_path": "projects/graphics", 07:54:05 INFO - "revision": "1ff17ad606b1dc2cf1135337b52acd80579889b2", 07:54:05 INFO - "stage_platform": "macosx64", 07:54:05 INFO - "builduid": "3f13d961382e49f7b88477d6c3539938", 07:54:05 INFO - "slavebuilddir": "test" 07:54:05 INFO - } 07:54:05 INFO - Found installer url https://queue.taskcluster.net/v1/task/dmWAvqaiSSi71l8PiezDJQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 07:54:05 INFO - Running post-action listener: _resource_record_post_action 07:54:05 INFO - [mozharness: 2016-11-22 15:54:05.161514Z] Finished read-buildbot-config step (success) 07:54:05 INFO - [mozharness: 2016-11-22 15:54:05.161656Z] Running download-and-extract step. 07:54:05 INFO - Running pre-action listener: _resource_record_pre_action 07:54:05 INFO - Running main action method: download_and_extract 07:54:05 INFO - mkdir: /builds/slave/test/build/tests 07:54:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:54:05 INFO - trying https://queue.taskcluster.net/v1/task/dmWAvqaiSSi71l8PiezDJQ/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 07:54:05 INFO - Downloading https://queue.taskcluster.net/v1/task/dmWAvqaiSSi71l8PiezDJQ/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 07:54:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dmWAvqaiSSi71l8PiezDJQ/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 07:54:06 INFO - Downloaded 1199 bytes. 07:54:06 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 07:54:06 INFO - Using the following test package requirements: 07:54:06 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 07:54:06 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:54:06 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 07:54:06 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:54:06 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 07:54:06 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:54:06 INFO - u'jsshell-mac.zip'], 07:54:06 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:54:06 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 07:54:06 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 07:54:06 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:54:06 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 07:54:06 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:54:06 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 07:54:06 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:54:06 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 07:54:06 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:54:06 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 07:54:06 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 07:54:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dmWAvqaiSSi71l8PiezDJQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 07:54:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dmWAvqaiSSi71l8PiezDJQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:54:06 INFO - Fetch https://queue.taskcluster.net/v1/task/dmWAvqaiSSi71l8PiezDJQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 07:54:06 INFO - Http code: 200 07:54:06 INFO - via: 1.1 41b2ee7cbe95749816a7586d9ab4629d.cloudfront.net (CloudFront) 07:54:06 INFO - x-amz-cf-id: 8SoDYOOGkjUCo7IcrfJ81cgxje0MWL6U8iCZugPhHifOOebKkeiUKw== 07:54:06 INFO - x-amz-version-id: v.QNiA_HLXWKPVQoHaKq9A.hXCfxIo97 07:54:07 INFO - Expected file size: 16281999 07:54:07 INFO - Obtained file size: 16281999 07:54:13 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 07:54:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dmWAvqaiSSi71l8PiezDJQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 07:54:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dmWAvqaiSSi71l8PiezDJQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:54:13 INFO - Fetch https://queue.taskcluster.net/v1/task/dmWAvqaiSSi71l8PiezDJQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 07:54:14 INFO - Http code: 200 07:54:14 INFO - via: 1.1 b9a8ce441329f20cea895e08f19f1ec8.cloudfront.net (CloudFront) 07:54:14 INFO - x-amz-cf-id: 28K3Y5xpIRhpmzZEMREap_55-JBql1L0HQhqD4ybVxn36V48S1nyOQ== 07:54:14 INFO - x-amz-version-id: v.QNiA_HLXWKPVQoHaKq9A.hXCfxIo97 07:54:14 INFO - Expected file size: 16281999 07:54:14 INFO - Obtained file size: 16281999 07:54:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dmWAvqaiSSi71l8PiezDJQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 07:54:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dmWAvqaiSSi71l8PiezDJQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 07:54:20 INFO - Fetch https://queue.taskcluster.net/v1/task/dmWAvqaiSSi71l8PiezDJQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 07:54:21 INFO - Http code: 200 07:54:21 INFO - via: 1.1 ae162f6796e551002447afd7c07ec67a.cloudfront.net (CloudFront) 07:54:21 INFO - x-amz-cf-id: mYNMCpRh3tcEYnlCgVAHtvG39yb4agD6c9Dt7T-aknmrbUACocgmXg== 07:54:21 INFO - x-amz-version-id: m6dqZdJ2Ne9eafUUjxevvtS73Dt_qQvc 07:54:21 INFO - Expected file size: 14409392 07:54:21 INFO - Obtained file size: 14409392 07:54:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:54:22 INFO - trying https://queue.taskcluster.net/v1/task/dmWAvqaiSSi71l8PiezDJQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 07:54:22 INFO - Downloading https://queue.taskcluster.net/v1/task/dmWAvqaiSSi71l8PiezDJQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 07:54:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dmWAvqaiSSi71l8PiezDJQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 07:54:24 INFO - Downloaded 107571052 bytes. 07:54:24 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dmWAvqaiSSi71l8PiezDJQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 07:54:24 INFO - mkdir: /builds/slave/test/properties 07:54:24 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:54:24 INFO - Writing to file /builds/slave/test/properties/build_url 07:54:24 INFO - Contents: 07:54:24 INFO - build_url:https://queue.taskcluster.net/v1/task/dmWAvqaiSSi71l8PiezDJQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 07:54:25 INFO - Running post-action listener: _resource_record_post_action 07:54:25 INFO - Running post-action listener: set_extra_try_arguments 07:54:25 INFO - [mozharness: 2016-11-22 15:54:25.493871Z] Finished download-and-extract step (success) 07:54:25 INFO - [mozharness: 2016-11-22 15:54:25.494024Z] Running populate-webroot step. 07:54:25 INFO - Running pre-action listener: _resource_record_pre_action 07:54:25 INFO - Running main action method: populate_webroot 07:54:25 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:54:25 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:54:25 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:54:25 INFO - '--tppagecycles', 07:54:25 INFO - '7'], 07:54:25 INFO - 'tests': ['tsvgm']}}, 07:54:25 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:54:25 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:54:25 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:54:25 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:54:25 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:54:25 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:54:25 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 07:54:25 INFO - 'talos_options': ['--disable-e10s'], 07:54:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:54:25 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 07:54:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:54:25 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 07:54:25 INFO - 'talos_options': ['--disable-e10s'], 07:54:25 INFO - 'tests': ['damp', 'tps']}, 07:54:25 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 07:54:25 INFO - 'tests': ['damp', 'tps']}, 07:54:25 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:54:25 INFO - 'tests': ['dromaeo_dom']}, 07:54:25 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:54:25 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:54:25 INFO - 'tests': ['basic_compositor_video']}, 07:54:25 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:54:25 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:54:25 INFO - 'tests': ['a11yr', 07:54:25 INFO - 'ts_paint', 07:54:25 INFO - 'tpaint', 07:54:25 INFO - 'sessionrestore', 07:54:25 INFO - 'sessionrestore_no_auto_restore', 07:54:25 INFO - 'tabpaint']}, 07:54:25 INFO - 'other-e10s': {'tests': ['a11yr', 07:54:25 INFO - 'ts_paint', 07:54:25 INFO - 'tpaint', 07:54:25 INFO - 'sessionrestore', 07:54:25 INFO - 'sessionrestore_no_auto_restore', 07:54:25 INFO - 'tabpaint']}, 07:54:25 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:54:25 INFO - 'ts_paint', 07:54:25 INFO - 'tpaint', 07:54:25 INFO - 'sessionrestore', 07:54:25 INFO - 'sessionrestore_no_auto_restore', 07:54:25 INFO - 'tabpaint']}, 07:54:25 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:54:25 INFO - 'ts_paint', 07:54:25 INFO - 'tpaint', 07:54:25 INFO - 'sessionrestore', 07:54:25 INFO - 'sessionrestore_no_auto_restore', 07:54:25 INFO - 'tabpaint']}, 07:54:25 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:54:25 INFO - 'tests': ['a11yr', 07:54:25 INFO - 'ts_paint', 07:54:25 INFO - 'tpaint', 07:54:25 INFO - 'sessionrestore', 07:54:25 INFO - 'sessionrestore_no_auto_restore', 07:54:25 INFO - 'tabpaint']}, 07:54:25 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:54:25 INFO - 'tests': ['a11yr', 07:54:25 INFO - 'ts_paint', 07:54:25 INFO - 'tpaint', 07:54:25 INFO - 'sessionrestore', 07:54:25 INFO - 'sessionrestore_no_auto_restore', 07:54:25 INFO - 'tabpaint']}, 07:54:25 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:54:25 INFO - 'tests': ['tsvgx', 07:54:25 INFO - 'tsvgr_opacity', 07:54:25 INFO - 'tart', 07:54:25 INFO - 'tscrollx', 07:54:25 INFO - 'cart']}, 07:54:25 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:54:25 INFO - 'tsvgr_opacity', 07:54:25 INFO - 'tart', 07:54:25 INFO - 'tscrollx', 07:54:25 INFO - 'cart']}, 07:54:25 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 07:54:25 INFO - 'talos_options': ['--disable-e10s'], 07:54:25 INFO - 'tests': ['tp5o']}, 07:54:25 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 07:54:25 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 07:54:25 INFO - 'talos_options': ['--disable-e10s', 07:54:25 INFO - '--xperf_path', 07:54:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:54:25 INFO - 'tests': ['tp5n']}, 07:54:25 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 07:54:25 INFO - 'talos_options': ['--xperf_path', 07:54:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:54:25 INFO - 'tests': ['tp5n']}}, 07:54:25 INFO - 'talos.zip': {'path': '', 07:54:25 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:54:25 INFO - Running post-action listener: _resource_record_post_action 07:54:25 INFO - [mozharness: 2016-11-22 15:54:25.511758Z] Finished populate-webroot step (success) 07:54:25 INFO - [mozharness: 2016-11-22 15:54:25.511901Z] Running create-virtualenv step. 07:54:25 INFO - Running pre-action listener: _resource_record_pre_action 07:54:25 INFO - Running main action method: create_virtualenv 07:54:25 INFO - Creating virtualenv /builds/slave/test/build/venv 07:54:25 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 07:54:25 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:54:25 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:54:25 INFO - Using real prefix '/tools/python27' 07:54:25 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:54:26 INFO - Installing distribute.............................................................................................................................................................................................done. 07:54:29 INFO - Installing pip.................done. 07:54:29 INFO - Return code: 0 07:54:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:54:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:54:29 INFO - Reading from file tmpfile_stdout 07:54:29 INFO - Output received: 07:54:29 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) 07:54:29 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:54:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:54:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:54:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:54:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:54:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:54:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:54:29 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 0x10fb153f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa8b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5dac86180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f92bd30>, '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 0x10f792db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f923b20>, '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.QjCB05Xsak/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.SmKiZYJBgQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/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 07:54:29 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 07:54:29 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 07:54:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QjCB05Xsak/Render', 07:54:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:54:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:54:29 INFO - 'HOME': '/Users/cltbld', 07:54:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:54:29 INFO - 'LOGNAME': 'cltbld', 07:54:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:54:29 INFO - 'MOZ_NO_REMOTE': '1', 07:54:29 INFO - 'NO_EM_RESTART': '1', 07:54:29 INFO - 'PAGER': '/bin/cat', 07:54:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:54:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:54:29 INFO - 'PWD': '/builds/slave/test', 07:54:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:54:29 INFO - 'SHELL': '/bin/bash', 07:54:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SmKiZYJBgQ/Listeners', 07:54:29 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 07:54:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:54:29 INFO - 'USER': 'cltbld', 07:54:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:54:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:54:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:54:29 INFO - 'XPC_FLAGS': '0x0', 07:54:29 INFO - 'XPC_SERVICE_NAME': '0', 07:54:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:54:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:54:29 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 07:54:29 INFO - Cleaning up... 07:54:29 INFO - Return code: 0 07:54:29 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:54:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:54:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:54:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:54:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:54:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:54:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:54:29 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 0x10fb153f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa8b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5dac86180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f92bd30>, '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 0x10f792db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f923b20>, '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.QjCB05Xsak/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.SmKiZYJBgQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/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 07:54:29 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 07:54:29 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 07:54:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QjCB05Xsak/Render', 07:54:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:54:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:54:29 INFO - 'HOME': '/Users/cltbld', 07:54:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:54:29 INFO - 'LOGNAME': 'cltbld', 07:54:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:54:29 INFO - 'MOZ_NO_REMOTE': '1', 07:54:29 INFO - 'NO_EM_RESTART': '1', 07:54:29 INFO - 'PAGER': '/bin/cat', 07:54:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:54:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:54:29 INFO - 'PWD': '/builds/slave/test', 07:54:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:54:29 INFO - 'SHELL': '/bin/bash', 07:54:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SmKiZYJBgQ/Listeners', 07:54:29 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 07:54:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:54:29 INFO - 'USER': 'cltbld', 07:54:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:54:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:54:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:54:29 INFO - 'XPC_FLAGS': '0x0', 07:54:29 INFO - 'XPC_SERVICE_NAME': '0', 07:54:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:54:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:54:30 INFO - Downloading/unpacking psutil>=3.1.1 07:54:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:54:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:54:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:54:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:54:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:54:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:54:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:54:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:54:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:54:34 INFO - Installing collected packages: psutil 07:54:34 INFO - Running setup.py install for psutil 07:54:34 INFO - building 'psutil._psutil_osx' extension 07:54:34 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 07:54:34 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 07:54:34 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 07:54:34 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 07:54:34 INFO - building 'psutil._psutil_posix' extension 07:54:34 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 07:54:35 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:54:35 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:54:35 INFO - ^ 07:54:35 INFO - 1 warning generated. 07:54:35 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 07:54:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:54:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:54:35 INFO - Successfully installed psutil 07:54:35 INFO - Cleaning up... 07:54:35 INFO - Return code: 0 07:54:35 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:54:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:54:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:54:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:54:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:54:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:54:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:54:35 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 0x10fb153f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa8b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5dac86180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f92bd30>, '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 0x10f792db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f923b20>, '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.QjCB05Xsak/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.SmKiZYJBgQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/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 07:54:35 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 07:54:35 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 07:54:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QjCB05Xsak/Render', 07:54:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:54:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:54:35 INFO - 'HOME': '/Users/cltbld', 07:54:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:54:35 INFO - 'LOGNAME': 'cltbld', 07:54:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:54:35 INFO - 'MOZ_NO_REMOTE': '1', 07:54:35 INFO - 'NO_EM_RESTART': '1', 07:54:35 INFO - 'PAGER': '/bin/cat', 07:54:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:54:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:54:35 INFO - 'PWD': '/builds/slave/test', 07:54:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:54:35 INFO - 'SHELL': '/bin/bash', 07:54:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SmKiZYJBgQ/Listeners', 07:54:35 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 07:54:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:54:35 INFO - 'USER': 'cltbld', 07:54:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:54:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:54:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:54:35 INFO - 'XPC_FLAGS': '0x0', 07:54:35 INFO - 'XPC_SERVICE_NAME': '0', 07:54:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:54:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:54:35 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:54:35 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 07:54:35 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 07:54:35 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 07:54:35 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 07:54:35 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 07:54:35 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 07:54:38 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:54:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:54:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:54:38 INFO - Installing collected packages: mozsystemmonitor 07:54:38 INFO - Running setup.py install for mozsystemmonitor 07:54:38 INFO - Successfully installed mozsystemmonitor 07:54:38 INFO - Cleaning up... 07:54:38 INFO - Return code: 0 07:54:38 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:54:38 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')]} 07:54:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:54:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:54:38 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')]} 07:54:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:54:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:54:38 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 0x10fb153f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa8b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5dac86180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f92bd30>, '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 0x10f792db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f923b20>, '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.QjCB05Xsak/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.SmKiZYJBgQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/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 07:54:38 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 07:54:38 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 07:54:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QjCB05Xsak/Render', 07:54:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:54:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:54:38 INFO - 'HOME': '/Users/cltbld', 07:54:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:54:38 INFO - 'LOGNAME': 'cltbld', 07:54:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:54:38 INFO - 'MOZ_NO_REMOTE': '1', 07:54:38 INFO - 'NO_EM_RESTART': '1', 07:54:38 INFO - 'PAGER': '/bin/cat', 07:54:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:54:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:54:38 INFO - 'PWD': '/builds/slave/test', 07:54:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:54:38 INFO - 'SHELL': '/bin/bash', 07:54:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SmKiZYJBgQ/Listeners', 07:54:38 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 07:54:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:54:38 INFO - 'USER': 'cltbld', 07:54:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:54:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:54:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:54:38 INFO - 'XPC_FLAGS': '0x0', 07:54:38 INFO - 'XPC_SERVICE_NAME': '0', 07:54:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:54:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:54:38 INFO - Downloading/unpacking jsonschema==2.5.1 07:54:38 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 07:54:38 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 07:54:38 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 07:54:38 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 07:54:38 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 07:54:38 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 07:54:41 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:54:41 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:54:41 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 07:54:41 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 07:54:41 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 07:54:41 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 07:54:41 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 07:54:41 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 07:54:41 INFO - Downloading functools32-3.2.3-2.tar.gz 07:54:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:54:41 INFO - warning: no files found matching '*.txt' 07:54:41 INFO - no previously-included directories found matching 'build' 07:54:41 INFO - no previously-included directories found matching 'dist' 07:54:41 INFO - no previously-included directories found matching '.git*' 07:54:41 INFO - Installing collected packages: jsonschema, functools32 07:54:41 INFO - Running setup.py install for functools32 07:54:41 INFO - warning: no files found matching '*.txt' 07:54:41 INFO - no previously-included directories found matching 'build' 07:54:41 INFO - no previously-included directories found matching 'dist' 07:54:41 INFO - no previously-included directories found matching '.git*' 07:54:41 INFO - Successfully installed jsonschema functools32 07:54:41 INFO - Cleaning up... 07:54:42 INFO - Return code: 0 07:54:42 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:54:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:54:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:54:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:54:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:54:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:54:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:54:42 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 0x10fb153f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa8b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5dac86180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f92bd30>, '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 0x10f792db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f923b20>, '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.QjCB05Xsak/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.SmKiZYJBgQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/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 07:54:42 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 07:54:42 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 07:54:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QjCB05Xsak/Render', 07:54:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:54:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:54:42 INFO - 'HOME': '/Users/cltbld', 07:54:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:54:42 INFO - 'LOGNAME': 'cltbld', 07:54:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:54:42 INFO - 'MOZ_NO_REMOTE': '1', 07:54:42 INFO - 'NO_EM_RESTART': '1', 07:54:42 INFO - 'PAGER': '/bin/cat', 07:54:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:54:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:54:42 INFO - 'PWD': '/builds/slave/test', 07:54:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:54:42 INFO - 'SHELL': '/bin/bash', 07:54:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SmKiZYJBgQ/Listeners', 07:54:42 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 07:54:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:54:42 INFO - 'USER': 'cltbld', 07:54:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:54:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:54:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:54:42 INFO - 'XPC_FLAGS': '0x0', 07:54:42 INFO - 'XPC_SERVICE_NAME': '0', 07:54:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:54:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:54:42 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:54:42 INFO - Cleaning up... 07:54:42 INFO - Return code: 0 07:54:42 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:54:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:54:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:54:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:54:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:54:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:54:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:54:42 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 0x10fb153f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa8b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5dac86180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f92bd30>, '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 0x10f792db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f923b20>, '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.QjCB05Xsak/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.SmKiZYJBgQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/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 07:54:42 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 07:54:42 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 07:54:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QjCB05Xsak/Render', 07:54:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:54:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:54:42 INFO - 'HOME': '/Users/cltbld', 07:54:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:54:42 INFO - 'LOGNAME': 'cltbld', 07:54:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:54:42 INFO - 'MOZ_NO_REMOTE': '1', 07:54:42 INFO - 'NO_EM_RESTART': '1', 07:54:42 INFO - 'PAGER': '/bin/cat', 07:54:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:54:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:54:42 INFO - 'PWD': '/builds/slave/test', 07:54:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:54:42 INFO - 'SHELL': '/bin/bash', 07:54:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SmKiZYJBgQ/Listeners', 07:54:42 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 07:54:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:54:42 INFO - 'USER': 'cltbld', 07:54:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:54:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:54:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:54:42 INFO - 'XPC_FLAGS': '0x0', 07:54:42 INFO - 'XPC_SERVICE_NAME': '0', 07:54:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:54:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:54:42 INFO - Downloading/unpacking blobuploader==1.2.4 07:54:42 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 07:54:42 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 07:54:42 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 07:54:42 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 07:54:42 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 07:54:42 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 07:54:45 INFO - Downloading blobuploader-1.2.4.tar.gz 07:54:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:54:45 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:54:45 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 07:54:45 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 07:54:45 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 07:54:45 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 07:54:45 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 07:54:45 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 07:54:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:54:45 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:54:45 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 07:54:45 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 07:54:45 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 07:54:45 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 07:54:45 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 07:54:45 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 07:54:46 INFO - Downloading docopt-0.6.1.tar.gz 07:54:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:54:46 INFO - Installing collected packages: blobuploader, requests, docopt 07:54:46 INFO - Running setup.py install for blobuploader 07:54:46 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:54:46 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:54:46 INFO - Running setup.py install for requests 07:54:46 INFO - Running setup.py install for docopt 07:54:47 INFO - Successfully installed blobuploader requests docopt 07:54:47 INFO - Cleaning up... 07:54:47 INFO - Return code: 0 07:54:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:54:47 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')]} 07:54:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:54:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:54:47 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')]} 07:54:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:54:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:54:47 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 0x10fb153f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa8b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5dac86180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f92bd30>, '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 0x10f792db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f923b20>, '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.QjCB05Xsak/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.SmKiZYJBgQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/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 07:54:47 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 07:54:47 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 07:54:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QjCB05Xsak/Render', 07:54:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:54:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:54:47 INFO - 'HOME': '/Users/cltbld', 07:54:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:54:47 INFO - 'LOGNAME': 'cltbld', 07:54:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:54:47 INFO - 'MOZ_NO_REMOTE': '1', 07:54:47 INFO - 'NO_EM_RESTART': '1', 07:54:47 INFO - 'PAGER': '/bin/cat', 07:54:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:54:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:54:47 INFO - 'PWD': '/builds/slave/test', 07:54:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:54:47 INFO - 'SHELL': '/bin/bash', 07:54:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SmKiZYJBgQ/Listeners', 07:54:47 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 07:54:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:54:47 INFO - 'USER': 'cltbld', 07:54:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:54:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:54:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:54:47 INFO - 'XPC_FLAGS': '0x0', 07:54:47 INFO - 'XPC_SERVICE_NAME': '0', 07:54:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:54:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:54:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:54:47 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-TJ0sDl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:54:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:54:47 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-XEgsLw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:54:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:54:47 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-1GVMmk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:54:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:54:47 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-YATdpG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:54:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:54:47 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-_PODH2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:54:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:54:47 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-9W7nHk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:54:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:54:48 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-22Ud3n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:54:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:54:48 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-HdSxyy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:54:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:54:48 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-TX4_IP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:54:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:54:48 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-mKB_bH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:54:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:54:48 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-pNa2vd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:54:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:54:48 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-gpk04A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:54:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:54:48 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-eKNb00-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:54:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:54:48 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-zxINnP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:54:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:54:48 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-_ikDmK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:54:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:54:49 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-zir7H7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:54:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:54:49 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-fmeEn5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:54:49 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:54:49 INFO - Running setup.py install for manifestparser 07:54:49 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:54:49 INFO - Running setup.py install for mozcrash 07:54:49 INFO - Running setup.py install for mozdebug 07:54:49 INFO - Running setup.py install for mozdevice 07:54:49 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:54:49 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:54:49 INFO - Running setup.py install for mozfile 07:54:50 INFO - Running setup.py install for mozhttpd 07:54:50 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:54:50 INFO - Running setup.py install for mozinfo 07:54:50 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:54:50 INFO - Running setup.py install for mozInstall 07:54:50 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:54:50 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:54:50 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:54:50 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:54:50 INFO - Running setup.py install for mozleak 07:54:50 INFO - Running setup.py install for mozlog 07:54:50 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:54:50 INFO - Running setup.py install for moznetwork 07:54:50 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:54:50 INFO - Running setup.py install for mozprocess 07:54:51 INFO - Running setup.py install for mozprofile 07:54:51 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:54:51 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:54:51 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:54:51 INFO - Running setup.py install for mozrunner 07:54:51 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:54:51 INFO - Running setup.py install for mozscreenshot 07:54:51 INFO - Running setup.py install for moztest 07:54:51 INFO - Running setup.py install for mozversion 07:54:51 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:54:51 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:54:51 INFO - Cleaning up... 07:54:51 INFO - Return code: 0 07:54:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:54: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')]} 07:54:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:54:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:54: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')]} 07:54:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:54:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:54:51 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 0x10fb153f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa8b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5dac86180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f92bd30>, '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 0x10f792db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f923b20>, '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.QjCB05Xsak/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.SmKiZYJBgQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/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 07:54:51 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 07:54:51 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 07:54:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QjCB05Xsak/Render', 07:54:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:54:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:54:51 INFO - 'HOME': '/Users/cltbld', 07:54:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:54:51 INFO - 'LOGNAME': 'cltbld', 07:54:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:54:51 INFO - 'MOZ_NO_REMOTE': '1', 07:54:51 INFO - 'NO_EM_RESTART': '1', 07:54:51 INFO - 'PAGER': '/bin/cat', 07:54:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:54:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:54:51 INFO - 'PWD': '/builds/slave/test', 07:54:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:54:51 INFO - 'SHELL': '/bin/bash', 07:54:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SmKiZYJBgQ/Listeners', 07:54:51 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 07:54:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:54:51 INFO - 'USER': 'cltbld', 07:54:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:54:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:54:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:54:51 INFO - 'XPC_FLAGS': '0x0', 07:54:51 INFO - 'XPC_SERVICE_NAME': '0', 07:54:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:54:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:54:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:54:52 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-fku6np-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:54:52 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)) 07:54:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:54:52 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-czDgiN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:54:52 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)) 07:54:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:54:52 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-zGJiQc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:54:52 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)) 07:54:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:54:52 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-EX10nN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:54:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:54:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:54:52 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-wlgOmG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:54:52 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)) 07:54:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:54:52 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-wKfjt9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:54:52 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)) 07:54:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:54:52 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-vQx5Es-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:54:52 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)) 07:54:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:54:52 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-s7pRym-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:54:52 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)) 07:54:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:54:52 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-Sz1mtD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:54:53 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)) 07:54:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:54:53 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-V5JnUL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:54:53 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)) 07:54:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:54:53 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-GLN14S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:54:53 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)) 07:54:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:54:53 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-37qQa7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:54:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 07:54:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:54:53 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-0cokKH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:54:53 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)) 07:54:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:54:53 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-p4njJJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:54:53 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)) 07:54:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:54:53 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-Mz3Qq3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:54:53 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)) 07:54:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:54:53 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-ZDrjaa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:54:53 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 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)) 07:54:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:54:53 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-u6IRrV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:54:53 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)) 07:54:53 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)) 07:54:53 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)) 07:54:53 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)) 07:54:53 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:54:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:54:53 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:54:53 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 07:54:53 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 07:54:53 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 07:54:53 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 07:54:53 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 07:54:53 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 07:54:56 INFO - Downloading blessings-1.6.tar.gz 07:54:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:54:56 INFO - Installing collected packages: blessings 07:54:56 INFO - Running setup.py install for blessings 07:54:56 INFO - Successfully installed blessings 07:54:56 INFO - Cleaning up... 07:54:56 INFO - Return code: 0 07:54:56 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:54:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:54:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:54:57 INFO - Reading from file tmpfile_stdout 07:54:57 INFO - Current package versions: 07:54:57 INFO - blessings == 1.6 07:54:57 INFO - blobuploader == 1.2.4 07:54:57 INFO - docopt == 0.6.1 07:54:57 INFO - functools32 == 3.2.3-2 07:54:57 INFO - jsonschema == 2.5.1 07:54:57 INFO - manifestparser == 1.1 07:54:57 INFO - mozInstall == 1.12 07:54:57 INFO - mozcrash == 1.0 07:54:57 INFO - mozdebug == 0.1 07:54:57 INFO - mozdevice == 0.49 07:54:57 INFO - mozfile == 1.2 07:54:57 INFO - mozhttpd == 0.7 07:54:57 INFO - mozinfo == 0.9 07:54:57 INFO - mozleak == 0.1 07:54:57 INFO - mozlog == 3.4 07:54:57 INFO - moznetwork == 0.27 07:54:57 INFO - mozprocess == 0.23 07:54:57 INFO - mozprofile == 0.28 07:54:57 INFO - mozrunner == 6.13 07:54:57 INFO - mozscreenshot == 0.1 07:54:57 INFO - mozsystemmonitor == 0.3 07:54:57 INFO - moztest == 0.7 07:54:57 INFO - mozversion == 1.4 07:54:57 INFO - psutil == 3.1.1 07:54:57 INFO - requests == 1.2.3 07:54:57 INFO - wsgiref == 0.1.2 07:54:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:54:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:54:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:54:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:54:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:54:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:54:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:54:57 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 0x10fb153f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa8b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5dac86180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f92bd30>, '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 0x10f792db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f923b20>, '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.QjCB05Xsak/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.SmKiZYJBgQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/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 07:54:57 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 07:54:57 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 07:54:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QjCB05Xsak/Render', 07:54:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:54:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:54:57 INFO - 'HOME': '/Users/cltbld', 07:54:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:54:57 INFO - 'LOGNAME': 'cltbld', 07:54:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:54:57 INFO - 'MOZ_NO_REMOTE': '1', 07:54:57 INFO - 'NO_EM_RESTART': '1', 07:54:57 INFO - 'PAGER': '/bin/cat', 07:54:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:54:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:54:57 INFO - 'PWD': '/builds/slave/test', 07:54:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:54:57 INFO - 'SHELL': '/bin/bash', 07:54:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SmKiZYJBgQ/Listeners', 07:54:57 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 07:54:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:54:57 INFO - 'USER': 'cltbld', 07:54:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:54:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:54:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:54:57 INFO - 'XPC_FLAGS': '0x0', 07:54:57 INFO - 'XPC_SERVICE_NAME': '0', 07:54:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:54:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:54:57 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)) 07:54:57 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)) 07:54:57 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)) 07:54:57 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)) 07:54:57 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)) 07:54:57 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)) 07:54:57 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)) 07:54:57 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)) 07:54:57 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)) 07:54:57 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)) 07:54:57 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)) 07:54:57 INFO - Cleaning up... 07:54:57 INFO - Return code: 0 07:54:57 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:54:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:54:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:54:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:54:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:54:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:54:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:54:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fb153f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa8b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5dac86180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f92bd30>, '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 0x10f792db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f923b20>, '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.QjCB05Xsak/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.SmKiZYJBgQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/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 07:54:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 07:54:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 07:54:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QjCB05Xsak/Render', 07:54:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:54:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:54:57 INFO - 'HOME': '/Users/cltbld', 07:54:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:54:57 INFO - 'LOGNAME': 'cltbld', 07:54:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:54:57 INFO - 'MOZ_NO_REMOTE': '1', 07:54:57 INFO - 'NO_EM_RESTART': '1', 07:54:57 INFO - 'PAGER': '/bin/cat', 07:54:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:54:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:54:57 INFO - 'PWD': '/builds/slave/test', 07:54:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:54:57 INFO - 'SHELL': '/bin/bash', 07:54:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SmKiZYJBgQ/Listeners', 07:54:57 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 07:54:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:54:57 INFO - 'USER': 'cltbld', 07:54:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:54:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:54:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:54:57 INFO - 'XPC_FLAGS': '0x0', 07:54:57 INFO - 'XPC_SERVICE_NAME': '0', 07:54:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:54:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:54:57 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:54:57 INFO - Cleaning up... 07:54:57 INFO - Return code: 0 07:54:57 INFO - Running post-action listener: _resource_record_post_action 07:54:57 INFO - Running post-action listener: _start_resource_monitoring 07:54:57 INFO - Starting resource monitoring. 07:54:57 INFO - [mozharness: 2016-11-22 15:54:57.721779Z] Finished create-virtualenv step (success) 07:54:57 INFO - [mozharness: 2016-11-22 15:54:57.722806Z] Running install step. 07:54:57 INFO - Running pre-action listener: _resource_record_pre_action 07:54:57 INFO - Running main action method: install 07:54:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:54:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:54:57 INFO - Reading from file tmpfile_stdout 07:54:57 INFO - Detecting whether we're running mozinstall >=1.0... 07:54:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:54:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:54:58 INFO - Reading from file tmpfile_stdout 07:54:58 INFO - Output received: 07:54:58 INFO - Usage: mozinstall [options] installer 07:54:58 INFO - Options: 07:54:58 INFO - -h, --help show this help message and exit 07:54:58 INFO - -d DEST, --destination=DEST 07:54:58 INFO - Directory to install application into. [default: 07:54:58 INFO - "/builds/slave/test"] 07:54:58 INFO - --app=APP Application being installed. [default: firefox] 07:54:58 INFO - mkdir: /builds/slave/test/build/application 07:54:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 07:54:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 07:55:31 INFO - Reading from file tmpfile_stdout 07:55:31 INFO - Output received: 07:55:31 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 07:55:31 INFO - Running post-action listener: _resource_record_post_action 07:55:31 INFO - [mozharness: 2016-11-22 15:55:31.467895Z] Finished install step (success) 07:55:31 INFO - [mozharness: 2016-11-22 15:55:31.468005Z] Running run-tests step. 07:55:31 INFO - Running pre-action listener: _resource_record_pre_action 07:55:31 INFO - Running main action method: run_tests 07:55:31 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:55:31 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:55:31 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:55:31 INFO - 2016-11-22 07:55:31.482 screenresolution[1627:7873] starting screenresolution argv=screenresolution get 07:55:31 INFO - 2016-11-22 07:55:31.485 screenresolution[1627:7873] Display 0: 1600x1200x32@60 07:55:31 INFO - 2016-11-22 07:55:31.491 screenresolution[1628:7875] starting screenresolution argv=screenresolution list 07:55:31 INFO - Available Modes on Display 0 07:55:31 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:55:31 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:55:31 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:55:31 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:55:31 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:55:31 INFO - Intel Iris: 07:55:31 INFO - Chipset Model: Intel Iris 07:55:31 INFO - Type: GPU 07:55:31 INFO - Bus: Built-In 07:55:31 INFO - VRAM (Dynamic, Max): 1536 MB 07:55:31 INFO - Vendor: Intel (0x8086) 07:55:31 INFO - Device ID: 0x0a2e 07:55:31 INFO - Revision ID: 0x0009 07:55:31 INFO - Displays: 07:55:31 INFO - Display: 07:55:31 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:55:31 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:55:31 INFO - Display Serial Number: 1600x1200 60 07:55:31 INFO - Main Display: Yes 07:55:31 INFO - Mirror: Off 07:55:31 INFO - Online: Yes 07:55:31 INFO - Rotation: Supported 07:55:31 INFO - Return code: 0 07:55:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:55:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:55:31 INFO - Python 2.7.3 07:55:31 INFO - Return code: 0 07:55:31 INFO - grabbing minidump binary from tooltool 07:55: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')]} 07:55:31 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 0x10f92bd30>, '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 0x10f792db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f923b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:55:31 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 07:55:31 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 07:55:31 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 07:55:31 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:55:31 INFO - Return code: 0 07:55:31 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:55:31 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:55:31 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:55:31 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:55:31 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:55:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dmWAvqaiSSi71l8PiezDJQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0098', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 07:55:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Graphics --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/dmWAvqaiSSi71l8PiezDJQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0098 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 07:55:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QjCB05Xsak/Render', 07:55:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:55:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:55:31 INFO - 'HOME': '/Users/cltbld', 07:55:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:55:31 INFO - 'LOGNAME': 'cltbld', 07:55:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:55:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:55:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:55:31 INFO - 'MOZ_NO_REMOTE': '1', 07:55:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:55:31 INFO - 'NO_EM_RESTART': '1', 07:55:31 INFO - 'PAGER': '/bin/cat', 07:55:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:55:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:55:31 INFO - 'PWD': '/builds/slave/test', 07:55:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:55:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:55:31 INFO - 'SHELL': '/bin/bash', 07:55:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SmKiZYJBgQ/Listeners', 07:55:31 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 07:55:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:55:31 INFO - 'USER': 'cltbld', 07:55:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:55:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:55:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:55:31 INFO - 'XPC_FLAGS': '0x0', 07:55:31 INFO - 'XPC_SERVICE_NAME': '0', 07:55:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:55:31 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dmWAvqaiSSi71l8PiezDJQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0098', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 07:55:32 INFO - mozversion application_buildid: 20161122065550 07:55:32 INFO - mozversion application_changeset: 1ff17ad606b1dc2cf1135337b52acd80579889b2 07:55:32 INFO - mozversion application_display_name: Nightly 07:55:32 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:55:32 INFO - mozversion application_name: Firefox 07:55:32 INFO - mozversion application_remotingname: firefox 07:55:32 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 07:55:32 INFO - mozversion application_vendor: Mozilla 07:55:32 INFO - mozversion application_version: 53.0a1 07:55:32 INFO - mozversion platform_buildid: 20161122065550 07:55:32 INFO - mozversion platform_changeset: 1ff17ad606b1dc2cf1135337b52acd80579889b2 07:55:32 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 07:55:32 INFO - mozversion platform_version: 53.0a1 07:55:32 INFO - using testdate: 1479830132 07:55:32 INFO - actual date: 1479830132 07:55:32 INFO - starting webserver on 'localhost:49235' 07:55:32 INFO - SUITE-START | Running 2 tests 07:55:32 INFO - TEST-START | dromaeo_css 07:55:32 INFO - operating with platform_type : mac_ 07:55:32 INFO - Initialising browser for dromaeo_css test... 07:55:32 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/tmpgHlpNz/profile http://localhost:49235/getInfo.html) 07:55:35 INFO - PROCESS | 1638 | 2016-11-22 07:55:35.413 plugin-container[1641:8061] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e3f, name = 'com.apple.tsm.portname' 07:55:35 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:55:35 INFO - PROCESS | 1638 | 2016-11-22 07:55:35.415 plugin-container[1641:8061] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x940b, name = 'com.apple.CFPasteboardClient' 07:55:35 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:55:35 INFO - PROCESS | 1638 | 2016-11-22 07:55:35.415 plugin-container[1641:8061] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:55:35 INFO - PROCESS | 1638 | __metrics Screen width/height:1600/1200 07:55:35 INFO - PROCESS | 1638 | colorDepth:24 07:55:35 INFO - PROCESS | 1638 | Browser inner width/height: 1024/689 07:55:35 INFO - PROCESS | 1638 | __metrics 07:55:35 ERROR - PROCESS | 1638 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3198: TypeError: this.tabs is undefined 07:55:36 INFO - TEST-INFO | 1638: exit 0 07:55:36 INFO - Browser initialized. 07:55:36 INFO - Running cycle 1/1 for dromaeo_css test... 07:55:36 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/tmpgHlpNz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:55:39 INFO - PROCESS | 1642 | 2016-11-22 07:55:39.269 plugin-container[1643:8176] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 07:55:39 INFO - PROCESS | 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:55:39 INFO - PROCESS | 1642 | 2016-11-22 07:55:39.271 plugin-container[1643:8176] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4707, name = 'com.apple.CFPasteboardClient' 07:55:39 INFO - PROCESS | 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:55:39 INFO - PROCESS | 1642 | 2016-11-22 07:55:39.271 plugin-container[1643:8176] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:55:48 INFO - PROCESS | 1642 | RSS: Main: 243363840 07:55:48 INFO - PROCESS | 1642 | 07:56:38 INFO - PROCESS | 1642 | 1479830198593 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 07:58:50 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49235/tests/dromaeo/cssquery-ext.html) 07:58:53 INFO - PROCESS | 1642 | RSS: Main: 251428864 07:58:53 INFO - PROCESS | 1642 | 08:01:55 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-ext.html (next: http://localhost:49235/tests/dromaeo/cssquery-jquery.html) 08:01:56 INFO - PROCESS | 1642 | RSS: Main: 260169728 08:01:56 INFO - PROCESS | 1642 | 08:04:57 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49235/tests/dromaeo/cssquery-mootools.html) 08:04:58 INFO - PROCESS | 1642 | RSS: Main: 264650752 08:04:58 INFO - PROCESS | 1642 | 08:08:00 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49235/tests/dromaeo/cssquery-prototype.html) 08:08:01 INFO - PROCESS | 1642 | RSS: Main: 263192576 08:08:01 INFO - PROCESS | 1642 | 08:11:03 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49235/tests/dromaeo/cssquery-yui.html) 08:11:04 INFO - PROCESS | 1642 | RSS: Main: 267714560 08:11:04 INFO - PROCESS | 1642 | 08:14:07 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-yui.html (next: http://localhost:49235/tests/dromaeo/cssquery-dojo.html) 08:14:07 INFO - PROCESS | 1642 | RSS: Main: 270262272 08:14:07 INFO - PROCESS | 1642 | 08:14:07 INFO - PROCESS | 1642 | __start_tp_report 08:14:07 INFO - PROCESS | 1642 | _x_x_mozilla_page_load 08:14:07 INFO - PROCESS | 1642 | _x_x_mozilla_page_load_details 08:14:07 INFO - PROCESS | 1642 | |i|pagename|runs| 08:14:07 INFO - PROCESS | 1642 | |0;dojo.html;3330.483775014872;3349.447341188704;3391.6099648540417;3397.8640854365813;3413.965860341396;5033.798648054082;5190.584753219743;5624.409437009112;5625.859353516164;5667.57493188011;5569;5729.799457019005;5742.79900203493;5755.136729490568;5777.133429985514;4709.434867815849;5006.173981293082;5121.795128194886;5240.711760853152;5329.040772660919;5224.4775522447835;5303.178007408857;5329.2006199070065;5333.226682131089;5335.493128152839;118.7013107612428;4272;5129.563987061104;5161.200013997836;5191.948080519184;5231.346081739783;5235.371755389339;5235.450277720861;5247.763850626693;5292.973535132338;5196.92204616931;5199.584033277329;5206;5208.374995000586;5301.867453313659;5129.384243963177;5400.91898621521;5627.774889004435;5689.943100569024;5774.971125144348;5335.706536140511;6214.5960512566535;6233.594816336924;6242.188515493002;6263;6245;6255.624662520263;6264.123022776769;6269.749210031593;6271.435570798604;5715.342735585377;5848.5321174305955;5864.354920958691;5899.823005309848;5919.2600924883955;6173;6188.504919606421;6192.504599632109;6239.937600624026;6308.936910630927;4792.945551978629;6113.807807477499;6210.068489726486;6214.192155019909;6225.688715564203;4942.1104201244125;5368;5955.255593050869;6198.876022479525;6231.3145553989025;6082.239720034996;6103.511719062464;6110.649890384107;6182.165407669997;6186.690665466709;5364.892702145935;6227.377262273736;6259.593126446766;6287.685615719196;6297.685115744194;4132.152908653718;4832.8791780205775;4885.389326334208;4903.509649035068;4947.950520494749;3877.8254978526033;4386.978065109718;4410.360497727812;4459.754713490789;4504.031633198878;5036.7156375124105;5423.403425623178;5631.521320687705;5835.2122463466885;5836.7957121501395;6159.753609855646;6160.414760597736;6202.162708034448;6218.06728990655;6241.313455519889;5813;5814.30228372598;5815.621984570989;5833.329167145747;5844.707764611838;5402.756875940513;5416.7020813855615;5422;5431.782728690887;5437.157240627709;6135.385782596134;6199.752009919553;6225.688715564294;6259.906101408392;6264.718087685974;5152.871178220575;6245.094461302995;6248.0003199487865;6286.7799627014665;6290.937090629218;6325.019621958604;6328.588641738273;6344.555881088279;6373.904391434225;6385.29761726219;6058.697065146813;6099.725512352044;6107.572469927062;6151.876962460637;6174.197354343906;6103.549811064127;6104.641056839945;6133.059244992972;6149.169862068564;6244.750209991731;6114.969425152845;6124.112003759519;6131.908021379593;6145.35473775265;6154.292256390572;5968.134620480093;6050.062240352752;6103.870783904992;6123.663198524124;6154.353792851865;5741.569382296428;5842.444967728059;5903.586748927534;5918.348981611933;5921.052631578927;6110.558759196553;6116.052011938156;6120.843333897588;6126.3039328557115;6135.601185922901;5796.275465566804;5819.94180058194;5872.911906321494;5890.322612899571;5984.640921544721 08:14:07 INFO - PROCESS | 1642 | |1;ext.html;493.64457590534823;509.0607828556314;537.7660717587847;540.4649397096875;562.4965655738117;29369;31127.688723112762;37829.81085094574;38337.04157396066;38492;38851.61148388522;39028;39141.434342626264;39342.40986385199;39467.60532394675;9260.351775375726;9404.965453800076;9427.858582121273;9442.055794420537;9457.432554046745;9349.111834375757;9381.76545586359;9442.150206481416;9444.102810233016;9448.48033358165;9414.058594140566;9473.289503287246;9485.668001619944;9489.81020379592;9506.762330941747;9372.671956481525;9412.529373531364;9449.291303152291;9451.480168590724;9454.763630909214;9283.978762336137;9373.484458354787;9378.062193780568;9388.577514011886;9394.718158455258;31001.689983100106;35802;38093.238135237414;38271.42592861109;38507.807460962795;2622.7508386703234;2821.985890070557;2917.737403633662;3088.737457316131;3737.794421306827;28649.71350286512;29058.854705726546;29152.562711559345;29350;29370.55944160818;3181.18879685681;3220.790648607833;3222.3716375306826;3266.5100234964707;3319.921025666668;455162;457644;458468;461247.9999999966;461354;57437;57691.711541442026;57873.42126578765;57971;58023.709881450326;33021;34035;34067.659323406944;34880.30239395198;59717.701411492664;15400.152991585346;15509;15805.446809361618;15842.841571584137;15846;11860.703482412837;12113.152079354537;12153.69615759613;12159.392030398445;12160.75678486443;18007.459776206473;18227.635447291246;18251.079827227844;18276.908615456836;18330.725039124423;6566.967165164144;7950.801229969182;7975.4018448615425;7981.321587665114;8025.23760242776;755;991.7074463033423;1014.2747935226348;1015.7151203727291;1104.8839871813504;20890.373288801362;27576.034838780546;27607.86196069007;29313.413731725672;29411.85294073516;1072.989270107289;1089.9945500272447;1131.9981816092818;1136.6476392318407;1142.8228924208079;12393.566225182076;14523.201223932574;14856.10863348181;14875.776863347059;15067.719243864176;633432;635572;641359;641710;642492;52961;53441.46558534443;54126.72936635291;54225;54325;1328.2097152194428;1344.6302266876492;1359.932003399826;1369.2127026959538;1377.0773581700284;28279.151625451268;28489.145325640264;28711.985241549337;29038.27404314909;29049.273768155967;45392.54607454015;46212;46496.53503464922;46625.83435414142;46704.29943550917;556620;558354;561735;562529;563694;20828.47928801792;24920.376990574656;25079.373015674024;25247.11635092763;25648.871755641103;43290.78354608207;44069.33895991499;44226.778866105466;44488.33267501055;44765.552344476135;31862.840685796422;35491.11272218215;36282.637173627925;37551.624483755906;37631;33819.830900846326;35274.470882936264;35858.10354741153;35983.28033439369;36971.815140924125;35874.28251435009;35885;35929.64070359263;36319;36323.09192270109;31646.683533164374;33049.669503304656;33088.00735977924;33103.00690979178;33137.83431082829;21312.78687213108;21796.89101554482;22877.542449151257;24253.514929700956;24663 08:14:07 INFO - PROCESS | 1642 | |2;jquery.html;340.25484189624706;343.3339321715869;366.891766928756;371.00015458339766;395.37333326677214;17819.910900445495;18516;19383.449324054076;19414.029298535068;19628.214871405125;19180.808191918077;19447.027648617583;19452.416427507163;19617.215311387565;19729.802701972974;4812.855614331567;5559.082751346044;6031.215941927543;6041.939580604182;6059.000264956277;4884.877878053042;5048.8892443662335;5489.972550137244;5620.381758006616;5949.851253718648;5651.180578816069;5898.115282707607;5919.822405327847;5925;5928.436798504157;5931.88136237275;6060.818175454743;6074.51403887688;6080.635161890279;6095.908561371583;5029.5221953914315;5331.653442526223;5396.676199428047;5884.9705751470965;5915.319738230116;12353.62939111828;12560.434780434902;12646.936765316115;12647.87352126476;23939.7606023941;1998.2606435618914;2108.95606674697;2135.1886283212434;2234.4078819112947;2248.8875556222124;9287.814243715155;9291.303152263607;9535;9921.75195620108;10094.29339946204;1777.191377923042;1806.801251862294;1832.1842744988621;1844.1148248840498;1860.8883466992024;117255;120087;121371;122473;123361.38319308436;8686.348523860735;11082;11172.497237624326;11199.496022678999;11406.030487408498;11222;13890.374933128034;13950.511732089328;13974.650633734238;13982.72034559303;6300.8424789379715;6908.447324214051;7817.687292508236;8240.587970601446;8348.248657620843;8079.798005049921;8115.026196856415;8244.464109832961;8259.752207433787;8301.418900677016;4291.442112525414;4350.73895566267;4385.802638881194;4444.355568442557;4485.0805584855025;1679.4877562343358;1868.588910439701;1868.9439316820517;1911.246491070892;1918.1080797429263;301.2378681934709;355.886116442741;368.12753773556307;812.3014207781304;926.305271046715;11054.336739795637;11217.439128043565;11529.596464123715;11536;11789;415.8690012646007;418.81572108272263;421.9852305169304;426.7290270678142;436.21699050204955;5756.856078598069;5759.625624334405;5810.6513609183585;5912.438318359749;5924.170616113747;542767;554543;559379;560009;561198;130921.9999999981;132504;132584.33707831398;132910.3544822755;136369.6363036357;356;439.580200908132;439.86803958812226;440.94929083155205;441.4459852884609;9260;10016.248781341283;10413.271071024956;10740.409277489813;10757.892421075689;49688.80757059956;50773.492265078356;51095.744521277156;51470;52161;77660.61169694115;78207.60896195483;78675.21324786908;79027;79930.2006979946;6373.012183111625;6533.509986750918;7479;7702.691892324245;7730.7680769577;9334.673286434941;11887.643370698894;11928.940355298168;12232.327222002876;12333.691657708629;10163.390196588229;10194.235432342752;10580;10633.574657013633;10745.301555398873;8580.528070955908;10186.592536298762;10212.387256764618;10691.572337106429;10855.67432977012;10366.585336586451;10409.9479502602;10905.236633435901;10945.945270273598;10993.450327483753;7448.217937116525;7687;7801.4929029612895;7839.529628222325;7843.803904902423;10495.919025527737;10519.421431821323;10605.628802991858;10633.468326583794;10731.517081731497 08:14:07 INFO - PROCESS | 1642 | |3;mootools.html;1324.9470021199152;1465.7947887295782;1618.5710786641541;1629.1365576244586;1785.2501949181342;8080.595970201496;8496.872546911802;8497.830043399128;8510.914890851089;8525.317974562036;7543.094828620559;7827.099883513398;7994.360451163908;8312.91687083129;8327.791805204874;2303.7465878753364;2351.036075209156;2351.5649604823075;2359.0563774490256;2369.1115831563166;2332.4285550040263;2363.1847012780563;2377.5720370333333;2383.6305372667266;2386.940326491843;2316.9073237070497;2322.779335963089;2347.542229265294;2348.3542025942834;2348.989934328247;2302.827287953402;2307.97692023081;2325.686032385623;2328.359701082199;2335.4511689752894;2323.2217207235594;2346.0263990443946;2349.295211436562;2350.095213342858;2362.61016932206;8424.073351931283;8458.323334133254;8479.109693482218;8486.490810551324;8491.74524764258;4955.132851750929;5038.722870242135;5214.191800270964;5216.895662086737;5218.1129208034345;3290.654481279455;3528.6647768462212;3614.114541937216;3619.1494998675553;3622.8188590570367;1022.8850552897378;1032.0866033560353;1076.9407682577455;1084.1543595995136;1089.1939964426347;10801.40592267425;10878.61924832635;10948.343099413982;10955.4522273886;10961.83557246642;4439.844605438794;4584.9541504585195;4669.719816811003;4685.672002959828;4721.480637129913;9419.952900235456;9492.240620750323;9502.90497095034;9508.809823803624;9518.476483793458;5196;5211.530962213419;5213.7914483421;5215.009148261818;5227.503387178288;4273.444452221192;4286.87139385819;4689.788959496831;4757.238841785298;4782.306565548046;3744.756590821588;3765.962340376616;3860.864869729602;3880.7477513961503;3889.0666240102737;1268.154834715505;1272.5482453728978;1274;1282.8588855225917;1287.6458973782098;814.1776805426562;870.8954925408865;886.1183122792862;894.9463032218088;899.3569597737649;5438.129899216106;5524.972375138099;5613.607047506635;5668.234788303609;5735.11105778605;678.4335080208082;942.528735632184;948.2129832239225;992.980140397188;998.3560603410759;3028.2883522372226;3596.478510615946;3652.7077833773233;3761.7178711596193;3850.441685955521;8252.711155109542;9822.410655360702;9913.75215619601;10126.291159618757;10155.796884062278;9898.455584942752;9947.751306217402;10054.49727513636;10074.69775906724;10092.192624590016;7397.18630950595;7674.117476490163;7727.574983375968;7843.490173138728;7863.056433228049;5640.71796410186;5721.141828725725;5767.653940763567;5856.150858125633;5869.442402971711;9118.908810911806;9220.400673956172;9331.813363732552;9418.62325506972;9419.434833909987;9293.117153870371;9445.291603129655;9548.713538593853;9581.568829402555;9592.808143836945;6606.669666516559;6866.485013623898;6954.547954382949;7165.498415110892;7296.306850849161;7870.29167374939;8039.316658084128;8048.87926681112;8057.194280571896;8078.757637270891;7011.193712722898;7130.964345178241;7164.355208031302;7224.8916266255;7266.309700578437;6925.231180950335;6984;7041.401480874183;7068.823279418056;7116.323949224816;6632.668366581555;6692;6741.696623651849;6758.222804377559;6775.966120169368;4825.324454576432;4870.586000190024;4883.194272944925;4932.926006109839;4935.975320123376;4750.907291322946;4758.214894542361;4795.20879054966;4834.685745426536;4946.134426475281 08:14:07 INFO - PROCESS | 1642 | |4;prototype.html;281.32622369425223;294.69646264347716;296.45600323406546;308.1556535093843;311.465836091104;5190.2474141249595;5366.436524164965;5367.731613419323;5525.673838278809;5551.333839939208;4054.5134583849904;5008.29883816266;5012.924806127902;5329.893402131955;5382.2195781611645;3444.5866496020385;3452.5511683481113;4070.694697897662;4128.195001974616;4189.434426352434;3244.5075265377955;3455.565940134841;3827.4453400453513;4163.0008797888595;4187.539370669224;3448.1552019755195;3538.82305884706;3747.47045744123;3818.746939305809;4036.2734707752593;3338.8998330050017;3834.6740527055094;4002.7598344099447;4064.3700226464944;4083.9795801020805;2692.909371704463;3076.6769489203534;3080.69070644975;3470.6355832637714;3533.522974398449;5117.692938423706;5210.270562121306;5245.554127899133;5249.527542521153;5276.4459731728;4018.6383225509553;4439.7780110994645;4611.2852507861335;4617.7762892833425;4640.419947506562;4700.660311811117;5603.915941260884;5626.299376299358;5649.604527683064;5666.716664166775;3610.2057547339805;3710.1466662667467;3806;3816.255830113101;3842.385218365076;5926.2295901532525;5947.851303717398;5953.434423729782;5959.599494118878;6001.35940186321;3313.9005829825146;3856.3444214483043;3862.517185351831;3874.709396795195;3893.8247778849454;3704.2776658551325;3948.6841052715713;3950.4074388841905;3953.9522026662958;3956.4263181838473;3795.6204379561823;4073.877783666495;4293.613574778285;4699.365585645963;4703.529647035338;4824.6863953842885;5723.427657234327;5749.741261643236;5771.7402716877;5819.796307129315;1020.0513522424217;1101.3116801998751;1200.439428742631;1204.584418375659;1263.4585805317497;803.9879401808978;824.3858325547386;844.6579135450153;862.4264863865459;873.6418068591847;4584.977075114603;4912.778924948386;4954.455009948902;4980.551750342414;5063.594912406998;5289.9155673086925;5391.816314392678;5518.641288316327;5558.416366281563;5608.513743857881;3816.4847745553993;4280.357946308079;4748.216544270226;5064.620153488503;5117.974410127926;2520.5841036229185;2848.5442329227226;2859.513882639956;2886.942261154765;2890.783191260664;4556.886077848014;5239.532930779312;5262.8686481112145;5687.350668306145;5720.42795720433;4829.541193586604;5341.3857406398765;5612.540739407784;5619.791744774893;5700.572457065737;5067.619928505377;5695.800646977336;5696.23100881384;5698.772049118072;5709.429057094258;5322.627416080992;5441.469716581809;5457.781688732406;5502.532284755681;5538.833834984957;4485.327200919888;4957.529034741694;5405.783768649368;5456.7544460498575;5473.454843497792;4629.907401851877;5010.924836127534;5018.824341148043;5642.971785141048;5682.77268909239;3407.3866703993517;3700.481932529502;3702.61122582125;3757.981210093932;3769.7738135711943;5064.606713502036;5125.718085505333;5404.918926216189;5479.178123281539;5692.829215123553;5044.445111037856;5160.277561141368;5516.972415137899;5674.205611214516;5675.77296908119;5635.520980716685;5639.436056394327;5639.533721232427;5657.3776884543495;5708;5192.662476938987;5382.3541175059245;5527.419620939743;5571.582131340085;5574.275344205227;5610.270664813548;5619.719014049363;5635.9436405635415;5659.773609055592;5660.29246344207;5529.806456773994;5545.584081193846;5547.916781248204;5560.582956278215;5594.748236329294 08:14:07 INFO - PROCESS | 1642 | |5;yui.html;582.9215950491589;660.9900851487229;695.9860802783944;715.3311653603884;765.7932358263268;14226.502072427467;15021.92489037556;15027.098374097535;15075.924620376896;15092.547223583297;13515.256660883671;13658;13709.245991470465;13721.65695857604;13725.93137034316;637.9330170332117;661.6228749612723;793.3137835772045;810.2181394953868;820.233082068265;793.8491686579531;815.902091748991;822.3339095332769;824.1305422778966;824.468217999393;647.6826355086021;695.9686814093378;870.3559366069127;872.4111224923183;872.8865247517846;734.6253410760497;849.9107593702633;864.7492227254089;867.0245973280059;867.132867132864;626.5363630913137;642.6240649220211;644.5230529408251;645.3224114679567;646.1599920103846;12830.777073119529;13443.66390840227;13611.319434028259;13710.834579060791;14418.423263069464;12274.447649855778;12431.875681243162;12499.750004999849;13513.324333783272;14052.71894562103;11292;11366.37484938328;11407;11497.655070347902;11519.42402879861;3556.6513772915223;3597.190632107781;3639.2721455708966;3707.295613833363;3775.8678446254526;69943;88752.55623721905;91996.54001729949;95399.9999999993;96596.51701741517;10342.896571034344;10361.94819025915;10407.63573274947;10420;10791.24461287718;15718.29267682957;18934.810651893582;19712.112954917062;19971.60056798885;20004.69992950107;7496.111820562348;7620.847583048308;7724.922750772533;7726.072871255373;7878.606069696492;4220.725652832556;4261.659067274611;4312.784360781949;4382.904273931517;4418.138462999685;3271.4765637497885;3320.900372988814;3361.663833616619;3378.172347774811;3379.5099710541836;615.1756646094256;618.7810014271877;619.9411055949678;626.1828314050208;640.471610920992;534.7353060235208;628.2210059526154;645.8062581225613;677.069030083635;691.4262155839189;8757.27086546227;8780.473171609723;8978.775530611787;8996.325275604357;9063.41087829289;629.175779728557;633.6926590603554;649.4317472211815;668.7325069972051;671.6507416143578;3733.9626603733614;4148.979255103705;4235.470566179228;4296.163146944503;4481.5070342262325;74583.62708186424;95253.09493810085;113388;119638;121479;84657.57671211728;85451;102807;104029.47985260177;104748.47625761823;64844;73201.63399183076;79213.60393197997;89486;91397;6700.564463309869;7438.128026142885;7668.923310766821;8675.783105422415;8790.34072444582;57708.71145644413;58090.4190958085;59046.704766475894;59276.40723592709;60041.69979150251;81207;81308.96727581858;82157;82507.74922507702;84752.1524784769;11141.665750027512;11145.331280123219;11272.323660580392;11499.597514086967;11698.2981021139;12270.263784172977;12313.630591082281;12409.379531023233;12474.126811123497;12977.805332919825;11823.645290641294;11837.940810295893;12146.514139434325;12244.816327755269;12248.265104093784;11802.76394472123;11906.404679765803;11961.521539138685;12251.38743062861;12304.569340073413;11779.469923853276;11885.22746021506;11934.940325298317;12303.56937507183;12309.815352769536;9670.468124253233;9702.563384647849;9723.513824308899;9903.504824758877;10215.080642742189;9587.472689002172;9944.651937182163;9952.00479951999;9968.053034961667;10004.049615286536 08:14:07 INFO - PROCESS | 1642 | __end_tp_report 08:14:07 INFO - PROCESS | 1642 | __start_cc_report 08:14:07 INFO - PROCESS | 1642 | _x_x_mozilla_cycle_collect,369 08:14:07 INFO - PROCESS | 1642 | __end_cc_report 08:14:07 INFO - PROCESS | 1642 | __startTimestamp1479831247964__endTimestamp 08:14:07 INFO - PROCESS | 1642 | 08:14:07 INFO - PROCESS | 1642 | ------- Summary: start ------- 08:14:07 INFO - PROCESS | 1642 | Number of tests: 6 08:14:07 INFO - PROCESS | 1642 | 08:14:07 INFO - PROCESS | 1642 | [#0] dojo.html Cycles:165 Average:5641.01 Median:5864.35 stddev:794.17 (13.5%) stddev-sans-first:775.65 08:14:07 INFO - PROCESS | 1642 | Values: 3330.5 3349.4 3391.6 3397.9 3414.0 5033.8 5190.6 5624.4 5625.9 5667.6 5569.0 5729.8 5742.8 5755.1 5777.1 4709.4 5006.2 5121.8 5240.7 5329.0 5224.5 5303.2 5329.2 5333.2 5335.5 118.7 4272.0 5129.6 5161.2 5191.9 5231.3 5235.4 5235.5 5247.8 5293.0 5196.9 5199.6 5206.0 5208.4 5301.9 5129.4 5400.9 5627.8 5689.9 5775.0 5335.7 6214.6 6233.6 6242.2 6263.0 6245.0 6255.6 6264.1 6269.7 6271.4 5715.3 5848.5 5864.4 5899.8 5919.3 6173.0 6188.5 6192.5 6239.9 6308.9 4792.9 6113.8 6210.1 6214.2 6225.7 4942.1 5368.0 5955.3 6198.9 6231.3 6082.2 6103.5 6110.6 6182.2 6186.7 5364.9 6227.4 6259.6 6287.7 6297.7 4132.2 4832.9 4885.4 4903.5 4948.0 3877.8 4387.0 4410.4 4459.8 4504.0 5036.7 5423.4 5631.5 5835.2 5836.8 6159.8 6160.4 6202.2 6218.1 6241.3 5813.0 5814.3 5815.6 5833.3 5844.7 5402.8 5416.7 5422.0 5431.8 5437.2 6135.4 6199.8 6225.7 6259.9 6264.7 5152.9 6245.1 6248.0 6286.8 6290.9 6325.0 6328.6 6344.6 6373.9 6385.3 6058.7 6099.7 6107.6 6151.9 6174.2 6103.5 6104.6 6133.1 6149.2 6244.8 6115.0 6124.1 6131.9 6145.4 6154.3 5968.1 6050.1 6103.9 6123.7 6154.4 5741.6 5842.4 5903.6 5918.3 5921.1 6110.6 6116.1 6120.8 6126.3 6135.6 5796.3 5819.9 5872.9 5890.3 5984.6 08:14:07 INFO - PROCESS | 1642 | 08:14:07 INFO - PROCESS | 1642 | [#1] ext.html Cycles:180 Average:66881.07 Median:36321.05 stddev:149293.68 (411.0%) stddev-sans-first:149629.28 08:14:07 INFO - PROCESS | 1642 | Values: 493.6 509.1 537.8 540.5 562.5 29369.0 31127.7 37829.8 38337.0 38492.0 38851.6 39028.0 39141.4 39342.4 39467.6 9260.4 9405.0 9427.9 9442.1 9457.4 9349.1 9381.8 9442.2 9444.1 9448.5 9414.1 9473.3 9485.7 9489.8 9506.8 9372.7 9412.5 9449.3 9451.5 9454.8 9284.0 9373.5 9378.1 9388.6 9394.7 31001.7 35802.0 38093.2 38271.4 38507.8 2622.8 2822.0 2917.7 3088.7 3737.8 28649.7 29058.9 29152.6 29350.0 29370.6 3181.2 3220.8 3222.4 3266.5 3319.9 455162.0 457644.0 458468.0 461248.0 461354.0 57437.0 57691.7 57873.4 57971.0 58023.7 33021.0 34035.0 34067.7 34880.3 59717.7 15400.2 15509.0 15805.4 15842.8 15846.0 11860.7 12113.2 12153.7 12159.4 12160.8 18007.5 18227.6 18251.1 18276.9 18330.7 6567.0 7950.8 7975.4 7981.3 8025.2 755.0 991.7 1014.3 1015.7 1104.9 20890.4 27576.0 27607.9 29313.4 29411.9 1073.0 1090.0 1132.0 1136.6 1142.8 12393.6 14523.2 14856.1 14875.8 15067.7 633432.0 635572.0 641359.0 641710.0 642492.0 52961.0 53441.5 54126.7 54225.0 54325.0 1328.2 1344.6 1359.9 1369.2 1377.1 28279.2 28489.1 28712.0 29038.3 29049.3 45392.5 46212.0 46496.5 46625.8 46704.3 556620.0 558354.0 561735.0 562529.0 563694.0 20828.5 24920.4 25079.4 25247.1 25648.9 43290.8 44069.3 44226.8 44488.3 44765.6 31862.8 35491.1 36282.6 37551.6 37631.0 33819.8 35274.5 35858.1 35983.3 36971.8 35874.3 35885.0 35929.6 36319.0 36323.1 31646.7 33049.7 33088.0 33103.0 33137.8 21312.8 21796.9 22877.5 24253.5 24663.0 08:14:07 INFO - PROCESS | 1642 | 08:14:07 INFO - PROCESS | 1642 | [#2] jquery.html Cycles:180 Average:32731.39 Median:405.62 stddev:93641.63 (23086.0%) stddev-sans-first:93872.73 08:14:07 INFO - PROCESS | 1642 | Values: 340.3 343.3 366.9 371.0 395.4 17819.9 18516.0 19383.4 19414.0 19628.2 19180.8 19447.0 19452.4 19617.2 19729.8 4812.9 5559.1 6031.2 6041.9 6059.0 4884.9 5048.9 5490.0 5620.4 5949.9 5651.2 5898.1 5919.8 5925.0 5928.4 5931.9 6060.8 6074.5 6080.6 6095.9 5029.5 5331.7 5396.7 5885.0 5915.3 12353.6 12560.4 12646.9 12647.9 23939.8 1998.3 2109.0 2135.2 2234.4 2248.9 9287.8 9291.3 9535.0 9921.8 10094.3 1777.2 1806.8 1832.2 1844.1 1860.9 117255.0 120087.0 121371.0 122473.0 123361.4 8686.3 11082.0 11172.5 11199.5 11406.0 11222.0 13890.4 13950.5 13974.7 13982.7 6300.8 6908.4 7817.7 8240.6 8348.2 8079.8 8115.0 8244.5 8259.8 8301.4 4291.4 4350.7 4385.8 4444.4 4485.1 1679.5 1868.6 1868.9 1911.2 1918.1 301.2 355.9 368.1 812.3 926.3 11054.3 11217.4 11529.6 11536.0 11789.0 415.9 418.8 422.0 426.7 436.2 5756.9 5759.6 5810.7 5912.4 5924.2 542767.0 554543.0 559379.0 560009.0 561198.0 130922.0 132504.0 132584.3 132910.4 136369.6 356.0 439.6 439.9 440.9 441.4 9260.0 10016.2 10413.3 10740.4 10757.9 49688.8 50773.5 51095.7 51470.0 52161.0 77660.6 78207.6 78675.2 79027.0 79930.2 6373.0 6533.5 7479.0 7702.7 7730.8 9334.7 11887.6 11928.9 12232.3 12333.7 10163.4 10194.2 10580.0 10633.6 10745.3 8580.5 10186.6 10212.4 10691.6 10855.7 10366.6 10409.9 10905.2 10945.9 10993.5 7448.2 7687.0 7801.5 7839.5 7843.8 10495.9 10519.4 10605.6 10633.5 10731.5 08:14:07 INFO - PROCESS | 1642 | 08:14:07 INFO - PROCESS | 1642 | [#3] mootools.html Cycles:180 Average:5452.69 Median:5214.60 stddev:2971.55 (57.0%) stddev-sans-first:2963.69 08:14:07 INFO - PROCESS | 1642 | Values: 1324.9 1465.8 1618.6 1629.1 1785.3 8080.6 8496.9 8497.8 8510.9 8525.3 7543.1 7827.1 7994.4 8312.9 8327.8 2303.7 2351.0 2351.6 2359.1 2369.1 2332.4 2363.2 2377.6 2383.6 2386.9 2316.9 2322.8 2347.5 2348.4 2349.0 2302.8 2308.0 2325.7 2328.4 2335.5 2323.2 2346.0 2349.3 2350.1 2362.6 8424.1 8458.3 8479.1 8486.5 8491.7 4955.1 5038.7 5214.2 5216.9 5218.1 3290.7 3528.7 3614.1 3619.1 3622.8 1022.9 1032.1 1076.9 1084.2 1089.2 10801.4 10878.6 10948.3 10955.5 10961.8 4439.8 4585.0 4669.7 4685.7 4721.5 9420.0 9492.2 9502.9 9508.8 9518.5 5196.0 5211.5 5213.8 5215.0 5227.5 4273.4 4286.9 4689.8 4757.2 4782.3 3744.8 3766.0 3860.9 3880.7 3889.1 1268.2 1272.5 1274.0 1282.9 1287.6 814.2 870.9 886.1 894.9 899.4 5438.1 5525.0 5613.6 5668.2 5735.1 678.4 942.5 948.2 993.0 998.4 3028.3 3596.5 3652.7 3761.7 3850.4 8252.7 9822.4 9913.8 10126.3 10155.8 9898.5 9947.8 10054.5 10074.7 10092.2 7397.2 7674.1 7727.6 7843.5 7863.1 5640.7 5721.1 5767.7 5856.2 5869.4 9118.9 9220.4 9331.8 9418.6 9419.4 9293.1 9445.3 9548.7 9581.6 9592.8 6606.7 6866.5 6954.5 7165.5 7296.3 7870.3 8039.3 8048.9 8057.2 8078.8 7011.2 7131.0 7164.4 7224.9 7266.3 6925.2 6984.0 7041.4 7068.8 7116.3 6632.7 6692.0 6741.7 6758.2 6776.0 4825.3 4870.6 4883.2 4932.9 4936.0 4750.9 4758.2 4795.2 4834.7 4946.1 08:14:07 INFO - PROCESS | 1642 | 08:14:07 INFO - PROCESS | 1642 | [#4] prototype.html Cycles:180 Average:4445.92 Median:5064.10 stddev:1406.13 (27.8%) stddev-sans-first:1374.89 08:14:07 INFO - PROCESS | 1642 | Values: 281.3 294.7 296.5 308.2 311.5 5190.2 5366.4 5367.7 5525.7 5551.3 4054.5 5008.3 5012.9 5329.9 5382.2 3444.6 3452.6 4070.7 4128.2 4189.4 3244.5 3455.6 3827.4 4163.0 4187.5 3448.2 3538.8 3747.5 3818.7 4036.3 3338.9 3834.7 4002.8 4064.4 4084.0 2692.9 3076.7 3080.7 3470.6 3533.5 5117.7 5210.3 5245.6 5249.5 5276.4 4018.6 4439.8 4611.3 4617.8 4640.4 4700.7 5603.9 5626.3 5649.6 5666.7 3610.2 3710.1 3806.0 3816.3 3842.4 5926.2 5947.9 5953.4 5959.6 6001.4 3313.9 3856.3 3862.5 3874.7 3893.8 3704.3 3948.7 3950.4 3954.0 3956.4 3795.6 4073.9 4293.6 4699.4 4703.5 4824.7 5723.4 5749.7 5771.7 5819.8 1020.1 1101.3 1200.4 1204.6 1263.5 804.0 824.4 844.7 862.4 873.6 4585.0 4912.8 4954.5 4980.6 5063.6 5289.9 5391.8 5518.6 5558.4 5608.5 3816.5 4280.4 4748.2 5064.6 5118.0 2520.6 2848.5 2859.5 2886.9 2890.8 4556.9 5239.5 5262.9 5687.4 5720.4 4829.5 5341.4 5612.5 5619.8 5700.6 5067.6 5695.8 5696.2 5698.8 5709.4 5322.6 5441.5 5457.8 5502.5 5538.8 4485.3 4957.5 5405.8 5456.8 5473.5 4629.9 5010.9 5018.8 5643.0 5682.8 3407.4 3700.5 3702.6 3758.0 3769.8 5064.6 5125.7 5404.9 5479.2 5692.8 5044.4 5160.3 5517.0 5674.2 5675.8 5635.5 5639.4 5639.5 5657.4 5708.0 5192.7 5382.4 5527.4 5571.6 5574.3 5610.3 5619.7 5635.9 5659.8 5660.3 5529.8 5545.6 5547.9 5560.6 5594.7 08:14:07 INFO - PROCESS | 1642 | 08:14:07 INFO - PROCESS | 1642 | [#5] yui.html Cycles:180 Average:20348.82 Median:29336.67 stddev:30332.79 (103.4%) stddev-sans-first:30381.58 08:14:07 INFO - PROCESS | 1642 | Values: 582.9 661.0 696.0 715.3 765.8 14226.5 15021.9 15027.1 15075.9 15092.5 13515.3 13658.0 13709.2 13721.7 13725.9 637.9 661.6 793.3 810.2 820.2 793.8 815.9 822.3 824.1 824.5 647.7 696.0 870.4 872.4 872.9 734.6 849.9 864.7 867.0 867.1 626.5 642.6 644.5 645.3 646.2 12830.8 13443.7 13611.3 13710.8 14418.4 12274.4 12431.9 12499.8 13513.3 14052.7 11292.0 11366.4 11407.0 11497.7 11519.4 3556.7 3597.2 3639.3 3707.3 3775.9 69943.0 88752.6 91996.5 95400.0 96596.5 10342.9 10361.9 10407.6 10420.0 10791.2 15718.3 18934.8 19712.1 19971.6 20004.7 7496.1 7620.8 7724.9 7726.1 7878.6 4220.7 4261.7 4312.8 4382.9 4418.1 3271.5 3320.9 3361.7 3378.2 3379.5 615.2 618.8 619.9 626.2 640.5 534.7 628.2 645.8 677.1 691.4 8757.3 8780.5 8978.8 8996.3 9063.4 629.2 633.7 649.4 668.7 671.7 3734.0 4149.0 4235.5 4296.2 4481.5 74583.6 95253.1 113388.0 119638.0 121479.0 84657.6 85451.0 102807.0 104029.5 104748.5 64844.0 73201.6 79213.6 89486.0 91397.0 6700.6 7438.1 7668.9 8675.8 8790.3 57708.7 58090.4 59046.7 59276.4 60041.7 81207.0 81309.0 82157.0 82507.7 84752.2 11141.7 11145.3 11272.3 11499.6 11698.3 12270.3 12313.6 12409.4 12474.1 12977.8 11823.6 11837.9 12146.5 12244.8 12248.3 11802.8 11906.4 11961.5 12251.4 12304.6 11779.5 11885.2 11934.9 12303.6 12309.8 9670.5 9702.6 9723.5 9903.5 10215.1 9587.5 9944.7 9952.0 9968.1 10004.0 08:14:07 INFO - PROCESS | 1642 | -------- Summary: end -------- 08:14:07 INFO - PROCESS | 1642 | 08:14:08 INFO - TEST-INFO | 1642: exit 0 08:14:08 INFO - TEST-OK | dromaeo_css | took 1116570ms 08:14:08 INFO - TEST-START | kraken 08:14:08 INFO - operating with platform_type : mac_ 08:14:08 INFO - Initialising browser for kraken test... 08:14:08 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/tmp7WISgS/profile http://localhost:49235/getInfo.html) 08:14:11 INFO - PROCESS | 1649 | 2016-11-22 08:14:11.769 plugin-container[1650:10558] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c47, name = 'com.apple.tsm.portname' 08:14:11 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:14:11 INFO - PROCESS | 1649 | 2016-11-22 08:14:11.772 plugin-container[1650:10558] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900f, name = 'com.apple.CFPasteboardClient' 08:14:11 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:14:11 INFO - PROCESS | 1649 | 2016-11-22 08:14:11.772 plugin-container[1650:10558] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:14:11 INFO - PROCESS | 1649 | __metrics Screen width/height:1600/1200 08:14:11 INFO - PROCESS | 1649 | colorDepth:24 08:14:11 INFO - PROCESS | 1649 | Browser inner width/height: 1024/689 08:14:11 INFO - PROCESS | 1649 | __metrics 08:14:11 ERROR - PROCESS | 1649 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3198: TypeError: this.tabs is undefined 08:14:12 INFO - TEST-INFO | 1649: exit 0 08:14:12 INFO - Browser initialized. 08:14:12 INFO - Running cycle 1/1 for kraken test... 08:14:12 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/tmp7WISgS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:14:15 INFO - PROCESS | 1651 | 2016-11-22 08:14:15.645 plugin-container[1652:10679] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x454f, name = 'com.apple.tsm.portname' 08:14:15 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:14:15 INFO - PROCESS | 1651 | 2016-11-22 08:14:15.645 plugin-container[1652:10679] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4817, name = 'com.apple.CFPasteboardClient' 08:14:15 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:14:15 INFO - PROCESS | 1651 | 2016-11-22 08:14:15.645 plugin-container[1652:10679] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:14:25 INFO - PROCESS | 1651 | RSS: Main: 243470336 08:14:25 INFO - PROCESS | 1651 | 08:14:28 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-beat-detection) 08:14:29 INFO - PROCESS | 1651 | RSS: Main: 255778816 08:14:29 INFO - PROCESS | 1651 | 08:14:31 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-dft) 08:14:32 INFO - PROCESS | 1651 | RSS: Main: 263417856 08:14:32 INFO - PROCESS | 1651 | 08:14:35 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-fft) 08:14:36 INFO - PROCESS | 1651 | RSS: Main: 266338304 08:14:36 INFO - PROCESS | 1651 | 08:14:38 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-oscillator) 08:14:39 INFO - PROCESS | 1651 | RSS: Main: 268509184 08:14:39 INFO - PROCESS | 1651 | 08:14:41 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:14:41 INFO - PROCESS | 1651 | RSS: Main: 268783616 08:14:41 INFO - PROCESS | 1651 | 08:14:44 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-darkroom) 08:14:45 INFO - PROCESS | 1651 | RSS: Main: 274317312 08:14:45 INFO - PROCESS | 1651 | 08:14:48 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-desaturate) 08:14:49 INFO - PROCESS | 1651 | RSS: Main: 273518592 08:14:49 INFO - PROCESS | 1651 | 08:14:52 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49235/tests/kraken/driver.html?testName=json-parse-financial) 08:14:53 INFO - PROCESS | 1651 | RSS: Main: 273641472 08:14:53 INFO - PROCESS | 1651 | 08:14:54 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49235/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:14:55 INFO - PROCESS | 1651 | RSS: Main: 272920576 08:14:55 INFO - PROCESS | 1651 | 08:14:57 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:14:57 INFO - PROCESS | 1651 | RSS: Main: 272973824 08:14:57 INFO - PROCESS | 1651 | 08:15:00 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:15:00 INFO - PROCESS | 1651 | RSS: Main: 272932864 08:15:00 INFO - PROCESS | 1651 | 08:15:03 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:15:04 INFO - PROCESS | 1651 | RSS: Main: 273223680 08:15:04 INFO - PROCESS | 1651 | 08:15:07 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:15:08 INFO - PROCESS | 1651 | RSS: Main: 272986112 08:15:08 INFO - PROCESS | 1651 | 08:15:10 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49235/tests/kraken/driver.html?testName=ai-astar) 08:15:10 INFO - PROCESS | 1651 | RSS: Main: 276074496 08:15:10 INFO - PROCESS | 1651 | 08:15:10 INFO - PROCESS | 1651 | __start_tp_report 08:15:10 INFO - PROCESS | 1651 | _x_x_mozilla_page_load 08:15:10 INFO - PROCESS | 1651 | _x_x_mozilla_page_load_details 08:15:10 INFO - PROCESS | 1651 | |i|pagename|runs| 08:15:10 INFO - PROCESS | 1651 | |0;ai-astar;106;116;120;119;118;116;115;119;116;117 08:15:10 INFO - PROCESS | 1651 | |1;audio-beat-detection;149;118;117;154;114;120;125;117;148;118 08:15:10 INFO - PROCESS | 1651 | |2;audio-dft;207;221;227;218;197;200;194;244;272;192 08:15:10 INFO - PROCESS | 1651 | |3;audio-fft;77;59;60;85;58;62;73;83;101;61 08:15:10 INFO - PROCESS | 1651 | |4;audio-oscillator;88;72;101;95;99;102;97;68;71;88 08:15:10 INFO - PROCESS | 1651 | |5;imaging-gaussian-blur;90;90;89;102;137;109;92;88;102;113 08:15:10 INFO - PROCESS | 1651 | |6;imaging-darkroom;97;99;98;103;139;96;119;111;96;98 08:15:10 INFO - PROCESS | 1651 | |7;imaging-desaturate;93;132;150;92;149;146;110;92;95;96 08:15:10 INFO - PROCESS | 1651 | |8;json-parse-financial;56;58;57;57;58;60;58;56;56;58 08:15:10 INFO - PROCESS | 1651 | |9;json-stringify-tinderbox;72;69;87;120;113;74;67;69;71;70 08:15:10 INFO - PROCESS | 1651 | |10;stanford-crypto-aes;101;108;109;92;90;86;88;87;89;91 08:15:10 INFO - PROCESS | 1651 | |11;stanford-crypto-ccm;159;172;173;151;169;171;174;150;219;158 08:15:10 INFO - PROCESS | 1651 | |12;stanford-crypto-pbkdf2;175;246;161;170;236;239;171;175;176;179 08:15:10 INFO - PROCESS | 1651 | |13;stanford-crypto-sha256-iterative;74;96;92;80;67;67;70;69;68;68 08:15:10 INFO - PROCESS | 1651 | __end_tp_report 08:15:10 INFO - PROCESS | 1651 | __start_cc_report 08:15:10 INFO - PROCESS | 1651 | _x_x_mozilla_cycle_collect,956 08:15:10 INFO - PROCESS | 1651 | __end_cc_report 08:15:10 INFO - PROCESS | 1651 | __startTimestamp1479831310478__endTimestamp 08:15:10 INFO - PROCESS | 1651 | 08:15:10 INFO - PROCESS | 1651 | ------- Summary: start ------- 08:15:10 INFO - PROCESS | 1651 | Number of tests: 14 08:15:10 INFO - PROCESS | 1651 | 08:15:10 INFO - PROCESS | 1651 | [#0] ai-astar Cycles:10 Average:116.20 Median:117.50 stddev:3.94 (3.4%) stddev-sans-first:1.73 08:15:10 INFO - PROCESS | 1651 | Values: 106.0 116.0 120.0 119.0 118.0 116.0 115.0 119.0 116.0 117.0 08:15:10 INFO - PROCESS | 1651 | 08:15:10 INFO - PROCESS | 1651 | [#1] audio-beat-detection Cycles:10 Average:128.00 Median:122.50 stddev:15.73 (12.8%) stddev-sans-first:14.74 08:15:10 INFO - PROCESS | 1651 | Values: 149.0 118.0 117.0 154.0 114.0 120.0 125.0 117.0 148.0 118.0 08:15:10 INFO - PROCESS | 1651 | 08:15:10 INFO - PROCESS | 1651 | [#2] audio-dft Cycles:10 Average:217.20 Median:219.50 stddev:25.42 (11.6%) stddev-sans-first:26.69 08:15:10 INFO - PROCESS | 1651 | Values: 207.0 221.0 227.0 218.0 197.0 200.0 194.0 244.0 272.0 192.0 08:15:10 INFO - PROCESS | 1651 | 08:15:10 INFO - PROCESS | 1651 | [#3] audio-fft Cycles:10 Average:71.90 Median:67.50 stddev:14.48 (21.5%) stddev-sans-first:15.24 08:15:10 INFO - PROCESS | 1651 | Values: 77.0 59.0 60.0 85.0 58.0 62.0 73.0 83.0 101.0 61.0 08:15:10 INFO - PROCESS | 1651 | 08:15:10 INFO - PROCESS | 1651 | [#4] audio-oscillator Cycles:10 Average:88.10 Median:88.00 stddev:13.17 (15.0%) stddev-sans-first:13.97 08:15:10 INFO - PROCESS | 1651 | Values: 88.0 72.0 101.0 95.0 99.0 102.0 97.0 68.0 71.0 88.0 08:15:10 INFO - PROCESS | 1651 | 08:15:10 INFO - PROCESS | 1651 | [#5] imaging-gaussian-blur Cycles:10 Average:101.20 Median:88.50 stddev:15.43 (17.4%) stddev-sans-first:15.82 08:15:10 INFO - PROCESS | 1651 | Values: 90.0 90.0 89.0 102.0 137.0 109.0 92.0 88.0 102.0 113.0 08:15:10 INFO - PROCESS | 1651 | 08:15:10 INFO - PROCESS | 1651 | [#6] imaging-darkroom Cycles:10 Average:105.60 Median:96.50 stddev:13.94 (14.4%) stddev-sans-first:14.43 08:15:10 INFO - PROCESS | 1651 | Values: 97.0 99.0 98.0 103.0 139.0 96.0 119.0 111.0 96.0 98.0 08:15:10 INFO - PROCESS | 1651 | 08:15:10 INFO - PROCESS | 1651 | [#7] imaging-desaturate Cycles:10 Average:115.50 Median:92.00 stddev:25.73 (28.0%) stddev-sans-first:25.97 08:15:10 INFO - PROCESS | 1651 | Values: 93.0 132.0 150.0 92.0 149.0 146.0 110.0 92.0 95.0 96.0 08:15:10 INFO - PROCESS | 1651 | 08:15:10 INFO - PROCESS | 1651 | [#8] json-parse-financial Cycles:10 Average:57.40 Median:58.00 stddev:1.26 (2.2%) stddev-sans-first:1.24 08:15:10 INFO - PROCESS | 1651 | Values: 56.0 58.0 57.0 57.0 58.0 60.0 58.0 56.0 56.0 58.0 08:15:10 INFO - PROCESS | 1651 | 08:15:10 INFO - PROCESS | 1651 | [#9] json-stringify-tinderbox Cycles:10 Average:81.20 Median:70.50 stddev:19.48 (27.6%) stddev-sans-first:20.38 08:15:10 INFO - PROCESS | 1651 | Values: 72.0 69.0 87.0 120.0 113.0 74.0 67.0 69.0 71.0 70.0 08:15:10 INFO - PROCESS | 1651 | 08:15:10 INFO - PROCESS | 1651 | [#10] stanford-crypto-aes Cycles:10 Average:94.10 Median:88.50 stddev:8.65 (9.8%) stddev-sans-first:8.80 08:15:10 INFO - PROCESS | 1651 | Values: 101.0 108.0 109.0 92.0 90.0 86.0 88.0 87.0 89.0 91.0 08:15:10 INFO - PROCESS | 1651 | 08:15:10 INFO - PROCESS | 1651 | [#11] stanford-crypto-ccm Cycles:10 Average:169.60 Median:171.50 stddev:19.60 (11.4%) stddev-sans-first:20.41 08:15:10 INFO - PROCESS | 1651 | Values: 159.0 172.0 173.0 151.0 169.0 171.0 174.0 150.0 219.0 158.0 08:15:10 INFO - PROCESS | 1651 | 08:15:10 INFO - PROCESS | 1651 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:192.80 Median:177.50 stddev:33.24 (18.7%) stddev-sans-first:34.63 08:15:10 INFO - PROCESS | 1651 | Values: 175.0 246.0 161.0 170.0 236.0 239.0 171.0 175.0 176.0 179.0 08:15:10 INFO - PROCESS | 1651 | 08:15:10 INFO - PROCESS | 1651 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.10 Median:72.00 stddev:10.76 (15.0%) stddev-sans-first:11.41 08:15:10 INFO - PROCESS | 1651 | Values: 74.0 96.0 92.0 80.0 67.0 67.0 70.0 69.0 68.0 68.0 08:15:10 INFO - PROCESS | 1651 | -------- Summary: end -------- 08:15:10 INFO - PROCESS | 1651 | 08:15:11 INFO - TEST-INFO | 1651: exit 0 08:15:11 INFO - TEST-OK | kraken | took 62879ms 08:15:11 INFO - SUITE-END | took 1179s 08:15:11 INFO - Completed test suite (00:19:39) 08:15:11 INFO - javascript benchmark 08:15:11 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6892.473602212929, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3330.483775014872, 3349.447341188704, 3391.6099648540417, 3397.8640854365813, 3413.965860341396, 5033.798648054082, 5190.584753219743, 5624.409437009112, 5625.859353516164, 5667.57493188011, 5569.0, 5729.799457019005, 5742.79900203493, 5755.136729490568, 5777.133429985514, 4709.434867815849, 5006.173981293082, 5121.795128194886, 5240.711760853152, 5329.040772660919, 5224.4775522447835, 5303.178007408857, 5329.2006199070065, 5333.226682131089, 5335.493128152839, 118.7013107612428, 4272.0, 5129.563987061104, 5161.200013997836, 5191.948080519184, 5231.346081739783, 5235.371755389339, 5235.450277720861, 5247.763850626693, 5292.973535132338, 5196.92204616931, 5199.584033277329, 5206.0, 5208.374995000586, 5301.867453313659, 5129.384243963177, 5400.91898621521, 5627.774889004435, 5689.943100569024, 5774.971125144348, 5335.706536140511, 6214.5960512566535, 6233.594816336924, 6242.188515493002, 6263.0, 6245.0, 6255.624662520263, 6264.123022776769, 6269.749210031593, 6271.435570798604, 5715.342735585377, 5848.5321174305955, 5864.354920958691, 5899.823005309848, 5919.2600924883955, 6173.0, 6188.504919606421, 6192.504599632109, 6239.937600624026, 6308.936910630927, 4792.945551978629, 6113.807807477499, 6210.068489726486, 6214.192155019909, 6225.688715564203, 4942.1104201244125, 5368.0, 5955.255593050869, 6198.876022479525, 6231.3145553989025, 6082.239720034996, 6103.511719062464, 6110.649890384107, 6182.165407669997, 6186.690665466709, 5364.892702145935, 6227.377262273736, 6259.593126446766, 6287.685615719196, 6297.685115744194, 4132.152908653718, 4832.8791780205775, 4885.389326334208, 4903.509649035068, 4947.950520494749, 3877.8254978526033, 4386.978065109718, 4410.360497727812, 4459.754713490789, 4504.031633198878, 5036.7156375124105, 5423.403425623178, 5631.521320687705, 5835.2122463466885, 5836.7957121501395, 6159.753609855646, 6160.414760597736, 6202.162708034448, 6218.06728990655, 6241.313455519889, 5813.0, 5814.30228372598, 5815.621984570989, 5833.329167145747, 5844.707764611838, 5402.756875940513, 5416.7020813855615, 5422.0, 5431.782728690887, 5437.157240627709, 6135.385782596134, 6199.752009919553, 6225.688715564294, 6259.906101408392, 6264.718087685974, 5152.871178220575, 6245.094461302995, 6248.0003199487865, 6286.7799627014665, 6290.937090629218, 6325.019621958604, 6328.588641738273, 6344.555881088279, 6373.904391434225, 6385.29761726219, 6058.697065146813, 6099.725512352044, 6107.572469927062, 6151.876962460637, 6174.197354343906, 6103.549811064127, 6104.641056839945, 6133.059244992972, 6149.169862068564, 6244.750209991731, 6114.969425152845, 6124.112003759519, 6131.908021379593, 6145.35473775265, 6154.292256390572, 5968.134620480093, 6050.062240352752, 6103.870783904992, 6123.663198524124, 6154.353792851865, 5741.569382296428, 5842.444967728059, 5903.586748927534, 5918.348981611933, 5921.052631578927, 6110.558759196553, 6116.052011938156, 6120.843333897588, 6126.3039328557115, 6135.601185922901, 5796.275465566804, 5819.94180058194, 5872.911906321494, 5890.322612899571, 5984.640921544721], "value": 5592.076814589605, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [493.64457590534823, 509.0607828556314, 537.7660717587847, 540.4649397096875, 562.4965655738117, 29369.0, 31127.688723112762, 37829.81085094574, 38337.04157396066, 38492.0, 38851.61148388522, 39028.0, 39141.434342626264, 39342.40986385199, 39467.60532394675, 9260.351775375726, 9404.965453800076, 9427.858582121273, 9442.055794420537, 9457.432554046745, 9349.111834375757, 9381.76545586359, 9442.150206481416, 9444.102810233016, 9448.48033358165, 9414.058594140566, 9473.289503287246, 9485.668001619944, 9489.81020379592, 9506.762330941747, 9372.671956481525, 9412.529373531364, 9449.291303152291, 9451.480168590724, 9454.763630909214, 9283.978762336137, 9373.484458354787, 9378.062193780568, 9388.577514011886, 9394.718158455258, 31001.689983100106, 35802.0, 38093.238135237414, 38271.42592861109, 38507.807460962795, 2622.7508386703234, 2821.985890070557, 2917.737403633662, 3088.737457316131, 3737.794421306827, 28649.71350286512, 29058.854705726546, 29152.562711559345, 29350.0, 29370.55944160818, 3181.18879685681, 3220.790648607833, 3222.3716375306826, 3266.5100234964707, 3319.921025666668, 455162.0, 457644.0, 458468.0, 461247.9999999966, 461354.0, 57437.0, 57691.711541442026, 57873.42126578765, 57971.0, 58023.709881450326, 33021.0, 34035.0, 34067.659323406944, 34880.30239395198, 59717.701411492664, 15400.152991585346, 15509.0, 15805.446809361618, 15842.841571584137, 15846.0, 11860.703482412837, 12113.152079354537, 12153.69615759613, 12159.392030398445, 12160.75678486443, 18007.459776206473, 18227.635447291246, 18251.079827227844, 18276.908615456836, 18330.725039124423, 6566.967165164144, 7950.801229969182, 7975.4018448615425, 7981.321587665114, 8025.23760242776, 755.0, 991.7074463033423, 1014.2747935226348, 1015.7151203727291, 1104.8839871813504, 20890.373288801362, 27576.034838780546, 27607.86196069007, 29313.413731725672, 29411.85294073516, 1072.989270107289, 1089.9945500272447, 1131.9981816092818, 1136.6476392318407, 1142.8228924208079, 12393.566225182076, 14523.201223932574, 14856.10863348181, 14875.776863347059, 15067.719243864176, 633432.0, 635572.0, 641359.0, 641710.0, 642492.0, 52961.0, 53441.46558534443, 54126.72936635291, 54225.0, 54325.0, 1328.2097152194428, 1344.6302266876492, 1359.932003399826, 1369.2127026959538, 1377.0773581700284, 28279.151625451268, 28489.145325640264, 28711.985241549337, 29038.27404314909, 29049.273768155967, 45392.54607454015, 46212.0, 46496.53503464922, 46625.83435414142, 46704.29943550917, 556620.0, 558354.0, 561735.0, 562529.0, 563694.0, 20828.47928801792, 24920.376990574656, 25079.373015674024, 25247.11635092763, 25648.871755641103, 43290.78354608207, 44069.33895991499, 44226.778866105466, 44488.33267501055, 44765.552344476135, 31862.840685796422, 35491.11272218215, 36282.637173627925, 37551.624483755906, 37631.0, 33819.830900846326, 35274.470882936264, 35858.10354741153, 35983.28033439369, 36971.815140924125, 35874.28251435009, 35885.0, 35929.64070359263, 36319.0, 36323.09192270109, 31646.683533164374, 33049.669503304656, 33088.00735977924, 33103.00690979178, 33137.83431082829, 21312.78687213108, 21796.89101554482, 22877.542449151257, 24253.514929700956, 24663.0], "value": 18868.024773535686, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [340.25484189624706, 343.3339321715869, 366.891766928756, 371.00015458339766, 395.37333326677214, 17819.910900445495, 18516.0, 19383.449324054076, 19414.029298535068, 19628.214871405125, 19180.808191918077, 19447.027648617583, 19452.416427507163, 19617.215311387565, 19729.802701972974, 4812.855614331567, 5559.082751346044, 6031.215941927543, 6041.939580604182, 6059.000264956277, 4884.877878053042, 5048.8892443662335, 5489.972550137244, 5620.381758006616, 5949.851253718648, 5651.180578816069, 5898.115282707607, 5919.822405327847, 5925.0, 5928.436798504157, 5931.88136237275, 6060.818175454743, 6074.51403887688, 6080.635161890279, 6095.908561371583, 5029.5221953914315, 5331.653442526223, 5396.676199428047, 5884.9705751470965, 5915.319738230116, 12353.62939111828, 12560.434780434902, 12646.936765316115, 12647.87352126476, 23939.7606023941, 1998.2606435618914, 2108.95606674697, 2135.1886283212434, 2234.4078819112947, 2248.8875556222124, 9287.814243715155, 9291.303152263607, 9535.0, 9921.75195620108, 10094.29339946204, 1777.191377923042, 1806.801251862294, 1832.1842744988621, 1844.1148248840498, 1860.8883466992024, 117255.0, 120087.0, 121371.0, 122473.0, 123361.38319308436, 8686.348523860735, 11082.0, 11172.497237624326, 11199.496022678999, 11406.030487408498, 11222.0, 13890.374933128034, 13950.511732089328, 13974.650633734238, 13982.72034559303, 6300.8424789379715, 6908.447324214051, 7817.687292508236, 8240.587970601446, 8348.248657620843, 8079.798005049921, 8115.026196856415, 8244.464109832961, 8259.752207433787, 8301.418900677016, 4291.442112525414, 4350.73895566267, 4385.802638881194, 4444.355568442557, 4485.0805584855025, 1679.4877562343358, 1868.588910439701, 1868.9439316820517, 1911.246491070892, 1918.1080797429263, 301.2378681934709, 355.886116442741, 368.12753773556307, 812.3014207781304, 926.305271046715, 11054.336739795637, 11217.439128043565, 11529.596464123715, 11536.0, 11789.0, 415.8690012646007, 418.81572108272263, 421.9852305169304, 426.7290270678142, 436.21699050204955, 5756.856078598069, 5759.625624334405, 5810.6513609183585, 5912.438318359749, 5924.170616113747, 542767.0, 554543.0, 559379.0, 560009.0, 561198.0, 130921.9999999981, 132504.0, 132584.33707831398, 132910.3544822755, 136369.6363036357, 356.0, 439.580200908132, 439.86803958812226, 440.94929083155205, 441.4459852884609, 9260.0, 10016.248781341283, 10413.271071024956, 10740.409277489813, 10757.892421075689, 49688.80757059956, 50773.492265078356, 51095.744521277156, 51470.0, 52161.0, 77660.61169694115, 78207.60896195483, 78675.21324786908, 79027.0, 79930.2006979946, 6373.012183111625, 6533.509986750918, 7479.0, 7702.691892324245, 7730.7680769577, 9334.673286434941, 11887.643370698894, 11928.940355298168, 12232.327222002876, 12333.691657708629, 10163.390196588229, 10194.235432342752, 10580.0, 10633.574657013633, 10745.301555398873, 8580.528070955908, 10186.592536298762, 10212.387256764618, 10691.572337106429, 10855.67432977012, 10366.585336586451, 10409.9479502602, 10905.236633435901, 10945.945270273598, 10993.450327483753, 7448.217937116525, 7687.0, 7801.4929029612895, 7839.529628222325, 7843.803904902423, 10495.919025527737, 10519.421431821323, 10605.628802991858, 10633.468326583794, 10731.517081731497], "value": 8141.858718576227, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1324.9470021199152, 1465.7947887295782, 1618.5710786641541, 1629.1365576244586, 1785.2501949181342, 8080.595970201496, 8496.872546911802, 8497.830043399128, 8510.914890851089, 8525.317974562036, 7543.094828620559, 7827.099883513398, 7994.360451163908, 8312.91687083129, 8327.791805204874, 2303.7465878753364, 2351.036075209156, 2351.5649604823075, 2359.0563774490256, 2369.1115831563166, 2332.4285550040263, 2363.1847012780563, 2377.5720370333333, 2383.6305372667266, 2386.940326491843, 2316.9073237070497, 2322.779335963089, 2347.542229265294, 2348.3542025942834, 2348.989934328247, 2302.827287953402, 2307.97692023081, 2325.686032385623, 2328.359701082199, 2335.4511689752894, 2323.2217207235594, 2346.0263990443946, 2349.295211436562, 2350.095213342858, 2362.61016932206, 8424.073351931283, 8458.323334133254, 8479.109693482218, 8486.490810551324, 8491.74524764258, 4955.132851750929, 5038.722870242135, 5214.191800270964, 5216.895662086737, 5218.1129208034345, 3290.654481279455, 3528.6647768462212, 3614.114541937216, 3619.1494998675553, 3622.8188590570367, 1022.8850552897378, 1032.0866033560353, 1076.9407682577455, 1084.1543595995136, 1089.1939964426347, 10801.40592267425, 10878.61924832635, 10948.343099413982, 10955.4522273886, 10961.83557246642, 4439.844605438794, 4584.9541504585195, 4669.719816811003, 4685.672002959828, 4721.480637129913, 9419.952900235456, 9492.240620750323, 9502.90497095034, 9508.809823803624, 9518.476483793458, 5196.0, 5211.530962213419, 5213.7914483421, 5215.009148261818, 5227.503387178288, 4273.444452221192, 4286.87139385819, 4689.788959496831, 4757.238841785298, 4782.306565548046, 3744.756590821588, 3765.962340376616, 3860.864869729602, 3880.7477513961503, 3889.0666240102737, 1268.154834715505, 1272.5482453728978, 1274.0, 1282.8588855225917, 1287.6458973782098, 814.1776805426562, 870.8954925408865, 886.1183122792862, 894.9463032218088, 899.3569597737649, 5438.129899216106, 5524.972375138099, 5613.607047506635, 5668.234788303609, 5735.11105778605, 678.4335080208082, 942.528735632184, 948.2129832239225, 992.980140397188, 998.3560603410759, 3028.2883522372226, 3596.478510615946, 3652.7077833773233, 3761.7178711596193, 3850.441685955521, 8252.711155109542, 9822.410655360702, 9913.75215619601, 10126.291159618757, 10155.796884062278, 9898.455584942752, 9947.751306217402, 10054.49727513636, 10074.69775906724, 10092.192624590016, 7397.18630950595, 7674.117476490163, 7727.574983375968, 7843.490173138728, 7863.056433228049, 5640.71796410186, 5721.141828725725, 5767.653940763567, 5856.150858125633, 5869.442402971711, 9118.908810911806, 9220.400673956172, 9331.813363732552, 9418.62325506972, 9419.434833909987, 9293.117153870371, 9445.291603129655, 9548.713538593853, 9581.568829402555, 9592.808143836945, 6606.669666516559, 6866.485013623898, 6954.547954382949, 7165.498415110892, 7296.306850849161, 7870.29167374939, 8039.316658084128, 8048.87926681112, 8057.194280571896, 8078.757637270891, 7011.193712722898, 7130.964345178241, 7164.355208031302, 7224.8916266255, 7266.309700578437, 6925.231180950335, 6984.0, 7041.401480874183, 7068.823279418056, 7116.323949224816, 6632.668366581555, 6692.0, 6741.696623651849, 6758.222804377559, 6775.966120169368, 4825.324454576432, 4870.586000190024, 4883.194272944925, 4932.926006109839, 4935.975320123376, 4750.907291322946, 4758.214894542361, 4795.20879054966, 4834.685745426536, 4946.134426475281], "value": 4427.297226299464, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [281.32622369425223, 294.69646264347716, 296.45600323406546, 308.1556535093843, 311.465836091104, 5190.2474141249595, 5366.436524164965, 5367.731613419323, 5525.673838278809, 5551.333839939208, 4054.5134583849904, 5008.29883816266, 5012.924806127902, 5329.893402131955, 5382.2195781611645, 3444.5866496020385, 3452.5511683481113, 4070.694697897662, 4128.195001974616, 4189.434426352434, 3244.5075265377955, 3455.565940134841, 3827.4453400453513, 4163.0008797888595, 4187.539370669224, 3448.1552019755195, 3538.82305884706, 3747.47045744123, 3818.746939305809, 4036.2734707752593, 3338.8998330050017, 3834.6740527055094, 4002.7598344099447, 4064.3700226464944, 4083.9795801020805, 2692.909371704463, 3076.6769489203534, 3080.69070644975, 3470.6355832637714, 3533.522974398449, 5117.692938423706, 5210.270562121306, 5245.554127899133, 5249.527542521153, 5276.4459731728, 4018.6383225509553, 4439.7780110994645, 4611.2852507861335, 4617.7762892833425, 4640.419947506562, 4700.660311811117, 5603.915941260884, 5626.299376299358, 5649.604527683064, 5666.716664166775, 3610.2057547339805, 3710.1466662667467, 3806.0, 3816.255830113101, 3842.385218365076, 5926.2295901532525, 5947.851303717398, 5953.434423729782, 5959.599494118878, 6001.35940186321, 3313.9005829825146, 3856.3444214483043, 3862.517185351831, 3874.709396795195, 3893.8247778849454, 3704.2776658551325, 3948.6841052715713, 3950.4074388841905, 3953.9522026662958, 3956.4263181838473, 3795.6204379561823, 4073.877783666495, 4293.613574778285, 4699.365585645963, 4703.529647035338, 4824.6863953842885, 5723.427657234327, 5749.741261643236, 5771.7402716877, 5819.796307129315, 1020.0513522424217, 1101.3116801998751, 1200.439428742631, 1204.584418375659, 1263.4585805317497, 803.9879401808978, 824.3858325547386, 844.6579135450153, 862.4264863865459, 873.6418068591847, 4584.977075114603, 4912.778924948386, 4954.455009948902, 4980.551750342414, 5063.594912406998, 5289.9155673086925, 5391.816314392678, 5518.641288316327, 5558.416366281563, 5608.513743857881, 3816.4847745553993, 4280.357946308079, 4748.216544270226, 5064.620153488503, 5117.974410127926, 2520.5841036229185, 2848.5442329227226, 2859.513882639956, 2886.942261154765, 2890.783191260664, 4556.886077848014, 5239.532930779312, 5262.8686481112145, 5687.350668306145, 5720.42795720433, 4829.541193586604, 5341.3857406398765, 5612.540739407784, 5619.791744774893, 5700.572457065737, 5067.619928505377, 5695.800646977336, 5696.23100881384, 5698.772049118072, 5709.429057094258, 5322.627416080992, 5441.469716581809, 5457.781688732406, 5502.532284755681, 5538.833834984957, 4485.327200919888, 4957.529034741694, 5405.783768649368, 5456.7544460498575, 5473.454843497792, 4629.907401851877, 5010.924836127534, 5018.824341148043, 5642.971785141048, 5682.77268909239, 3407.3866703993517, 3700.481932529502, 3702.61122582125, 3757.981210093932, 3769.7738135711943, 5064.606713502036, 5125.718085505333, 5404.918926216189, 5479.178123281539, 5692.829215123553, 5044.445111037856, 5160.277561141368, 5516.972415137899, 5674.205611214516, 5675.77296908119, 5635.520980716685, 5639.436056394327, 5639.533721232427, 5657.3776884543495, 5708.0, 5192.662476938987, 5382.3541175059245, 5527.419620939743, 5571.582131340085, 5574.275344205227, 5610.270664813548, 5619.719014049363, 5635.9436405635415, 5659.773609055592, 5660.29246344207, 5529.806456773994, 5545.584081193846, 5547.916781248204, 5560.582956278215, 5594.748236329294], "value": 3990.088060975313, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [582.9215950491589, 660.9900851487229, 695.9860802783944, 715.3311653603884, 765.7932358263268, 14226.502072427467, 15021.92489037556, 15027.098374097535, 15075.924620376896, 15092.547223583297, 13515.256660883671, 13658.0, 13709.245991470465, 13721.65695857604, 13725.93137034316, 637.9330170332117, 661.6228749612723, 793.3137835772045, 810.2181394953868, 820.233082068265, 793.8491686579531, 815.902091748991, 822.3339095332769, 824.1305422778966, 824.468217999393, 647.6826355086021, 695.9686814093378, 870.3559366069127, 872.4111224923183, 872.8865247517846, 734.6253410760497, 849.9107593702633, 864.7492227254089, 867.0245973280059, 867.132867132864, 626.5363630913137, 642.6240649220211, 644.5230529408251, 645.3224114679567, 646.1599920103846, 12830.777073119529, 13443.66390840227, 13611.319434028259, 13710.834579060791, 14418.423263069464, 12274.447649855778, 12431.875681243162, 12499.750004999849, 13513.324333783272, 14052.71894562103, 11292.0, 11366.37484938328, 11407.0, 11497.655070347902, 11519.42402879861, 3556.6513772915223, 3597.190632107781, 3639.2721455708966, 3707.295613833363, 3775.8678446254526, 69943.0, 88752.55623721905, 91996.54001729949, 95399.9999999993, 96596.51701741517, 10342.896571034344, 10361.94819025915, 10407.63573274947, 10420.0, 10791.24461287718, 15718.29267682957, 18934.810651893582, 19712.112954917062, 19971.60056798885, 20004.69992950107, 7496.111820562348, 7620.847583048308, 7724.922750772533, 7726.072871255373, 7878.606069696492, 4220.725652832556, 4261.659067274611, 4312.784360781949, 4382.904273931517, 4418.138462999685, 3271.4765637497885, 3320.900372988814, 3361.663833616619, 3378.172347774811, 3379.5099710541836, 615.1756646094256, 618.7810014271877, 619.9411055949678, 626.1828314050208, 640.471610920992, 534.7353060235208, 628.2210059526154, 645.8062581225613, 677.069030083635, 691.4262155839189, 8757.27086546227, 8780.473171609723, 8978.775530611787, 8996.325275604357, 9063.41087829289, 629.175779728557, 633.6926590603554, 649.4317472211815, 668.7325069972051, 671.6507416143578, 3733.9626603733614, 4148.979255103705, 4235.470566179228, 4296.163146944503, 4481.5070342262325, 74583.62708186424, 95253.09493810085, 113388.0, 119638.0, 121479.0, 84657.57671211728, 85451.0, 102807.0, 104029.47985260177, 104748.47625761823, 64844.0, 73201.63399183076, 79213.60393197997, 89486.0, 91397.0, 6700.564463309869, 7438.128026142885, 7668.923310766821, 8675.783105422415, 8790.34072444582, 57708.71145644413, 58090.4190958085, 59046.704766475894, 59276.40723592709, 60041.69979150251, 81207.0, 81308.96727581858, 82157.0, 82507.74922507702, 84752.1524784769, 11141.665750027512, 11145.331280123219, 11272.323660580392, 11499.597514086967, 11698.2981021139, 12270.263784172977, 12313.630591082281, 12409.379531023233, 12474.126811123497, 12977.805332919825, 11823.645290641294, 11837.940810295893, 12146.514139434325, 12244.816327755269, 12248.265104093784, 11802.76394472123, 11906.404679765803, 11961.521539138685, 12251.38743062861, 12304.569340073413, 11779.469923853276, 11885.22746021506, 11934.940325298317, 12303.56937507183, 12309.815352769536, 9670.468124253233, 9702.563384647849, 9723.513824308899, 9903.504824758877, 10215.080642742189, 9587.472689002172, 9944.651937182163, 9952.00479951999, 9968.053034961667, 10004.049615286536], "value": 7064.184207804715, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1613.8999999999996, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 116.0, 120.0, 119.0, 118.0, 116.0, 115.0, 119.0, 116.0, 117.0], "value": 116.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 118.0, 117.0, 154.0, 114.0, 120.0, 125.0, 117.0, 148.0, 118.0], "value": 128.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [207.0, 221.0, 227.0, 218.0, 197.0, 200.0, 194.0, 244.0, 272.0, 192.0], "value": 217.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 59.0, 60.0, 85.0, 58.0, 62.0, 73.0, 83.0, 101.0, 61.0], "value": 71.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 72.0, 101.0, 95.0, 99.0, 102.0, 97.0, 68.0, 71.0, 88.0], "value": 88.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 90.0, 89.0, 102.0, 137.0, 109.0, 92.0, 88.0, 102.0, 113.0], "value": 101.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 99.0, 98.0, 103.0, 139.0, 96.0, 119.0, 111.0, 96.0, 98.0], "value": 105.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 132.0, 150.0, 92.0, 149.0, 146.0, 110.0, 92.0, 95.0, 96.0], "value": 115.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 58.0, 57.0, 57.0, 58.0, 60.0, 58.0, 56.0, 56.0, 58.0], "value": 57.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 69.0, 87.0, 120.0, 113.0, 74.0, 67.0, 69.0, 71.0, 70.0], "value": 81.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 108.0, 109.0, 92.0, 90.0, 86.0, 88.0, 87.0, 89.0, 91.0], "value": 94.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 172.0, 173.0, 151.0, 169.0, 171.0, 174.0, 150.0, 219.0, 158.0], "value": 169.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 246.0, 161.0, 170.0, 236.0, 239.0, 171.0, 175.0, 176.0, 179.0], "value": 192.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 96.0, 92.0, 80.0, 67.0, 67.0, 70.0, 69.0, 68.0, 68.0], "value": 75.1, "unit": "score"}]}]} 08:15:11 INFO - Return code: 0 08:15:11 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:15:11 ERROR - # TBPL SUCCESS # 08:15:11 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:15:11 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:15:11 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:15:11 INFO - 2016-11-22 08:15:11.828 screenresolution[1656:11069] starting screenresolution argv=screenresolution get 08:15:11 INFO - 2016-11-22 08:15:11.830 screenresolution[1656:11069] Display 0: 1600x1200x32@60 08:15:11 INFO - 2016-11-22 08:15:11.837 screenresolution[1657:11072] starting screenresolution argv=screenresolution list 08:15:11 INFO - Available Modes on Display 0 08:15:11 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:15:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:15:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:15:11 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:15:12 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:15:12 INFO - Intel Iris: 08:15:12 INFO - Chipset Model: Intel Iris 08:15:12 INFO - Type: GPU 08:15:12 INFO - Bus: Built-In 08:15:12 INFO - VRAM (Dynamic, Max): 1536 MB 08:15:12 INFO - Vendor: Intel (0x8086) 08:15:12 INFO - Device ID: 0x0a2e 08:15:12 INFO - Revision ID: 0x0009 08:15:12 INFO - Displays: 08:15:12 INFO - Display: 08:15:12 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:15:12 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:15:12 INFO - Display Serial Number: 1600x1200 60 08:15:12 INFO - Main Display: Yes 08:15:12 INFO - Mirror: Off 08:15:12 INFO - Online: Yes 08:15:12 INFO - Rotation: Supported 08:15:12 INFO - Return code: 0 08:15:12 INFO - Running post-action listener: _resource_record_post_action 08:15:12 INFO - [mozharness: 2016-11-22 16:15:12.079745Z] Finished run-tests step (success) 08:15:12 INFO - Running post-run listener: _resource_record_post_run 08:15:12 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 34206208; Write bytes: 695872512; Read time: 317; Write time: 2480 08:15:12 INFO - TinderboxPrint: CPU usage
54.4% 08:15:12 INFO - TinderboxPrint: I/O read bytes / time
34,206,208 / 317 08:15:12 INFO - TinderboxPrint: I/O write bytes / time
695,872,512 / 2,480 08:15:12 INFO - TinderboxPrint: CPU idle
2,214.3 (45.6%) 08:15:12 INFO - TinderboxPrint: CPU system
976.2 (20.1%) 08:15:12 INFO - TinderboxPrint: CPU user
1,663.2 (34.3%) 08:15:12 INFO - TinderboxPrint: Swap in / out
413,339,648 / 0 08:15:12 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 371991040; Write bytes: 366749696; Read time: 28867; Write time: 704 08:15:12 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 33268224; Write bytes: 309194240; Read time: 302; Write time: 1606 08:15:12 INFO - Running post-run listener: _upload_blobber_files 08:15:12 INFO - Blob upload gear active. 08:15:12 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:15:12 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 08:15:12 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', 'Graphics', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:15:12 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 Graphics -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:15:13 INFO - (blobuploader) - INFO - Open directory for files ... 08:15:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 08:15:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:15:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:15:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 08:15:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:15:14 INFO - (blobuploader) - INFO - Done attempting. 08:15:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 08:15:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:15:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:15:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 08:15:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:15:15 INFO - (blobuploader) - INFO - Done attempting. 08:15:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:15:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:15:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:15:17 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:15:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:15:17 INFO - (blobuploader) - INFO - Done attempting. 08:15:17 INFO - (blobuploader) - INFO - Iteration through files over. 08:15:17 INFO - Return code: 0 08:15:17 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:15:17 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:15:17 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/22a51ad4bfd5080ae88c76a79076835c9e4f4c798f6cb030870939e977aafa88fad05cc1533ffe77fa8b26680296571f6490ec72e7d43ed024a247f87129dbe5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/a233e366009880c723bbdcda6f2529e1642396bbc47e381b7d2c0ddb9d6a7ffcbfe33c91a56b8f5af54a7769e6c7b860de87ad4323b8dbc0e2f1c7f20662f010", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/cefa7217174e51bed0ee738ec905d791c25834ab773144d232dc389ede07fca3b7a8445fa4531892404be040a664b6561f8278fdbf2390c194e1ff5b05f1b5bf"} 08:15:17 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:15:17 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:15:17 INFO - Contents: 08:15:17 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/22a51ad4bfd5080ae88c76a79076835c9e4f4c798f6cb030870939e977aafa88fad05cc1533ffe77fa8b26680296571f6490ec72e7d43ed024a247f87129dbe5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/a233e366009880c723bbdcda6f2529e1642396bbc47e381b7d2c0ddb9d6a7ffcbfe33c91a56b8f5af54a7769e6c7b860de87ad4323b8dbc0e2f1c7f20662f010", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/cefa7217174e51bed0ee738ec905d791c25834ab773144d232dc389ede07fca3b7a8445fa4531892404be040a664b6561f8278fdbf2390c194e1ff5b05f1b5bf"} 08:15:17 INFO - Running post-run listener: copy_logs_to_upload_dir 08:15:17 INFO - Copying logs to upload dir... 08:15:17 INFO - mkdir: /builds/slave/test/build/upload/logs 08:15:17 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.025434 ========= master_lag: 1.41 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-11-22 08:15:18.946109) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-22 08:15:18.951558) ========= 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.QjCB05Xsak/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.SmKiZYJBgQ/Listeners TMPDIR=/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/22a51ad4bfd5080ae88c76a79076835c9e4f4c798f6cb030870939e977aafa88fad05cc1533ffe77fa8b26680296571f6490ec72e7d43ed024a247f87129dbe5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/a233e366009880c723bbdcda6f2529e1642396bbc47e381b7d2c0ddb9d6a7ffcbfe33c91a56b8f5af54a7769e6c7b860de87ad4323b8dbc0e2f1c7f20662f010", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/cefa7217174e51bed0ee738ec905d791c25834ab773144d232dc389ede07fca3b7a8445fa4531892404be040a664b6561f8278fdbf2390c194e1ff5b05f1b5bf"} build_url:https://queue.taskcluster.net/v1/task/dmWAvqaiSSi71l8PiezDJQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011497 build_url: 'https://queue.taskcluster.net/v1/task/dmWAvqaiSSi71l8PiezDJQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/22a51ad4bfd5080ae88c76a79076835c9e4f4c798f6cb030870939e977aafa88fad05cc1533ffe77fa8b26680296571f6490ec72e7d43ed024a247f87129dbe5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/a233e366009880c723bbdcda6f2529e1642396bbc47e381b7d2c0ddb9d6a7ffcbfe33c91a56b8f5af54a7769e6c7b860de87ad4323b8dbc0e2f1c7f20662f010", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/cefa7217174e51bed0ee738ec905d791c25834ab773144d232dc389ede07fca3b7a8445fa4531892404be040a664b6561f8278fdbf2390c194e1ff5b05f1b5bf"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-22 08:15:18.991916) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 08:15:18.992234) ========= 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.QjCB05Xsak/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.SmKiZYJBgQ/Listeners TMPDIR=/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/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.005093 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 08:15:19.045093) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-22 08:15:19.045411) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-22 08:15:19.048784) ========= ========= Total master_lag: 1.56 =========