builder: graphics_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0005 starttime: 1480918826.66 results: success (0) buildid: 20161204201538 builduid: 5a945a861c1b4c11b0babb22b7b7a174 revision: 46322372c0f4c6b176c953ec1bef8516d4b382dd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-04 22:20:26.661871) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-04 22:20:26.662331) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-04 22:20:26.662719) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-04 22:20:26.838186) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-04 22:20:26.838477) ========= 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.HavLnOcel8/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.YmPhP8PDnT/Listeners TMPDIR=/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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-12-04 22:20:26-- 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% 15.7M=0.001s 2016-12-04 22:20:27 (15.7 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.381296 ========= master_lag: 0.11 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-04 22:20:27.334706) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-04 22:20:27.335230) ========= 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.HavLnOcel8/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.YmPhP8PDnT/Listeners TMPDIR=/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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.026917 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-04 22:20:27.396017) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-04 22:20:27.396333) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev 46322372c0f4c6b176c953ec1bef8516d4b382dd --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 46322372c0f4c6b176c953ec1bef8516d4b382dd --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.HavLnOcel8/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.YmPhP8PDnT/Listeners TMPDIR=/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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-12-04 22:20:27,497 truncating revision to first 12 chars 2016-12-04 22:20:27,497 Setting DEBUG logging. 2016-12-04 22:20:27,498 attempt 1/10 2016-12-04 22:20:27,498 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/46322372c0f4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-04 22:20:27,947 unpacking tar archive at: graphics-46322372c0f4/testing/mozharness/ program finished with exit code 0 elapsedTime=0.721141 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-04 22:20:28.153082) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-04 22:20:28.153377) ========= script_repo_revision: 46322372c0f4c6b176c953ec1bef8516d4b382dd ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-04 22:20:28.153748) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-04 22:20:28.154028) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-04 22:20:28.187245) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2016-12-04 22:20:28.187519) ========= /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.HavLnOcel8/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.YmPhP8PDnT/Listeners TMPDIR=/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 22:20:28 INFO - MultiFileLogger online at 20161204 22:20:28 in /builds/slave/test 22:20:28 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 22:20:28 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:20:28 INFO - {'append_to_log': False, 22:20:28 INFO - 'base_work_dir': '/builds/slave/test', 22:20:28 INFO - 'blob_upload_branch': 'Graphics', 22:20:28 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:20:28 INFO - 'branch': 'Graphics', 22:20:28 INFO - 'buildbot_json_path': 'buildprops.json', 22:20:28 INFO - 'config_files': ('talos/mac_config.py',), 22:20:28 INFO - 'default_actions': ('clobber', 22:20:28 INFO - 'read-buildbot-config', 22:20:28 INFO - 'download-and-extract', 22:20:28 INFO - 'populate-webroot', 22:20:28 INFO - 'create-virtualenv', 22:20:28 INFO - 'install', 22:20:28 INFO - 'run-tests'), 22:20:28 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:20:28 INFO - 'download_minidump_stackwalk': True, 22:20:28 INFO - 'download_symbols': 'ondemand', 22:20:28 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:20:28 INFO - 'tooltool.py': '/tools/tooltool.py', 22:20:28 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:20:28 INFO - '/tools/misc-python/virtualenv.py')}, 22:20:28 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:20:28 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:20:28 INFO - 'installer_path': 'installer.exe', 22:20:28 INFO - 'log_level': 'info', 22:20:28 INFO - 'log_name': 'talos', 22:20:28 INFO - 'log_to_console': True, 22:20:28 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 22:20:28 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 22:20:28 INFO - 'opt_config_files': (), 22:20:28 INFO - 'pip_index': False, 22:20:28 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:20:28 INFO - 'cmd': ('bash', 22:20:28 INFO - '-c', 22:20:28 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:20:28 INFO - 'enabled': True, 22:20:28 INFO - 'halt_on_failure': False, 22:20:28 INFO - 'name': 'check_screen_resolution'},), 22:20:28 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:20:28 INFO - 'cmd': ('bash', 22:20:28 INFO - '-c', 22:20:28 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:20:28 INFO - 'enabled': True, 22:20:28 INFO - 'halt_on_failure': False, 22:20:28 INFO - 'name': 'check_screen_resolution'},), 22:20:28 INFO - 'run_cmd_checks_enabled': True, 22:20:28 INFO - 'sps_profile': False, 22:20:28 INFO - 'sps_profile_interval': 0, 22:20:28 INFO - 'suite': 'dromaeojs-e10s', 22:20:28 INFO - 'system_bits': '32', 22:20:28 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:20:28 INFO - 'title': 't-yosemite-r7-0005', 22:20:28 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:20:28 INFO - 'use_talos_json': True, 22:20:28 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:20:28 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:20:28 INFO - 'work_dir': 'build'} 22:20:28 INFO - [mozharness: 2016-12-05 06:20:28.397064Z] Running clobber step. 22:20:28 INFO - Running pre-action listener: _resource_record_pre_action 22:20:28 INFO - Running main action method: clobber 22:20:28 INFO - rmtree: /builds/slave/test/build 22:20:28 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:20:31 INFO - Running post-action listener: _resource_record_post_action 22:20:31 INFO - [mozharness: 2016-12-05 06:20:31.299119Z] Finished clobber step (success) 22:20:31 INFO - [mozharness: 2016-12-05 06:20:31.299219Z] Running read-buildbot-config step. 22:20:31 INFO - Running pre-action listener: _resource_record_pre_action 22:20:31 INFO - Running main action method: read_buildbot_config 22:20:31 INFO - Using buildbot properties: 22:20:31 INFO - { 22:20:31 INFO - "project": "", 22:20:31 INFO - "product": "firefox", 22:20:31 INFO - "script_repo_revision": "default", 22:20:31 INFO - "builddir": "graphics_yosemite_r7_test-dromaeojs-e10s", 22:20:31 INFO - "repository": "", 22:20:31 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 graphics talos dromaeojs-e10s", 22:20:31 INFO - "buildid": "20161204201538", 22:20:31 INFO - "pgo_build": "False", 22:20:31 INFO - "basedir": "/builds/slave/test", 22:20:31 INFO - "buildnumber": 9, 22:20:31 INFO - "slavename": "t-yosemite-r7-0005", 22:20:31 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 22:20:31 INFO - "platform": "yosemite_r7", 22:20:31 INFO - "scheduler": "tests-graphics-macosx64-talos-8-3600", 22:20:31 INFO - "branch": "graphics", 22:20:31 INFO - "repo_path": "projects/graphics", 22:20:31 INFO - "revision": "46322372c0f4c6b176c953ec1bef8516d4b382dd", 22:20:31 INFO - "stage_platform": "macosx64", 22:20:31 INFO - "builduid": "5a945a861c1b4c11b0babb22b7b7a174", 22:20:31 INFO - "slavebuilddir": "test" 22:20:31 INFO - } 22:20:31 INFO - Found installer url https://queue.taskcluster.net/v1/task/UDx0J90KQqWzgVPrZy37ug/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 22:20:31 INFO - Running post-action listener: _resource_record_post_action 22:20:31 INFO - [mozharness: 2016-12-05 06:20:31.302606Z] Finished read-buildbot-config step (success) 22:20:31 INFO - [mozharness: 2016-12-05 06:20:31.302744Z] Running download-and-extract step. 22:20:31 INFO - Running pre-action listener: _resource_record_pre_action 22:20:31 INFO - Running main action method: download_and_extract 22:20:31 INFO - mkdir: /builds/slave/test/build/tests 22:20:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:20:31 INFO - trying https://queue.taskcluster.net/v1/task/UDx0J90KQqWzgVPrZy37ug/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 22:20:31 INFO - Downloading https://queue.taskcluster.net/v1/task/UDx0J90KQqWzgVPrZy37ug/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 22:20:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UDx0J90KQqWzgVPrZy37ug/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 22:20:32 INFO - Downloaded 1199 bytes. 22:20:32 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 22:20:32 INFO - Using the following test package requirements: 22:20:32 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 22:20:32 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 22:20:32 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 22:20:32 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 22:20:32 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 22:20:32 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 22:20:32 INFO - u'jsshell-mac.zip'], 22:20:32 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 22:20:32 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 22:20:32 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 22:20:32 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 22:20:32 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 22:20:32 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 22:20:32 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 22:20:32 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 22:20:32 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 22:20:32 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 22:20:32 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 22:20:32 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 22:20:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UDx0J90KQqWzgVPrZy37ug/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 22:20:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UDx0J90KQqWzgVPrZy37ug/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:20:32 INFO - Fetch https://queue.taskcluster.net/v1/task/UDx0J90KQqWzgVPrZy37ug/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 22:20:32 INFO - Http code: 200 22:20:32 INFO - via: 1.1 336f0e6ef9a3462f682d6ca49029b665.cloudfront.net (CloudFront) 22:20:32 INFO - x-amz-cf-id: 2onhes1aIZcEQ06GnwGHD-F8J7AHygk_LHZJtePdlICDOsviPm72hA== 22:20:32 INFO - x-amz-version-id: OTIgGORS_11KW2DtcteekPSZ1RZ82tFF 22:20:33 INFO - Expected file size: 16282551 22:20:33 INFO - Obtained file size: 16282551 22:20:39 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 22:20:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UDx0J90KQqWzgVPrZy37ug/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 22:20:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UDx0J90KQqWzgVPrZy37ug/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:20:39 INFO - Fetch https://queue.taskcluster.net/v1/task/UDx0J90KQqWzgVPrZy37ug/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 22:20:40 INFO - Http code: 200 22:20:40 INFO - via: 1.1 37af14ee951b12568cf1cc160d08abe6.cloudfront.net (CloudFront) 22:20:40 INFO - x-amz-cf-id: 8GnaJzTMWvglsGZDrc7xmNbRuo2fpvsc_sQ_G0xDm-WyDgddjuQ0lQ== 22:20:40 INFO - x-amz-version-id: OTIgGORS_11KW2DtcteekPSZ1RZ82tFF 22:20:40 INFO - Expected file size: 16282551 22:20:40 INFO - Obtained file size: 16282551 22:20:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UDx0J90KQqWzgVPrZy37ug/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 22:20:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UDx0J90KQqWzgVPrZy37ug/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 22:20:47 INFO - Fetch https://queue.taskcluster.net/v1/task/UDx0J90KQqWzgVPrZy37ug/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 22:20:49 INFO - Http code: 200 22:20:49 INFO - via: 1.1 20f1c35f343f4b271ae8dcacfd7ea0e9.cloudfront.net (CloudFront) 22:20:49 INFO - x-amz-cf-id: xbDfMyhk5g7nMHuFxP2MT4Lfs9JXsS-De5Y8rEh2VogTXFgBM9R7FA== 22:20:49 INFO - x-amz-version-id: tZ9VkZ6LdlohTJnsMbCLzA_U_E91UDyj 22:20:49 INFO - Expected file size: 14409702 22:20:49 INFO - Obtained file size: 14409702 22:20:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:20:50 INFO - trying https://queue.taskcluster.net/v1/task/UDx0J90KQqWzgVPrZy37ug/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 22:20:50 INFO - Downloading https://queue.taskcluster.net/v1/task/UDx0J90KQqWzgVPrZy37ug/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 22:20:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UDx0J90KQqWzgVPrZy37ug/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 22:20:55 INFO - Downloaded 107762816 bytes. 22:20:55 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/UDx0J90KQqWzgVPrZy37ug/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 22:20:55 INFO - mkdir: /builds/slave/test/properties 22:20:55 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:20:55 INFO - Writing to file /builds/slave/test/properties/build_url 22:20:55 INFO - Contents: 22:20:55 INFO - build_url:https://queue.taskcluster.net/v1/task/UDx0J90KQqWzgVPrZy37ug/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 22:20:56 INFO - Running post-action listener: _resource_record_post_action 22:20:56 INFO - Running post-action listener: set_extra_try_arguments 22:20:56 INFO - [mozharness: 2016-12-05 06:20:56.196665Z] Finished download-and-extract step (success) 22:20:56 INFO - [mozharness: 2016-12-05 06:20:56.196756Z] Running populate-webroot step. 22:20:56 INFO - Running pre-action listener: _resource_record_pre_action 22:20:56 INFO - Running main action method: populate_webroot 22:20:56 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:20:56 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:20:56 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:20:56 INFO - '--tppagecycles', 22:20:56 INFO - '7'], 22:20:56 INFO - 'tests': ['tsvgm']}}, 22:20:56 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:20:56 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:20:56 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:20:56 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:20:56 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:20:56 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:20:56 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 22:20:56 INFO - 'talos_options': ['--disable-e10s'], 22:20:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:20:56 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 22:20:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:20:56 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 22:20:56 INFO - 'talos_options': ['--disable-e10s'], 22:20:56 INFO - 'tests': ['damp', 'tps']}, 22:20:56 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 22:20:56 INFO - 'tests': ['damp', 'tps']}, 22:20:56 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:20:56 INFO - 'tests': ['dromaeo_dom']}, 22:20:56 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:20:56 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:20:56 INFO - 'tests': ['basic_compositor_video']}, 22:20:56 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:20:56 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:20:56 INFO - 'tests': ['a11yr', 22:20:56 INFO - 'ts_paint', 22:20:56 INFO - 'tpaint', 22:20:56 INFO - 'sessionrestore', 22:20:56 INFO - 'sessionrestore_no_auto_restore', 22:20:56 INFO - 'tabpaint']}, 22:20:56 INFO - 'other-e10s': {'tests': ['a11yr', 22:20:56 INFO - 'ts_paint', 22:20:56 INFO - 'tpaint', 22:20:56 INFO - 'sessionrestore', 22:20:56 INFO - 'sessionrestore_no_auto_restore', 22:20:56 INFO - 'tabpaint']}, 22:20:56 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:20:56 INFO - 'ts_paint', 22:20:56 INFO - 'tpaint', 22:20:56 INFO - 'sessionrestore', 22:20:56 INFO - 'sessionrestore_no_auto_restore', 22:20:56 INFO - 'tabpaint']}, 22:20:56 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:20:56 INFO - 'ts_paint', 22:20:56 INFO - 'tpaint', 22:20:56 INFO - 'sessionrestore', 22:20:56 INFO - 'sessionrestore_no_auto_restore', 22:20:56 INFO - 'tabpaint']}, 22:20:56 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:20:56 INFO - 'tests': ['a11yr', 22:20:56 INFO - 'ts_paint', 22:20:56 INFO - 'tpaint', 22:20:56 INFO - 'sessionrestore', 22:20:56 INFO - 'sessionrestore_no_auto_restore', 22:20:56 INFO - 'tabpaint']}, 22:20:56 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:20:56 INFO - 'tests': ['a11yr', 22:20:56 INFO - 'ts_paint', 22:20:56 INFO - 'tpaint', 22:20:56 INFO - 'sessionrestore', 22:20:56 INFO - 'sessionrestore_no_auto_restore', 22:20:56 INFO - 'tabpaint']}, 22:20:56 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:20:56 INFO - 'tests': ['tsvgx', 22:20:56 INFO - 'tsvgr_opacity', 22:20:56 INFO - 'tart', 22:20:56 INFO - 'tscrollx', 22:20:56 INFO - 'cart', 22:20:56 INFO - 'tsvg_static']}, 22:20:56 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:20:56 INFO - 'tsvgr_opacity', 22:20:56 INFO - 'tart', 22:20:56 INFO - 'tscrollx', 22:20:56 INFO - 'cart', 22:20:56 INFO - 'tsvg_static']}, 22:20:56 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 22:20:56 INFO - 'talos_options': ['--disable-e10s'], 22:20:56 INFO - 'tests': ['tp5o']}, 22:20:56 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 22:20:56 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 22:20:56 INFO - 'talos_options': ['--disable-e10s', 22:20:56 INFO - '--xperf_path', 22:20:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:20:56 INFO - 'tests': ['tp5n']}, 22:20:56 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 22:20:56 INFO - 'talos_options': ['--xperf_path', 22:20:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:20:56 INFO - 'tests': ['tp5n']}}, 22:20:56 INFO - 'talos.zip': {'path': '', 22:20:56 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:20:56 INFO - Running post-action listener: _resource_record_post_action 22:20:56 INFO - [mozharness: 2016-12-05 06:20:56.215299Z] Finished populate-webroot step (success) 22:20:56 INFO - [mozharness: 2016-12-05 06:20:56.215429Z] Running create-virtualenv step. 22:20:56 INFO - Running pre-action listener: _resource_record_pre_action 22:20:56 INFO - Running main action method: create_virtualenv 22:20:56 INFO - Creating virtualenv /builds/slave/test/build/venv 22:20:56 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 22:20:56 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:20:56 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:20:56 INFO - Using real prefix '/tools/python27' 22:20:56 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:20:57 INFO - Installing distribute.............................................................................................................................................................................................done. 22:20:59 INFO - Installing pip.................done. 22:20:59 INFO - Return code: 0 22:20:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 22:20:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 22:21:00 INFO - Reading from file tmpfile_stdout 22:21:00 INFO - Output received: 22:21:00 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 22:21:00 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:21:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:21:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:21:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:21:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:21:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:00 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 0x1070733f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106fe9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdcd04a88d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e89d30>, '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 0x106cf0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e81b20>, '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.HavLnOcel8/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.YmPhP8PDnT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:21:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 22:21:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 22:21:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HavLnOcel8/Render', 22:21:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:21:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:21:00 INFO - 'HOME': '/Users/cltbld', 22:21:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:21:00 INFO - 'LOGNAME': 'cltbld', 22:21:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:21:00 INFO - 'MOZ_NO_REMOTE': '1', 22:21:00 INFO - 'NO_EM_RESTART': '1', 22:21:00 INFO - 'PAGER': '/bin/cat', 22:21:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:21:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:21:00 INFO - 'PWD': '/builds/slave/test', 22:21:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:21:00 INFO - 'SHELL': '/bin/bash', 22:21:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YmPhP8PDnT/Listeners', 22:21:00 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 22:21:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:21:00 INFO - 'USER': 'cltbld', 22:21:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:21:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:21:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:21:00 INFO - 'XPC_FLAGS': '0x0', 22:21:00 INFO - 'XPC_SERVICE_NAME': '0', 22:21:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:21:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:21:00 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 22:21:00 INFO - Cleaning up... 22:21:00 INFO - Return code: 0 22:21:00 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:21:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:21:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:21:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:21:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:21:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:00 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 0x1070733f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106fe9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdcd04a88d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e89d30>, '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 0x106cf0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e81b20>, '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.HavLnOcel8/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.YmPhP8PDnT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:21:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 22:21:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 22:21:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HavLnOcel8/Render', 22:21:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:21:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:21:00 INFO - 'HOME': '/Users/cltbld', 22:21:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:21:00 INFO - 'LOGNAME': 'cltbld', 22:21:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:21:00 INFO - 'MOZ_NO_REMOTE': '1', 22:21:00 INFO - 'NO_EM_RESTART': '1', 22:21:00 INFO - 'PAGER': '/bin/cat', 22:21:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:21:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:21:00 INFO - 'PWD': '/builds/slave/test', 22:21:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:21:00 INFO - 'SHELL': '/bin/bash', 22:21:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YmPhP8PDnT/Listeners', 22:21:00 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 22:21:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:21:00 INFO - 'USER': 'cltbld', 22:21:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:21:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:21:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:21:00 INFO - 'XPC_FLAGS': '0x0', 22:21:00 INFO - 'XPC_SERVICE_NAME': '0', 22:21:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:21:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:21:00 INFO - Downloading/unpacking psutil>=3.1.1 22:21:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:21:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:21:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:21:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:21:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:21:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:21:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:21:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:21:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:21:04 INFO - Installing collected packages: psutil 22:21:04 INFO - Running setup.py install for psutil 22:21:04 INFO - building 'psutil._psutil_osx' extension 22:21:04 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 22:21:05 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 22:21:05 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 22:21:05 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 22:21:05 INFO - building 'psutil._psutil_posix' extension 22:21:05 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 22:21:05 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 22:21:05 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 22:21:05 INFO - ^ 22:21:05 INFO - 1 warning generated. 22:21:05 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 22:21:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:21:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:21:06 INFO - Successfully installed psutil 22:21:06 INFO - Cleaning up... 22:21:06 INFO - Return code: 0 22:21:06 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:21:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:21:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:21:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:21:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:21:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1070733f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106fe9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdcd04a88d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e89d30>, '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 0x106cf0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e81b20>, '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.HavLnOcel8/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.YmPhP8PDnT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:21:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 22:21:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 22:21:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HavLnOcel8/Render', 22:21:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:21:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:21:06 INFO - 'HOME': '/Users/cltbld', 22:21:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:21:06 INFO - 'LOGNAME': 'cltbld', 22:21:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:21:06 INFO - 'MOZ_NO_REMOTE': '1', 22:21:06 INFO - 'NO_EM_RESTART': '1', 22:21:06 INFO - 'PAGER': '/bin/cat', 22:21:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:21:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:21:06 INFO - 'PWD': '/builds/slave/test', 22:21:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:21:06 INFO - 'SHELL': '/bin/bash', 22:21:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YmPhP8PDnT/Listeners', 22:21:06 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 22:21:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:21:06 INFO - 'USER': 'cltbld', 22:21:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:21:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:21:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:21:06 INFO - 'XPC_FLAGS': '0x0', 22:21:06 INFO - 'XPC_SERVICE_NAME': '0', 22:21:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:21:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:21:06 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:21:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:21:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:21:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:21:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:21:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:21:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:21:09 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:21:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:21:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:21:09 INFO - Installing collected packages: mozsystemmonitor 22:21:09 INFO - Running setup.py install for mozsystemmonitor 22:21:09 INFO - Successfully installed mozsystemmonitor 22:21:09 INFO - Cleaning up... 22:21:09 INFO - Return code: 0 22:21:09 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:21:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:21:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:21:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:21:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:21:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1070733f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106fe9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdcd04a88d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e89d30>, '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 0x106cf0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e81b20>, '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.HavLnOcel8/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.YmPhP8PDnT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:21:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 22:21:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 22:21:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HavLnOcel8/Render', 22:21:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:21:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:21:09 INFO - 'HOME': '/Users/cltbld', 22:21:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:21:09 INFO - 'LOGNAME': 'cltbld', 22:21:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:21:09 INFO - 'MOZ_NO_REMOTE': '1', 22:21:09 INFO - 'NO_EM_RESTART': '1', 22:21:09 INFO - 'PAGER': '/bin/cat', 22:21:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:21:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:21:09 INFO - 'PWD': '/builds/slave/test', 22:21:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:21:09 INFO - 'SHELL': '/bin/bash', 22:21:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YmPhP8PDnT/Listeners', 22:21:09 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 22:21:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:21:09 INFO - 'USER': 'cltbld', 22:21:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:21:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:21:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:21:09 INFO - 'XPC_FLAGS': '0x0', 22:21:09 INFO - 'XPC_SERVICE_NAME': '0', 22:21:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:21:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:21:09 INFO - Downloading/unpacking jsonschema==2.5.1 22:21:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:21:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:21:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:21:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:21:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:21:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:21:12 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:21:12 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:21:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:21:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:21:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:21:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:21:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:21:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:21:12 INFO - Downloading functools32-3.2.3-2.tar.gz 22:21:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:21:12 INFO - warning: no files found matching '*.txt' 22:21:12 INFO - no previously-included directories found matching 'build' 22:21:12 INFO - no previously-included directories found matching 'dist' 22:21:12 INFO - no previously-included directories found matching '.git*' 22:21:12 INFO - Installing collected packages: jsonschema, functools32 22:21:12 INFO - Running setup.py install for functools32 22:21:12 INFO - warning: no files found matching '*.txt' 22:21:12 INFO - no previously-included directories found matching 'build' 22:21:12 INFO - no previously-included directories found matching 'dist' 22:21:12 INFO - no previously-included directories found matching '.git*' 22:21:12 INFO - Successfully installed jsonschema functools32 22:21:12 INFO - Cleaning up... 22:21:12 INFO - Return code: 0 22:21:12 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:21:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:21:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:21:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:21:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:21:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:13 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 0x1070733f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106fe9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdcd04a88d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e89d30>, '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 0x106cf0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e81b20>, '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.HavLnOcel8/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.YmPhP8PDnT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:21:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 22:21:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 22:21:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HavLnOcel8/Render', 22:21:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:21:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:21:13 INFO - 'HOME': '/Users/cltbld', 22:21:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:21:13 INFO - 'LOGNAME': 'cltbld', 22:21:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:21:13 INFO - 'MOZ_NO_REMOTE': '1', 22:21:13 INFO - 'NO_EM_RESTART': '1', 22:21:13 INFO - 'PAGER': '/bin/cat', 22:21:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:21:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:21:13 INFO - 'PWD': '/builds/slave/test', 22:21:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:21:13 INFO - 'SHELL': '/bin/bash', 22:21:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YmPhP8PDnT/Listeners', 22:21:13 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 22:21:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:21:13 INFO - 'USER': 'cltbld', 22:21:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:21:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:21:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:21:13 INFO - 'XPC_FLAGS': '0x0', 22:21:13 INFO - 'XPC_SERVICE_NAME': '0', 22:21:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:21:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:21:13 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:21:13 INFO - Cleaning up... 22:21:13 INFO - Return code: 0 22:21:13 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:21:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:21:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:21:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:21:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:21:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:13 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 0x1070733f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106fe9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdcd04a88d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e89d30>, '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 0x106cf0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e81b20>, '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.HavLnOcel8/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.YmPhP8PDnT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:21:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 22:21:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 22:21:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HavLnOcel8/Render', 22:21:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:21:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:21:13 INFO - 'HOME': '/Users/cltbld', 22:21:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:21:13 INFO - 'LOGNAME': 'cltbld', 22:21:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:21:13 INFO - 'MOZ_NO_REMOTE': '1', 22:21:13 INFO - 'NO_EM_RESTART': '1', 22:21:13 INFO - 'PAGER': '/bin/cat', 22:21:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:21:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:21:13 INFO - 'PWD': '/builds/slave/test', 22:21:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:21:13 INFO - 'SHELL': '/bin/bash', 22:21:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YmPhP8PDnT/Listeners', 22:21:13 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 22:21:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:21:13 INFO - 'USER': 'cltbld', 22:21:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:21:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:21:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:21:13 INFO - 'XPC_FLAGS': '0x0', 22:21:13 INFO - 'XPC_SERVICE_NAME': '0', 22:21:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:21:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:21:13 INFO - Downloading/unpacking blobuploader==1.2.4 22:21:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:21:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:21:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:21:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:21:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:21:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:21:16 INFO - Downloading blobuploader-1.2.4.tar.gz 22:21:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:21:16 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:21:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:21:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:21:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:21:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:21:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:21:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:21:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:21:16 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:21:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:21:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:21:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:21:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:21:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:21:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:21:17 INFO - Downloading docopt-0.6.1.tar.gz 22:21:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:21:17 INFO - Installing collected packages: blobuploader, requests, docopt 22:21:17 INFO - Running setup.py install for blobuploader 22:21:17 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:21:17 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:21:17 INFO - Running setup.py install for requests 22:21:18 INFO - Running setup.py install for docopt 22:21:18 INFO - Successfully installed blobuploader requests docopt 22:21:18 INFO - Cleaning up... 22:21:18 INFO - Return code: 0 22:21:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:21:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:21:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:21:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:21:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:21:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:18 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 0x1070733f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106fe9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdcd04a88d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e89d30>, '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 0x106cf0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e81b20>, '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.HavLnOcel8/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.YmPhP8PDnT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:21:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 22:21:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:21:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HavLnOcel8/Render', 22:21:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:21:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:21:18 INFO - 'HOME': '/Users/cltbld', 22:21:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:21:18 INFO - 'LOGNAME': 'cltbld', 22:21:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:21:18 INFO - 'MOZ_NO_REMOTE': '1', 22:21:18 INFO - 'NO_EM_RESTART': '1', 22:21:18 INFO - 'PAGER': '/bin/cat', 22:21:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:21:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:21:18 INFO - 'PWD': '/builds/slave/test', 22:21:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:21:18 INFO - 'SHELL': '/bin/bash', 22:21:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YmPhP8PDnT/Listeners', 22:21:18 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 22:21:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:21:18 INFO - 'USER': 'cltbld', 22:21:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:21:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:21:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:21:18 INFO - 'XPC_FLAGS': '0x0', 22:21:18 INFO - 'XPC_SERVICE_NAME': '0', 22:21:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:21:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:21:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:21:18 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-9MEGQW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:21:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:21:18 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-qAlq0K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:21:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:21:18 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-S0et_d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:21:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:21:18 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-e_WsqT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:21:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:21:19 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-La9Wr9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:21:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:21:19 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-w8DD3Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:21:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:21:19 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-jWS7BE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:21:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:21:19 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-Q6u7eY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:21:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:21:19 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-pSoqvN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:21:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:21:19 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-G5Yzam-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:21:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:21:19 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-UxIsiu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:21:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:21:19 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-CgtanT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:21:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:21:19 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-jo_tRX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:21:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:21:20 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-gYGTAK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:21:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:21:20 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-S5hYSM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:21:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:21:20 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-pMpB4l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:21:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:21:20 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-mbsFyz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:21:20 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:21:20 INFO - Running setup.py install for manifestparser 22:21:20 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:21:20 INFO - Running setup.py install for mozcrash 22:21:20 INFO - Running setup.py install for mozdebug 22:21:20 INFO - Running setup.py install for mozdevice 22:21:21 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:21:21 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:21:21 INFO - Running setup.py install for mozfile 22:21:21 INFO - Running setup.py install for mozhttpd 22:21:21 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:21:21 INFO - Running setup.py install for mozinfo 22:21:21 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:21:21 INFO - Running setup.py install for mozInstall 22:21:21 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:21:21 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:21:21 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:21:21 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:21:21 INFO - Running setup.py install for mozleak 22:21:21 INFO - Running setup.py install for mozlog 22:21:21 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:21:21 INFO - Running setup.py install for moznetwork 22:21:22 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:21:22 INFO - Running setup.py install for mozprocess 22:21:22 INFO - Running setup.py install for mozprofile 22:21:22 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:21:22 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:21:22 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:21:22 INFO - Running setup.py install for mozrunner 22:21:22 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:21:22 INFO - Running setup.py install for mozscreenshot 22:21:22 INFO - Running setup.py install for moztest 22:21:22 INFO - Running setup.py install for mozversion 22:21:22 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:21:23 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:21:23 INFO - Cleaning up... 22:21:23 INFO - Return code: 0 22:21:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:21:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:21:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:21:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:21:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:21:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:23 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 0x1070733f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106fe9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdcd04a88d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e89d30>, '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 0x106cf0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e81b20>, '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.HavLnOcel8/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.YmPhP8PDnT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:21:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 22:21:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:21:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HavLnOcel8/Render', 22:21:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:21:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:21:23 INFO - 'HOME': '/Users/cltbld', 22:21:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:21:23 INFO - 'LOGNAME': 'cltbld', 22:21:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:21:23 INFO - 'MOZ_NO_REMOTE': '1', 22:21:23 INFO - 'NO_EM_RESTART': '1', 22:21:23 INFO - 'PAGER': '/bin/cat', 22:21:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:21:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:21:23 INFO - 'PWD': '/builds/slave/test', 22:21:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:21:23 INFO - 'SHELL': '/bin/bash', 22:21:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YmPhP8PDnT/Listeners', 22:21:23 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 22:21:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:21:23 INFO - 'USER': 'cltbld', 22:21:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:21:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:21:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:21:23 INFO - 'XPC_FLAGS': '0x0', 22:21:23 INFO - 'XPC_SERVICE_NAME': '0', 22:21:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:21:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:21:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:21:23 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-qO27_e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:21:23 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 22:21:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:21:23 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-_IQUZ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:21:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:21:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:21:23 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-mA8iUh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:21:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 22:21:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:21:23 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-1BhVOF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:21:23 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)) 22:21:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:21:23 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-p9ntQr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:21:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 22:21:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:21:23 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-NNeW7e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:21:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 22:21:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:21:23 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-hiqR6X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:21:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 22:21:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:21:24 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-3hy5Xi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:21:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 22:21:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:21:24 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-DaBI47-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:21:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 22:21:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:21:24 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-F4Evsf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:21:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:21:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:21:24 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-rB0IhD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:21:24 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 22:21:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:21:24 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-vr_yzG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:21:24 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)) 22:21:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:21:24 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-xgKs__-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:21:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 22:21:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:21:24 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-VOI7Ut-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:21:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 22:21:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:21:24 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-BHZgWt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:21:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 22:21:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:21:24 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-0ht1U7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:21:24 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)) 22:21:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:21:25 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-k00Hql-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:21:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 22:21:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:21:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:21:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 22:21:25 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)) 22:21:25 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)) 22:21:25 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:21:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:21:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:21:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:21:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:21:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:21:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:21:27 INFO - Downloading blessings-1.6.tar.gz 22:21:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:21:27 INFO - Installing collected packages: blessings 22:21:27 INFO - Running setup.py install for blessings 22:21:27 INFO - Successfully installed blessings 22:21:27 INFO - Cleaning up... 22:21:28 INFO - Return code: 0 22:21:28 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:21:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:21:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:21:28 INFO - Reading from file tmpfile_stdout 22:21:28 INFO - Current package versions: 22:21:28 INFO - blessings == 1.6 22:21:28 INFO - blobuploader == 1.2.4 22:21:28 INFO - docopt == 0.6.1 22:21:28 INFO - functools32 == 3.2.3-2 22:21:28 INFO - jsonschema == 2.5.1 22:21:28 INFO - manifestparser == 1.1 22:21:28 INFO - mozInstall == 1.12 22:21:28 INFO - mozcrash == 1.0 22:21:28 INFO - mozdebug == 0.1 22:21:28 INFO - mozdevice == 0.49 22:21:28 INFO - mozfile == 1.2 22:21:28 INFO - mozhttpd == 0.7 22:21:28 INFO - mozinfo == 0.9 22:21:28 INFO - mozleak == 0.1 22:21:28 INFO - mozlog == 3.4 22:21:28 INFO - moznetwork == 0.27 22:21:28 INFO - mozprocess == 0.23 22:21:28 INFO - mozprofile == 0.28 22:21:28 INFO - mozrunner == 6.13 22:21:28 INFO - mozscreenshot == 0.1 22:21:28 INFO - mozsystemmonitor == 0.3 22:21:28 INFO - moztest == 0.7 22:21:28 INFO - mozversion == 1.4 22:21:28 INFO - psutil == 3.1.1 22:21:28 INFO - requests == 1.2.3 22:21:28 INFO - wsgiref == 0.1.2 22:21:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:21:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:21:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:21:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:21:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:21:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:28 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 0x1070733f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106fe9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdcd04a88d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e89d30>, '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 0x106cf0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e81b20>, '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.HavLnOcel8/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.YmPhP8PDnT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:21:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 22:21:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:21:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HavLnOcel8/Render', 22:21:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:21:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:21:28 INFO - 'HOME': '/Users/cltbld', 22:21:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:21:28 INFO - 'LOGNAME': 'cltbld', 22:21:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:21:28 INFO - 'MOZ_NO_REMOTE': '1', 22:21:28 INFO - 'NO_EM_RESTART': '1', 22:21:28 INFO - 'PAGER': '/bin/cat', 22:21:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:21:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:21:28 INFO - 'PWD': '/builds/slave/test', 22:21:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:21:28 INFO - 'SHELL': '/bin/bash', 22:21:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YmPhP8PDnT/Listeners', 22:21:28 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 22:21:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:21:28 INFO - 'USER': 'cltbld', 22:21:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:21:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:21:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:21:28 INFO - 'XPC_FLAGS': '0x0', 22:21:28 INFO - 'XPC_SERVICE_NAME': '0', 22:21:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:21:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:21:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 22:21:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 22:21:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 22:21:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 22:21:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 22:21:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 22:21:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 22:21:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 22:21:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 22:21:28 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 22:21:28 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 22:21:28 INFO - Cleaning up... 22:21:28 INFO - Return code: 0 22:21:28 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:21:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:21:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:21:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:21:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:21:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:28 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 0x1070733f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106fe9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdcd04a88d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e89d30>, '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 0x106cf0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e81b20>, '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.HavLnOcel8/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.YmPhP8PDnT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 22:21:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 22:21:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 22:21:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HavLnOcel8/Render', 22:21:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:21:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:21:28 INFO - 'HOME': '/Users/cltbld', 22:21:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:21:28 INFO - 'LOGNAME': 'cltbld', 22:21:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:21:28 INFO - 'MOZ_NO_REMOTE': '1', 22:21:28 INFO - 'NO_EM_RESTART': '1', 22:21:28 INFO - 'PAGER': '/bin/cat', 22:21:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:21:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:21:28 INFO - 'PWD': '/builds/slave/test', 22:21:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:21:28 INFO - 'SHELL': '/bin/bash', 22:21:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YmPhP8PDnT/Listeners', 22:21:28 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 22:21:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:21:28 INFO - 'USER': 'cltbld', 22:21:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:21:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:21:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:21:28 INFO - 'XPC_FLAGS': '0x0', 22:21:28 INFO - 'XPC_SERVICE_NAME': '0', 22:21:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:21:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:21:28 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:21:28 INFO - Cleaning up... 22:21:28 INFO - Return code: 0 22:21:28 INFO - Running post-action listener: _resource_record_post_action 22:21:28 INFO - Running post-action listener: _start_resource_monitoring 22:21:28 INFO - Starting resource monitoring. 22:21:28 INFO - [mozharness: 2016-12-05 06:21:28.915556Z] Finished create-virtualenv step (success) 22:21:28 INFO - [mozharness: 2016-12-05 06:21:28.916533Z] Running install step. 22:21:28 INFO - Running pre-action listener: _resource_record_pre_action 22:21:28 INFO - Running main action method: install 22:21:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:21:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:21:29 INFO - Reading from file tmpfile_stdout 22:21:29 INFO - Detecting whether we're running mozinstall >=1.0... 22:21:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:21:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:21:29 INFO - Reading from file tmpfile_stdout 22:21:29 INFO - Output received: 22:21:29 INFO - Usage: mozinstall [options] installer 22:21:29 INFO - Options: 22:21:29 INFO - -h, --help show this help message and exit 22:21:29 INFO - -d DEST, --destination=DEST 22:21:29 INFO - Directory to install application into. [default: 22:21:29 INFO - "/builds/slave/test"] 22:21:29 INFO - --app=APP Application being installed. [default: firefox] 22:21:29 INFO - mkdir: /builds/slave/test/build/application 22:21:29 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'] 22:21:29 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 22:22:02 INFO - Reading from file tmpfile_stdout 22:22:02 INFO - Output received: 22:22:02 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 22:22:02 INFO - Running post-action listener: _resource_record_post_action 22:22:02 INFO - [mozharness: 2016-12-05 06:22:02.764047Z] Finished install step (success) 22:22:02 INFO - [mozharness: 2016-12-05 06:22:02.764177Z] Running run-tests step. 22:22:02 INFO - Running pre-action listener: _resource_record_pre_action 22:22:02 INFO - Running main action method: run_tests 22:22:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:22:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:22:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:22:02 INFO - 2016-12-04 22:22:02.778 screenresolution[1637:8460] starting screenresolution argv=screenresolution get 22:22:02 INFO - 2016-12-04 22:22:02.781 screenresolution[1637:8460] Display 0: 1600x1200x32@60 22:22:02 INFO - 2016-12-04 22:22:02.786 screenresolution[1638:8462] starting screenresolution argv=screenresolution list 22:22:02 INFO - Available Modes on Display 0 22:22:02 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:22:02 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:22:02 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:22:02 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:22:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:22:03 INFO - Intel Iris: 22:22:03 INFO - Chipset Model: Intel Iris 22:22:03 INFO - Type: GPU 22:22:03 INFO - Bus: Built-In 22:22:03 INFO - VRAM (Dynamic, Max): 1536 MB 22:22:03 INFO - Vendor: Intel (0x8086) 22:22:03 INFO - Device ID: 0x0a2e 22:22:03 INFO - Revision ID: 0x0009 22:22:03 INFO - Displays: 22:22:03 INFO - Display: 22:22:03 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:22:03 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:22:03 INFO - Display Serial Number: 1600x1200 60 22:22:03 INFO - Main Display: Yes 22:22:03 INFO - Mirror: Off 22:22:03 INFO - Online: Yes 22:22:03 INFO - Rotation: Supported 22:22:03 INFO - Return code: 0 22:22:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:22:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:22:03 INFO - Python 2.7.3 22:22:03 INFO - Return code: 0 22:22:03 INFO - grabbing minidump binary from tooltool 22:22:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:22:03 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 0x106e89d30>, '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 0x106cf0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e81b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:22:03 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 22:22:03 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 22:22:03 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 22:22:03 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:22:03 INFO - Return code: 0 22:22:03 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 22:22:03 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:22:03 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:22:03 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 22:22:03 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:22:03 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/UDx0J90KQqWzgVPrZy37ug/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0005', '--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 22:22:03 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/UDx0J90KQqWzgVPrZy37ug/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0005 --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 22:22:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HavLnOcel8/Render', 22:22:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:22:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:22:03 INFO - 'HOME': '/Users/cltbld', 22:22:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:22:03 INFO - 'LOGNAME': 'cltbld', 22:22:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:22:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 22:22:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:22:03 INFO - 'MOZ_NO_REMOTE': '1', 22:22:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:22:03 INFO - 'NO_EM_RESTART': '1', 22:22:03 INFO - 'PAGER': '/bin/cat', 22:22:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:22:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:22:03 INFO - 'PWD': '/builds/slave/test', 22:22:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:22:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:22:03 INFO - 'SHELL': '/bin/bash', 22:22:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YmPhP8PDnT/Listeners', 22:22:03 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 22:22:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:22:03 INFO - 'USER': 'cltbld', 22:22:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:22:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:22:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:22:03 INFO - 'XPC_FLAGS': '0x0', 22:22:03 INFO - 'XPC_SERVICE_NAME': '0', 22:22:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:22:03 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/UDx0J90KQqWzgVPrZy37ug/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0005', '--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 22:22:03 INFO - mozversion application_buildid: 20161204201538 22:22:03 INFO - mozversion application_changeset: 46322372c0f4c6b176c953ec1bef8516d4b382dd 22:22:03 INFO - mozversion application_display_name: Nightly 22:22:03 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:22:03 INFO - mozversion application_name: Firefox 22:22:03 INFO - mozversion application_remotingname: firefox 22:22:03 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 22:22:03 INFO - mozversion application_vendor: Mozilla 22:22:03 INFO - mozversion application_version: 53.0a1 22:22:03 INFO - mozversion platform_buildid: 20161204201538 22:22:03 INFO - mozversion platform_changeset: 46322372c0f4c6b176c953ec1bef8516d4b382dd 22:22:03 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 22:22:03 INFO - mozversion platform_version: 53.0a1 22:22:03 INFO - using testdate: 1480918923 22:22:03 INFO - actual date: 1480918923 22:22:03 INFO - starting webserver on 'localhost:49245' 22:22:03 INFO - SUITE-START | Running 2 tests 22:22:03 INFO - TEST-START | dromaeo_css 22:22:03 INFO - operating with platform_type : mac_ 22:22:03 INFO - Initialising browser for dromaeo_css test... 22:22:03 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/tmp74_4st/profile http://localhost:49245/getInfo.html) 22:22:06 INFO - PROCESS | 1648 | 2016-12-04 22:22:06.471 plugin-container[1650:8629] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e3b, name = 'com.apple.tsm.portname' 22:22:06 INFO - PROCESS | 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:22:06 INFO - PROCESS | 1648 | 2016-12-04 22:22:06.474 plugin-container[1650:8629] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x910b, name = 'com.apple.CFPasteboardClient' 22:22:06 INFO - PROCESS | 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:22:06 INFO - PROCESS | 1648 | 2016-12-04 22:22:06.474 plugin-container[1650:8629] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:22:06 INFO - PROCESS | 1648 | __metrics Screen width/height:1600/1200 22:22:06 INFO - PROCESS | 1648 | colorDepth:24 22:22:06 INFO - PROCESS | 1648 | Browser inner width/height: 1024/689 22:22:06 INFO - PROCESS | 1648 | __metrics 22:22:06 ERROR - PROCESS | 1648 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3220: TypeError: this.tabs is undefined 22:22:07 INFO - TEST-INFO | 1648: exit 0 22:22:07 INFO - Browser initialized. 22:22:07 INFO - Running cycle 1/1 for dromaeo_css test... 22:22:07 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/tmp74_4st/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:22:10 INFO - PROCESS | 1652 | 2016-12-04 22:22:10.341 plugin-container[1653:8756] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 22:22:10 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:22:10 INFO - PROCESS | 1652 | 2016-12-04 22:22:10.341 plugin-container[1653:8756] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c13, name = 'com.apple.CFPasteboardClient' 22:22:10 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:22:10 INFO - PROCESS | 1652 | 2016-12-04 22:22:10.341 plugin-container[1653:8756] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:22:20 INFO - PROCESS | 1652 | RSS: Main: 243056640 22:22:20 INFO - PROCESS | 1652 | 22:23:09 INFO - PROCESS | 1652 | 1480918989687 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] 22:25:22 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49245/tests/dromaeo/cssquery-ext.html) 22:25:24 INFO - PROCESS | 1652 | RSS: Main: 251465728 22:25:24 INFO - PROCESS | 1652 | 22:28:27 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-ext.html (next: http://localhost:49245/tests/dromaeo/cssquery-jquery.html) 22:28:27 INFO - PROCESS | 1652 | RSS: Main: 263151616 22:28:27 INFO - PROCESS | 1652 | 22:31:29 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49245/tests/dromaeo/cssquery-mootools.html) 22:31:30 INFO - PROCESS | 1652 | RSS: Main: 267812864 22:31:30 INFO - PROCESS | 1652 | 22:34:32 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49245/tests/dromaeo/cssquery-prototype.html) 22:34:32 INFO - PROCESS | 1652 | RSS: Main: 266502144 22:34:32 INFO - PROCESS | 1652 | 22:37:35 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49245/tests/dromaeo/cssquery-yui.html) 22:37:36 INFO - PROCESS | 1652 | RSS: Main: 271695872 22:37:36 INFO - PROCESS | 1652 | 22:40:39 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-yui.html (next: http://localhost:49245/tests/dromaeo/cssquery-dojo.html) 22:40:39 INFO - PROCESS | 1652 | RSS: Main: 273985536 22:40:39 INFO - PROCESS | 1652 | 22:40:39 INFO - PROCESS | 1652 | __start_tp_report 22:40:39 INFO - PROCESS | 1652 | _x_x_mozilla_page_load 22:40:39 INFO - PROCESS | 1652 | _x_x_mozilla_page_load_details 22:40:39 INFO - PROCESS | 1652 | |i|pagename|runs| 22:40:39 INFO - PROCESS | 1652 | |0;dojo.html;3143.779935404522;3407.6081250656184;3413.795172289663;3425.9999999999986;3460.7058400035994;5392.460753924611;5511.173324001402;5578.553715702744;5675.949949259385;5705.6006079574445;5572.972135139329;5664.745086471108;5670.68811215383;5696.487316141546;5705.48650621444;5091.389033315998;5124.205748109049;5173.87065323368;5177.611679124062;5197.246399272103;5226.660267082647;5227.973860130694;5267.367915850104;5270.7628156733035;5285.920711189336;201.13900810403086;4393.406890069831;5108.6168537359845;5144.794208231666;5308.097623404031;4124.587541245852;4263.339182426729;5149.536541711226;5152;5154.175331946871;4252.978735106336;5116.744162791845;5118.950615123891;5149.613778966593;5198.532132108091;4511.526289739563;5387.81142660005;5575.581831362623;5604;5647.548196144339;5049.949500504985;6157.630542167484;6166.691665416712;6206.224221972254;6206.410391012847;6210.72051757672;6212.689365531751;6233.470155036827;6236.875262494725;6248.937510624881;5598.1602759586385;5700.97149514258;5701.287339082614;5756.366799652035;5789.942100578983;6119.837693858789;6139.539534534928;6147.0779383092;6150.384961503813;6158.876822463526;6057.697115144225;6070.939290607126;6071.666058366833;6119.357467465941;6127.754889804358;6112.847178820476;6159.662440523155;6178.505719542426;6209.782657607052;6299;6017.277926648742;6069.786042791371;6113.993245360534;6121.0206366981065;6139.584134579375;6174.012158054689;6198.101275315054;6202.100695399142;6209.192804935329;6212.658303793244;4178.247915375201;4859.927101093486;4887.755612219375;4896.755162241873;4902.338184345068;4409.007973206003;4417.5803298687215;4465.776711164429;4471;4544;4794.89717365008;5324.653897496651;5850.824475265749;5975.43233392827;6008.369121242206;4886.853394398174;5239.952009598096;6138.817583427401;6177.135201071854;6189.84525386869;4327.264365057885;5218.982298451841;5810.854728631818;6016.578839481282;6017.488513476316;5200.427952925175;5482.205080263339;5483.122700367922;5485.835424937259;5524.392316845144;5355.009323275207;6190.628562286277;6223.3465486125115;6264.248290205204;6398.744050238031;6159.568830182024;6220.409061139185;6222.160008398808;6233.563650544418;6276.340984196594;6346.650934198663;6359.777407790705;6392.616443013434;6415.935840641534;6433.646149461824;6049.606775559574;6083.361247069171;6100.993336099494;6198.380161983946;6201.844953876189;6104.398524058527;6131.294901086432;6149.2620885494025;6199.535034872312;6216.316205217513;6023.969880150571;6059.21230240066;6075.483583895419;6080.640423763037;6126.326104128453;5888.558358123244;5967.194428752063;6019.337872833898;6062.575619706578;6091.512678985582;4984.8753781155765;5808.738606762621;5820.76716931335;5842.123681447817;5890.528757699288;6103.277060575968;6116.396640261158;6137.438273608381;6137.867325793431;6152.169457123231;5109.233614957786;5749.770009199585;5793.884122317614;5814.360420353673;5980.790672326448 22:40:39 INFO - PROCESS | 1652 | |1;ext.html;461.79680940386226;482.29825603746553;496.8882001549652;500.95300821283524;524.3157679228608;27862.860685696567;38517;39207.43170273189;39332.82001539951;40840.59159408405;40083.19833603326;40476.59523404765;40492.79753601228;40511.79744101276;40522.79738601303;7849.332806711422;9436.764080897963;9448;9452.8582071269;9490.71527854162;8508;8712.651493940235;11072.612458563952;11414.771704565903;11429.599964001261;11431;11469.827952580717;11485.253458525169;11489.827652585218;11503.252288601256;11266.098712103054;11294.661160165126;11332.773344533145;11333.320000799937;11339.206255562114;11246.98777110056;11296.265742726697;11320.773584528346;11328.32030078198;11356.772864542663;38652.226955460734;38912.41631375532;38918;38995.02512437184;39072.02319941996;3291.391092647844;3386.238096428284;3461.8788342408147;3632.2553876455263;3758.642928921748;29566.556501652492;29993.70006299931;30257;30651.540226896614;30684.846575767202;2650.3771613670774;3198.7600929930118;3215.5015972524297;3217.179619197113;3358.4794356874722;443308;453673;459347;459725;459736;56326;57113;57174.42825571774;57212.85574288574;57338;59416;60183.398166018655;60220;60262;60346.09480857791;15452.381904723685;15631.452899148475;15754.76367854483;15762.290696918666;15829.683406332038;10932.56269749217;12114.69713257158;12218.022558195324;12223.327716975475;12245.632631020906;14136.172765446952;16157.4344897928;16438.75341869873;16519.587010324598;16545.6690866182;6320;6409.359064093646;6447.194100737408;6463.6768161591735;6485.967570162214;826.900771907375;912.2109375390239;945.4185675809465;955.7753927826955;1082.3019152646498;28677.713222867922;28848.567271490945;29584.852075739484;29718.851405743266;29719.85140074329;971.6016433262329;977.9119879210905;983.7196399026243;1054.5518154784186;1096.0354887698934;14122.576322710334;14634.780478292834;14725.631859203606;14970.17664028489;15008.499150084905;580259;587607;594885;596139.0193049094;597572;53351.199732004054;53621;53807.73096134494;53860.73069634627;53984.730076350155;1104.9060829829393;1291.9987010067273;1359.904806663544;1377.3870627570834;1379.7309524642799;27272.454550908475;28339.85830070919;28564.714352856205;28622.713772862004;28838;41763.7911810439;43637.78181109074;47442.28836567523;47640.761796190796;48240.070397185125;418115;443673;521250;530977;531429;22157.55684886261;23064.192753253556;24805;25383.61924571096;26143.954241830113;36995;37674.81162594169;43090;43972.780136099114;44261.77869110634;27960.86019569889;34873.65126348806;37426.8128659355;38126;38700.80649596734;37143;37857.24285514219;37946.62053379431;38112.856614301614;38479.807600961816;30095.69904300929;35664.930052098476;36481.27037459289;36965.891023269345;37186.62813371832;29089.7091029087;32193;33193.336133277684;33397.66409343599;33508.32983340368;22343.44141396478;23609.52780944337;23810.88094559516;24639.753602463745;24924.57391756053 22:40:39 INFO - PROCESS | 1652 | |2;jquery.html;273.17501146537455;315.43851943540494;370.5460810507129;382.7282629333175;383.9520059992501;16634.750478742815;18464.81535184648;19057.14242859073;19476.902615486903;19618.215271389163;13728.725425491484;19649.803501964976;19907.60184796307;20023.998800060013;20047.599048019067;4489.640828733702;5821.155932389801;5821.825345239649;5822.272215973004;5834.862201870637;5062.190049592066;5319.281896943921;5761.395053519383;5774;5778.971105144468;5706.115552089433;5719.399463056381;5721.6853073080965;5733.885322293551;5752.7986520471795;5718.313802343725;5721.942780572203;6092.573519853612;6400.807975760735;6479.254885688112;5759.798407055775;5760.308762948431;5768.913466298009;5772.191893134965;5776.566757493163;12423.19249248805;12509.937450312782;12635.49458021678;12798.552050678181;12835.229886206764;2245.38251980705;2314.884255787204;2448.5592593333195;2732.562789953598;2863.0551917867056;9322.67370642031;9576.61693532258;9598.328117031811;10017.749556261078;10062.39625622465;1748.9038102904335;1749.9387521436688;1781.616952355237;1818.6237790267953;1852.0739630184908;106471;111033.44483277612;111804;111890;115064.84935150624;10969.725756855983;10985.890141098647;11145.331280123219;11210.55157793679;11244.044256238149;13897.930510347383;13945.511907083204;13970.930145349412;13975.79036314456;13980.58058258254;6793.961811456543;8011.359091272684;8154.836903261901;8163.918360816435;8325.542095184823;8094.069182044022;8119.3612076075515;8148.307393871469;8280.917190828015;8303.709370172015;4330.285502891988;4429.11417716458;4431.977840110779;4459.554044595515;4483.170613436525;1817.4184261036605;1881.990590047041;1884.9717254241198;1889.4426144287193;1914.2438736698925;752.6801109528428;785.8781888807245;830.3979614779237;967.1682353176262;989.8911119776819;18058.187381567863;19181.904090479456;19907.502312442306;20088.598228035356;20414.89792551028;876.912308769118;892.3976315986683;939.2251392601131;956.1920177450044;989.4211886046743;10555.736106597396;10994.505247263893;10997.175211859143;11031.062359699356;11044.282121662067;427164;431845;433158.83420583326;544420;558350;125575;126069;129406;132589;137219;1011.206203130539;1062.7768168684509;1063.7021633942509;1063.9521221544894;1064.7018834726289;18327.725084124017;18545.258189672262;18747.437576872715;18839.24643014264;18912;52330.738346308026;52652.96164818672;53040.67398314919;53350;53677;55637;74076.88884666841;75581;77599;79569;6154.723037463235;6664.666766661744;7778.377729781717;8032.116467188488;8035.959820201096;11629.360385178896;12364.258144511357;12447.751044979232;12645.241285522898;12667;11011.28426652265;11071.889281107407;11087.22389432732;11089.611863584736;11160.330380177214;8724.389292749447;10724.946375268073;10830.241883068109;10884.149694551787;11017.953294437015;10983.272459103007;11051.392173430539;11115.72210694739;11145.442727863736;11171.938665826734;6741.157355330583;7884.899852076774;7922.4850384724805;8039.196080391914;8062.274395304451;8988.46069235848;9153.405028673114;10585.576576936835;10802.89197108019;11077.612283570037 22:40:39 INFO - PROCESS | 1652 | |3;mootools.html;1440.596632942776;1488.3376897280712;1636.9768894440974;1729.4811556533036;1784.081198182936;7386.852262954738;7619.847603047936;7856.292933635971;8241.258286754191;8247.175282471757;7813.687452501893;8092.352611791057;8116.1478044805335;8193.590320483981;8202.753917382473;2273.874936878467;2296.47181148336;2297.8621282723007;2298.4598619324447;2317.872517011572;2297.781710737477;2329.23135365329;2340.765923407654;2341.8711970841596;2345.6364263539094;2276.3057267533395;2278.3506700590333;2303.1133013789727;2305.6426253930667;2306.031466783955;1996.7420525069113;2267.5918334699745;2280.897359618821;2289.9313020609325;2294.33464295355;2181.792729690677;2292.5300313435705;2308.699869017017;2312.6184179610345;2313.398516385735;8052.758417247492;8090.757277281691;8113.472624279462;8151.714689985822;8188.836223275561;4929.2359684248995;5072.163093089635;5075.568576670988;5080.695158290478;5099.923501147486;3218.91952701182;3309.3546758382126;3545.4859045438266;3554.36021516127;3561.287742451494;1035.2649618770681;1035.7307100154014;1078.3529882070773;1088.3524303039678;1095.2825899036145;10399.116075133623;10571.312864663772;10576.524056417402;10646.893531064668;10687.251892367538;4368.453943257093;4484.57396547334;4587;4595.839145629887;4600.194965880957;9025.909740902638;9115.726528204164;9121.498317592463;9124.81750364989;9126;5054.166062599704;5084.51697088776;5087.567556757717;5092.236164575269;5097.770600322994;3792.8482860685262;4043.838246470167;4218.894527636772;4367.170237654899;4656.976715116402;2767.8200916940336;2785.9303517412227;3231.757618178646;3798.3542797724454;3845.1925095729653;1224.5714000099895;1242.1615409598485;1245.9377031148588;1250.6748245456247;1255.215490318551;877.7322916510526;887.9911200888038;891.80826122384;910.9842525583927;911.6809116809196;4493.955060449419;5264.710440925785;5406.107992181246;5550.890661548667;5593.636413633101;435.55790872264674;436.1690978685609;444.2336968728917;946.9205106178962;949.7340744591526;3742.7380083394446;3774;3811.5997820228476;3835.3096442640035;3902.20004898995;9667.758306042264;9862.408255504693;9907.306488545877;9996.800063998679;10004.54979525923;9570.042995700513;9614;9782.75543111428;10023.649172278936;10117.645882394228;7586.506877052974;7706.07527096752;7713.922860771433;7718.266764657461;7727.49771264866;4752.453467851241;5427.945720542902;5461.1808228766;5521.337439507285;5528.474794894479;8476.830463390821;9131.634734610807;9200.95399522998;9275.86086208694;9298.72103836886;7182.928170718226;7559.508631938907;9280.303977201602;9468.621255149717;9492.193163581172;6164;6362.554621176473;6475.611463312216;6685.799426017227;6710.429613482908;7651.311381975649;7925.564093974886;7936.523808571505;7939.245771651684;7983.600819958862;7097.25478824716;7243.855122897618;7258.564486130849;7352.228016058237;7414.332710056121;7068.469864760061;7131.2155662876;7183.173934997542;7183.245759195209;7357.227491113356;6406.775762848278;6520.934790652223;6611.735530578723;6615.206175259;6636.13730215069;4566.1552612766745;5023.547880690755;5049.116404629249;5073.061483625541;5081.339425874613;4553.749543775124;4965.652404331662;5070.923936140887;5108.514691104339;5139.1263485206855 22:40:39 INFO - PROCESS | 1652 | |4;prototype.html;296.2046904062593;301.7601007199277;303.691752870836;308.3093869731801;310.6396579967238;4446.62203712684;5153.716545589991;5373.5969802264835;5440;5493.2584101146385;5223.216517522374;5339.344803111038;5395.622306438541;5418.7019713915715;5457.781688732456;4131.091159944823;4134.524529679088;4147.709660323779;4149.149424367997;4169.207850508394;3312.9171770705684;3339.4990751387245;3354.76516643835;3843.711721620876;4047.4131250968594;3926.725129240954;4097.959020409788;4121.690873184508;4128.938065929014;4133.689973252003;3629.5100161478126;3922.666573341241;4126.463559737245;4139.2135494256145;4154.86590842111;3193.760467964912;3598.316319899237;3660.2313514162056;3662.406853018945;3677.797721125337;5181.119209734355;5358.973205133989;5362.6782393056155;5389.164679474688;5395.946040539623;4053.6351728344293;4181.414601955698;4649.465311489188;4744.786484608201;4760.690555113941;5088.491150884919;5362.249285100089;5711.257536520226;5752;5758.971205143989;3735.831887565066;3737.5141231639986;3843.7693738375506;4013.150663029253;4066.5323487798987;5967.672607683007;5980.55145864062;5993.430624090704;6003.939960600382;6034.607750496248;3859.749116307431;3937.684985201177;4054.2904991626347;4059.654929330981;4090.4886889138857;3292.456744637156;3832;4004.759714417086;4083.7549747015273;4187.392828039917;4735.076660051326;4737.668363214542;4771.761411929389;4784.7607619619575;4823.397075365579;4347.608715215646;4806.399200099988;5759.8560035999435;5763.472679739873;5839.270091238595;1101.4107452512867;1174.3717111345388;1176.5529098942347;1217.7747116783423;1304.4672509800946;855.5508358111916;897.282174260602;914.4604683236922;931.3759780946782;937.9155875971195;4597.528790786916;4613.1004454131125;4845.6608037437045;5224.634275600672;5284.142575919524;4791.425028996543;4819.156647586658;5429.837104886859;5683.602147849693;5756.913646295309;4709.929351059737;4777.737224452619;4861.041737427804;4908.45383704132;5137.588992880561;2308.437634226008;2382.9753206121186;2542.5931850903767;2758.6758355988836;2914.4316858212446;5547.5007249347755;5763.538916886639;5774.566907481955;5799.942000580025;5819.679917604488;4829.082474329866;5356.276902618097;5592.440755924456;5655.4910058094965;5659.839732854755;5361.410244873061;5544.223808666709;5816.243888294494;5849.561282903799;5899.7050147492455;5538.169274608841;5563.582731295088;5593.049181639212;5607.009511623315;5698.686572238401;5069.569086627678;5158.5298190015665;5209.6874187548865;5266.657667251617;5511.118221084607;4874.8537543873745;5164.7417629119145;5270.7101109439345;5744.310682717934;5802.854928626818;3260.820654864005;3699.8890033299044;3738.0281126906657;3852.8651497197466;3876.8061596920606;4798.424189097331;5058.974705126451;5311.814086506954;5590.099366215343;5609.017597505654;4461;4955.876103097451;5469.72651367438;5514.09017512122;5630.5214056805635;4879.024195160911;4896.779644916059;5332.520073193431;5708.286464191976;5713.9714301429885;5375.0493387359475;5563;5581.386047534686;5611.3266408031295;5633.943660563506;5258.027264955996;5670.8015219467125;5708.429157084258;5747.885042299215;5751.4536119068625;4804.447488538723;5602.607817452739;5630.493255607015;5651.689157096399;5661.820199223411 22:40:39 INFO - PROCESS | 1652 | |5;yui.html;590.4626789621445;676.5791837142002;729.9525530840492;739.408473221423;792.9524228546288;14309.141451512904;15093.320800563959;15199.620009499768;15241.390344386225;16174.919125404385;12041.578544750913;15273.541793746177;15887.84112158878;16082.919585402085;16106;817.9222973817477;850.5917159763318;850.6937502499095;856.0369584217755;856.2293935458107;823.1192623892438;849.1593322610632;849.7110982266029;854.747849384433;859.9269062129727;858.5578427110044;893.423741686615;903.8960519540238;903.9186473217409;904.5070436612062;860.7805009722481;886.6807949138305;894.9463032218088;896.1218006353777;896.1799953042957;646.5764923974804;662.0334311904625;662.5825729790201;663.4625952978104;665.8501837086665;14393.424263029467;14672.193029383487;14724.779128313085;14786.482473113498;14790.926045369704;13334.399952002183;13794.65513362174;13874.93062534681;13947.79078313816;14093.634697861595;11678.532858685727;11741;11752.941235293854;11959.103067269989;11984.460699268553;3139.7959132656533;3673.2837096766143;3745.8688945887034;3747.2130852521004;3760.9247815043545;105843;106634;110775.44612276967;111398;113600.72798544065;10408.219383546264;10487.423191724376;10518.842217366746;10575.841362379571;11025.503852326665;21008.684869726967;21205.681914771292;21237.68143477849;21242;21253;7445.691835531003;7482.214367491444;7492.175860655324;7498.889737179587;7642.7071878436245;4201.432806571135;4203.62167404935;4205.103630046726;4268.210381079511;4350.151720414552;3452.58568971725;3496.9300613988084;3555.2711694102645;3560.946585801215;3562.946555801665;620.3827191943955;623.8739075967922;638.8307098618869;643.5913195121038;646.7219095788762;548.9945100549023;587.2395248153651;637.827786497643;671.8051764988098;675.9121314229119;7963.681452741941;8523.232909038092;8559.957200214083;9218.677346292847;9461.858072128924;559.8439223004483;573.4466240078344;628.6479571440008;646.2019406033638;664.5016237821634;4164.729292595972;4546.272596384562;4564.2012647786505;4599.517050709695;4687.976560117246;60377.39622603806;85825;92066;113860;117373;73144.63427682828;73459.63270183722;84624.30751384937;91706;97462.51268743612;74439.25560744431;77574.22425775783;78385.21614783893;84899.66103728586;87583.68624470638;6873.931260687529;8653.567321633767;8782.209601135928;9029.367944244103;9236.399634024036;38913.49778519947;47011.764941175075;54586.727066364416;55085;58074.83850322886;60853.69573152283;71830;72027.27972720205;72362;72677;12535.435905384462;12552.43514041852;12743.362831858556;12780.744385112432;13449.932750336186;14322.713545729235;14622.268886555843;14759.188244646648;14912.328945197276;14936.253187340371;13531.729365412833;13954.23251721165;14214.396747578654;14231.928840355731;14283.500077497238;13409.195448273134;13458;13616.38726257301;13712.93143534276;13817.861821381657;12467.753224677459;13416.262105584286;13486.393112310167;13544.79682804739;13731.87304024453;9773.462459564793;10956.068734157687;11187.875423745447;11225.494852731483;11266.436678165896;8592.871106933277;9339.813203736023;11259.437028148724;11603.5358585659;11640.417979100841 22:40:39 INFO - PROCESS | 1652 | __end_tp_report 22:40:39 INFO - PROCESS | 1652 | __start_cc_report 22:40:39 INFO - PROCESS | 1652 | _x_x_mozilla_cycle_collect,383 22:40:39 INFO - PROCESS | 1652 | __end_cc_report 22:40:39 INFO - PROCESS | 1652 | __startTimestamp1480920039338__endTimestamp 22:40:39 INFO - PROCESS | 1652 | 22:40:39 INFO - PROCESS | 1652 | ------- Summary: start ------- 22:40:39 INFO - PROCESS | 1652 | Number of tests: 6 22:40:39 INFO - PROCESS | 1652 | 22:40:39 INFO - PROCESS | 1652 | [#0] dojo.html Cycles:165 Average:5597.82 Median:5850.82 stddev:809.34 (13.8%) stddev-sans-first:788.60 22:40:39 INFO - PROCESS | 1652 | Values: 3143.8 3407.6 3413.8 3426.0 3460.7 5392.5 5511.2 5578.6 5675.9 5705.6 5573.0 5664.7 5670.7 5696.5 5705.5 5091.4 5124.2 5173.9 5177.6 5197.2 5226.7 5228.0 5267.4 5270.8 5285.9 201.1 4393.4 5108.6 5144.8 5308.1 4124.6 4263.3 5149.5 5152.0 5154.2 4253.0 5116.7 5119.0 5149.6 5198.5 4511.5 5387.8 5575.6 5604.0 5647.5 5049.9 6157.6 6166.7 6206.2 6206.4 6210.7 6212.7 6233.5 6236.9 6248.9 5598.2 5701.0 5701.3 5756.4 5789.9 6119.8 6139.5 6147.1 6150.4 6158.9 6057.7 6070.9 6071.7 6119.4 6127.8 6112.8 6159.7 6178.5 6209.8 6299.0 6017.3 6069.8 6114.0 6121.0 6139.6 6174.0 6198.1 6202.1 6209.2 6212.7 4178.2 4859.9 4887.8 4896.8 4902.3 4409.0 4417.6 4465.8 4471.0 4544.0 4794.9 5324.7 5850.8 5975.4 6008.4 4886.9 5240.0 6138.8 6177.1 6189.8 4327.3 5219.0 5810.9 6016.6 6017.5 5200.4 5482.2 5483.1 5485.8 5524.4 5355.0 6190.6 6223.3 6264.2 6398.7 6159.6 6220.4 6222.2 6233.6 6276.3 6346.7 6359.8 6392.6 6415.9 6433.6 6049.6 6083.4 6101.0 6198.4 6201.8 6104.4 6131.3 6149.3 6199.5 6216.3 6024.0 6059.2 6075.5 6080.6 6126.3 5888.6 5967.2 6019.3 6062.6 6091.5 4984.9 5808.7 5820.8 5842.1 5890.5 6103.3 6116.4 6137.4 6137.9 6152.2 5109.2 5749.8 5793.9 5814.4 5980.8 22:40:39 INFO - PROCESS | 1652 | 22:40:39 INFO - PROCESS | 1652 | [#1] ext.html Cycles:180 Average:64313.74 Median:36723.58 stddev:137604.41 (374.7%) stddev-sans-first:137906.91 22:40:39 INFO - PROCESS | 1652 | Values: 461.8 482.3 496.9 501.0 524.3 27862.9 38517.0 39207.4 39332.8 40840.6 40083.2 40476.6 40492.8 40511.8 40522.8 7849.3 9436.8 9448.0 9452.9 9490.7 8508.0 8712.7 11072.6 11414.8 11429.6 11431.0 11469.8 11485.3 11489.8 11503.3 11266.1 11294.7 11332.8 11333.3 11339.2 11247.0 11296.3 11320.8 11328.3 11356.8 38652.2 38912.4 38918.0 38995.0 39072.0 3291.4 3386.2 3461.9 3632.3 3758.6 29566.6 29993.7 30257.0 30651.5 30684.8 2650.4 3198.8 3215.5 3217.2 3358.5 443308.0 453673.0 459347.0 459725.0 459736.0 56326.0 57113.0 57174.4 57212.9 57338.0 59416.0 60183.4 60220.0 60262.0 60346.1 15452.4 15631.5 15754.8 15762.3 15829.7 10932.6 12114.7 12218.0 12223.3 12245.6 14136.2 16157.4 16438.8 16519.6 16545.7 6320.0 6409.4 6447.2 6463.7 6486.0 826.9 912.2 945.4 955.8 1082.3 28677.7 28848.6 29584.9 29718.9 29719.9 971.6 977.9 983.7 1054.6 1096.0 14122.6 14634.8 14725.6 14970.2 15008.5 580259.0 587607.0 594885.0 596139.0 597572.0 53351.2 53621.0 53807.7 53860.7 53984.7 1104.9 1292.0 1359.9 1377.4 1379.7 27272.5 28339.9 28564.7 28622.7 28838.0 41763.8 43637.8 47442.3 47640.8 48240.1 418115.0 443673.0 521250.0 530977.0 531429.0 22157.6 23064.2 24805.0 25383.6 26144.0 36995.0 37674.8 43090.0 43972.8 44261.8 27960.9 34873.7 37426.8 38126.0 38700.8 37143.0 37857.2 37946.6 38112.9 38479.8 30095.7 35664.9 36481.3 36965.9 37186.6 29089.7 32193.0 33193.3 33397.7 33508.3 22343.4 23609.5 23810.9 24639.8 24924.6 22:40:39 INFO - PROCESS | 1652 | 22:40:39 INFO - PROCESS | 1652 | [#2] jquery.html Cycles:180 Average:30791.66 Median:2381.72 stddev:81789.54 (3434.1%) stddev-sans-first:81986.88 22:40:39 INFO - PROCESS | 1652 | Values: 273.2 315.4 370.5 382.7 384.0 16634.8 18464.8 19057.1 19476.9 19618.2 13728.7 19649.8 19907.6 20024.0 20047.6 4489.6 5821.2 5821.8 5822.3 5834.9 5062.2 5319.3 5761.4 5774.0 5779.0 5706.1 5719.4 5721.7 5733.9 5752.8 5718.3 5721.9 6092.6 6400.8 6479.3 5759.8 5760.3 5768.9 5772.2 5776.6 12423.2 12509.9 12635.5 12798.6 12835.2 2245.4 2314.9 2448.6 2732.6 2863.1 9322.7 9576.6 9598.3 10017.7 10062.4 1748.9 1749.9 1781.6 1818.6 1852.1 106471.0 111033.4 111804.0 111890.0 115064.8 10969.7 10985.9 11145.3 11210.6 11244.0 13897.9 13945.5 13970.9 13975.8 13980.6 6794.0 8011.4 8154.8 8163.9 8325.5 8094.1 8119.4 8148.3 8280.9 8303.7 4330.3 4429.1 4432.0 4459.6 4483.2 1817.4 1882.0 1885.0 1889.4 1914.2 752.7 785.9 830.4 967.2 989.9 18058.2 19181.9 19907.5 20088.6 20414.9 876.9 892.4 939.2 956.2 989.4 10555.7 10994.5 10997.2 11031.1 11044.3 427164.0 431845.0 433158.8 544420.0 558350.0 125575.0 126069.0 129406.0 132589.0 137219.0 1011.2 1062.8 1063.7 1064.0 1064.7 18327.7 18545.3 18747.4 18839.2 18912.0 52330.7 52653.0 53040.7 53350.0 53677.0 55637.0 74076.9 75581.0 77599.0 79569.0 6154.7 6664.7 7778.4 8032.1 8036.0 11629.4 12364.3 12447.8 12645.2 12667.0 11011.3 11071.9 11087.2 11089.6 11160.3 8724.4 10724.9 10830.2 10884.1 11018.0 10983.3 11051.4 11115.7 11145.4 11171.9 6741.2 7884.9 7922.5 8039.2 8062.3 8988.5 9153.4 10585.6 10802.9 11077.6 22:40:39 INFO - PROCESS | 1652 | 22:40:39 INFO - PROCESS | 1652 | [#3] mootools.html Cycles:180 Average:5306.79 Median:5082.93 stddev:2907.08 (57.2%) stddev-sans-first:2900.72 22:40:39 INFO - PROCESS | 1652 | Values: 1440.6 1488.3 1637.0 1729.5 1784.1 7386.9 7619.8 7856.3 8241.3 8247.2 7813.7 8092.4 8116.1 8193.6 8202.8 2273.9 2296.5 2297.9 2298.5 2317.9 2297.8 2329.2 2340.8 2341.9 2345.6 2276.3 2278.4 2303.1 2305.6 2306.0 1996.7 2267.6 2280.9 2289.9 2294.3 2181.8 2292.5 2308.7 2312.6 2313.4 8052.8 8090.8 8113.5 8151.7 8188.8 4929.2 5072.2 5075.6 5080.7 5099.9 3218.9 3309.4 3545.5 3554.4 3561.3 1035.3 1035.7 1078.4 1088.4 1095.3 10399.1 10571.3 10576.5 10646.9 10687.3 4368.5 4484.6 4587.0 4595.8 4600.2 9025.9 9115.7 9121.5 9124.8 9126.0 5054.2 5084.5 5087.6 5092.2 5097.8 3792.8 4043.8 4218.9 4367.2 4657.0 2767.8 2785.9 3231.8 3798.4 3845.2 1224.6 1242.2 1245.9 1250.7 1255.2 877.7 888.0 891.8 911.0 911.7 4494.0 5264.7 5406.1 5550.9 5593.6 435.6 436.2 444.2 946.9 949.7 3742.7 3774.0 3811.6 3835.3 3902.2 9667.8 9862.4 9907.3 9996.8 10004.5 9570.0 9614.0 9782.8 10023.6 10117.6 7586.5 7706.1 7713.9 7718.3 7727.5 4752.5 5427.9 5461.2 5521.3 5528.5 8476.8 9131.6 9201.0 9275.9 9298.7 7182.9 7559.5 9280.3 9468.6 9492.2 6164.0 6362.6 6475.6 6685.8 6710.4 7651.3 7925.6 7936.5 7939.2 7983.6 7097.3 7243.9 7258.6 7352.2 7414.3 7068.5 7131.2 7183.2 7183.2 7357.2 6406.8 6520.9 6611.7 6615.2 6636.1 4566.2 5023.5 5049.1 5073.1 5081.3 4553.7 4965.7 5070.9 5108.5 5139.1 22:40:39 INFO - PROCESS | 1652 | 22:40:39 INFO - PROCESS | 1652 | [#4] prototype.html Cycles:180 Average:4509.88 Median:5064.27 stddev:1394.77 (27.5%) stddev-sans-first:1362.35 22:40:39 INFO - PROCESS | 1652 | Values: 296.2 301.8 303.7 308.3 310.6 4446.6 5153.7 5373.6 5440.0 5493.3 5223.2 5339.3 5395.6 5418.7 5457.8 4131.1 4134.5 4147.7 4149.1 4169.2 3312.9 3339.5 3354.8 3843.7 4047.4 3926.7 4098.0 4121.7 4128.9 4133.7 3629.5 3922.7 4126.5 4139.2 4154.9 3193.8 3598.3 3660.2 3662.4 3677.8 5181.1 5359.0 5362.7 5389.2 5395.9 4053.6 4181.4 4649.5 4744.8 4760.7 5088.5 5362.2 5711.3 5752.0 5759.0 3735.8 3737.5 3843.8 4013.2 4066.5 5967.7 5980.6 5993.4 6003.9 6034.6 3859.7 3937.7 4054.3 4059.7 4090.5 3292.5 3832.0 4004.8 4083.8 4187.4 4735.1 4737.7 4771.8 4784.8 4823.4 4347.6 4806.4 5759.9 5763.5 5839.3 1101.4 1174.4 1176.6 1217.8 1304.5 855.6 897.3 914.5 931.4 937.9 4597.5 4613.1 4845.7 5224.6 5284.1 4791.4 4819.2 5429.8 5683.6 5756.9 4709.9 4777.7 4861.0 4908.5 5137.6 2308.4 2383.0 2542.6 2758.7 2914.4 5547.5 5763.5 5774.6 5799.9 5819.7 4829.1 5356.3 5592.4 5655.5 5659.8 5361.4 5544.2 5816.2 5849.6 5899.7 5538.2 5563.6 5593.0 5607.0 5698.7 5069.6 5158.5 5209.7 5266.7 5511.1 4874.9 5164.7 5270.7 5744.3 5802.9 3260.8 3699.9 3738.0 3852.9 3876.8 4798.4 5059.0 5311.8 5590.1 5609.0 4461.0 4955.9 5469.7 5514.1 5630.5 4879.0 4896.8 5332.5 5708.3 5714.0 5375.0 5563.0 5581.4 5611.3 5633.9 5258.0 5670.8 5708.4 5747.9 5751.5 4804.4 5602.6 5630.5 5651.7 5661.8 22:40:39 INFO - PROCESS | 1652 | 22:40:39 INFO - PROCESS | 1652 | [#5] yui.html Cycles:180 Average:20236.72 Median:4448.21 stddev:29315.16 (659.0%) stddev-sans-first:29360.28 22:40:39 INFO - PROCESS | 1652 | Values: 590.5 676.6 730.0 739.4 793.0 14309.1 15093.3 15199.6 15241.4 16174.9 12041.6 15273.5 15887.8 16082.9 16106.0 817.9 850.6 850.7 856.0 856.2 823.1 849.2 849.7 854.7 859.9 858.6 893.4 903.9 903.9 904.5 860.8 886.7 894.9 896.1 896.2 646.6 662.0 662.6 663.5 665.9 14393.4 14672.2 14724.8 14786.5 14790.9 13334.4 13794.7 13874.9 13947.8 14093.6 11678.5 11741.0 11752.9 11959.1 11984.5 3139.8 3673.3 3745.9 3747.2 3760.9 105843.0 106634.0 110775.4 111398.0 113600.7 10408.2 10487.4 10518.8 10575.8 11025.5 21008.7 21205.7 21237.7 21242.0 21253.0 7445.7 7482.2 7492.2 7498.9 7642.7 4201.4 4203.6 4205.1 4268.2 4350.2 3452.6 3496.9 3555.3 3560.9 3562.9 620.4 623.9 638.8 643.6 646.7 549.0 587.2 637.8 671.8 675.9 7963.7 8523.2 8560.0 9218.7 9461.9 559.8 573.4 628.6 646.2 664.5 4164.7 4546.3 4564.2 4599.5 4688.0 60377.4 85825.0 92066.0 113860.0 117373.0 73144.6 73459.6 84624.3 91706.0 97462.5 74439.3 77574.2 78385.2 84899.7 87583.7 6873.9 8653.6 8782.2 9029.4 9236.4 38913.5 47011.8 54586.7 55085.0 58074.8 60853.7 71830.0 72027.3 72362.0 72677.0 12535.4 12552.4 12743.4 12780.7 13449.9 14322.7 14622.3 14759.2 14912.3 14936.3 13531.7 13954.2 14214.4 14231.9 14283.5 13409.2 13458.0 13616.4 13712.9 13817.9 12467.8 13416.3 13486.4 13544.8 13731.9 9773.5 10956.1 11187.9 11225.5 11266.4 8592.9 9339.8 11259.4 11603.5 11640.4 22:40:39 INFO - PROCESS | 1652 | -------- Summary: end -------- 22:40:39 INFO - PROCESS | 1652 | 22:40:39 INFO - TEST-INFO | 1652: exit 0 22:40:39 INFO - TEST-OK | dromaeo_css | took 1116626ms 22:40:39 INFO - TEST-START | kraken 22:40:39 INFO - operating with platform_type : mac_ 22:40:40 INFO - Initialising browser for kraken test... 22:40:40 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/tmprWN3FG/profile http://localhost:49245/getInfo.html) 22:40:43 INFO - PROCESS | 1661 | 2016-12-04 22:40:43.016 plugin-container[1662:10892] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b4f, name = 'com.apple.tsm.portname' 22:40:43 INFO - PROCESS | 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:40:43 INFO - PROCESS | 1661 | 2016-12-04 22:40:43.019 plugin-container[1662:10892] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 22:40:43 INFO - PROCESS | 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:40:43 INFO - PROCESS | 1661 | 2016-12-04 22:40:43.019 plugin-container[1662:10892] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:40:43 INFO - PROCESS | 1661 | __metrics Screen width/height:1600/1200 22:40:43 INFO - PROCESS | 1661 | colorDepth:24 22:40:43 INFO - PROCESS | 1661 | Browser inner width/height: 1024/689 22:40:43 INFO - PROCESS | 1661 | __metrics 22:40:43 ERROR - PROCESS | 1661 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3220: TypeError: this.tabs is undefined 22:40:44 INFO - TEST-INFO | 1661: exit 0 22:40:44 INFO - Browser initialized. 22:40:44 INFO - Running cycle 1/1 for kraken test... 22:40:44 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/tmprWN3FG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:40:46 INFO - PROCESS | 1663 | 2016-12-04 22:40:46.592 plugin-container[1664:11011] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4343, name = 'com.apple.tsm.portname' 22:40:46 INFO - PROCESS | 1663 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:40:46 INFO - PROCESS | 1663 | 2016-12-04 22:40:46.592 plugin-container[1664:11011] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 22:40:46 INFO - PROCESS | 1663 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:40:46 INFO - PROCESS | 1663 | 2016-12-04 22:40:46.592 plugin-container[1664:11011] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:40:56 INFO - PROCESS | 1663 | RSS: Main: 243040256 22:40:56 INFO - PROCESS | 1663 | 22:40:59 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-beat-detection) 22:41:00 INFO - PROCESS | 1663 | RSS: Main: 255283200 22:41:00 INFO - PROCESS | 1663 | 22:41:03 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-dft) 22:41:03 INFO - PROCESS | 1663 | RSS: Main: 262963200 22:41:03 INFO - PROCESS | 1663 | 22:41:07 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-fft) 22:41:07 INFO - PROCESS | 1663 | RSS: Main: 265596928 22:41:07 INFO - PROCESS | 1663 | 22:41:09 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-oscillator) 22:41:10 INFO - PROCESS | 1663 | RSS: Main: 267808768 22:41:10 INFO - PROCESS | 1663 | 22:41:12 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49245/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:41:13 INFO - PROCESS | 1663 | RSS: Main: 268062720 22:41:13 INFO - PROCESS | 1663 | 22:41:16 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49245/tests/kraken/driver.html?testName=imaging-darkroom) 22:41:16 INFO - PROCESS | 1663 | RSS: Main: 274247680 22:41:16 INFO - PROCESS | 1663 | 22:41:19 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49245/tests/kraken/driver.html?testName=imaging-desaturate) 22:41:20 INFO - PROCESS | 1663 | RSS: Main: 273125376 22:41:20 INFO - PROCESS | 1663 | 22:41:23 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49245/tests/kraken/driver.html?testName=json-parse-financial) 22:41:23 INFO - PROCESS | 1663 | RSS: Main: 273453056 22:41:23 INFO - PROCESS | 1663 | 22:41:25 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49245/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:41:26 INFO - PROCESS | 1663 | RSS: Main: 272465920 22:41:26 INFO - PROCESS | 1663 | 22:41:28 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:41:28 INFO - PROCESS | 1663 | RSS: Main: 272506880 22:41:28 INFO - PROCESS | 1663 | 22:41:31 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:41:31 INFO - PROCESS | 1663 | RSS: Main: 272535552 22:41:31 INFO - PROCESS | 1663 | 22:41:34 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:41:35 INFO - PROCESS | 1663 | RSS: Main: 272838656 22:41:35 INFO - PROCESS | 1663 | 22:41:38 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:41:39 INFO - PROCESS | 1663 | RSS: Main: 272625664 22:41:39 INFO - PROCESS | 1663 | 22:41:41 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49245/tests/kraken/driver.html?testName=ai-astar) 22:41:41 INFO - PROCESS | 1663 | RSS: Main: 274460672 22:41:41 INFO - PROCESS | 1663 | 22:41:41 INFO - PROCESS | 1663 | __start_tp_report 22:41:41 INFO - PROCESS | 1663 | _x_x_mozilla_page_load 22:41:41 INFO - PROCESS | 1663 | _x_x_mozilla_page_load_details 22:41:41 INFO - PROCESS | 1663 | |i|pagename|runs| 22:41:41 INFO - PROCESS | 1663 | |0;ai-astar;107;119;118;119;127;174;122;120;120;120 22:41:41 INFO - PROCESS | 1663 | |1;audio-beat-detection;149;141;152;194;121;123;106;141;171;150 22:41:41 INFO - PROCESS | 1663 | |2;audio-dft;201;234;197;204;199;198;194;220;273;234 22:41:41 INFO - PROCESS | 1663 | |3;audio-fft;100;53;67;111;88;87;75;60;74;53 22:41:41 INFO - PROCESS | 1663 | |4;audio-oscillator;86;72;84;73;76;105;118;106;102;113 22:41:41 INFO - PROCESS | 1663 | |5;imaging-gaussian-blur;89;87;102;87;90;87;92;88;89;87 22:41:41 INFO - PROCESS | 1663 | |6;imaging-darkroom;132;154;96;97;96;97;95;94;99;152 22:41:41 INFO - PROCESS | 1663 | |7;imaging-desaturate;97;95;92;94;94;94;146;93;99;137 22:41:41 INFO - PROCESS | 1663 | |8;json-parse-financial;60;58;56;58;59;60;58;59;59;59 22:41:41 INFO - PROCESS | 1663 | |9;json-stringify-tinderbox;68;73;127;117;66;82;107;111;89;74 22:41:41 INFO - PROCESS | 1663 | |10;stanford-crypto-aes;91;91;91;91;89;89;92;92;89;88 22:41:41 INFO - PROCESS | 1663 | |11;stanford-crypto-ccm;150;149;163;191;168;167;158;186;198;166 22:41:41 INFO - PROCESS | 1663 | |12;stanford-crypto-pbkdf2;212;249;200;175;177;177;181;179;174;179 22:41:41 INFO - PROCESS | 1663 | |13;stanford-crypto-sha256-iterative;72;71;69;69;73;73;74;87;96;94 22:41:41 INFO - PROCESS | 1663 | __end_tp_report 22:41:41 INFO - PROCESS | 1663 | __start_cc_report 22:41:41 INFO - PROCESS | 1663 | _x_x_mozilla_cycle_collect,959 22:41:41 INFO - PROCESS | 1663 | __end_cc_report 22:41:41 INFO - PROCESS | 1663 | __startTimestamp1480920101395__endTimestamp 22:41:41 INFO - PROCESS | 1663 | 22:41:41 INFO - PROCESS | 1663 | ------- Summary: start ------- 22:41:41 INFO - PROCESS | 1663 | Number of tests: 14 22:41:41 INFO - PROCESS | 1663 | 22:41:41 INFO - PROCESS | 1663 | [#0] ai-astar Cycles:10 Average:124.60 Median:120.00 stddev:18.05 (15.0%) stddev-sans-first:17.99 22:41:41 INFO - PROCESS | 1663 | Values: 107.0 119.0 118.0 119.0 127.0 174.0 122.0 120.0 120.0 120.0 22:41:41 INFO - PROCESS | 1663 | 22:41:41 INFO - PROCESS | 1663 | [#1] audio-beat-detection Cycles:10 Average:144.80 Median:149.50 stddev:25.34 (17.0%) stddev-sans-first:26.83 22:41:41 INFO - PROCESS | 1663 | Values: 149.0 141.0 152.0 194.0 121.0 123.0 106.0 141.0 171.0 150.0 22:41:41 INFO - PROCESS | 1663 | 22:41:41 INFO - PROCESS | 1663 | [#2] audio-dft Cycles:10 Average:215.40 Median:212.00 stddev:25.25 (11.9%) stddev-sans-first:26.23 22:41:41 INFO - PROCESS | 1663 | Values: 201.0 234.0 197.0 204.0 199.0 198.0 194.0 220.0 273.0 234.0 22:41:41 INFO - PROCESS | 1663 | 22:41:41 INFO - PROCESS | 1663 | [#3] audio-fft Cycles:10 Average:76.80 Median:70.50 stddev:19.61 (27.8%) stddev-sans-first:18.91 22:41:41 INFO - PROCESS | 1663 | Values: 100.0 53.0 67.0 111.0 88.0 87.0 75.0 60.0 74.0 53.0 22:41:41 INFO - PROCESS | 1663 | 22:41:41 INFO - PROCESS | 1663 | [#4] audio-oscillator Cycles:10 Average:93.50 Median:72.50 stddev:17.24 (23.8%) stddev-sans-first:18.08 22:41:41 INFO - PROCESS | 1663 | Values: 86.0 72.0 84.0 73.0 76.0 105.0 118.0 106.0 102.0 113.0 22:41:41 INFO - PROCESS | 1663 | 22:41:41 INFO - PROCESS | 1663 | [#5] imaging-gaussian-blur Cycles:10 Average:89.80 Median:88.50 stddev:4.59 (5.2%) stddev-sans-first:4.86 22:41:41 INFO - PROCESS | 1663 | Values: 89.0 87.0 102.0 87.0 90.0 87.0 92.0 88.0 89.0 87.0 22:41:41 INFO - PROCESS | 1663 | 22:41:41 INFO - PROCESS | 1663 | [#6] imaging-darkroom Cycles:10 Average:111.20 Median:96.00 stddev:24.72 (25.8%) stddev-sans-first:25.05 22:41:41 INFO - PROCESS | 1663 | Values: 132.0 154.0 96.0 97.0 96.0 97.0 95.0 94.0 99.0 152.0 22:41:41 INFO - PROCESS | 1663 | 22:41:41 INFO - PROCESS | 1663 | [#7] imaging-desaturate Cycles:10 Average:104.10 Median:94.00 stddev:19.92 (21.2%) stddev-sans-first:20.97 22:41:41 INFO - PROCESS | 1663 | Values: 97.0 95.0 92.0 94.0 94.0 94.0 146.0 93.0 99.0 137.0 22:41:41 INFO - PROCESS | 1663 | 22:41:41 INFO - PROCESS | 1663 | [#8] json-parse-financial Cycles:10 Average:58.60 Median:59.00 stddev:1.17 (2.0%) stddev-sans-first:1.13 22:41:41 INFO - PROCESS | 1663 | Values: 60.0 58.0 56.0 58.0 59.0 60.0 58.0 59.0 59.0 59.0 22:41:41 INFO - PROCESS | 1663 | 22:41:41 INFO - PROCESS | 1663 | [#9] json-stringify-tinderbox Cycles:10 Average:91.40 Median:70.50 stddev:22.31 (31.6%) stddev-sans-first:21.99 22:41:41 INFO - PROCESS | 1663 | Values: 68.0 73.0 127.0 117.0 66.0 82.0 107.0 111.0 89.0 74.0 22:41:41 INFO - PROCESS | 1663 | 22:41:41 INFO - PROCESS | 1663 | [#10] stanford-crypto-aes Cycles:10 Average:90.30 Median:91.00 stddev:1.42 (1.6%) stddev-sans-first:1.48 22:41:41 INFO - PROCESS | 1663 | Values: 91.0 91.0 91.0 91.0 89.0 89.0 92.0 92.0 89.0 88.0 22:41:41 INFO - PROCESS | 1663 | 22:41:41 INFO - PROCESS | 1663 | [#11] stanford-crypto-ccm Cycles:10 Average:169.60 Median:167.50 stddev:16.81 (10.0%) stddev-sans-first:16.26 22:41:41 INFO - PROCESS | 1663 | Values: 150.0 149.0 163.0 191.0 168.0 167.0 158.0 186.0 198.0 166.0 22:41:41 INFO - PROCESS | 1663 | 22:41:41 INFO - PROCESS | 1663 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:190.30 Median:180.00 stddev:24.05 (13.4%) stddev-sans-first:24.19 22:41:41 INFO - PROCESS | 1663 | Values: 212.0 249.0 200.0 175.0 177.0 177.0 181.0 179.0 174.0 179.0 22:41:41 INFO - PROCESS | 1663 | 22:41:41 INFO - PROCESS | 1663 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:77.80 Median:73.50 stddev:10.40 (14.2%) stddev-sans-first:10.82 22:41:41 INFO - PROCESS | 1663 | Values: 72.0 71.0 69.0 69.0 73.0 73.0 74.0 87.0 96.0 94.0 22:41:41 INFO - PROCESS | 1663 | -------- Summary: end -------- 22:41:41 INFO - PROCESS | 1663 | 22:41:42 INFO - TEST-INFO | 1663: exit 0 22:41:42 INFO - TEST-OK | kraken | took 62473ms 22:41:42 INFO - SUITE-END | took 1179s 22:41:42 INFO - Completed test suite (00:19:39) 22:41:42 INFO - javascript benchmark 22:41:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7054.974201064418, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3143.779935404522, 3407.6081250656184, 3413.795172289663, 3425.9999999999986, 3460.7058400035994, 5392.460753924611, 5511.173324001402, 5578.553715702744, 5675.949949259385, 5705.6006079574445, 5572.972135139329, 5664.745086471108, 5670.68811215383, 5696.487316141546, 5705.48650621444, 5091.389033315998, 5124.205748109049, 5173.87065323368, 5177.611679124062, 5197.246399272103, 5226.660267082647, 5227.973860130694, 5267.367915850104, 5270.7628156733035, 5285.920711189336, 201.13900810403086, 4393.406890069831, 5108.6168537359845, 5144.794208231666, 5308.097623404031, 4124.587541245852, 4263.339182426729, 5149.536541711226, 5152.0, 5154.175331946871, 4252.978735106336, 5116.744162791845, 5118.950615123891, 5149.613778966593, 5198.532132108091, 4511.526289739563, 5387.81142660005, 5575.581831362623, 5604.0, 5647.548196144339, 5049.949500504985, 6157.630542167484, 6166.691665416712, 6206.224221972254, 6206.410391012847, 6210.72051757672, 6212.689365531751, 6233.470155036827, 6236.875262494725, 6248.937510624881, 5598.1602759586385, 5700.97149514258, 5701.287339082614, 5756.366799652035, 5789.942100578983, 6119.837693858789, 6139.539534534928, 6147.0779383092, 6150.384961503813, 6158.876822463526, 6057.697115144225, 6070.939290607126, 6071.666058366833, 6119.357467465941, 6127.754889804358, 6112.847178820476, 6159.662440523155, 6178.505719542426, 6209.782657607052, 6299.0, 6017.277926648742, 6069.786042791371, 6113.993245360534, 6121.0206366981065, 6139.584134579375, 6174.012158054689, 6198.101275315054, 6202.100695399142, 6209.192804935329, 6212.658303793244, 4178.247915375201, 4859.927101093486, 4887.755612219375, 4896.755162241873, 4902.338184345068, 4409.007973206003, 4417.5803298687215, 4465.776711164429, 4471.0, 4544.0, 4794.89717365008, 5324.653897496651, 5850.824475265749, 5975.43233392827, 6008.369121242206, 4886.853394398174, 5239.952009598096, 6138.817583427401, 6177.135201071854, 6189.84525386869, 4327.264365057885, 5218.982298451841, 5810.854728631818, 6016.578839481282, 6017.488513476316, 5200.427952925175, 5482.205080263339, 5483.122700367922, 5485.835424937259, 5524.392316845144, 5355.009323275207, 6190.628562286277, 6223.3465486125115, 6264.248290205204, 6398.744050238031, 6159.568830182024, 6220.409061139185, 6222.160008398808, 6233.563650544418, 6276.340984196594, 6346.650934198663, 6359.777407790705, 6392.616443013434, 6415.935840641534, 6433.646149461824, 6049.606775559574, 6083.361247069171, 6100.993336099494, 6198.380161983946, 6201.844953876189, 6104.398524058527, 6131.294901086432, 6149.2620885494025, 6199.535034872312, 6216.316205217513, 6023.969880150571, 6059.21230240066, 6075.483583895419, 6080.640423763037, 6126.326104128453, 5888.558358123244, 5967.194428752063, 6019.337872833898, 6062.575619706578, 6091.512678985582, 4984.8753781155765, 5808.738606762621, 5820.76716931335, 5842.123681447817, 5890.528757699288, 6103.277060575968, 6116.396640261158, 6137.438273608381, 6137.867325793431, 6152.169457123231, 5109.233614957786, 5749.770009199585, 5793.884122317614, 5814.360420353673, 5980.790672326448], "value": 5548.931568686565, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [461.79680940386226, 482.29825603746553, 496.8882001549652, 500.95300821283524, 524.3157679228608, 27862.860685696567, 38517.0, 39207.43170273189, 39332.82001539951, 40840.59159408405, 40083.19833603326, 40476.59523404765, 40492.79753601228, 40511.79744101276, 40522.79738601303, 7849.332806711422, 9436.764080897963, 9448.0, 9452.8582071269, 9490.71527854162, 8508.0, 8712.651493940235, 11072.612458563952, 11414.771704565903, 11429.599964001261, 11431.0, 11469.827952580717, 11485.253458525169, 11489.827652585218, 11503.252288601256, 11266.098712103054, 11294.661160165126, 11332.773344533145, 11333.320000799937, 11339.206255562114, 11246.98777110056, 11296.265742726697, 11320.773584528346, 11328.32030078198, 11356.772864542663, 38652.226955460734, 38912.41631375532, 38918.0, 38995.02512437184, 39072.02319941996, 3291.391092647844, 3386.238096428284, 3461.8788342408147, 3632.2553876455263, 3758.642928921748, 29566.556501652492, 29993.70006299931, 30257.0, 30651.540226896614, 30684.846575767202, 2650.3771613670774, 3198.7600929930118, 3215.5015972524297, 3217.179619197113, 3358.4794356874722, 443308.0, 453673.0, 459347.0, 459725.0, 459736.0, 56326.0, 57113.0, 57174.42825571774, 57212.85574288574, 57338.0, 59416.0, 60183.398166018655, 60220.0, 60262.0, 60346.09480857791, 15452.381904723685, 15631.452899148475, 15754.76367854483, 15762.290696918666, 15829.683406332038, 10932.56269749217, 12114.69713257158, 12218.022558195324, 12223.327716975475, 12245.632631020906, 14136.172765446952, 16157.4344897928, 16438.75341869873, 16519.587010324598, 16545.6690866182, 6320.0, 6409.359064093646, 6447.194100737408, 6463.6768161591735, 6485.967570162214, 826.900771907375, 912.2109375390239, 945.4185675809465, 955.7753927826955, 1082.3019152646498, 28677.713222867922, 28848.567271490945, 29584.852075739484, 29718.851405743266, 29719.85140074329, 971.6016433262329, 977.9119879210905, 983.7196399026243, 1054.5518154784186, 1096.0354887698934, 14122.576322710334, 14634.780478292834, 14725.631859203606, 14970.17664028489, 15008.499150084905, 580259.0, 587607.0, 594885.0, 596139.0193049094, 597572.0, 53351.199732004054, 53621.0, 53807.73096134494, 53860.73069634627, 53984.730076350155, 1104.9060829829393, 1291.9987010067273, 1359.904806663544, 1377.3870627570834, 1379.7309524642799, 27272.454550908475, 28339.85830070919, 28564.714352856205, 28622.713772862004, 28838.0, 41763.7911810439, 43637.78181109074, 47442.28836567523, 47640.761796190796, 48240.070397185125, 418115.0, 443673.0, 521250.0, 530977.0, 531429.0, 22157.55684886261, 23064.192753253556, 24805.0, 25383.61924571096, 26143.954241830113, 36995.0, 37674.81162594169, 43090.0, 43972.780136099114, 44261.77869110634, 27960.86019569889, 34873.65126348806, 37426.8128659355, 38126.0, 38700.80649596734, 37143.0, 37857.24285514219, 37946.62053379431, 38112.856614301614, 38479.807600961816, 30095.69904300929, 35664.930052098476, 36481.27037459289, 36965.891023269345, 37186.62813371832, 29089.7091029087, 32193.0, 33193.336133277684, 33397.66409343599, 33508.32983340368, 22343.44141396478, 23609.52780944337, 23810.88094559516, 24639.753602463745, 24924.57391756053], "value": 19194.76332671102, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [273.17501146537455, 315.43851943540494, 370.5460810507129, 382.7282629333175, 383.9520059992501, 16634.750478742815, 18464.81535184648, 19057.14242859073, 19476.902615486903, 19618.215271389163, 13728.725425491484, 19649.803501964976, 19907.60184796307, 20023.998800060013, 20047.599048019067, 4489.640828733702, 5821.155932389801, 5821.825345239649, 5822.272215973004, 5834.862201870637, 5062.190049592066, 5319.281896943921, 5761.395053519383, 5774.0, 5778.971105144468, 5706.115552089433, 5719.399463056381, 5721.6853073080965, 5733.885322293551, 5752.7986520471795, 5718.313802343725, 5721.942780572203, 6092.573519853612, 6400.807975760735, 6479.254885688112, 5759.798407055775, 5760.308762948431, 5768.913466298009, 5772.191893134965, 5776.566757493163, 12423.19249248805, 12509.937450312782, 12635.49458021678, 12798.552050678181, 12835.229886206764, 2245.38251980705, 2314.884255787204, 2448.5592593333195, 2732.562789953598, 2863.0551917867056, 9322.67370642031, 9576.61693532258, 9598.328117031811, 10017.749556261078, 10062.39625622465, 1748.9038102904335, 1749.9387521436688, 1781.616952355237, 1818.6237790267953, 1852.0739630184908, 106471.0, 111033.44483277612, 111804.0, 111890.0, 115064.84935150624, 10969.725756855983, 10985.890141098647, 11145.331280123219, 11210.55157793679, 11244.044256238149, 13897.930510347383, 13945.511907083204, 13970.930145349412, 13975.79036314456, 13980.58058258254, 6793.961811456543, 8011.359091272684, 8154.836903261901, 8163.918360816435, 8325.542095184823, 8094.069182044022, 8119.3612076075515, 8148.307393871469, 8280.917190828015, 8303.709370172015, 4330.285502891988, 4429.11417716458, 4431.977840110779, 4459.554044595515, 4483.170613436525, 1817.4184261036605, 1881.990590047041, 1884.9717254241198, 1889.4426144287193, 1914.2438736698925, 752.6801109528428, 785.8781888807245, 830.3979614779237, 967.1682353176262, 989.8911119776819, 18058.187381567863, 19181.904090479456, 19907.502312442306, 20088.598228035356, 20414.89792551028, 876.912308769118, 892.3976315986683, 939.2251392601131, 956.1920177450044, 989.4211886046743, 10555.736106597396, 10994.505247263893, 10997.175211859143, 11031.062359699356, 11044.282121662067, 427164.0, 431845.0, 433158.83420583326, 544420.0, 558350.0, 125575.0, 126069.0, 129406.0, 132589.0, 137219.0, 1011.206203130539, 1062.7768168684509, 1063.7021633942509, 1063.9521221544894, 1064.7018834726289, 18327.725084124017, 18545.258189672262, 18747.437576872715, 18839.24643014264, 18912.0, 52330.738346308026, 52652.96164818672, 53040.67398314919, 53350.0, 53677.0, 55637.0, 74076.88884666841, 75581.0, 77599.0, 79569.0, 6154.723037463235, 6664.666766661744, 7778.377729781717, 8032.116467188488, 8035.959820201096, 11629.360385178896, 12364.258144511357, 12447.751044979232, 12645.241285522898, 12667.0, 11011.28426652265, 11071.889281107407, 11087.22389432732, 11089.611863584736, 11160.330380177214, 8724.389292749447, 10724.946375268073, 10830.241883068109, 10884.149694551787, 11017.953294437015, 10983.272459103007, 11051.392173430539, 11115.72210694739, 11145.442727863736, 11171.938665826734, 6741.157355330583, 7884.899852076774, 7922.4850384724805, 8039.196080391914, 8062.274395304451, 8988.46069235848, 9153.405028673114, 10585.576576936835, 10802.89197108019, 11077.612283570037], "value": 9057.09095947339, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1440.596632942776, 1488.3376897280712, 1636.9768894440974, 1729.4811556533036, 1784.081198182936, 7386.852262954738, 7619.847603047936, 7856.292933635971, 8241.258286754191, 8247.175282471757, 7813.687452501893, 8092.352611791057, 8116.1478044805335, 8193.590320483981, 8202.753917382473, 2273.874936878467, 2296.47181148336, 2297.8621282723007, 2298.4598619324447, 2317.872517011572, 2297.781710737477, 2329.23135365329, 2340.765923407654, 2341.8711970841596, 2345.6364263539094, 2276.3057267533395, 2278.3506700590333, 2303.1133013789727, 2305.6426253930667, 2306.031466783955, 1996.7420525069113, 2267.5918334699745, 2280.897359618821, 2289.9313020609325, 2294.33464295355, 2181.792729690677, 2292.5300313435705, 2308.699869017017, 2312.6184179610345, 2313.398516385735, 8052.758417247492, 8090.757277281691, 8113.472624279462, 8151.714689985822, 8188.836223275561, 4929.2359684248995, 5072.163093089635, 5075.568576670988, 5080.695158290478, 5099.923501147486, 3218.91952701182, 3309.3546758382126, 3545.4859045438266, 3554.36021516127, 3561.287742451494, 1035.2649618770681, 1035.7307100154014, 1078.3529882070773, 1088.3524303039678, 1095.2825899036145, 10399.116075133623, 10571.312864663772, 10576.524056417402, 10646.893531064668, 10687.251892367538, 4368.453943257093, 4484.57396547334, 4587.0, 4595.839145629887, 4600.194965880957, 9025.909740902638, 9115.726528204164, 9121.498317592463, 9124.81750364989, 9126.0, 5054.166062599704, 5084.51697088776, 5087.567556757717, 5092.236164575269, 5097.770600322994, 3792.8482860685262, 4043.838246470167, 4218.894527636772, 4367.170237654899, 4656.976715116402, 2767.8200916940336, 2785.9303517412227, 3231.757618178646, 3798.3542797724454, 3845.1925095729653, 1224.5714000099895, 1242.1615409598485, 1245.9377031148588, 1250.6748245456247, 1255.215490318551, 877.7322916510526, 887.9911200888038, 891.80826122384, 910.9842525583927, 911.6809116809196, 4493.955060449419, 5264.710440925785, 5406.107992181246, 5550.890661548667, 5593.636413633101, 435.55790872264674, 436.1690978685609, 444.2336968728917, 946.9205106178962, 949.7340744591526, 3742.7380083394446, 3774.0, 3811.5997820228476, 3835.3096442640035, 3902.20004898995, 9667.758306042264, 9862.408255504693, 9907.306488545877, 9996.800063998679, 10004.54979525923, 9570.042995700513, 9614.0, 9782.75543111428, 10023.649172278936, 10117.645882394228, 7586.506877052974, 7706.07527096752, 7713.922860771433, 7718.266764657461, 7727.49771264866, 4752.453467851241, 5427.945720542902, 5461.1808228766, 5521.337439507285, 5528.474794894479, 8476.830463390821, 9131.634734610807, 9200.95399522998, 9275.86086208694, 9298.72103836886, 7182.928170718226, 7559.508631938907, 9280.303977201602, 9468.621255149717, 9492.193163581172, 6164.0, 6362.554621176473, 6475.611463312216, 6685.799426017227, 6710.429613482908, 7651.311381975649, 7925.564093974886, 7936.523808571505, 7939.245771651684, 7983.600819958862, 7097.25478824716, 7243.855122897618, 7258.564486130849, 7352.228016058237, 7414.332710056121, 7068.469864760061, 7131.2155662876, 7183.173934997542, 7183.245759195209, 7357.227491113356, 6406.775762848278, 6520.934790652223, 6611.735530578723, 6615.206175259, 6636.13730215069, 4566.1552612766745, 5023.547880690755, 5049.116404629249, 5073.061483625541, 5081.339425874613, 4553.749543775124, 4965.652404331662, 5070.923936140887, 5108.514691104339, 5139.1263485206855], "value": 4287.09255029576, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [296.2046904062593, 301.7601007199277, 303.691752870836, 308.3093869731801, 310.6396579967238, 4446.62203712684, 5153.716545589991, 5373.5969802264835, 5440.0, 5493.2584101146385, 5223.216517522374, 5339.344803111038, 5395.622306438541, 5418.7019713915715, 5457.781688732456, 4131.091159944823, 4134.524529679088, 4147.709660323779, 4149.149424367997, 4169.207850508394, 3312.9171770705684, 3339.4990751387245, 3354.76516643835, 3843.711721620876, 4047.4131250968594, 3926.725129240954, 4097.959020409788, 4121.690873184508, 4128.938065929014, 4133.689973252003, 3629.5100161478126, 3922.666573341241, 4126.463559737245, 4139.2135494256145, 4154.86590842111, 3193.760467964912, 3598.316319899237, 3660.2313514162056, 3662.406853018945, 3677.797721125337, 5181.119209734355, 5358.973205133989, 5362.6782393056155, 5389.164679474688, 5395.946040539623, 4053.6351728344293, 4181.414601955698, 4649.465311489188, 4744.786484608201, 4760.690555113941, 5088.491150884919, 5362.249285100089, 5711.257536520226, 5752.0, 5758.971205143989, 3735.831887565066, 3737.5141231639986, 3843.7693738375506, 4013.150663029253, 4066.5323487798987, 5967.672607683007, 5980.55145864062, 5993.430624090704, 6003.939960600382, 6034.607750496248, 3859.749116307431, 3937.684985201177, 4054.2904991626347, 4059.654929330981, 4090.4886889138857, 3292.456744637156, 3832.0, 4004.759714417086, 4083.7549747015273, 4187.392828039917, 4735.076660051326, 4737.668363214542, 4771.761411929389, 4784.7607619619575, 4823.397075365579, 4347.608715215646, 4806.399200099988, 5759.8560035999435, 5763.472679739873, 5839.270091238595, 1101.4107452512867, 1174.3717111345388, 1176.5529098942347, 1217.7747116783423, 1304.4672509800946, 855.5508358111916, 897.282174260602, 914.4604683236922, 931.3759780946782, 937.9155875971195, 4597.528790786916, 4613.1004454131125, 4845.6608037437045, 5224.634275600672, 5284.142575919524, 4791.425028996543, 4819.156647586658, 5429.837104886859, 5683.602147849693, 5756.913646295309, 4709.929351059737, 4777.737224452619, 4861.041737427804, 4908.45383704132, 5137.588992880561, 2308.437634226008, 2382.9753206121186, 2542.5931850903767, 2758.6758355988836, 2914.4316858212446, 5547.5007249347755, 5763.538916886639, 5774.566907481955, 5799.942000580025, 5819.679917604488, 4829.082474329866, 5356.276902618097, 5592.440755924456, 5655.4910058094965, 5659.839732854755, 5361.410244873061, 5544.223808666709, 5816.243888294494, 5849.561282903799, 5899.7050147492455, 5538.169274608841, 5563.582731295088, 5593.049181639212, 5607.009511623315, 5698.686572238401, 5069.569086627678, 5158.5298190015665, 5209.6874187548865, 5266.657667251617, 5511.118221084607, 4874.8537543873745, 5164.7417629119145, 5270.7101109439345, 5744.310682717934, 5802.854928626818, 3260.820654864005, 3699.8890033299044, 3738.0281126906657, 3852.8651497197466, 3876.8061596920606, 4798.424189097331, 5058.974705126451, 5311.814086506954, 5590.099366215343, 5609.017597505654, 4461.0, 4955.876103097451, 5469.72651367438, 5514.09017512122, 5630.5214056805635, 4879.024195160911, 4896.779644916059, 5332.520073193431, 5708.286464191976, 5713.9714301429885, 5375.0493387359475, 5563.0, 5581.386047534686, 5611.3266408031295, 5633.943660563506, 5258.027264955996, 5670.8015219467125, 5708.429157084258, 5747.885042299215, 5751.4536119068625, 4804.447488538723, 5602.607817452739, 5630.493255607015, 5651.689157096399, 5661.820199223411], "value": 4059.6367111985023, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [590.4626789621445, 676.5791837142002, 729.9525530840492, 739.408473221423, 792.9524228546288, 14309.141451512904, 15093.320800563959, 15199.620009499768, 15241.390344386225, 16174.919125404385, 12041.578544750913, 15273.541793746177, 15887.84112158878, 16082.919585402085, 16106.0, 817.9222973817477, 850.5917159763318, 850.6937502499095, 856.0369584217755, 856.2293935458107, 823.1192623892438, 849.1593322610632, 849.7110982266029, 854.747849384433, 859.9269062129727, 858.5578427110044, 893.423741686615, 903.8960519540238, 903.9186473217409, 904.5070436612062, 860.7805009722481, 886.6807949138305, 894.9463032218088, 896.1218006353777, 896.1799953042957, 646.5764923974804, 662.0334311904625, 662.5825729790201, 663.4625952978104, 665.8501837086665, 14393.424263029467, 14672.193029383487, 14724.779128313085, 14786.482473113498, 14790.926045369704, 13334.399952002183, 13794.65513362174, 13874.93062534681, 13947.79078313816, 14093.634697861595, 11678.532858685727, 11741.0, 11752.941235293854, 11959.103067269989, 11984.460699268553, 3139.7959132656533, 3673.2837096766143, 3745.8688945887034, 3747.2130852521004, 3760.9247815043545, 105843.0, 106634.0, 110775.44612276967, 111398.0, 113600.72798544065, 10408.219383546264, 10487.423191724376, 10518.842217366746, 10575.841362379571, 11025.503852326665, 21008.684869726967, 21205.681914771292, 21237.68143477849, 21242.0, 21253.0, 7445.691835531003, 7482.214367491444, 7492.175860655324, 7498.889737179587, 7642.7071878436245, 4201.432806571135, 4203.62167404935, 4205.103630046726, 4268.210381079511, 4350.151720414552, 3452.58568971725, 3496.9300613988084, 3555.2711694102645, 3560.946585801215, 3562.946555801665, 620.3827191943955, 623.8739075967922, 638.8307098618869, 643.5913195121038, 646.7219095788762, 548.9945100549023, 587.2395248153651, 637.827786497643, 671.8051764988098, 675.9121314229119, 7963.681452741941, 8523.232909038092, 8559.957200214083, 9218.677346292847, 9461.858072128924, 559.8439223004483, 573.4466240078344, 628.6479571440008, 646.2019406033638, 664.5016237821634, 4164.729292595972, 4546.272596384562, 4564.2012647786505, 4599.517050709695, 4687.976560117246, 60377.39622603806, 85825.0, 92066.0, 113860.0, 117373.0, 73144.63427682828, 73459.63270183722, 84624.30751384937, 91706.0, 97462.51268743612, 74439.25560744431, 77574.22425775783, 78385.21614783893, 84899.66103728586, 87583.68624470638, 6873.931260687529, 8653.567321633767, 8782.209601135928, 9029.367944244103, 9236.399634024036, 38913.49778519947, 47011.764941175075, 54586.727066364416, 55085.0, 58074.83850322886, 60853.69573152283, 71830.0, 72027.27972720205, 72362.0, 72677.0, 12535.435905384462, 12552.43514041852, 12743.362831858556, 12780.744385112432, 13449.932750336186, 14322.713545729235, 14622.268886555843, 14759.188244646648, 14912.328945197276, 14936.253187340371, 13531.729365412833, 13954.23251721165, 14214.396747578654, 14231.928840355731, 14283.500077497238, 13409.195448273134, 13458.0, 13616.38726257301, 13712.93143534276, 13817.861821381657, 12467.753224677459, 13416.262105584286, 13486.393112310167, 13544.79682804739, 13731.87304024453, 9773.462459564793, 10956.068734157687, 11187.875423745447, 11225.494852731483, 11266.436678165896, 8592.871106933277, 9339.813203736023, 11259.437028148724, 11603.5358585659, 11640.417979100841], "value": 7343.359481865978, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1638.1999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 119.0, 118.0, 119.0, 127.0, 174.0, 122.0, 120.0, 120.0, 120.0], "value": 124.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 141.0, 152.0, 194.0, 121.0, 123.0, 106.0, 141.0, 171.0, 150.0], "value": 144.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [201.0, 234.0, 197.0, 204.0, 199.0, 198.0, 194.0, 220.0, 273.0, 234.0], "value": 215.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 53.0, 67.0, 111.0, 88.0, 87.0, 75.0, 60.0, 74.0, 53.0], "value": 76.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 72.0, 84.0, 73.0, 76.0, 105.0, 118.0, 106.0, 102.0, 113.0], "value": 93.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 102.0, 87.0, 90.0, 87.0, 92.0, 88.0, 89.0, 87.0], "value": 89.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 154.0, 96.0, 97.0, 96.0, 97.0, 95.0, 94.0, 99.0, 152.0], "value": 111.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 95.0, 92.0, 94.0, 94.0, 94.0, 146.0, 93.0, 99.0, 137.0], "value": 104.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 58.0, 56.0, 58.0, 59.0, 60.0, 58.0, 59.0, 59.0, 59.0], "value": 58.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 73.0, 127.0, 117.0, 66.0, 82.0, 107.0, 111.0, 89.0, 74.0], "value": 91.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 91.0, 91.0, 91.0, 89.0, 89.0, 92.0, 92.0, 89.0, 88.0], "value": 90.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 149.0, 163.0, 191.0, 168.0, 167.0, 158.0, 186.0, 198.0, 166.0], "value": 169.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [212.0, 249.0, 200.0, 175.0, 177.0, 177.0, 181.0, 179.0, 174.0, 179.0], "value": 190.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 71.0, 69.0, 69.0, 73.0, 73.0, 74.0, 87.0, 96.0, 94.0], "value": 77.8, "unit": "score"}]}]} 22:41:42 INFO - Return code: 0 22:41:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:41:42 ERROR - # TBPL SUCCESS # 22:41:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:41:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:41:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:41:42 INFO - 2016-12-04 22:41:42.698 screenresolution[1668:11398] starting screenresolution argv=screenresolution get 22:41:42 INFO - 2016-12-04 22:41:42.701 screenresolution[1668:11398] Display 0: 1600x1200x32@60 22:41:42 INFO - 2016-12-04 22:41:42.707 screenresolution[1669:11401] starting screenresolution argv=screenresolution list 22:41:42 INFO - Available Modes on Display 0 22:41:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:41:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:41:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:41:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:41:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:41:42 INFO - Intel Iris: 22:41:42 INFO - Chipset Model: Intel Iris 22:41:42 INFO - Type: GPU 22:41:42 INFO - Bus: Built-In 22:41:42 INFO - VRAM (Dynamic, Max): 1536 MB 22:41:42 INFO - Vendor: Intel (0x8086) 22:41:42 INFO - Device ID: 0x0a2e 22:41:42 INFO - Revision ID: 0x0009 22:41:42 INFO - Displays: 22:41:42 INFO - Display: 22:41:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:41:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:41:42 INFO - Display Serial Number: 1600x1200 60 22:41:42 INFO - Main Display: Yes 22:41:42 INFO - Mirror: Off 22:41:42 INFO - Online: Yes 22:41:42 INFO - Rotation: Supported 22:41:42 INFO - Return code: 0 22:41:42 INFO - Running post-action listener: _resource_record_post_action 22:41:42 INFO - [mozharness: 2016-12-05 06:41:42.964922Z] Finished run-tests step (success) 22:41:42 INFO - Running post-run listener: _resource_record_post_run 22:41:43 INFO - Total resource usage - Wall time: 1213s; CPU: 55.0%; Read bytes: 34583040; Write bytes: 698535936; Read time: 318; Write time: 1989 22:41:43 INFO - TinderboxPrint: CPU usage
54.6% 22:41:43 INFO - TinderboxPrint: I/O read bytes / time
34,583,040 / 318 22:41:43 INFO - TinderboxPrint: I/O write bytes / time
698,535,936 / 1,989 22:41:43 INFO - TinderboxPrint: CPU idle
2,205.3 (45.4%) 22:41:43 INFO - TinderboxPrint: CPU system
984.1 (20.3%) 22:41:43 INFO - TinderboxPrint: CPU user
1,664.4 (34.3%) 22:41:43 INFO - TinderboxPrint: Swap in / out
415,510,528 / 0 22:41:43 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 367727104; Write bytes: 367580672; Read time: 28426; Write time: 664 22:41:43 INFO - run-tests - Wall time: 1180s; CPU: 55.0%; Read bytes: 33661440; Write bytes: 312408576; Read time: 305; Write time: 1279 22:41:43 INFO - Running post-run listener: _upload_blobber_files 22:41:43 INFO - Blob upload gear active. 22:41:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:41:43 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 22:41:43 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'] 22:41:43 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 22:41:43 INFO - (blobuploader) - INFO - Open directory for files ... 22:41:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 22:41:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:41:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:41:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 22:41:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:41:44 INFO - (blobuploader) - INFO - Done attempting. 22:41:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 22:41:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:41:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:41:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 22:41:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:41:45 INFO - (blobuploader) - INFO - Done attempting. 22:41:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:41:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:41:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:41:47 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:41:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:41:47 INFO - (blobuploader) - INFO - Done attempting. 22:41:47 INFO - (blobuploader) - INFO - Iteration through files over. 22:41:48 INFO - Return code: 0 22:41:48 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:41:48 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:41:48 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/96270c9f2177692161f6a2ca78c011cd07a982b8050e560262e795758b61be69b9c86b4857e9297bcdf55c3c556a6c8c1720689cdc4395ea37b36250f5f0f984", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/bf05a57184387baaf1c4182209e99cf979af2840e4cb6cc1071b90f7649039144569a8e1ea74d7613d769f671334d0fafb1f89dc3be03433d0f7a965ed2bb12e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/b22ae6958b6b7c57d4b6fc8cc3a119d90753fa03c43c0e7dde1075e2345684f8b0220ba5fdcd41dafa5902613b6569c6c181c5fbd52667f7a6f29bf2f379e94a"} 22:41:48 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:41:48 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:41:48 INFO - Contents: 22:41:48 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/96270c9f2177692161f6a2ca78c011cd07a982b8050e560262e795758b61be69b9c86b4857e9297bcdf55c3c556a6c8c1720689cdc4395ea37b36250f5f0f984", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/bf05a57184387baaf1c4182209e99cf979af2840e4cb6cc1071b90f7649039144569a8e1ea74d7613d769f671334d0fafb1f89dc3be03433d0f7a965ed2bb12e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/b22ae6958b6b7c57d4b6fc8cc3a119d90753fa03c43c0e7dde1075e2345684f8b0220ba5fdcd41dafa5902613b6569c6c181c5fbd52667f7a6f29bf2f379e94a"} 22:41:48 INFO - Running post-run listener: copy_logs_to_upload_dir 22:41:48 INFO - Copying logs to upload dir... 22:41:48 INFO - mkdir: /builds/slave/test/build/upload/logs 22:41:48 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1279.846296 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2016-12-04 22:41:48.092334) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-04 22:41:48.096746) ========= 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.HavLnOcel8/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.YmPhP8PDnT/Listeners TMPDIR=/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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/96270c9f2177692161f6a2ca78c011cd07a982b8050e560262e795758b61be69b9c86b4857e9297bcdf55c3c556a6c8c1720689cdc4395ea37b36250f5f0f984", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/bf05a57184387baaf1c4182209e99cf979af2840e4cb6cc1071b90f7649039144569a8e1ea74d7613d769f671334d0fafb1f89dc3be03433d0f7a965ed2bb12e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/b22ae6958b6b7c57d4b6fc8cc3a119d90753fa03c43c0e7dde1075e2345684f8b0220ba5fdcd41dafa5902613b6569c6c181c5fbd52667f7a6f29bf2f379e94a"} build_url:https://queue.taskcluster.net/v1/task/UDx0J90KQqWzgVPrZy37ug/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010956 build_url: 'https://queue.taskcluster.net/v1/task/UDx0J90KQqWzgVPrZy37ug/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/96270c9f2177692161f6a2ca78c011cd07a982b8050e560262e795758b61be69b9c86b4857e9297bcdf55c3c556a6c8c1720689cdc4395ea37b36250f5f0f984", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/bf05a57184387baaf1c4182209e99cf979af2840e4cb6cc1071b90f7649039144569a8e1ea74d7613d769f671334d0fafb1f89dc3be03433d0f7a965ed2bb12e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/b22ae6958b6b7c57d4b6fc8cc3a119d90753fa03c43c0e7dde1075e2345684f8b0220ba5fdcd41dafa5902613b6569c6c181c5fbd52667f7a6f29bf2f379e94a"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-04 22:41:48.136638) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-04 22:41:48.137213) ========= 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.HavLnOcel8/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.YmPhP8PDnT/Listeners TMPDIR=/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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.005038 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-04 22:41:48.187691) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-04 22:41:48.188240) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-04 22:41:48.188622) ========= ========= Total master_lag: 0.32 =========