builder: graphics_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0343 starttime: 1479931193.91 results: success (0) buildid: 20161123081537 builduid: d6f154c85f264270867d1fdd476d9f61 revision: 81b8be8cac805809cb42651ab90befa5cf51d75c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-23 11:59:53.913707) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-23 11:59:53.914166) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-23 11:59:53.914436) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-23 11:59:53.940921) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-23 11:59:53.941308) ========= 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.6zs13kVB34/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.4Ye8jQ5W1O/Listeners TMPDIR=/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-11-23 11:59:53-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 20.3M=0.001s 2016-11-23 11:59:54 (20.3 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.119786 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-23 11:59:54.085266) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-23 11:59:54.085591) ========= 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.6zs13kVB34/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.4Ye8jQ5W1O/Listeners TMPDIR=/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/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.025235 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-23 11:59:54.133694) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-23 11:59:54.134123) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev 81b8be8cac805809cb42651ab90befa5cf51d75c --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 81b8be8cac805809cb42651ab90befa5cf51d75c --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.6zs13kVB34/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.4Ye8jQ5W1O/Listeners TMPDIR=/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-11-23 11:59:54,232 truncating revision to first 12 chars 2016-11-23 11:59:54,233 Setting DEBUG logging. 2016-11-23 11:59:54,233 attempt 1/10 2016-11-23 11:59:54,233 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/81b8be8cac80?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-23 11:59:55,902 unpacking tar archive at: graphics-81b8be8cac80/testing/mozharness/ program finished with exit code 0 elapsedTime=1.948064 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-23 11:59:56.106436) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-23 11:59:56.106914) ========= script_repo_revision: 81b8be8cac805809cb42651ab90befa5cf51d75c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-23 11:59:56.107410) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-23 11:59:56.107740) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-23 11:59:56.127809) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 20 secs) (at 2016-11-23 11:59:56.128180) ========= /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.6zs13kVB34/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.4Ye8jQ5W1O/Listeners TMPDIR=/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/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 11:59:56 INFO - MultiFileLogger online at 20161123 11:59:56 in /builds/slave/test 11:59:56 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 11:59:56 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:59:56 INFO - {'append_to_log': False, 11:59:56 INFO - 'base_work_dir': '/builds/slave/test', 11:59:56 INFO - 'blob_upload_branch': 'Graphics', 11:59:56 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:59:56 INFO - 'branch': 'Graphics', 11:59:56 INFO - 'buildbot_json_path': 'buildprops.json', 11:59:56 INFO - 'config_files': ('talos/mac_config.py',), 11:59:56 INFO - 'default_actions': ('clobber', 11:59:56 INFO - 'read-buildbot-config', 11:59:56 INFO - 'download-and-extract', 11:59:56 INFO - 'populate-webroot', 11:59:56 INFO - 'create-virtualenv', 11:59:56 INFO - 'install', 11:59:56 INFO - 'run-tests'), 11:59:56 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:59:56 INFO - 'download_minidump_stackwalk': True, 11:59:56 INFO - 'download_symbols': 'ondemand', 11:59:56 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:59:56 INFO - 'tooltool.py': '/tools/tooltool.py', 11:59:56 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:59:56 INFO - '/tools/misc-python/virtualenv.py')}, 11:59:56 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:59:56 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:59:56 INFO - 'installer_path': 'installer.exe', 11:59:56 INFO - 'log_level': 'info', 11:59:56 INFO - 'log_name': 'talos', 11:59:56 INFO - 'log_to_console': True, 11:59:56 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:59:56 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:59:56 INFO - 'opt_config_files': (), 11:59:56 INFO - 'pip_index': False, 11:59:56 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:59:56 INFO - 'cmd': ('bash', 11:59:56 INFO - '-c', 11:59:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:59:56 INFO - 'enabled': True, 11:59:56 INFO - 'halt_on_failure': False, 11:59:56 INFO - 'name': 'check_screen_resolution'},), 11:59:56 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:59:56 INFO - 'cmd': ('bash', 11:59:56 INFO - '-c', 11:59:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:59:56 INFO - 'enabled': True, 11:59:56 INFO - 'halt_on_failure': False, 11:59:56 INFO - 'name': 'check_screen_resolution'},), 11:59:56 INFO - 'run_cmd_checks_enabled': True, 11:59:56 INFO - 'sps_profile': False, 11:59:56 INFO - 'sps_profile_interval': 0, 11:59:56 INFO - 'suite': 'dromaeojs-e10s', 11:59:56 INFO - 'system_bits': '32', 11:59:56 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:59:56 INFO - 'title': 't-yosemite-r7-0343', 11:59:56 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:59:56 INFO - 'use_talos_json': True, 11:59:56 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:59:56 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:59:56 INFO - 'work_dir': 'build'} 11:59:56 INFO - [mozharness: 2016-11-23 19:59:56.331959Z] Running clobber step. 11:59:56 INFO - Running pre-action listener: _resource_record_pre_action 11:59:56 INFO - Running main action method: clobber 11:59:56 INFO - rmtree: /builds/slave/test/build 11:59:56 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:59:58 INFO - Running post-action listener: _resource_record_post_action 11:59:58 INFO - [mozharness: 2016-11-23 19:59:58.678452Z] Finished clobber step (success) 11:59:58 INFO - [mozharness: 2016-11-23 19:59:58.678619Z] Running read-buildbot-config step. 11:59:58 INFO - Running pre-action listener: _resource_record_pre_action 11:59:58 INFO - Running main action method: read_buildbot_config 11:59:58 INFO - Using buildbot properties: 11:59:58 INFO - { 11:59:58 INFO - "project": "", 11:59:58 INFO - "product": "firefox", 11:59:58 INFO - "script_repo_revision": "default", 11:59:58 INFO - "builddir": "graphics_yosemite_r7_test-dromaeojs-e10s", 11:59:58 INFO - "repository": "", 11:59:58 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 graphics talos dromaeojs-e10s", 11:59:58 INFO - "buildid": "20161123081537", 11:59:58 INFO - "pgo_build": "False", 11:59:58 INFO - "basedir": "/builds/slave/test", 11:59:58 INFO - "buildnumber": 2, 11:59:58 INFO - "slavename": "t-yosemite-r7-0343", 11:59:58 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 11:59:58 INFO - "platform": "yosemite_r7", 11:59:58 INFO - "scheduler": "tests-graphics-macosx64-talos-8-3600", 11:59:58 INFO - "branch": "graphics", 11:59:58 INFO - "repo_path": "projects/graphics", 11:59:58 INFO - "revision": "81b8be8cac805809cb42651ab90befa5cf51d75c", 11:59:58 INFO - "stage_platform": "macosx64", 11:59:58 INFO - "builduid": "d6f154c85f264270867d1fdd476d9f61", 11:59:58 INFO - "slavebuilddir": "test" 11:59:58 INFO - } 11:59:58 INFO - Found installer url https://queue.taskcluster.net/v1/task/VIx2gix_QfO6j9SATyQQEA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 11:59:58 INFO - Running post-action listener: _resource_record_post_action 11:59:58 INFO - [mozharness: 2016-11-23 19:59:58.681841Z] Finished read-buildbot-config step (success) 11:59:58 INFO - [mozharness: 2016-11-23 19:59:58.681946Z] Running download-and-extract step. 11:59:58 INFO - Running pre-action listener: _resource_record_pre_action 11:59:58 INFO - Running main action method: download_and_extract 11:59:58 INFO - mkdir: /builds/slave/test/build/tests 11:59:58 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')]} 11:59:58 INFO - trying https://queue.taskcluster.net/v1/task/VIx2gix_QfO6j9SATyQQEA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 11:59:58 INFO - Downloading https://queue.taskcluster.net/v1/task/VIx2gix_QfO6j9SATyQQEA/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 11:59:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VIx2gix_QfO6j9SATyQQEA/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 11:59:59 INFO - Downloaded 1199 bytes. 11:59:59 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 11:59:59 INFO - Using the following test package requirements: 11:59:59 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 11:59:59 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 11:59:59 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 11:59:59 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 11:59:59 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 11:59:59 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 11:59:59 INFO - u'jsshell-mac.zip'], 11:59:59 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 11:59:59 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 11:59:59 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 11:59:59 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 11:59:59 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 11:59:59 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 11:59:59 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 11:59:59 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 11:59:59 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 11:59:59 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 11:59:59 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 11:59:59 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 11:59:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VIx2gix_QfO6j9SATyQQEA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 11:59:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VIx2gix_QfO6j9SATyQQEA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 11:59:59 INFO - Fetch https://queue.taskcluster.net/v1/task/VIx2gix_QfO6j9SATyQQEA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 12:00:00 INFO - Http code: 200 12:00:00 INFO - via: 1.1 d897b81191cbfd68a1b6fc44f98c52b0.cloudfront.net (CloudFront) 12:00:00 INFO - x-amz-cf-id: sNMNImp3F4ZkCWu-9CxEQ9tdXYpmw3AtUOMZP1g_uZjPozOxqaL8Kg== 12:00:00 INFO - x-amz-version-id: DvvWBIjVnbPzy17tvSlVra8u0W7auwJj 12:00:00 INFO - Expected file size: 16267212 12:00:00 INFO - Obtained file size: 16267212 12:00:07 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 12:00:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VIx2gix_QfO6j9SATyQQEA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 12:00:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VIx2gix_QfO6j9SATyQQEA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:00:07 INFO - Fetch https://queue.taskcluster.net/v1/task/VIx2gix_QfO6j9SATyQQEA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 12:00:08 INFO - Http code: 200 12:00:08 INFO - via: 1.1 5c2ff4ca1e447265402af29264e83497.cloudfront.net (CloudFront) 12:00:08 INFO - x-amz-cf-id: NwRDepJusWi0p0C56Y1FpFvRUhn65JVQk75fbuwQvXJG82D8wIm9_A== 12:00:08 INFO - x-amz-version-id: DvvWBIjVnbPzy17tvSlVra8u0W7auwJj 12:00:08 INFO - Expected file size: 16267212 12:00:08 INFO - Obtained file size: 16267212 12:00:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VIx2gix_QfO6j9SATyQQEA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 12:00:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VIx2gix_QfO6j9SATyQQEA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 12:00:14 INFO - Fetch https://queue.taskcluster.net/v1/task/VIx2gix_QfO6j9SATyQQEA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 12:00:15 INFO - Http code: 200 12:00:15 INFO - via: 1.1 37af14ee951b12568cf1cc160d08abe6.cloudfront.net (CloudFront) 12:00:15 INFO - x-amz-cf-id: BCdWNFdvlzn_svTeDjy5ifmEpAD4NvvhlEY117A8U5fLmMGJMaaH4g== 12:00:15 INFO - x-amz-version-id: 6Yuao_10746DxE2HQKr_Cikzyuj.aNoj 12:00:15 INFO - Expected file size: 14409506 12:00:15 INFO - Obtained file size: 14409506 12:00:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:00:15 INFO - trying https://queue.taskcluster.net/v1/task/VIx2gix_QfO6j9SATyQQEA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 12:00:15 INFO - Downloading https://queue.taskcluster.net/v1/task/VIx2gix_QfO6j9SATyQQEA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 12:00:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VIx2gix_QfO6j9SATyQQEA/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 12:00:18 INFO - Downloaded 107592402 bytes. 12:00:18 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VIx2gix_QfO6j9SATyQQEA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 12:00:18 INFO - mkdir: /builds/slave/test/properties 12:00:18 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:00:18 INFO - Writing to file /builds/slave/test/properties/build_url 12:00:18 INFO - Contents: 12:00:18 INFO - build_url:https://queue.taskcluster.net/v1/task/VIx2gix_QfO6j9SATyQQEA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 12:00:19 INFO - Running post-action listener: _resource_record_post_action 12:00:19 INFO - Running post-action listener: set_extra_try_arguments 12:00:19 INFO - [mozharness: 2016-11-23 20:00:19.228786Z] Finished download-and-extract step (success) 12:00:19 INFO - [mozharness: 2016-11-23 20:00:19.228921Z] Running populate-webroot step. 12:00:19 INFO - Running pre-action listener: _resource_record_pre_action 12:00:19 INFO - Running main action method: populate_webroot 12:00:19 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:00:19 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:00:19 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:00:19 INFO - '--tppagecycles', 12:00:19 INFO - '7'], 12:00:19 INFO - 'tests': ['tsvgm']}}, 12:00:19 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:00:19 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:00:19 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:00:19 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:00:19 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:00:19 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:00:19 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 12:00:19 INFO - 'talos_options': ['--disable-e10s'], 12:00:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:00:19 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:00:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:00:19 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 12:00:19 INFO - 'talos_options': ['--disable-e10s'], 12:00:19 INFO - 'tests': ['damp', 'tps']}, 12:00:19 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:00:19 INFO - 'tests': ['damp', 'tps']}, 12:00:19 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:00:19 INFO - 'tests': ['dromaeo_dom']}, 12:00:19 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:00:19 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:00:19 INFO - 'tests': ['basic_compositor_video']}, 12:00:19 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:00:19 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:00:19 INFO - 'tests': ['a11yr', 12:00:19 INFO - 'ts_paint', 12:00:19 INFO - 'tpaint', 12:00:19 INFO - 'sessionrestore', 12:00:19 INFO - 'sessionrestore_no_auto_restore', 12:00:19 INFO - 'tabpaint']}, 12:00:19 INFO - 'other-e10s': {'tests': ['a11yr', 12:00:19 INFO - 'ts_paint', 12:00:19 INFO - 'tpaint', 12:00:19 INFO - 'sessionrestore', 12:00:19 INFO - 'sessionrestore_no_auto_restore', 12:00:19 INFO - 'tabpaint']}, 12:00:19 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:00:19 INFO - 'ts_paint', 12:00:19 INFO - 'tpaint', 12:00:19 INFO - 'sessionrestore', 12:00:19 INFO - 'sessionrestore_no_auto_restore', 12:00:19 INFO - 'tabpaint']}, 12:00:19 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:00:19 INFO - 'ts_paint', 12:00:19 INFO - 'tpaint', 12:00:19 INFO - 'sessionrestore', 12:00:19 INFO - 'sessionrestore_no_auto_restore', 12:00:19 INFO - 'tabpaint']}, 12:00:19 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:00:19 INFO - 'tests': ['a11yr', 12:00:19 INFO - 'ts_paint', 12:00:19 INFO - 'tpaint', 12:00:19 INFO - 'sessionrestore', 12:00:19 INFO - 'sessionrestore_no_auto_restore', 12:00:19 INFO - 'tabpaint']}, 12:00:19 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:00:19 INFO - 'tests': ['a11yr', 12:00:19 INFO - 'ts_paint', 12:00:19 INFO - 'tpaint', 12:00:19 INFO - 'sessionrestore', 12:00:19 INFO - 'sessionrestore_no_auto_restore', 12:00:19 INFO - 'tabpaint']}, 12:00:19 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:00:19 INFO - 'tests': ['tsvgx', 12:00:19 INFO - 'tsvgr_opacity', 12:00:19 INFO - 'tart', 12:00:19 INFO - 'tscrollx', 12:00:19 INFO - 'cart']}, 12:00:19 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:00:19 INFO - 'tsvgr_opacity', 12:00:19 INFO - 'tart', 12:00:19 INFO - 'tscrollx', 12:00:19 INFO - 'cart']}, 12:00:19 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 12:00:19 INFO - 'talos_options': ['--disable-e10s'], 12:00:19 INFO - 'tests': ['tp5o']}, 12:00:19 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:00:19 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 12:00:19 INFO - 'talos_options': ['--disable-e10s', 12:00:19 INFO - '--xperf_path', 12:00:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:00:19 INFO - 'tests': ['tp5n']}, 12:00:19 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:00:19 INFO - 'talos_options': ['--xperf_path', 12:00:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:00:19 INFO - 'tests': ['tp5n']}}, 12:00:19 INFO - 'talos.zip': {'path': '', 12:00:19 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:00:19 INFO - Running post-action listener: _resource_record_post_action 12:00:19 INFO - [mozharness: 2016-11-23 20:00:19.247583Z] Finished populate-webroot step (success) 12:00:19 INFO - [mozharness: 2016-11-23 20:00:19.247721Z] Running create-virtualenv step. 12:00:19 INFO - Running pre-action listener: _resource_record_pre_action 12:00:19 INFO - Running main action method: create_virtualenv 12:00:19 INFO - Creating virtualenv /builds/slave/test/build/venv 12:00:19 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 12:00:19 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:00:19 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:00:19 INFO - Using real prefix '/tools/python27' 12:00:19 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:00:20 INFO - Installing distribute.............................................................................................................................................................................................done. 12:00:22 INFO - Installing pip.................done. 12:00:22 INFO - Return code: 0 12:00:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:00:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:00:23 INFO - Reading from file tmpfile_stdout 12:00:23 INFO - Output received: 12:00:23 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) 12:00:23 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:00: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')]} 12:00:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:00:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:00: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')]} 12:00:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:00:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:00:23 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 0x10c7853f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6fb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb27957e520>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c59bd30>, '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 0x10c402db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c593b20>, '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.6zs13kVB34/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.4Ye8jQ5W1O/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/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 12:00:23 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 12:00:23 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 12:00:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6zs13kVB34/Render', 12:00:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:00:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:00:23 INFO - 'HOME': '/Users/cltbld', 12:00:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:00:23 INFO - 'LOGNAME': 'cltbld', 12:00:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:00:23 INFO - 'MOZ_NO_REMOTE': '1', 12:00:23 INFO - 'NO_EM_RESTART': '1', 12:00:23 INFO - 'PAGER': '/bin/cat', 12:00:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:00:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:00:23 INFO - 'PWD': '/builds/slave/test', 12:00:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:00:23 INFO - 'SHELL': '/bin/bash', 12:00:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4Ye8jQ5W1O/Listeners', 12:00:23 INFO - 'TMPDIR': '/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/', 12:00:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:00:23 INFO - 'USER': 'cltbld', 12:00:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:00:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:00:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:00:23 INFO - 'XPC_FLAGS': '0x0', 12:00:23 INFO - 'XPC_SERVICE_NAME': '0', 12:00:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:00:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:00:23 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 12:00:23 INFO - Cleaning up... 12:00:23 INFO - Return code: 0 12:00:23 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:00: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')]} 12:00:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:00:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:00: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')]} 12:00:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:00:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:00:23 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 0x10c7853f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6fb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb27957e520>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c59bd30>, '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 0x10c402db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c593b20>, '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.6zs13kVB34/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.4Ye8jQ5W1O/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/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 12:00:23 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 12:00:23 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 12:00:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6zs13kVB34/Render', 12:00:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:00:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:00:23 INFO - 'HOME': '/Users/cltbld', 12:00:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:00:23 INFO - 'LOGNAME': 'cltbld', 12:00:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:00:23 INFO - 'MOZ_NO_REMOTE': '1', 12:00:23 INFO - 'NO_EM_RESTART': '1', 12:00:23 INFO - 'PAGER': '/bin/cat', 12:00:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:00:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:00:23 INFO - 'PWD': '/builds/slave/test', 12:00:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:00:23 INFO - 'SHELL': '/bin/bash', 12:00:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4Ye8jQ5W1O/Listeners', 12:00:23 INFO - 'TMPDIR': '/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/', 12:00:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:00:23 INFO - 'USER': 'cltbld', 12:00:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:00:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:00:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:00:23 INFO - 'XPC_FLAGS': '0x0', 12:00:23 INFO - 'XPC_SERVICE_NAME': '0', 12:00:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:00:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:00:23 INFO - Downloading/unpacking psutil>=3.1.1 12:00:23 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 12:00:23 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 12:00:23 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 12:00:23 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 12:00:23 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 12:00:23 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 12:00:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:00:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:00:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:00:27 INFO - Installing collected packages: psutil 12:00:27 INFO - Running setup.py install for psutil 12:00:27 INFO - building 'psutil._psutil_osx' extension 12:00:27 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 12:00:27 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 12:00:27 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 12:00:28 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 12:00:28 INFO - building 'psutil._psutil_posix' extension 12:00:28 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 12:00:28 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:00:28 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:00:28 INFO - ^ 12:00:28 INFO - 1 warning generated. 12:00:28 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 12:00:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:00:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:00:28 INFO - Successfully installed psutil 12:00:28 INFO - Cleaning up... 12:00:28 INFO - Return code: 0 12:00:28 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:00: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')]} 12:00:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:00:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:00: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')]} 12:00:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:00:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:00: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c7853f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6fb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb27957e520>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c59bd30>, '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 0x10c402db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c593b20>, '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.6zs13kVB34/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.4Ye8jQ5W1O/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/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 12:00: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 12:00: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 mozsystemmonitor==0.3 12:00:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6zs13kVB34/Render', 12:00:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:00:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:00:28 INFO - 'HOME': '/Users/cltbld', 12:00:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:00:28 INFO - 'LOGNAME': 'cltbld', 12:00:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:00:28 INFO - 'MOZ_NO_REMOTE': '1', 12:00:28 INFO - 'NO_EM_RESTART': '1', 12:00:28 INFO - 'PAGER': '/bin/cat', 12:00:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:00:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:00:28 INFO - 'PWD': '/builds/slave/test', 12:00:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:00:28 INFO - 'SHELL': '/bin/bash', 12:00:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4Ye8jQ5W1O/Listeners', 12:00:28 INFO - 'TMPDIR': '/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/', 12:00:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:00:28 INFO - 'USER': 'cltbld', 12:00:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:00:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:00:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:00:28 INFO - 'XPC_FLAGS': '0x0', 12:00:28 INFO - 'XPC_SERVICE_NAME': '0', 12:00:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:00:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:00:28 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:00:28 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 12:00:28 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 12:00:28 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 12:00:28 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 12:00:28 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 12:00:28 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 12:00:31 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:00:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:00:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:00:31 INFO - Installing collected packages: mozsystemmonitor 12:00:31 INFO - Running setup.py install for mozsystemmonitor 12:00:31 INFO - Successfully installed mozsystemmonitor 12:00:31 INFO - Cleaning up... 12:00:31 INFO - Return code: 0 12:00:31 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:00: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')]} 12:00:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:00:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:00: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')]} 12:00:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:00:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:00:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c7853f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6fb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb27957e520>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c59bd30>, '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 0x10c402db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c593b20>, '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.6zs13kVB34/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.4Ye8jQ5W1O/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/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 12:00:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 12:00:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 12:00:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6zs13kVB34/Render', 12:00:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:00:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:00:31 INFO - 'HOME': '/Users/cltbld', 12:00:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:00:31 INFO - 'LOGNAME': 'cltbld', 12:00:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:00:31 INFO - 'MOZ_NO_REMOTE': '1', 12:00:31 INFO - 'NO_EM_RESTART': '1', 12:00:31 INFO - 'PAGER': '/bin/cat', 12:00:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:00:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:00:31 INFO - 'PWD': '/builds/slave/test', 12:00:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:00:31 INFO - 'SHELL': '/bin/bash', 12:00:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4Ye8jQ5W1O/Listeners', 12:00:31 INFO - 'TMPDIR': '/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/', 12:00:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:00:31 INFO - 'USER': 'cltbld', 12:00:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:00:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:00:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:00:31 INFO - 'XPC_FLAGS': '0x0', 12:00:31 INFO - 'XPC_SERVICE_NAME': '0', 12:00:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:00:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:00:31 INFO - Downloading/unpacking jsonschema==2.5.1 12:00:31 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 12:00:31 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 12:00:31 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 12:00:31 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 12:00:31 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 12:00:31 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 12:00:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:00:34 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:00:34 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 12:00:34 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 12:00:34 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 12:00:34 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 12:00:34 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 12:00:34 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 12:00:34 INFO - Downloading functools32-3.2.3-2.tar.gz 12:00:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:00:34 INFO - warning: no files found matching '*.txt' 12:00:34 INFO - no previously-included directories found matching 'build' 12:00:34 INFO - no previously-included directories found matching 'dist' 12:00:34 INFO - no previously-included directories found matching '.git*' 12:00:34 INFO - Installing collected packages: jsonschema, functools32 12:00:34 INFO - Running setup.py install for functools32 12:00:35 INFO - warning: no files found matching '*.txt' 12:00:35 INFO - no previously-included directories found matching 'build' 12:00:35 INFO - no previously-included directories found matching 'dist' 12:00:35 INFO - no previously-included directories found matching '.git*' 12:00:35 INFO - Successfully installed jsonschema functools32 12:00:35 INFO - Cleaning up... 12:00:35 INFO - Return code: 0 12:00:35 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:00:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:00:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:00:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:00:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:00:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:00:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:00:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10c7853f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6fb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb27957e520>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c59bd30>, '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 0x10c402db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c593b20>, '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.6zs13kVB34/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.4Ye8jQ5W1O/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/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 12:00:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 12:00:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 12:00:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6zs13kVB34/Render', 12:00:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:00:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:00:35 INFO - 'HOME': '/Users/cltbld', 12:00:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:00:35 INFO - 'LOGNAME': 'cltbld', 12:00:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:00:35 INFO - 'MOZ_NO_REMOTE': '1', 12:00:35 INFO - 'NO_EM_RESTART': '1', 12:00:35 INFO - 'PAGER': '/bin/cat', 12:00:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:00:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:00:35 INFO - 'PWD': '/builds/slave/test', 12:00:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:00:35 INFO - 'SHELL': '/bin/bash', 12:00:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4Ye8jQ5W1O/Listeners', 12:00:35 INFO - 'TMPDIR': '/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/', 12:00:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:00:35 INFO - 'USER': 'cltbld', 12:00:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:00:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:00:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:00:35 INFO - 'XPC_FLAGS': '0x0', 12:00:35 INFO - 'XPC_SERVICE_NAME': '0', 12:00:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:00:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:00:35 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:00:35 INFO - Cleaning up... 12:00:35 INFO - Return code: 0 12:00:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:00:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:00:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:00:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:00:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:00:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:00:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:00:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10c7853f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6fb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb27957e520>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c59bd30>, '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 0x10c402db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c593b20>, '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.6zs13kVB34/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.4Ye8jQ5W1O/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/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 12:00:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:00:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 12:00:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6zs13kVB34/Render', 12:00:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:00:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:00:35 INFO - 'HOME': '/Users/cltbld', 12:00:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:00:35 INFO - 'LOGNAME': 'cltbld', 12:00:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:00:35 INFO - 'MOZ_NO_REMOTE': '1', 12:00:35 INFO - 'NO_EM_RESTART': '1', 12:00:35 INFO - 'PAGER': '/bin/cat', 12:00:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:00:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:00:35 INFO - 'PWD': '/builds/slave/test', 12:00:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:00:35 INFO - 'SHELL': '/bin/bash', 12:00:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4Ye8jQ5W1O/Listeners', 12:00:35 INFO - 'TMPDIR': '/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/', 12:00:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:00:35 INFO - 'USER': 'cltbld', 12:00:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:00:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:00:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:00:35 INFO - 'XPC_FLAGS': '0x0', 12:00:35 INFO - 'XPC_SERVICE_NAME': '0', 12:00:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:00:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:00:35 INFO - Downloading/unpacking blobuploader==1.2.4 12:00:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:00:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:00:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:00:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:00:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:00:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:00:38 INFO - Downloading blobuploader-1.2.4.tar.gz 12:00:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:00:38 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:00:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:00:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:00:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:00:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:00:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:00:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:00:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:00:39 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:00:39 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 12:00:39 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 12:00:39 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 12:00:39 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 12:00:39 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 12:00:39 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 12:00:39 INFO - Downloading docopt-0.6.1.tar.gz 12:00:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:00:39 INFO - Installing collected packages: blobuploader, requests, docopt 12:00:39 INFO - Running setup.py install for blobuploader 12:00:39 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:00:39 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:00:39 INFO - Running setup.py install for requests 12:00:40 INFO - Running setup.py install for docopt 12:00:40 INFO - Successfully installed blobuploader requests docopt 12:00:40 INFO - Cleaning up... 12:00:40 INFO - Return code: 0 12:00:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:00:40 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')]} 12:00:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:00:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:00:40 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')]} 12:00:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:00:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:00:40 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 0x10c7853f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6fb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb27957e520>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c59bd30>, '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 0x10c402db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c593b20>, '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.6zs13kVB34/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.4Ye8jQ5W1O/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/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 12:00:40 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 12:00:40 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 12:00:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6zs13kVB34/Render', 12:00:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:00:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:00:40 INFO - 'HOME': '/Users/cltbld', 12:00:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:00:40 INFO - 'LOGNAME': 'cltbld', 12:00:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:00:40 INFO - 'MOZ_NO_REMOTE': '1', 12:00:40 INFO - 'NO_EM_RESTART': '1', 12:00:40 INFO - 'PAGER': '/bin/cat', 12:00:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:00:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:00:40 INFO - 'PWD': '/builds/slave/test', 12:00:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:00:40 INFO - 'SHELL': '/bin/bash', 12:00:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4Ye8jQ5W1O/Listeners', 12:00:40 INFO - 'TMPDIR': '/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/', 12:00:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:00:40 INFO - 'USER': 'cltbld', 12:00:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:00:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:00:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:00:40 INFO - 'XPC_FLAGS': '0x0', 12:00:40 INFO - 'XPC_SERVICE_NAME': '0', 12:00:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:00:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:00:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:00:40 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-LUW0t0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:00:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:00:40 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-Kzeb25-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:00:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:00:40 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-0Sqq1w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:00:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:00:40 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-BExemD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:00:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:00:41 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-Xpv8sb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:00:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:00:41 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-w5iPT8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:00:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:00:41 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-thYrMx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:00:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:00:41 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-zpx1kD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:00:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:00:41 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-Ab2mYh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:00:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:00:41 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-OH3wlv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:00:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:00:41 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-yNst6S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:00:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:00:41 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-USmE2W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:00:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:00:41 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-TJtskB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:00:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:00:41 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-fxMGgK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:00:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:00:42 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-f2oLQ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:00:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:00:42 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-QRi8eS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:00:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:00:42 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-Rfy2WA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:00:42 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:00:42 INFO - Running setup.py install for manifestparser 12:00:42 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:00:42 INFO - Running setup.py install for mozcrash 12:00:42 INFO - Running setup.py install for mozdebug 12:00:42 INFO - Running setup.py install for mozdevice 12:00:42 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:00:42 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:00:43 INFO - Running setup.py install for mozfile 12:00:43 INFO - Running setup.py install for mozhttpd 12:00:43 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:00:43 INFO - Running setup.py install for mozinfo 12:00:43 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:00:43 INFO - Running setup.py install for mozInstall 12:00:43 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:00:43 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:00:43 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:00:43 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:00:43 INFO - Running setup.py install for mozleak 12:00:43 INFO - Running setup.py install for mozlog 12:00:43 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:00:43 INFO - Running setup.py install for moznetwork 12:00:44 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:00:44 INFO - Running setup.py install for mozprocess 12:00:44 INFO - Running setup.py install for mozprofile 12:00:44 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:00:44 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:00:44 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:00:44 INFO - Running setup.py install for mozrunner 12:00:44 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:00:44 INFO - Running setup.py install for mozscreenshot 12:00:44 INFO - Running setup.py install for moztest 12:00:44 INFO - Running setup.py install for mozversion 12:00:44 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:00:44 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:00:44 INFO - Cleaning up... 12:00:45 INFO - Return code: 0 12:00:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:00:45 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')]} 12:00:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:00:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:00:45 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')]} 12:00:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:00:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:00:45 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 0x10c7853f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6fb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb27957e520>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c59bd30>, '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 0x10c402db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c593b20>, '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.6zs13kVB34/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.4Ye8jQ5W1O/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/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 12:00:45 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 12:00:45 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 12:00:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6zs13kVB34/Render', 12:00:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:00:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:00:45 INFO - 'HOME': '/Users/cltbld', 12:00:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:00:45 INFO - 'LOGNAME': 'cltbld', 12:00:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:00:45 INFO - 'MOZ_NO_REMOTE': '1', 12:00:45 INFO - 'NO_EM_RESTART': '1', 12:00:45 INFO - 'PAGER': '/bin/cat', 12:00:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:00:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:00:45 INFO - 'PWD': '/builds/slave/test', 12:00:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:00:45 INFO - 'SHELL': '/bin/bash', 12:00:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4Ye8jQ5W1O/Listeners', 12:00:45 INFO - 'TMPDIR': '/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/', 12:00:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:00:45 INFO - 'USER': 'cltbld', 12:00:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:00:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:00:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:00:45 INFO - 'XPC_FLAGS': '0x0', 12:00:45 INFO - 'XPC_SERVICE_NAME': '0', 12:00:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:00:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:00:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:00:45 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-wUx3iD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:00:45 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)) 12:00:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:00:45 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-zTuaQH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:00:45 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)) 12:00:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:00:45 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-X3x7OV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:00:45 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)) 12:00:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:00:45 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-hTGr6r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:00:45 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)) 12:00:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:00:45 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-2MgWpo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:00:45 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)) 12:00:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:00:45 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-FoNI2M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:00:45 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)) 12:00:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:00:45 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-KelqYy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:00:46 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)) 12:00:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:00:46 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-HJzBg3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:00:46 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)) 12:00:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:00:46 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-gzmpqn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:00:46 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)) 12:00:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:00:46 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-b9muNG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:00:46 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)) 12:00:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:00:46 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-0z1VI3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:00:46 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)) 12:00:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:00:46 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-2WvcFl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:00:46 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)) 12:00:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:00:46 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-07YpqY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:00:46 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)) 12:00:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:00:46 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-W7BPwt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:00:46 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)) 12:00:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:00:46 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-OS2nXm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:00:46 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)) 12:00:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:00:46 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-xsIQuS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:00:46 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)) 12:00:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:00:46 INFO - Running setup.py (path:/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/pip-jYsRrU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:00:47 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)) 12:00:47 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)) 12:00:47 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)) 12:00:47 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)) 12:00:47 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)) 12:00:47 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)) 12:00:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:00:47 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 12:00:47 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 12:00:47 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 12:00:47 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 12:00:47 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 12:00:47 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 12:00:49 INFO - Downloading blessings-1.6.tar.gz 12:00:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:00:49 INFO - Installing collected packages: blessings 12:00:49 INFO - Running setup.py install for blessings 12:00:49 INFO - Successfully installed blessings 12:00:49 INFO - Cleaning up... 12:00:50 INFO - Return code: 0 12:00:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:00:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:00:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:00:50 INFO - Reading from file tmpfile_stdout 12:00:50 INFO - Current package versions: 12:00:50 INFO - blessings == 1.6 12:00:50 INFO - blobuploader == 1.2.4 12:00:50 INFO - docopt == 0.6.1 12:00:50 INFO - functools32 == 3.2.3-2 12:00:50 INFO - jsonschema == 2.5.1 12:00:50 INFO - manifestparser == 1.1 12:00:50 INFO - mozInstall == 1.12 12:00:50 INFO - mozcrash == 1.0 12:00:50 INFO - mozdebug == 0.1 12:00:50 INFO - mozdevice == 0.49 12:00:50 INFO - mozfile == 1.2 12:00:50 INFO - mozhttpd == 0.7 12:00:50 INFO - mozinfo == 0.9 12:00:50 INFO - mozleak == 0.1 12:00:50 INFO - mozlog == 3.4 12:00:50 INFO - moznetwork == 0.27 12:00:50 INFO - mozprocess == 0.23 12:00:50 INFO - mozprofile == 0.28 12:00:50 INFO - mozrunner == 6.13 12:00:50 INFO - mozscreenshot == 0.1 12:00:50 INFO - mozsystemmonitor == 0.3 12:00:50 INFO - moztest == 0.7 12:00:50 INFO - mozversion == 1.4 12:00:50 INFO - psutil == 3.1.1 12:00:50 INFO - requests == 1.2.3 12:00:50 INFO - wsgiref == 0.1.2 12:00:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:00: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')]} 12:00:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:00:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:00:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:00:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:00:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:00:51 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 0x10c7853f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6fb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb27957e520>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c59bd30>, '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 0x10c402db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c593b20>, '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.6zs13kVB34/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.4Ye8jQ5W1O/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/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 12:00:51 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 12:00:51 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 12:00:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6zs13kVB34/Render', 12:00:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:00:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:00:51 INFO - 'HOME': '/Users/cltbld', 12:00:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:00:51 INFO - 'LOGNAME': 'cltbld', 12:00:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:00:51 INFO - 'MOZ_NO_REMOTE': '1', 12:00:51 INFO - 'NO_EM_RESTART': '1', 12:00:51 INFO - 'PAGER': '/bin/cat', 12:00:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:00:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:00:51 INFO - 'PWD': '/builds/slave/test', 12:00:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:00:51 INFO - 'SHELL': '/bin/bash', 12:00:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4Ye8jQ5W1O/Listeners', 12:00:51 INFO - 'TMPDIR': '/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/', 12:00:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:00:51 INFO - 'USER': 'cltbld', 12:00:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:00:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:00:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:00:51 INFO - 'XPC_FLAGS': '0x0', 12:00:51 INFO - 'XPC_SERVICE_NAME': '0', 12:00:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:00:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:00:51 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)) 12:00:51 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)) 12:00:51 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)) 12:00:51 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)) 12:00:51 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)) 12:00:51 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)) 12:00:51 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)) 12:00:51 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)) 12:00:51 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)) 12:00:51 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)) 12:00:51 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)) 12:00:51 INFO - Cleaning up... 12:00:51 INFO - Return code: 0 12:00:51 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:00:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:00:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:00:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:00:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:00:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:00:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:00:52 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 0x10c7853f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6fb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb27957e520>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c59bd30>, '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 0x10c402db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c593b20>, '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.6zs13kVB34/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.4Ye8jQ5W1O/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/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 12:00:52 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 12:00:52 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 12:00:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6zs13kVB34/Render', 12:00:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:00:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:00:52 INFO - 'HOME': '/Users/cltbld', 12:00:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:00:52 INFO - 'LOGNAME': 'cltbld', 12:00:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:00:52 INFO - 'MOZ_NO_REMOTE': '1', 12:00:52 INFO - 'NO_EM_RESTART': '1', 12:00:52 INFO - 'PAGER': '/bin/cat', 12:00:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:00:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:00:52 INFO - 'PWD': '/builds/slave/test', 12:00:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:00:52 INFO - 'SHELL': '/bin/bash', 12:00:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4Ye8jQ5W1O/Listeners', 12:00:52 INFO - 'TMPDIR': '/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/', 12:00:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:00:52 INFO - 'USER': 'cltbld', 12:00:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:00:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:00:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:00:52 INFO - 'XPC_FLAGS': '0x0', 12:00:52 INFO - 'XPC_SERVICE_NAME': '0', 12:00:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:00:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:00:52 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:00:52 INFO - Cleaning up... 12:00:52 INFO - Return code: 0 12:00:52 INFO - Running post-action listener: _resource_record_post_action 12:00:52 INFO - Running post-action listener: _start_resource_monitoring 12:00:52 INFO - Starting resource monitoring. 12:00:52 INFO - [mozharness: 2016-11-23 20:00:52.370232Z] Finished create-virtualenv step (success) 12:00:52 INFO - [mozharness: 2016-11-23 20:00:52.371212Z] Running install step. 12:00:52 INFO - Running pre-action listener: _resource_record_pre_action 12:00:52 INFO - Running main action method: install 12:00:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:00:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:00:52 INFO - Reading from file tmpfile_stdout 12:00:52 INFO - Detecting whether we're running mozinstall >=1.0... 12:00:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:00:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:00:52 INFO - Reading from file tmpfile_stdout 12:00:52 INFO - Output received: 12:00:52 INFO - Usage: mozinstall [options] installer 12:00:52 INFO - Options: 12:00:52 INFO - -h, --help show this help message and exit 12:00:52 INFO - -d DEST, --destination=DEST 12:00:52 INFO - Directory to install application into. [default: 12:00:52 INFO - "/builds/slave/test"] 12:00:52 INFO - --app=APP Application being installed. [default: firefox] 12:00:52 INFO - mkdir: /builds/slave/test/build/application 12:00:52 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'] 12:00:52 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 12:01:26 INFO - Reading from file tmpfile_stdout 12:01:26 INFO - Output received: 12:01:26 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 12:01:26 INFO - Running post-action listener: _resource_record_post_action 12:01:26 INFO - [mozharness: 2016-11-23 20:01:26.027442Z] Finished install step (success) 12:01:26 INFO - [mozharness: 2016-11-23 20:01:26.027568Z] Running run-tests step. 12:01:26 INFO - Running pre-action listener: _resource_record_pre_action 12:01:26 INFO - Running main action method: run_tests 12:01:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:01:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:01:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:01:26 INFO - 2016-11-23 12:01:26.040 screenresolution[1621:7507] starting screenresolution argv=screenresolution get 12:01:26 INFO - 2016-11-23 12:01:26.042 screenresolution[1621:7507] Display 0: 1600x1200x32@60 12:01:26 INFO - 2016-11-23 12:01:26.048 screenresolution[1622:7509] starting screenresolution argv=screenresolution list 12:01:26 INFO - Available Modes on Display 0 12:01:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:01:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:01:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:01:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:01:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:01:26 INFO - Intel Iris: 12:01:26 INFO - Chipset Model: Intel Iris 12:01:26 INFO - Type: GPU 12:01:26 INFO - Bus: Built-In 12:01:26 INFO - VRAM (Dynamic, Max): 1536 MB 12:01:26 INFO - Vendor: Intel (0x8086) 12:01:26 INFO - Device ID: 0x0a2e 12:01:26 INFO - Revision ID: 0x0009 12:01:26 INFO - Displays: 12:01:26 INFO - Display: 12:01:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:01:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:01:26 INFO - Display Serial Number: 1600x1200 60 12:01:26 INFO - Main Display: Yes 12:01:26 INFO - Mirror: Off 12:01:26 INFO - Online: Yes 12:01:26 INFO - Rotation: Supported 12:01:26 INFO - Return code: 0 12:01:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:01:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:01:26 INFO - Python 2.7.3 12:01:26 INFO - Return code: 0 12:01:26 INFO - grabbing minidump binary from tooltool 12:01:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:01:26 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 0x10c59bd30>, '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 0x10c402db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c593b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:01:26 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 12:01:26 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 12:01:26 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 12:01:26 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:01:26 INFO - Return code: 0 12:01:26 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:01:26 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:01:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:01:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:01:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:01:26 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/VIx2gix_QfO6j9SATyQQEA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0343', '--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 12:01:26 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/VIx2gix_QfO6j9SATyQQEA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0343 --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 12:01:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6zs13kVB34/Render', 12:01:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:01:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:01:26 INFO - 'HOME': '/Users/cltbld', 12:01:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:01:26 INFO - 'LOGNAME': 'cltbld', 12:01:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:01:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:01:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:01:26 INFO - 'MOZ_NO_REMOTE': '1', 12:01:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:01:26 INFO - 'NO_EM_RESTART': '1', 12:01:26 INFO - 'PAGER': '/bin/cat', 12:01:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:01:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:01:26 INFO - 'PWD': '/builds/slave/test', 12:01:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:01:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:01:26 INFO - 'SHELL': '/bin/bash', 12:01:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4Ye8jQ5W1O/Listeners', 12:01:26 INFO - 'TMPDIR': '/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/', 12:01:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:01:26 INFO - 'USER': 'cltbld', 12:01:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:01:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:01:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:01:26 INFO - 'XPC_FLAGS': '0x0', 12:01:26 INFO - 'XPC_SERVICE_NAME': '0', 12:01:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:01:26 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/VIx2gix_QfO6j9SATyQQEA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0343', '--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 12:01:26 INFO - mozversion application_buildid: 20161123081537 12:01:26 INFO - mozversion application_changeset: 81b8be8cac805809cb42651ab90befa5cf51d75c 12:01:26 INFO - mozversion application_display_name: Nightly 12:01:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:01:26 INFO - mozversion application_name: Firefox 12:01:26 INFO - mozversion application_remotingname: firefox 12:01:26 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 12:01:26 INFO - mozversion application_vendor: Mozilla 12:01:26 INFO - mozversion application_version: 53.0a1 12:01:26 INFO - mozversion platform_buildid: 20161123081537 12:01:26 INFO - mozversion platform_changeset: 81b8be8cac805809cb42651ab90befa5cf51d75c 12:01:26 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 12:01:26 INFO - mozversion platform_version: 53.0a1 12:01:26 INFO - using testdate: 1479931286 12:01:26 INFO - actual date: 1479931286 12:01:26 INFO - starting webserver on 'localhost:49247' 12:01:26 INFO - SUITE-START | Running 2 tests 12:01:26 INFO - TEST-START | dromaeo_css 12:01:26 INFO - operating with platform_type : mac_ 12:01:26 INFO - Initialising browser for dromaeo_css test... 12:01:26 INFO - TEST-INFO | started process 1632 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/tmpRW84oV/profile http://localhost:49247/getInfo.html) 12:01:29 INFO - PROCESS | 1632 | 2016-11-23 12:01:29.821 plugin-container[1634:7680] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c3f, name = 'com.apple.tsm.portname' 12:01:29 INFO - PROCESS | 1632 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:01:29 INFO - PROCESS | 1632 | 2016-11-23 12:01:29.825 plugin-container[1634:7680] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a1f, name = 'com.apple.CFPasteboardClient' 12:01:29 INFO - PROCESS | 1632 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:01:29 INFO - PROCESS | 1632 | 2016-11-23 12:01:29.825 plugin-container[1634:7680] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:01:29 INFO - PROCESS | 1632 | __metrics Screen width/height:1600/1200 12:01:29 INFO - PROCESS | 1632 | colorDepth:24 12:01:29 INFO - PROCESS | 1632 | Browser inner width/height: 1024/689 12:01:29 INFO - PROCESS | 1632 | __metrics 12:01:30 ERROR - PROCESS | 1632 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3198: TypeError: this.tabs is undefined 12:01:30 INFO - TEST-INFO | 1632: exit 0 12:01:30 INFO - Browser initialized. 12:01:30 INFO - Running cycle 1/1 for dromaeo_css test... 12:01:30 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/tmpRW84oV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:01:33 INFO - PROCESS | 1635 | 2016-11-23 12:01:33.423 plugin-container[1636:7808] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873b, name = 'com.apple.tsm.portname' 12:01:33 INFO - PROCESS | 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:01:33 INFO - PROCESS | 1635 | 2016-11-23 12:01:33.425 plugin-container[1636:7808] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0f, name = 'com.apple.CFPasteboardClient' 12:01:33 INFO - PROCESS | 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:01:33 INFO - PROCESS | 1635 | 2016-11-23 12:01:33.425 plugin-container[1636:7808] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:01:43 INFO - PROCESS | 1635 | RSS: Main: 241328128 12:01:43 INFO - PROCESS | 1635 | 12:02:32 INFO - PROCESS | 1635 | 1479931352774 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] 12:04:46 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49247/tests/dromaeo/cssquery-ext.html) 12:04:48 INFO - PROCESS | 1635 | RSS: Main: 248549376 12:04:48 INFO - PROCESS | 1635 | 12:07:50 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-ext.html (next: http://localhost:49247/tests/dromaeo/cssquery-jquery.html) 12:07:51 INFO - PROCESS | 1635 | RSS: Main: 261054464 12:07:51 INFO - PROCESS | 1635 | 12:10:53 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49247/tests/dromaeo/cssquery-mootools.html) 12:10:54 INFO - PROCESS | 1635 | RSS: Main: 266334208 12:10:54 INFO - PROCESS | 1635 | 12:13:56 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49247/tests/dromaeo/cssquery-prototype.html) 12:13:56 INFO - PROCESS | 1635 | RSS: Main: 265273344 12:13:56 INFO - PROCESS | 1635 | 12:16:59 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49247/tests/dromaeo/cssquery-yui.html) 12:17:00 INFO - PROCESS | 1635 | RSS: Main: 270807040 12:17:00 INFO - PROCESS | 1635 | 12:20:02 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-yui.html (next: http://localhost:49247/tests/dromaeo/cssquery-dojo.html) 12:20:03 INFO - PROCESS | 1635 | RSS: Main: 273350656 12:20:03 INFO - PROCESS | 1635 | 12:20:03 INFO - PROCESS | 1635 | __start_tp_report 12:20:03 INFO - PROCESS | 1635 | _x_x_mozilla_page_load 12:20:03 INFO - PROCESS | 1635 | _x_x_mozilla_page_load_details 12:20:03 INFO - PROCESS | 1635 | |i|pagename|runs| 12:20:03 INFO - PROCESS | 1635 | |0;dojo.html;2547.566913624684;2633;3409.994051754735;3450.6376830432805;3468.167639766455;5693.943060569398;5763;5804.303483581971;5820.417958204177;5841.240638716961;4778.737169455679;5672.716364181785;5860.150278209657;5881.206037184978;5901.793437229698;4973.676711013791;5252.8686782830355;5255.553277971377;5380.488853558905;5394.78420863167;4423.358613001113;5308.124159513675;5392.595555333346;5443;5453.2092846537425;5362.785488580452;5379.9731001345135;5408.783648654049;5438.91841622374;5449;5213.895722085575;5229.189475631283;5244.029854476934;5270.288511050997;5339.893202135974;5194.870128246786;5326.014687282865;5329.493698098674;5374.247605335257;5382.138857782736;5591.133374326986;5858.794942177004;5859.267591551056;5893.5579831512805;5945.821625351246;6139.614800607363;6276.215473065867;6319.905201421982;6354.841128971766;6371.904421433682;5249.685018898878;6256.436920677161;6281.120643109968;6300.369963003709;6317.93682063178;5967.4629283364275;5987.401259874065;5991.460768530809;6001.4298641628975;6011.977963746173;6232.719527621268;6373.681315934185;6408.935910640927;6416.262129855033;6417.743290268337;6077.908831367533;6108.5545605439265;6126.731429653714;6185.536084793568;6199.845003874939;6316.399942005502;6324.114623952651;6326.2092238470195;6332.430081292614;6358.650274234869;6119.490423091814;6120.142398366865;6164.537659675542;6176.289726681399;6298.905516417258;6207.410296021871;6240.500759939194;6328.810135695937;6332.778352757631;6358.936410635927;4479.865604031885;4953.157963146274;4963.8262660806695;5038.2190760432195;5068.898622027613;4430.357598148251;4453.688241823106;4482.731036137777;4505.414296141481;4514.080962419171;5162.638615296968;5863.2670916135485;5915.467607915309;5978.970105149447;6111;5157.303763991888;6241.500679945593;6275.654838983808;6306.590071645328;6363.395477429637;6064.666443345658;6065.3934606539;6093.451589356979;6096.72564734588;6114.631938157225;5529.557635389239;5531.3915469298345;5538.833834984957;5542.667439953616;5553;5014.34813474246;5479.53423958968;5730.971345143247;5923;6234.501239900706;5822.970885145716;6182.474489668249;6236.563440559117;6316.62100273985;6330.987042073246;6421.775237866652;6454.419102280817;6495.480361571157;6501.4798816095545;6505.967470162619;5129.9743501282255;6304.65324407162;6306.936930630818;6308.179936608395;6333.081703153109;6169.876602468015;6173.81478555644;6275.717592708431;6282.1519094921605;6337.4613157882095;5528.944710552843;5973.701314934323;6007.038873780174;6036.879262414639;6160.44555989963;6016.007358785926;6132.724027418866;6135.875804921736;6172;6192.133101365723;6030.4572588467245;6110.388961104032;6132.356102609162;6151.723172457339;6165.876682466415;5210.739463026909;6009.098635204754;6238.220222472191;6280.586867954674;6293.307736148929;6028.638281703112;6074.42292982166;6124.991279909952;6126.938730612636;6187.84530386726 12:20:03 INFO - PROCESS | 1635 | |1;ext.html;467.5418090271533;492.17806263539904;504.29147048397016;520.3807469111757;544.1375419959364;35798.642013579854;39946.60053399465;40079;40170.799146004305;40294;39006;39415.802920985356;39530.60469395305;39536.802315988454;39706;8330.750077497685;9186.49474278916;9335.113164249386;9337.906620933805;9376.109269619375;8384.161583841627;10947.397893115876;11202.75944811037;11226.158038147172;11380.487878045466;11247;11304.886951130466;11311.490982905749;11313.98174164325;11315.943420282887;11119.27724697892;11165.83251251232;11218.775624487545;11224.943875280653;11261.831072533918;9682.661106861226;10745;11061.668149955514;11071.778564428667;11162.497687604076;31291.061268161993;37386.25227495462;38026.42960355597;38192.427113593316;38309.85070447891;3255.4953982132806;3257.38109759145;3361.2605226850055;3470.35798377301;3552.626974167727;27966.720332796616;28712.282192945135;28782.568261476095;29154;29252.414951700848;3163.9683603163903;3170.239142605781;3205.6794320567988;3244.0754385000164;3305.9751477042196;419344;419412;420218;420728;423847;40262.993425164605;41981.58018419838;54567.72716136473;54731.52684731575;54735;56598.05720399381;56646;56938;56965.71517142471;57057.14414283789;14916.105033698013;15288.617784555476;15402.537923862303;15403;15415.614609634624;10574.629887953884;10768.946155269174;11880.702982425508;11918.582849600223;12080.21478603888;14220.288985550682;14321.283935803169;14573.562793116223;14731.895107866952;14789.778153327708;4886.486918873538;5754.63353937951;6009.2188015557695;6092.116643086728;6106.175666284995;820.2658620534588;853.7908212487856;894.7494701483595;932.2215949682092;996.8405055191134;25713.357166070997;28285.57571636427;28440.431191376058;28948.710512895024;28982.42035159327;987.8320685483485;1061.2518174686827;1094.8357746338113;1102.9393383363831;1111.6553868300853;14858.2570871456;14865.925670371578;14866.256687165598;15028.023178493362;15208.847911520965;520428;520867;527885;528100;531035;52597.7370113147;52883.29816912779;52903;53097.734511327966;53175;1278.194737315504;1318.2683610596212;1332.8667133286788;1332.9133606315559;1337.5519201067752;27501.31246718768;28560.42879142364;28787.99242026519;28864.42271154607;29018;45999.77000114978;46259.074818504116;46403.83990400267;46433.53566464292;46456;467774;468825;469370;470017;471122;31277.8436107818;32019.839900800347;34218.315633687685;35166.47250291299;37772.81113594415;40804;43006;43180.13639727125;43935;44020.33969490396;33240.1689957753;36142.63857361393;36254.63745362618;36917.44623830591;37159.81420092882;35841.64158358383;35888.46167307545;36581.08547286339;36919.63080369269;37230.8138459306;35313;35364.29271414609;35815.46276805798;36113.27773444569;36181.63818361888;30642;32018.19954501156;32269.838650806596;32279.677203227668;32301.51547726833;24035.879820600785;24039.27882163538;24326.63510047315;24585.877070614533;24660.630090548297 12:20:03 INFO - PROCESS | 1635 | |2;jquery.html;356.38523546881635;364.10611947668474;367.68746565419394;373.6823699855122;390.9600462769035;19009.619807603838;19666;19744.506387340287;20065.197392104317;20261.290854820065;18874.811251887477;19576.314828980987;19624.803751962478;20058.699119513218;20093.799062009377;4312.913741725163;5079.120632770123;5112.56543193828;5263.473652634744;5266.894662106755;5343.198520221959;5392.703401312926;5434.592405569579;5435.402105768382;5459.645123067008;5419.729013549327;5421.21392398102;5435.456454354553;5439.891202175954;7167.2832716728435;7457.105147382322;7604.505707129047;7651.1583725790115;7691.884621730651;7695.345895598881;6931.890897456433;7129.287071292881;7360.153582338046;7430.554166750012;7436.293552112541;22958.540829183323;25266.621000685005;25926.481470370676;26114.216573502825;26904.461910761675;2252.628316327804;2334.8365614407;2382.702162229721;2414.710234771821;2438.451348446603;13332.666683332995;14027.834507217633;16048.993875765529;16570.254338554794;16964.0669763163;1595.3937503748618;1981.4352909420745;2000.3298894870236;2006.5485265815075;2065;132088.01867971892;138768.30615846958;140367;140396.29801851028;140567;16705.498835034967;19999.600007999758;20632.484187895123;20668.483287917923;20690.172393104407;27463;32650;33065.008049758544;33101;33224.501632475534;10068.345557538736;10236.795264094677;10314.638987635546;10548;10550.208734344955;7082.893756593655;8613.741587752378;8618.698345558001;8643.35174861888;8820.647174113092;9323.440593564272;9507.667231646998;9574.234061275081;9613.471259080818;9648.807023859483;3204.4872820348633;3666.7983260920905;3769.3791669581797;3802.885913422602;3815.4086116651965;801.3028665061317;830.9418340716092;869.2350731356493;904.1230006893303;967.8064387122463;20469.488262793548;21018.369448916557;21216.151353945977;21283.574328513652;21289.89355053215;877.7103555826562;906.3881879731287;910.1035480052143;918.1828172926101;991.2268430624125;9961.953994830583;10284.948575257076;10612.734681633021;11164.720881977853;11186.776264474665;489523;493637;494783;498333;505019;69837.65081174561;71674.64162679153;103185.96814031914;125095;125851.3707431457;954.4511905654276;955.2644463762864;978.9559469823876;980.3823591137681;1037.206536999181;15654.295556699748;17985.820141798413;18417.81582184161;19810.910399928143;19904.800951990874;52915;54616;54764.45235547753;55026.44973550213;56627.867442650095;67641.32358676351;78307;78494.06088157145;81057.18942810496;81660.77508837254;7797.103333116537;7890;8184.386171037077;8278.627461764114;8303.4602750821;11688.590899318484;12506.812397813857;12729.236245825281;12953.870461295266;13013.739725205633;9124.543772811465;9639.614415423584;9907.851382229128;10352.792944141225;11216.046636036028;10656.680299591024;10777.730556736144;11056.11551075928;11266.324020558794;11304.039156671446;9580.377275477073;10687;11119.944400277947;11349.659510214708;11352.54589816398;7934.920650793649;8191.385646076687;8199.59002049907;8200.712975045844;8409.453385529921;8296.958515207385;8782.604782784918;9312.34813563044;11013.504392302206;11376 12:20:03 INFO - PROCESS | 1635 | |3;mootools.html;1336.3451908564803;1476.7489526780446;1610.1144370596176;1637.165045826628;1722.5607470095124;7522.5110367826055;7763.728269510569;7900.328472079873;8367.372447066471;8369.832603347937;7941.00737407824;8224.383171262165;8246.752597422086;8386.62260198291;8420.157984201576;1978.6042791441769;2308.7691230876903;2339.8128149748063;2341;2344.742078371378;1969.9756126814048;2002.8283454179323;2234.575430668168;2255.8872056397195;2348.5068135691527;1876.6246750649857;2197.0772275644267;2271.0915633746467;2290.1755368067484;2312.6993490846166;1865.533616595851;2276.4764104256033;2302.182725132579;2305.4582173189287;2309.4111001694628;1895.6872116100826;2292.86242825431;2304.8617082975074;2310.0413328468667;2311.410590299463;8274.048484424247;8289.336853051802;8319.001719793603;8355.916440835575;8374.958125209396;5024.422191447957;5165.793368265265;5187.636865419422;5194.194899790538;5201.24581935621;3241.286916878283;3257.5602293690285;3462.7056700180515;3532.717382609385;3533.911652208715;1022.6676330192643;1064.5848119233506;1073.125402796726;1074.6346242277584;1080.3733834376121;10769.299995500267;10825;10827.729306767314;10877.945610271898;10980.286281391684;4478.910421791546;4595.218812801765;4679.485256621769;4685.1098291325225;4705.905881882343;9279.814403711887;9386.296027797942;9412.482313472825;9428.340016198801;9430.151286384122;5171.534561889448;5179.223116532475;5187.429382767892;5194.428612852583;5199.402068762065;4191.64371028466;4593.770311484479;4691.202495575761;4746.264329029006;4754.976225118852;3726.0312318796764;3761.2289480656946;3809.1810260794064;3840.731148819556;3847.153626202231;1253.360785999147;1267.163671976491;1270.4029106320015;1276.5723482633236;1279.411470723459;852.1861622150831;878.8110556230441;892.2594246775161;912.8630705394111;915.7435917942987;5494.587905907073;5690.6870122142855;5793.246877905845;5839.766409343579;5843.970780146072;915.5193523400268;920.6225447566466;924.3852837862746;941;983.8475036369374;3824.043989002749;3824.1778017726324;3829.7893615851394;3908.1988192420486;3932.5870783567884;9698.272629552812;10063.597456101674;10073.24450666203;10119.342242754197;10126.13927816144;9847.458389788488;10043.146332561813;10048.246381521416;10054.195664346835;10065.849012264822;7393.593352365564;7690.846183076419;7713.460057796013;7758.844823103619;7818.944442500303;5617.466340697627;5754.942450575608;5783.8554036149435;5795.652260864362;5853;8944.552772361225;9263.907360926574;9303.395279306824;9389.248860091313;9499.335046546676;9343.112404321579;9412.388194767318;9459.668911588062;9627.951860240653;9642.228621710106;6597.868042639217;6756.729730810713;7104.857902841811;7136.429085673238;7264.309890560388;7413.555186688816;7896.210378962058;7950.681972721027;7953.761387158394;7959.204079591995;7043.542169758949;7142.285771422816;7153.785386438415;7229.49393542468;7289.708411663474;6902.689378978059;7024.964875175591;7044.436445084483;7054.118235220598;7091.893621595577;5515.696636685021;5889.941100589111;6648.468122550281;6698.430633396216;6784.898226526508;3996.780177090288;4518.938049558417;4608.331791890224;4854.199406320908;4955.554000140005;4089.26393249218;4825.831095911626;4926.679765815211;4980.750962451935;5024.648274620741 12:20:03 INFO - PROCESS | 1635 | |4;prototype.html;298.48064367999683;304.67704233512467;305.2871544942559;306.5922323309998;312.10270472391875;4297.989972356498;5039.84880453587;5447.836564903055;5468.562514998801;5468.644538105021;4408.537103604123;4620.653450991181;4717.433907931045;5333.35999680038;5371.623986320959;3630.4917311576332;3965.781881996489;3985.820638071279;4028.45615841862;4065.5324637666604;4059.5940405959313;4072.6538244249127;4074.3684728867074;4075.919881231476;4109.342505199169;4088.407180958759;4090.284200264942;4098.426220329156;4107.418643822135;4108.424820525129;3423.058658868806;3517.979785862097;3962.7622342659533;4043.818028188709;4090.6727461803134;3121.953170702441;3342.8161451120177;3532.788032718045;3548.3435564420665;3551.4140166872435;5155.278261043457;5194.168932970707;5208.66143700662;5219.608529360313;5255.816046438395;4433.334999750031;4438.245498265271;4680.742559159245;4710.576048155648;4715.41057367829;5590.825926555428;5615.438456154393;5626.578006649518;5633.971830140823;5674.914876276859;3002.789804713671;3830.8659196927974;3900.4539364488996;3910.921781564381;3923.901902452433;4493.68544201906;5931.406859314077;5956.463918247336;5962.672053037081;5970.641761494324;3021.9697803022127;3825.158465137665;3875.399313106473;3880.3791393376923;3887.883363499043;3948.7235893487755;3961.227560625708;3967.305721498784;3969.821358038895;3974.4038394240515;3883.825227864753;4252.55348188442;4644.769136178916;4666.370040044619;4667.056337394896;4840.903181936342;4851.331767527846;5698;5744.224529688438;5818.941810581925;1156.0232003274327;1262.2363470100456;1272.3193091695898;1272.732726127505;1274.3688079258563;834.9540775257419;864.304235090758;877.8419884420865;884.4339622641514;885.5698047652994;4395.84614538496;4987.950120498748;5149.845504634867;5401.919616076801;5411.269478620336;4968.652194346361;5399.433059528693;5881.235439392852;5953.713326404982;5968.761249550056;4348.673849461303;4728.503507131771;5378.11261141915;5406.675599464044;5410.97294513525;2493.5262300162913;2609.4781043791318;2635.0908936416904;2891.5373540233463;2947.3073827650487;5469.398366179717;5520.751566179451;5691.634007838089;5876.471117599436;5887.4406931341455;4676.462206846256;5413.810516631899;5626.099824028136;6092.786752463642;6141.477974372139;5508.20131080999;5871.240516957023;6077.116094010871;6210.627362358273;6219.191505104398;4930.436173189993;4963.4540200577185;5706.42935706426;5996.400359963969;6025.969870150621;5543.196236545759;5861.032929566573;5899.345294617525;5906.940930590639;5921.535901497672;6187.195664563578;6218.626882387071;6236.316194627516;6238.625682459067;6289.654069026247;3729.645683660048;3867.2072225193206;3897.201073779925;3907.628775266345;3948.6446219840354;4662.790174442091;4882.438519570295;5373.462653734595;5905.5570832186895;5929.258842644669;4830.6135509159885;5000.824971125993;5340.546053585489;5438.782448702166;5826.271716035496;5050.116229659721;5512.310961129859;5688.317401911631;5757.740901659352;5765.654060756368;5144.614153938394;5582.274304340573;5585.748641311069;5647.39737114267;5660.490555849993;4962.478939711423;5019.648624596243;5600.943990560042;5719.628224165416;5761.711914404179;5150.510701483353;5483.972580137074;5634.605577609528;5645.209670646195;5653.632513886584 12:20:03 INFO - PROCESS | 1635 | |5;yui.html;640.8269767162864;650.4958657040794;682.5461068389521;771.459978015389;772.5712229712509;13941.581752547414;14773.409063637442;14885.404583816635;14939.02896311739;14945.925270373646;13262;13408.798868016987;13416.798748018788;13445.865541344609;13492;642.9357064293556;666.6300203387118;811.655046605192;833.5707110837923;837.3008537870885;792.0178978067213;805.1103530598692;826.1284345016036;829.4898637338054;832.4131487301438;870.3602851903846;873.2097451806126;874.204473928725;874.3835595904872;875.426595579893;849.583703985049;858.3690987124489;861.6079683743882;865.4763867859948;866.7746385939638;641.9518536109811;643.0450780590819;644.0050122560655;644.3588629313816;644.6422235659207;11582.76834463306;14018.789718154236;14043.64890877726;14134.646633834236;14144.646383840487;12548.874511254862;12647.810282845672;12867.034972377016;13868.583942481742;14217.786733199011;12049;12446.190997585129;12458.26252124359;12608.68478288041;12706.237625742393;4500.8649740507835;4541.477730061052;4542.931856022163;4550.930531325136;4632.861014169546;88528;89293;91259.54370228107;91998.54000730021;92962.5351873243;10723.892761072446;10749.301295415773;10813.026827585554;10968.23222374442;11073.335599864035;20433.897830510752;20446.07992640335;20470.59058818845;20484.69272960907;20526.07632656534;7509.399248060251;7789.182135875765;7831;7894.328982036591;7896.447248692536;3398.76208665396;4141.8964525886495;4220.11377610699;4284.036091879302;4447.08834688888;3296.159479332755;3362.747793915466;3407.216340241758;3409.0454672691685;3411;550.5760564365416;599.0355527600559;601.9006863867413;609.2141137932018;622.4149299658307;535.898179345923;575.516566084491;604.7973928733924;626.79629120536;676.4081428749844;9088.136627020422;9847.261455390731;9903.950480247697;9944.80110397788;9995.750106247257;537.046742032887;542.8832800947815;576.2336092996305;588.3902259637157;659.307726886767;3959.7228194026716;4207.389928460418;4270.807813648394;4604.194266003436;4620.353150558924;84434;106673.46663266634;113692;114658;115736;95008;97351.05297894003;98264.50867745615;98566;98871;82077.58961205036;82307;83615.58192209;83977;87320;9351.485668288038;9373.953130234579;9478.763030924281;9520.333576649846;9523.61905523799;26755.732442675326;49768.75115624399;54642.45357546374;54680.45319546753;54933.72533137309;51046;51200.74399628127;70228.64885675539;77066;77935.44129117273;12442.502299908265;12665.240085594894;12968.610941671388;12979.221246725227;13368.933155334162;11382.772344553228;13766.862331376558;13858.445662173803;14051.508197213048;14828.555143345717;12849.743005140032;12915.418806153933;13034.283114428417;13067.607971760863;13167;12778.324286805808;12999.805002924775;13002.674933126747;13041.95664346869;13089;12629.431675574437;12704.237745735285;13102.410391532594;13253.403596837972;13341;8667.829842971118;10120.696379108638;10220.897791021995;10379.844302335621;10609.469526523797;10330.793384132125;10861.076808471369;11065.170112241774;11116.666500005012;11127.72180695489 12:20:03 INFO - PROCESS | 1635 | __end_tp_report 12:20:03 INFO - PROCESS | 1635 | __start_cc_report 12:20:03 INFO - PROCESS | 1635 | _x_x_mozilla_cycle_collect,367 12:20:03 INFO - PROCESS | 1635 | __end_cc_report 12:20:03 INFO - PROCESS | 1635 | __startTimestamp1479932403110__endTimestamp 12:20:03 INFO - PROCESS | 1635 | 12:20:03 INFO - PROCESS | 1635 | ------- Summary: start ------- 12:20:03 INFO - PROCESS | 1635 | Number of tests: 6 12:20:03 INFO - PROCESS | 1635 | 12:20:03 INFO - PROCESS | 1635 | [#0] dojo.html Cycles:165 Average:5766.48 Median:6016.01 stddev:696.20 (11.6%) stddev-sans-first:650.94 12:20:03 INFO - PROCESS | 1635 | Values: 2547.6 2633.0 3410.0 3450.6 3468.2 5693.9 5763.0 5804.3 5820.4 5841.2 4778.7 5672.7 5860.2 5881.2 5901.8 4973.7 5252.9 5255.6 5380.5 5394.8 4423.4 5308.1 5392.6 5443.0 5453.2 5362.8 5380.0 5408.8 5438.9 5449.0 5213.9 5229.2 5244.0 5270.3 5339.9 5194.9 5326.0 5329.5 5374.2 5382.1 5591.1 5858.8 5859.3 5893.6 5945.8 6139.6 6276.2 6319.9 6354.8 6371.9 5249.7 6256.4 6281.1 6300.4 6317.9 5967.5 5987.4 5991.5 6001.4 6012.0 6232.7 6373.7 6408.9 6416.3 6417.7 6077.9 6108.6 6126.7 6185.5 6199.8 6316.4 6324.1 6326.2 6332.4 6358.7 6119.5 6120.1 6164.5 6176.3 6298.9 6207.4 6240.5 6328.8 6332.8 6358.9 4479.9 4953.2 4963.8 5038.2 5068.9 4430.4 4453.7 4482.7 4505.4 4514.1 5162.6 5863.3 5915.5 5979.0 6111.0 5157.3 6241.5 6275.7 6306.6 6363.4 6064.7 6065.4 6093.5 6096.7 6114.6 5529.6 5531.4 5538.8 5542.7 5553.0 5014.3 5479.5 5731.0 5923.0 6234.5 5823.0 6182.5 6236.6 6316.6 6331.0 6421.8 6454.4 6495.5 6501.5 6506.0 5130.0 6304.7 6306.9 6308.2 6333.1 6169.9 6173.8 6275.7 6282.2 6337.5 5528.9 5973.7 6007.0 6036.9 6160.4 6016.0 6132.7 6135.9 6172.0 6192.1 6030.5 6110.4 6132.4 6151.7 6165.9 5210.7 6009.1 6238.2 6280.6 6293.3 6028.6 6074.4 6125.0 6126.9 6187.8 12:20:03 INFO - PROCESS | 1635 | 12:20:03 INFO - PROCESS | 1635 | [#1] ext.html Cycles:180 Average:60798.32 Median:35807.05 stddev:125972.54 (351.8%) stddev-sans-first:126244.48 12:20:03 INFO - PROCESS | 1635 | Values: 467.5 492.2 504.3 520.4 544.1 35798.6 39946.6 40079.0 40170.8 40294.0 39006.0 39415.8 39530.6 39536.8 39706.0 8330.8 9186.5 9335.1 9337.9 9376.1 8384.2 10947.4 11202.8 11226.2 11380.5 11247.0 11304.9 11311.5 11314.0 11315.9 11119.3 11165.8 11218.8 11224.9 11261.8 9682.7 10745.0 11061.7 11071.8 11162.5 31291.1 37386.3 38026.4 38192.4 38309.9 3255.5 3257.4 3361.3 3470.4 3552.6 27966.7 28712.3 28782.6 29154.0 29252.4 3164.0 3170.2 3205.7 3244.1 3306.0 419344.0 419412.0 420218.0 420728.0 423847.0 40263.0 41981.6 54567.7 54731.5 54735.0 56598.1 56646.0 56938.0 56965.7 57057.1 14916.1 15288.6 15402.5 15403.0 15415.6 10574.6 10768.9 11880.7 11918.6 12080.2 14220.3 14321.3 14573.6 14731.9 14789.8 4886.5 5754.6 6009.2 6092.1 6106.2 820.3 853.8 894.7 932.2 996.8 25713.4 28285.6 28440.4 28948.7 28982.4 987.8 1061.3 1094.8 1102.9 1111.7 14858.3 14865.9 14866.3 15028.0 15208.8 520428.0 520867.0 527885.0 528100.0 531035.0 52597.7 52883.3 52903.0 53097.7 53175.0 1278.2 1318.3 1332.9 1332.9 1337.6 27501.3 28560.4 28788.0 28864.4 29018.0 45999.8 46259.1 46403.8 46433.5 46456.0 467774.0 468825.0 469370.0 470017.0 471122.0 31277.8 32019.8 34218.3 35166.5 37772.8 40804.0 43006.0 43180.1 43935.0 44020.3 33240.2 36142.6 36254.6 36917.4 37159.8 35841.6 35888.5 36581.1 36919.6 37230.8 35313.0 35364.3 35815.5 36113.3 36181.6 30642.0 32018.2 32269.8 32279.7 32301.5 24035.9 24039.3 24326.6 24585.9 24660.6 12:20:03 INFO - PROCESS | 1635 | 12:20:03 INFO - PROCESS | 1635 | [#2] jquery.html Cycles:180 Average:33023.59 Median:370.68 stddev:83710.32 (22582.6%) stddev-sans-first:83909.22 12:20:03 INFO - PROCESS | 1635 | Values: 356.4 364.1 367.7 373.7 391.0 19009.6 19666.0 19744.5 20065.2 20261.3 18874.8 19576.3 19624.8 20058.7 20093.8 4312.9 5079.1 5112.6 5263.5 5266.9 5343.2 5392.7 5434.6 5435.4 5459.6 5419.7 5421.2 5435.5 5439.9 7167.3 7457.1 7604.5 7651.2 7691.9 7695.3 6931.9 7129.3 7360.2 7430.6 7436.3 22958.5 25266.6 25926.5 26114.2 26904.5 2252.6 2334.8 2382.7 2414.7 2438.5 13332.7 14027.8 16049.0 16570.3 16964.1 1595.4 1981.4 2000.3 2006.5 2065.0 132088.0 138768.3 140367.0 140396.3 140567.0 16705.5 19999.6 20632.5 20668.5 20690.2 27463.0 32650.0 33065.0 33101.0 33224.5 10068.3 10236.8 10314.6 10548.0 10550.2 7082.9 8613.7 8618.7 8643.4 8820.6 9323.4 9507.7 9574.2 9613.5 9648.8 3204.5 3666.8 3769.4 3802.9 3815.4 801.3 830.9 869.2 904.1 967.8 20469.5 21018.4 21216.2 21283.6 21289.9 877.7 906.4 910.1 918.2 991.2 9962.0 10284.9 10612.7 11164.7 11186.8 489523.0 493637.0 494783.0 498333.0 505019.0 69837.7 71674.6 103186.0 125095.0 125851.4 954.5 955.3 979.0 980.4 1037.2 15654.3 17985.8 18417.8 19810.9 19904.8 52915.0 54616.0 54764.5 55026.4 56627.9 67641.3 78307.0 78494.1 81057.2 81660.8 7797.1 7890.0 8184.4 8278.6 8303.5 11688.6 12506.8 12729.2 12953.9 13013.7 9124.5 9639.6 9907.9 10352.8 11216.0 10656.7 10777.7 11056.1 11266.3 11304.0 9580.4 10687.0 11119.9 11349.7 11352.5 7934.9 8191.4 8199.6 8200.7 8409.5 8297.0 8782.6 9312.3 11013.5 11376.0 12:20:03 INFO - PROCESS | 1635 | 12:20:03 INFO - PROCESS | 1635 | [#3] mootools.html Cycles:180 Average:5411.99 Median:5175.38 stddev:2980.47 (57.6%) stddev-sans-first:2973.09 12:20:03 INFO - PROCESS | 1635 | Values: 1336.3 1476.7 1610.1 1637.2 1722.6 7522.5 7763.7 7900.3 8367.4 8369.8 7941.0 8224.4 8246.8 8386.6 8420.2 1978.6 2308.8 2339.8 2341.0 2344.7 1970.0 2002.8 2234.6 2255.9 2348.5 1876.6 2197.1 2271.1 2290.2 2312.7 1865.5 2276.5 2302.2 2305.5 2309.4 1895.7 2292.9 2304.9 2310.0 2311.4 8274.0 8289.3 8319.0 8355.9 8375.0 5024.4 5165.8 5187.6 5194.2 5201.2 3241.3 3257.6 3462.7 3532.7 3533.9 1022.7 1064.6 1073.1 1074.6 1080.4 10769.3 10825.0 10827.7 10877.9 10980.3 4478.9 4595.2 4679.5 4685.1 4705.9 9279.8 9386.3 9412.5 9428.3 9430.2 5171.5 5179.2 5187.4 5194.4 5199.4 4191.6 4593.8 4691.2 4746.3 4755.0 3726.0 3761.2 3809.2 3840.7 3847.2 1253.4 1267.2 1270.4 1276.6 1279.4 852.2 878.8 892.3 912.9 915.7 5494.6 5690.7 5793.2 5839.8 5844.0 915.5 920.6 924.4 941.0 983.8 3824.0 3824.2 3829.8 3908.2 3932.6 9698.3 10063.6 10073.2 10119.3 10126.1 9847.5 10043.1 10048.2 10054.2 10065.8 7393.6 7690.8 7713.5 7758.8 7818.9 5617.5 5754.9 5783.9 5795.7 5853.0 8944.6 9263.9 9303.4 9389.2 9499.3 9343.1 9412.4 9459.7 9628.0 9642.2 6597.9 6756.7 7104.9 7136.4 7264.3 7413.6 7896.2 7950.7 7953.8 7959.2 7043.5 7142.3 7153.8 7229.5 7289.7 6902.7 7025.0 7044.4 7054.1 7091.9 5515.7 5889.9 6648.5 6698.4 6784.9 3996.8 4518.9 4608.3 4854.2 4955.6 4089.3 4825.8 4926.7 4980.8 5024.6 12:20:03 INFO - PROCESS | 1635 | 12:20:03 INFO - PROCESS | 1635 | [#4] prototype.html Cycles:180 Average:4553.03 Median:5029.75 stddev:1440.50 (28.6%) stddev-sans-first:1408.70 12:20:03 INFO - PROCESS | 1635 | Values: 298.5 304.7 305.3 306.6 312.1 4298.0 5039.8 5447.8 5468.6 5468.6 4408.5 4620.7 4717.4 5333.4 5371.6 3630.5 3965.8 3985.8 4028.5 4065.5 4059.6 4072.7 4074.4 4075.9 4109.3 4088.4 4090.3 4098.4 4107.4 4108.4 3423.1 3518.0 3962.8 4043.8 4090.7 3122.0 3342.8 3532.8 3548.3 3551.4 5155.3 5194.2 5208.7 5219.6 5255.8 4433.3 4438.2 4680.7 4710.6 4715.4 5590.8 5615.4 5626.6 5634.0 5674.9 3002.8 3830.9 3900.5 3910.9 3923.9 4493.7 5931.4 5956.5 5962.7 5970.6 3022.0 3825.2 3875.4 3880.4 3887.9 3948.7 3961.2 3967.3 3969.8 3974.4 3883.8 4252.6 4644.8 4666.4 4667.1 4840.9 4851.3 5698.0 5744.2 5818.9 1156.0 1262.2 1272.3 1272.7 1274.4 835.0 864.3 877.8 884.4 885.6 4395.8 4988.0 5149.8 5401.9 5411.3 4968.7 5399.4 5881.2 5953.7 5968.8 4348.7 4728.5 5378.1 5406.7 5411.0 2493.5 2609.5 2635.1 2891.5 2947.3 5469.4 5520.8 5691.6 5876.5 5887.4 4676.5 5413.8 5626.1 6092.8 6141.5 5508.2 5871.2 6077.1 6210.6 6219.2 4930.4 4963.5 5706.4 5996.4 6026.0 5543.2 5861.0 5899.3 5906.9 5921.5 6187.2 6218.6 6236.3 6238.6 6289.7 3729.6 3867.2 3897.2 3907.6 3948.6 4662.8 4882.4 5373.5 5905.6 5929.3 4830.6 5000.8 5340.5 5438.8 5826.3 5050.1 5512.3 5688.3 5757.7 5765.7 5144.6 5582.3 5585.7 5647.4 5660.5 4962.5 5019.6 5600.9 5719.6 5761.7 5150.5 5484.0 5634.6 5645.2 5653.6 12:20:03 INFO - PROCESS | 1635 | 12:20:03 INFO - PROCESS | 1635 | [#5] yui.html Cycles:180 Average:20217.39 Median:4577.56 stddev:29621.57 (647.1%) stddev-sans-first:29668.19 12:20:03 INFO - PROCESS | 1635 | Values: 640.8 650.5 682.5 771.5 772.6 13941.6 14773.4 14885.4 14939.0 14945.9 13262.0 13408.8 13416.8 13445.9 13492.0 642.9 666.6 811.7 833.6 837.3 792.0 805.1 826.1 829.5 832.4 870.4 873.2 874.2 874.4 875.4 849.6 858.4 861.6 865.5 866.8 642.0 643.0 644.0 644.4 644.6 11582.8 14018.8 14043.6 14134.6 14144.6 12548.9 12647.8 12867.0 13868.6 14217.8 12049.0 12446.2 12458.3 12608.7 12706.2 4500.9 4541.5 4542.9 4550.9 4632.9 88528.0 89293.0 91259.5 91998.5 92962.5 10723.9 10749.3 10813.0 10968.2 11073.3 20433.9 20446.1 20470.6 20484.7 20526.1 7509.4 7789.2 7831.0 7894.3 7896.4 3398.8 4141.9 4220.1 4284.0 4447.1 3296.2 3362.7 3407.2 3409.0 3411.0 550.6 599.0 601.9 609.2 622.4 535.9 575.5 604.8 626.8 676.4 9088.1 9847.3 9904.0 9944.8 9995.8 537.0 542.9 576.2 588.4 659.3 3959.7 4207.4 4270.8 4604.2 4620.4 84434.0 106673.5 113692.0 114658.0 115736.0 95008.0 97351.1 98264.5 98566.0 98871.0 82077.6 82307.0 83615.6 83977.0 87320.0 9351.5 9374.0 9478.8 9520.3 9523.6 26755.7 49768.8 54642.5 54680.5 54933.7 51046.0 51200.7 70228.6 77066.0 77935.4 12442.5 12665.2 12968.6 12979.2 13368.9 11382.8 13766.9 13858.4 14051.5 14828.6 12849.7 12915.4 13034.3 13067.6 13167.0 12778.3 12999.8 13002.7 13042.0 13089.0 12629.4 12704.2 13102.4 13253.4 13341.0 8667.8 10120.7 10220.9 10379.8 10609.5 10330.8 10861.1 11065.2 11116.7 11127.7 12:20:03 INFO - PROCESS | 1635 | -------- Summary: end -------- 12:20:03 INFO - PROCESS | 1635 | 12:20:03 INFO - TEST-INFO | 1635: exit 0 12:20:03 INFO - TEST-OK | dromaeo_css | took 1117117ms 12:20:03 INFO - TEST-START | kraken 12:20:03 INFO - operating with platform_type : mac_ 12:20:03 INFO - Initialising browser for kraken test... 12:20:03 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/tmpicaYYV/profile http://localhost:49247/getInfo.html) 12:20:06 INFO - PROCESS | 1647 | 2016-11-23 12:20:06.800 plugin-container[1648:10211] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8937, name = 'com.apple.tsm.portname' 12:20:06 INFO - PROCESS | 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:20:06 INFO - PROCESS | 1647 | 2016-11-23 12:20:06.805 plugin-container[1648:10211] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x470b, name = 'com.apple.CFPasteboardClient' 12:20:06 INFO - PROCESS | 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:20:06 INFO - PROCESS | 1647 | 2016-11-23 12:20:06.805 plugin-container[1648:10211] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:20:06 INFO - PROCESS | 1647 | __metrics Screen width/height:1600/1200 12:20:06 INFO - PROCESS | 1647 | colorDepth:24 12:20:06 INFO - PROCESS | 1647 | Browser inner width/height: 1024/689 12:20:06 INFO - PROCESS | 1647 | __metrics 12:20:06 ERROR - PROCESS | 1647 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3198: TypeError: this.tabs is undefined 12:20:07 INFO - TEST-INFO | 1647: exit 0 12:20:07 INFO - Browser initialized. 12:20:07 INFO - Running cycle 1/1 for kraken test... 12:20:07 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/T/tmpicaYYV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:20:10 INFO - PROCESS | 1649 | 2016-11-23 12:20:10.396 plugin-container[1650:10325] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a4f, name = 'com.apple.tsm.portname' 12:20:10 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:20:10 INFO - PROCESS | 1649 | 2016-11-23 12:20:10.396 plugin-container[1650:10325] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x430f, name = 'com.apple.CFPasteboardClient' 12:20:10 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:20:10 INFO - PROCESS | 1649 | 2016-11-23 12:20:10.396 plugin-container[1650:10325] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:20:20 INFO - PROCESS | 1649 | RSS: Main: 241725440 12:20:20 INFO - PROCESS | 1649 | 12:20:23 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49247/tests/kraken/driver.html?testName=audio-beat-detection) 12:20:24 INFO - PROCESS | 1649 | RSS: Main: 253763584 12:20:24 INFO - PROCESS | 1649 | 12:20:26 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49247/tests/kraken/driver.html?testName=audio-dft) 12:20:27 INFO - PROCESS | 1649 | RSS: Main: 261345280 12:20:27 INFO - PROCESS | 1649 | 12:20:30 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49247/tests/kraken/driver.html?testName=audio-fft) 12:20:31 INFO - PROCESS | 1649 | RSS: Main: 263987200 12:20:31 INFO - PROCESS | 1649 | 12:20:33 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49247/tests/kraken/driver.html?testName=audio-oscillator) 12:20:34 INFO - PROCESS | 1649 | RSS: Main: 265228288 12:20:34 INFO - PROCESS | 1649 | 12:20:35 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49247/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:20:36 INFO - PROCESS | 1649 | RSS: Main: 266854400 12:20:36 INFO - PROCESS | 1649 | 12:20:39 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49247/tests/kraken/driver.html?testName=imaging-darkroom) 12:20:40 INFO - PROCESS | 1649 | RSS: Main: 272338944 12:20:40 INFO - PROCESS | 1649 | 12:20:43 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49247/tests/kraken/driver.html?testName=imaging-desaturate) 12:20:43 INFO - PROCESS | 1649 | RSS: Main: 271536128 12:20:43 INFO - PROCESS | 1649 | 12:20:46 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49247/tests/kraken/driver.html?testName=json-parse-financial) 12:20:47 INFO - PROCESS | 1649 | RSS: Main: 271749120 12:20:47 INFO - PROCESS | 1649 | 12:20:48 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49247/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:20:49 INFO - PROCESS | 1649 | RSS: Main: 270770176 12:20:49 INFO - PROCESS | 1649 | 12:20:51 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:20:52 INFO - PROCESS | 1649 | RSS: Main: 270913536 12:20:52 INFO - PROCESS | 1649 | 12:20:54 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:20:55 INFO - PROCESS | 1649 | RSS: Main: 270872576 12:20:55 INFO - PROCESS | 1649 | 12:20:57 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:20:58 INFO - PROCESS | 1649 | RSS: Main: 271048704 12:20:58 INFO - PROCESS | 1649 | 12:21:01 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:21:02 INFO - PROCESS | 1649 | RSS: Main: 271241216 12:21:02 INFO - PROCESS | 1649 | 12:21:03 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49247/tests/kraken/driver.html?testName=ai-astar) 12:21:04 INFO - PROCESS | 1649 | RSS: Main: 273113088 12:21:04 INFO - PROCESS | 1649 | 12:21:04 INFO - PROCESS | 1649 | __start_tp_report 12:21:04 INFO - PROCESS | 1649 | _x_x_mozilla_page_load 12:21:04 INFO - PROCESS | 1649 | _x_x_mozilla_page_load_details 12:21:04 INFO - PROCESS | 1649 | |i|pagename|runs| 12:21:04 INFO - PROCESS | 1649 | |0;ai-astar;108;119;119;120;122;119;146;177;177;119 12:21:04 INFO - PROCESS | 1649 | |1;audio-beat-detection;145;116;112;155;119;118;119;117;140;111 12:21:04 INFO - PROCESS | 1649 | |2;audio-dft;198;212;207;198;194;271;220;196;193;193 12:21:04 INFO - PROCESS | 1649 | |3;audio-fft;81;59;63;84;60;66;82;84;99;74 12:21:04 INFO - PROCESS | 1649 | |4;audio-oscillator;94;69;85;74;70;70;79;71;71;81 12:21:04 INFO - PROCESS | 1649 | |5;imaging-gaussian-blur;126;90;99;132;98;88;93;89;93;88 12:21:04 INFO - PROCESS | 1649 | |6;imaging-darkroom;113;96;96;121;118;97;96;97;96;119 12:21:04 INFO - PROCESS | 1649 | |7;imaging-desaturate;93;100;93;93;96;93;93;96;92;91 12:21:04 INFO - PROCESS | 1649 | |8;json-parse-financial;56;60;63;85;91;84;59;59;57;54 12:21:04 INFO - PROCESS | 1649 | |9;json-stringify-tinderbox;68;66;99;71;72;102;107;110;86;73 12:21:04 INFO - PROCESS | 1649 | |10;stanford-crypto-aes;86;91;90;95;91;88;91;91;87;90 12:21:04 INFO - PROCESS | 1649 | |11;stanford-crypto-ccm;150;150;148;152;151;165;164;166;204;153 12:21:04 INFO - PROCESS | 1649 | |12;stanford-crypto-pbkdf2;175;178;177;172;178;175;173;175;172;175 12:21:04 INFO - PROCESS | 1649 | |13;stanford-crypto-sha256-iterative;70;69;69;67;71;68;69;69;68;68 12:21:04 INFO - PROCESS | 1649 | __end_tp_report 12:21:04 INFO - PROCESS | 1649 | __start_cc_report 12:21:04 INFO - PROCESS | 1649 | _x_x_mozilla_cycle_collect,959 12:21:04 INFO - PROCESS | 1649 | __end_cc_report 12:21:04 INFO - PROCESS | 1649 | __startTimestamp1479932464232__endTimestamp 12:21:04 INFO - PROCESS | 1649 | 12:21:04 INFO - PROCESS | 1649 | ------- Summary: start ------- 12:21:04 INFO - PROCESS | 1649 | Number of tests: 14 12:21:04 INFO - PROCESS | 1649 | 12:21:04 INFO - PROCESS | 1649 | [#0] ai-astar Cycles:10 Average:132.60 Median:121.00 stddev:25.25 (20.9%) stddev-sans-first:25.16 12:21:04 INFO - PROCESS | 1649 | Values: 108.0 119.0 119.0 120.0 122.0 119.0 146.0 177.0 177.0 119.0 12:21:04 INFO - PROCESS | 1649 | 12:21:04 INFO - PROCESS | 1649 | [#1] audio-beat-detection Cycles:10 Average:125.20 Median:119.00 stddev:15.48 (13.0%) stddev-sans-first:14.66 12:21:04 INFO - PROCESS | 1649 | Values: 145.0 116.0 112.0 155.0 119.0 118.0 119.0 117.0 140.0 111.0 12:21:04 INFO - PROCESS | 1649 | 12:21:04 INFO - PROCESS | 1649 | [#2] audio-dft Cycles:10 Average:208.20 Median:202.50 stddev:23.85 (11.8%) stddev-sans-first:25.01 12:21:04 INFO - PROCESS | 1649 | Values: 198.0 212.0 207.0 198.0 194.0 271.0 220.0 196.0 193.0 193.0 12:21:04 INFO - PROCESS | 1649 | 12:21:04 INFO - PROCESS | 1649 | [#3] audio-fft Cycles:10 Average:75.20 Median:81.50 stddev:13.04 (16.0%) stddev-sans-first:13.66 12:21:04 INFO - PROCESS | 1649 | Values: 81.0 59.0 63.0 84.0 60.0 66.0 82.0 84.0 99.0 74.0 12:21:04 INFO - PROCESS | 1649 | 12:21:04 INFO - PROCESS | 1649 | [#4] audio-oscillator Cycles:10 Average:76.40 Median:76.50 stddev:8.25 (10.8%) stddev-sans-first:5.79 12:21:04 INFO - PROCESS | 1649 | Values: 94.0 69.0 85.0 74.0 70.0 70.0 79.0 71.0 71.0 81.0 12:21:04 INFO - PROCESS | 1649 | 12:21:04 INFO - PROCESS | 1649 | [#5] imaging-gaussian-blur Cycles:10 Average:99.60 Median:91.50 stddev:16.02 (17.5%) stddev-sans-first:13.86 12:21:04 INFO - PROCESS | 1649 | Values: 126.0 90.0 99.0 132.0 98.0 88.0 93.0 89.0 93.0 88.0 12:21:04 INFO - PROCESS | 1649 | 12:21:04 INFO - PROCESS | 1649 | [#6] imaging-darkroom Cycles:10 Average:104.90 Median:96.00 stddev:11.24 (11.7%) stddev-sans-first:11.53 12:21:04 INFO - PROCESS | 1649 | Values: 113.0 96.0 96.0 121.0 118.0 97.0 96.0 97.0 96.0 119.0 12:21:04 INFO - PROCESS | 1649 | 12:21:04 INFO - PROCESS | 1649 | [#7] imaging-desaturate Cycles:10 Average:94.00 Median:93.00 stddev:2.62 (2.8%) stddev-sans-first:2.76 12:21:04 INFO - PROCESS | 1649 | Values: 93.0 100.0 93.0 93.0 96.0 93.0 93.0 96.0 92.0 91.0 12:21:04 INFO - PROCESS | 1649 | 12:21:04 INFO - PROCESS | 1649 | [#8] json-parse-financial Cycles:10 Average:66.80 Median:61.50 stddev:14.03 (22.8%) stddev-sans-first:14.33 12:21:04 INFO - PROCESS | 1649 | Values: 56.0 60.0 63.0 85.0 91.0 84.0 59.0 59.0 57.0 54.0 12:21:04 INFO - PROCESS | 1649 | 12:21:04 INFO - PROCESS | 1649 | [#9] json-stringify-tinderbox Cycles:10 Average:85.40 Median:71.50 stddev:17.49 (24.5%) stddev-sans-first:17.38 12:21:04 INFO - PROCESS | 1649 | Values: 68.0 66.0 99.0 71.0 72.0 102.0 107.0 110.0 86.0 73.0 12:21:04 INFO - PROCESS | 1649 | 12:21:04 INFO - PROCESS | 1649 | [#10] stanford-crypto-aes Cycles:10 Average:90.00 Median:91.00 stddev:2.54 (2.8%) stddev-sans-first:2.24 12:21:04 INFO - PROCESS | 1649 | Values: 86.0 91.0 90.0 95.0 91.0 88.0 91.0 91.0 87.0 90.0 12:21:04 INFO - PROCESS | 1649 | 12:21:04 INFO - PROCESS | 1649 | [#11] stanford-crypto-ccm Cycles:10 Average:160.30 Median:158.50 stddev:16.83 (10.6%) stddev-sans-first:17.44 12:21:04 INFO - PROCESS | 1649 | Values: 150.0 150.0 148.0 152.0 151.0 165.0 164.0 166.0 204.0 153.0 12:21:04 INFO - PROCESS | 1649 | 12:21:04 INFO - PROCESS | 1649 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.00 Median:175.00 stddev:2.21 (1.3%) stddev-sans-first:2.35 12:21:04 INFO - PROCESS | 1649 | Values: 175.0 178.0 177.0 172.0 178.0 175.0 173.0 175.0 172.0 175.0 12:21:04 INFO - PROCESS | 1649 | 12:21:04 INFO - PROCESS | 1649 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.80 Median:69.00 stddev:1.14 (1.6%) stddev-sans-first:1.12 12:21:04 INFO - PROCESS | 1649 | Values: 70.0 69.0 69.0 67.0 71.0 68.0 69.0 69.0 68.0 68.0 12:21:04 INFO - PROCESS | 1649 | -------- Summary: end -------- 12:21:04 INFO - PROCESS | 1649 | 12:21:05 INFO - TEST-INFO | 1649: exit 0 12:21:05 INFO - TEST-OK | kraken | took 61723ms 12:21:05 INFO - SUITE-END | took 1178s 12:21:05 INFO - Completed test suite (00:19:39) 12:21:05 INFO - javascript benchmark 12:21:05 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7258.121782147879, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2547.566913624684, 2633.0, 3409.994051754735, 3450.6376830432805, 3468.167639766455, 5693.943060569398, 5763.0, 5804.303483581971, 5820.417958204177, 5841.240638716961, 4778.737169455679, 5672.716364181785, 5860.150278209657, 5881.206037184978, 5901.793437229698, 4973.676711013791, 5252.8686782830355, 5255.553277971377, 5380.488853558905, 5394.78420863167, 4423.358613001113, 5308.124159513675, 5392.595555333346, 5443.0, 5453.2092846537425, 5362.785488580452, 5379.9731001345135, 5408.783648654049, 5438.91841622374, 5449.0, 5213.895722085575, 5229.189475631283, 5244.029854476934, 5270.288511050997, 5339.893202135974, 5194.870128246786, 5326.014687282865, 5329.493698098674, 5374.247605335257, 5382.138857782736, 5591.133374326986, 5858.794942177004, 5859.267591551056, 5893.5579831512805, 5945.821625351246, 6139.614800607363, 6276.215473065867, 6319.905201421982, 6354.841128971766, 6371.904421433682, 5249.685018898878, 6256.436920677161, 6281.120643109968, 6300.369963003709, 6317.93682063178, 5967.4629283364275, 5987.401259874065, 5991.460768530809, 6001.4298641628975, 6011.977963746173, 6232.719527621268, 6373.681315934185, 6408.935910640927, 6416.262129855033, 6417.743290268337, 6077.908831367533, 6108.5545605439265, 6126.731429653714, 6185.536084793568, 6199.845003874939, 6316.399942005502, 6324.114623952651, 6326.2092238470195, 6332.430081292614, 6358.650274234869, 6119.490423091814, 6120.142398366865, 6164.537659675542, 6176.289726681399, 6298.905516417258, 6207.410296021871, 6240.500759939194, 6328.810135695937, 6332.778352757631, 6358.936410635927, 4479.865604031885, 4953.157963146274, 4963.8262660806695, 5038.2190760432195, 5068.898622027613, 4430.357598148251, 4453.688241823106, 4482.731036137777, 4505.414296141481, 4514.080962419171, 5162.638615296968, 5863.2670916135485, 5915.467607915309, 5978.970105149447, 6111.0, 5157.303763991888, 6241.500679945593, 6275.654838983808, 6306.590071645328, 6363.395477429637, 6064.666443345658, 6065.3934606539, 6093.451589356979, 6096.72564734588, 6114.631938157225, 5529.557635389239, 5531.3915469298345, 5538.833834984957, 5542.667439953616, 5553.0, 5014.34813474246, 5479.53423958968, 5730.971345143247, 5923.0, 6234.501239900706, 5822.970885145716, 6182.474489668249, 6236.563440559117, 6316.62100273985, 6330.987042073246, 6421.775237866652, 6454.419102280817, 6495.480361571157, 6501.4798816095545, 6505.967470162619, 5129.9743501282255, 6304.65324407162, 6306.936930630818, 6308.179936608395, 6333.081703153109, 6169.876602468015, 6173.81478555644, 6275.717592708431, 6282.1519094921605, 6337.4613157882095, 5528.944710552843, 5973.701314934323, 6007.038873780174, 6036.879262414639, 6160.44555989963, 6016.007358785926, 6132.724027418866, 6135.875804921736, 6172.0, 6192.133101365723, 6030.4572588467245, 6110.388961104032, 6132.356102609162, 6151.723172457339, 6165.876682466415, 5210.739463026909, 6009.098635204754, 6238.220222472191, 6280.586867954674, 6293.307736148929, 6028.638281703112, 6074.42292982166, 6124.991279909952, 6126.938730612636, 6187.84530386726], "value": 5719.906768428877, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [467.5418090271533, 492.17806263539904, 504.29147048397016, 520.3807469111757, 544.1375419959364, 35798.642013579854, 39946.60053399465, 40079.0, 40170.799146004305, 40294.0, 39006.0, 39415.802920985356, 39530.60469395305, 39536.802315988454, 39706.0, 8330.750077497685, 9186.49474278916, 9335.113164249386, 9337.906620933805, 9376.109269619375, 8384.161583841627, 10947.397893115876, 11202.75944811037, 11226.158038147172, 11380.487878045466, 11247.0, 11304.886951130466, 11311.490982905749, 11313.98174164325, 11315.943420282887, 11119.27724697892, 11165.83251251232, 11218.775624487545, 11224.943875280653, 11261.831072533918, 9682.661106861226, 10745.0, 11061.668149955514, 11071.778564428667, 11162.497687604076, 31291.061268161993, 37386.25227495462, 38026.42960355597, 38192.427113593316, 38309.85070447891, 3255.4953982132806, 3257.38109759145, 3361.2605226850055, 3470.35798377301, 3552.626974167727, 27966.720332796616, 28712.282192945135, 28782.568261476095, 29154.0, 29252.414951700848, 3163.9683603163903, 3170.239142605781, 3205.6794320567988, 3244.0754385000164, 3305.9751477042196, 419344.0, 419412.0, 420218.0, 420728.0, 423847.0, 40262.993425164605, 41981.58018419838, 54567.72716136473, 54731.52684731575, 54735.0, 56598.05720399381, 56646.0, 56938.0, 56965.71517142471, 57057.14414283789, 14916.105033698013, 15288.617784555476, 15402.537923862303, 15403.0, 15415.614609634624, 10574.629887953884, 10768.946155269174, 11880.702982425508, 11918.582849600223, 12080.21478603888, 14220.288985550682, 14321.283935803169, 14573.562793116223, 14731.895107866952, 14789.778153327708, 4886.486918873538, 5754.63353937951, 6009.2188015557695, 6092.116643086728, 6106.175666284995, 820.2658620534588, 853.7908212487856, 894.7494701483595, 932.2215949682092, 996.8405055191134, 25713.357166070997, 28285.57571636427, 28440.431191376058, 28948.710512895024, 28982.42035159327, 987.8320685483485, 1061.2518174686827, 1094.8357746338113, 1102.9393383363831, 1111.6553868300853, 14858.2570871456, 14865.925670371578, 14866.256687165598, 15028.023178493362, 15208.847911520965, 520428.0, 520867.0, 527885.0, 528100.0, 531035.0, 52597.7370113147, 52883.29816912779, 52903.0, 53097.734511327966, 53175.0, 1278.194737315504, 1318.2683610596212, 1332.8667133286788, 1332.9133606315559, 1337.5519201067752, 27501.31246718768, 28560.42879142364, 28787.99242026519, 28864.42271154607, 29018.0, 45999.77000114978, 46259.074818504116, 46403.83990400267, 46433.53566464292, 46456.0, 467774.0, 468825.0, 469370.0, 470017.0, 471122.0, 31277.8436107818, 32019.839900800347, 34218.315633687685, 35166.47250291299, 37772.81113594415, 40804.0, 43006.0, 43180.13639727125, 43935.0, 44020.33969490396, 33240.1689957753, 36142.63857361393, 36254.63745362618, 36917.44623830591, 37159.81420092882, 35841.64158358383, 35888.46167307545, 36581.08547286339, 36919.63080369269, 37230.8138459306, 35313.0, 35364.29271414609, 35815.46276805798, 36113.27773444569, 36181.63818361888, 30642.0, 32018.19954501156, 32269.838650806596, 32279.677203227668, 32301.51547726833, 24035.879820600785, 24039.27882163538, 24326.63510047315, 24585.877070614533, 24660.630090548297], "value": 18962.820316475936, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [356.38523546881635, 364.10611947668474, 367.68746565419394, 373.6823699855122, 390.9600462769035, 19009.619807603838, 19666.0, 19744.506387340287, 20065.197392104317, 20261.290854820065, 18874.811251887477, 19576.314828980987, 19624.803751962478, 20058.699119513218, 20093.799062009377, 4312.913741725163, 5079.120632770123, 5112.56543193828, 5263.473652634744, 5266.894662106755, 5343.198520221959, 5392.703401312926, 5434.592405569579, 5435.402105768382, 5459.645123067008, 5419.729013549327, 5421.21392398102, 5435.456454354553, 5439.891202175954, 7167.2832716728435, 7457.105147382322, 7604.505707129047, 7651.1583725790115, 7691.884621730651, 7695.345895598881, 6931.890897456433, 7129.287071292881, 7360.153582338046, 7430.554166750012, 7436.293552112541, 22958.540829183323, 25266.621000685005, 25926.481470370676, 26114.216573502825, 26904.461910761675, 2252.628316327804, 2334.8365614407, 2382.702162229721, 2414.710234771821, 2438.451348446603, 13332.666683332995, 14027.834507217633, 16048.993875765529, 16570.254338554794, 16964.0669763163, 1595.3937503748618, 1981.4352909420745, 2000.3298894870236, 2006.5485265815075, 2065.0, 132088.01867971892, 138768.30615846958, 140367.0, 140396.29801851028, 140567.0, 16705.498835034967, 19999.600007999758, 20632.484187895123, 20668.483287917923, 20690.172393104407, 27463.0, 32650.0, 33065.008049758544, 33101.0, 33224.501632475534, 10068.345557538736, 10236.795264094677, 10314.638987635546, 10548.0, 10550.208734344955, 7082.893756593655, 8613.741587752378, 8618.698345558001, 8643.35174861888, 8820.647174113092, 9323.440593564272, 9507.667231646998, 9574.234061275081, 9613.471259080818, 9648.807023859483, 3204.4872820348633, 3666.7983260920905, 3769.3791669581797, 3802.885913422602, 3815.4086116651965, 801.3028665061317, 830.9418340716092, 869.2350731356493, 904.1230006893303, 967.8064387122463, 20469.488262793548, 21018.369448916557, 21216.151353945977, 21283.574328513652, 21289.89355053215, 877.7103555826562, 906.3881879731287, 910.1035480052143, 918.1828172926101, 991.2268430624125, 9961.953994830583, 10284.948575257076, 10612.734681633021, 11164.720881977853, 11186.776264474665, 489523.0, 493637.0, 494783.0, 498333.0, 505019.0, 69837.65081174561, 71674.64162679153, 103185.96814031914, 125095.0, 125851.3707431457, 954.4511905654276, 955.2644463762864, 978.9559469823876, 980.3823591137681, 1037.206536999181, 15654.295556699748, 17985.820141798413, 18417.81582184161, 19810.910399928143, 19904.800951990874, 52915.0, 54616.0, 54764.45235547753, 55026.44973550213, 56627.867442650095, 67641.32358676351, 78307.0, 78494.06088157145, 81057.18942810496, 81660.77508837254, 7797.103333116537, 7890.0, 8184.386171037077, 8278.627461764114, 8303.4602750821, 11688.590899318484, 12506.812397813857, 12729.236245825281, 12953.870461295266, 13013.739725205633, 9124.543772811465, 9639.614415423584, 9907.851382229128, 10352.792944141225, 11216.046636036028, 10656.680299591024, 10777.730556736144, 11056.11551075928, 11266.324020558794, 11304.039156671446, 9580.377275477073, 10687.0, 11119.944400277947, 11349.659510214708, 11352.54589816398, 7934.920650793649, 8191.385646076687, 8199.59002049907, 8200.712975045844, 8409.453385529921, 8296.958515207385, 8782.604782784918, 9312.34813563044, 11013.504392302206, 11376.0], "value": 10383.282347126358, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1336.3451908564803, 1476.7489526780446, 1610.1144370596176, 1637.165045826628, 1722.5607470095124, 7522.5110367826055, 7763.728269510569, 7900.328472079873, 8367.372447066471, 8369.832603347937, 7941.00737407824, 8224.383171262165, 8246.752597422086, 8386.62260198291, 8420.157984201576, 1978.6042791441769, 2308.7691230876903, 2339.8128149748063, 2341.0, 2344.742078371378, 1969.9756126814048, 2002.8283454179323, 2234.575430668168, 2255.8872056397195, 2348.5068135691527, 1876.6246750649857, 2197.0772275644267, 2271.0915633746467, 2290.1755368067484, 2312.6993490846166, 1865.533616595851, 2276.4764104256033, 2302.182725132579, 2305.4582173189287, 2309.4111001694628, 1895.6872116100826, 2292.86242825431, 2304.8617082975074, 2310.0413328468667, 2311.410590299463, 8274.048484424247, 8289.336853051802, 8319.001719793603, 8355.916440835575, 8374.958125209396, 5024.422191447957, 5165.793368265265, 5187.636865419422, 5194.194899790538, 5201.24581935621, 3241.286916878283, 3257.5602293690285, 3462.7056700180515, 3532.717382609385, 3533.911652208715, 1022.6676330192643, 1064.5848119233506, 1073.125402796726, 1074.6346242277584, 1080.3733834376121, 10769.299995500267, 10825.0, 10827.729306767314, 10877.945610271898, 10980.286281391684, 4478.910421791546, 4595.218812801765, 4679.485256621769, 4685.1098291325225, 4705.905881882343, 9279.814403711887, 9386.296027797942, 9412.482313472825, 9428.340016198801, 9430.151286384122, 5171.534561889448, 5179.223116532475, 5187.429382767892, 5194.428612852583, 5199.402068762065, 4191.64371028466, 4593.770311484479, 4691.202495575761, 4746.264329029006, 4754.976225118852, 3726.0312318796764, 3761.2289480656946, 3809.1810260794064, 3840.731148819556, 3847.153626202231, 1253.360785999147, 1267.163671976491, 1270.4029106320015, 1276.5723482633236, 1279.411470723459, 852.1861622150831, 878.8110556230441, 892.2594246775161, 912.8630705394111, 915.7435917942987, 5494.587905907073, 5690.6870122142855, 5793.246877905845, 5839.766409343579, 5843.970780146072, 915.5193523400268, 920.6225447566466, 924.3852837862746, 941.0, 983.8475036369374, 3824.043989002749, 3824.1778017726324, 3829.7893615851394, 3908.1988192420486, 3932.5870783567884, 9698.272629552812, 10063.597456101674, 10073.24450666203, 10119.342242754197, 10126.13927816144, 9847.458389788488, 10043.146332561813, 10048.246381521416, 10054.195664346835, 10065.849012264822, 7393.593352365564, 7690.846183076419, 7713.460057796013, 7758.844823103619, 7818.944442500303, 5617.466340697627, 5754.942450575608, 5783.8554036149435, 5795.652260864362, 5853.0, 8944.552772361225, 9263.907360926574, 9303.395279306824, 9389.248860091313, 9499.335046546676, 9343.112404321579, 9412.388194767318, 9459.668911588062, 9627.951860240653, 9642.228621710106, 6597.868042639217, 6756.729730810713, 7104.857902841811, 7136.429085673238, 7264.309890560388, 7413.555186688816, 7896.210378962058, 7950.681972721027, 7953.761387158394, 7959.204079591995, 7043.542169758949, 7142.285771422816, 7153.785386438415, 7229.49393542468, 7289.708411663474, 6902.689378978059, 7024.964875175591, 7044.436445084483, 7054.118235220598, 7091.893621595577, 5515.696636685021, 5889.941100589111, 6648.468122550281, 6698.430633396216, 6784.898226526508, 3996.780177090288, 4518.938049558417, 4608.331791890224, 4854.199406320908, 4955.554000140005, 4089.26393249218, 4825.831095911626, 4926.679765815211, 4980.750962451935, 5024.648274620741], "value": 4380.971486690361, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [298.48064367999683, 304.67704233512467, 305.2871544942559, 306.5922323309998, 312.10270472391875, 4297.989972356498, 5039.84880453587, 5447.836564903055, 5468.562514998801, 5468.644538105021, 4408.537103604123, 4620.653450991181, 4717.433907931045, 5333.35999680038, 5371.623986320959, 3630.4917311576332, 3965.781881996489, 3985.820638071279, 4028.45615841862, 4065.5324637666604, 4059.5940405959313, 4072.6538244249127, 4074.3684728867074, 4075.919881231476, 4109.342505199169, 4088.407180958759, 4090.284200264942, 4098.426220329156, 4107.418643822135, 4108.424820525129, 3423.058658868806, 3517.979785862097, 3962.7622342659533, 4043.818028188709, 4090.6727461803134, 3121.953170702441, 3342.8161451120177, 3532.788032718045, 3548.3435564420665, 3551.4140166872435, 5155.278261043457, 5194.168932970707, 5208.66143700662, 5219.608529360313, 5255.816046438395, 4433.334999750031, 4438.245498265271, 4680.742559159245, 4710.576048155648, 4715.41057367829, 5590.825926555428, 5615.438456154393, 5626.578006649518, 5633.971830140823, 5674.914876276859, 3002.789804713671, 3830.8659196927974, 3900.4539364488996, 3910.921781564381, 3923.901902452433, 4493.68544201906, 5931.406859314077, 5956.463918247336, 5962.672053037081, 5970.641761494324, 3021.9697803022127, 3825.158465137665, 3875.399313106473, 3880.3791393376923, 3887.883363499043, 3948.7235893487755, 3961.227560625708, 3967.305721498784, 3969.821358038895, 3974.4038394240515, 3883.825227864753, 4252.55348188442, 4644.769136178916, 4666.370040044619, 4667.056337394896, 4840.903181936342, 4851.331767527846, 5698.0, 5744.224529688438, 5818.941810581925, 1156.0232003274327, 1262.2363470100456, 1272.3193091695898, 1272.732726127505, 1274.3688079258563, 834.9540775257419, 864.304235090758, 877.8419884420865, 884.4339622641514, 885.5698047652994, 4395.84614538496, 4987.950120498748, 5149.845504634867, 5401.919616076801, 5411.269478620336, 4968.652194346361, 5399.433059528693, 5881.235439392852, 5953.713326404982, 5968.761249550056, 4348.673849461303, 4728.503507131771, 5378.11261141915, 5406.675599464044, 5410.97294513525, 2493.5262300162913, 2609.4781043791318, 2635.0908936416904, 2891.5373540233463, 2947.3073827650487, 5469.398366179717, 5520.751566179451, 5691.634007838089, 5876.471117599436, 5887.4406931341455, 4676.462206846256, 5413.810516631899, 5626.099824028136, 6092.786752463642, 6141.477974372139, 5508.20131080999, 5871.240516957023, 6077.116094010871, 6210.627362358273, 6219.191505104398, 4930.436173189993, 4963.4540200577185, 5706.42935706426, 5996.400359963969, 6025.969870150621, 5543.196236545759, 5861.032929566573, 5899.345294617525, 5906.940930590639, 5921.535901497672, 6187.195664563578, 6218.626882387071, 6236.316194627516, 6238.625682459067, 6289.654069026247, 3729.645683660048, 3867.2072225193206, 3897.201073779925, 3907.628775266345, 3948.6446219840354, 4662.790174442091, 4882.438519570295, 5373.462653734595, 5905.5570832186895, 5929.258842644669, 4830.6135509159885, 5000.824971125993, 5340.546053585489, 5438.782448702166, 5826.271716035496, 5050.116229659721, 5512.310961129859, 5688.317401911631, 5757.740901659352, 5765.654060756368, 5144.614153938394, 5582.274304340573, 5585.748641311069, 5647.39737114267, 5660.490555849993, 4962.478939711423, 5019.648624596243, 5600.943990560042, 5719.628224165416, 5761.711914404179, 5150.510701483353, 5483.972580137074, 5634.605577609528, 5645.209670646195, 5653.632513886584], "value": 4091.2072238991777, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [640.8269767162864, 650.4958657040794, 682.5461068389521, 771.459978015389, 772.5712229712509, 13941.581752547414, 14773.409063637442, 14885.404583816635, 14939.02896311739, 14945.925270373646, 13262.0, 13408.798868016987, 13416.798748018788, 13445.865541344609, 13492.0, 642.9357064293556, 666.6300203387118, 811.655046605192, 833.5707110837923, 837.3008537870885, 792.0178978067213, 805.1103530598692, 826.1284345016036, 829.4898637338054, 832.4131487301438, 870.3602851903846, 873.2097451806126, 874.204473928725, 874.3835595904872, 875.426595579893, 849.583703985049, 858.3690987124489, 861.6079683743882, 865.4763867859948, 866.7746385939638, 641.9518536109811, 643.0450780590819, 644.0050122560655, 644.3588629313816, 644.6422235659207, 11582.76834463306, 14018.789718154236, 14043.64890877726, 14134.646633834236, 14144.646383840487, 12548.874511254862, 12647.810282845672, 12867.034972377016, 13868.583942481742, 14217.786733199011, 12049.0, 12446.190997585129, 12458.26252124359, 12608.68478288041, 12706.237625742393, 4500.8649740507835, 4541.477730061052, 4542.931856022163, 4550.930531325136, 4632.861014169546, 88528.0, 89293.0, 91259.54370228107, 91998.54000730021, 92962.5351873243, 10723.892761072446, 10749.301295415773, 10813.026827585554, 10968.23222374442, 11073.335599864035, 20433.897830510752, 20446.07992640335, 20470.59058818845, 20484.69272960907, 20526.07632656534, 7509.399248060251, 7789.182135875765, 7831.0, 7894.328982036591, 7896.447248692536, 3398.76208665396, 4141.8964525886495, 4220.11377610699, 4284.036091879302, 4447.08834688888, 3296.159479332755, 3362.747793915466, 3407.216340241758, 3409.0454672691685, 3411.0, 550.5760564365416, 599.0355527600559, 601.9006863867413, 609.2141137932018, 622.4149299658307, 535.898179345923, 575.516566084491, 604.7973928733924, 626.79629120536, 676.4081428749844, 9088.136627020422, 9847.261455390731, 9903.950480247697, 9944.80110397788, 9995.750106247257, 537.046742032887, 542.8832800947815, 576.2336092996305, 588.3902259637157, 659.307726886767, 3959.7228194026716, 4207.389928460418, 4270.807813648394, 4604.194266003436, 4620.353150558924, 84434.0, 106673.46663266634, 113692.0, 114658.0, 115736.0, 95008.0, 97351.05297894003, 98264.50867745615, 98566.0, 98871.0, 82077.58961205036, 82307.0, 83615.58192209, 83977.0, 87320.0, 9351.485668288038, 9373.953130234579, 9478.763030924281, 9520.333576649846, 9523.61905523799, 26755.732442675326, 49768.75115624399, 54642.45357546374, 54680.45319546753, 54933.72533137309, 51046.0, 51200.74399628127, 70228.64885675539, 77066.0, 77935.44129117273, 12442.502299908265, 12665.240085594894, 12968.610941671388, 12979.221246725227, 13368.933155334162, 11382.772344553228, 13766.862331376558, 13858.445662173803, 14051.508197213048, 14828.555143345717, 12849.743005140032, 12915.418806153933, 13034.283114428417, 13067.607971760863, 13167.0, 12778.324286805808, 12999.805002924775, 13002.674933126747, 13041.95664346869, 13089.0, 12629.431675574437, 12704.237745735285, 13102.410391532594, 13253.403596837972, 13341.0, 8667.829842971118, 10120.696379108638, 10220.897791021995, 10379.844302335621, 10609.469526523797, 10330.793384132125, 10861.076808471369, 11065.170112241774, 11116.666500005012, 11127.72180695489], "value": 7241.8608110151845, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1562.3999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 119.0, 119.0, 120.0, 122.0, 119.0, 146.0, 177.0, 177.0, 119.0], "value": 132.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 116.0, 112.0, 155.0, 119.0, 118.0, 119.0, 117.0, 140.0, 111.0], "value": 125.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 212.0, 207.0, 198.0, 194.0, 271.0, 220.0, 196.0, 193.0, 193.0], "value": 208.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 59.0, 63.0, 84.0, 60.0, 66.0, 82.0, 84.0, 99.0, 74.0], "value": 75.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 69.0, 85.0, 74.0, 70.0, 70.0, 79.0, 71.0, 71.0, 81.0], "value": 76.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 90.0, 99.0, 132.0, 98.0, 88.0, 93.0, 89.0, 93.0, 88.0], "value": 99.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 96.0, 96.0, 121.0, 118.0, 97.0, 96.0, 97.0, 96.0, 119.0], "value": 104.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 100.0, 93.0, 93.0, 96.0, 93.0, 93.0, 96.0, 92.0, 91.0], "value": 94.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 60.0, 63.0, 85.0, 91.0, 84.0, 59.0, 59.0, 57.0, 54.0], "value": 66.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 99.0, 71.0, 72.0, 102.0, 107.0, 110.0, 86.0, 73.0], "value": 85.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 91.0, 90.0, 95.0, 91.0, 88.0, 91.0, 91.0, 87.0, 90.0], "value": 90.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 150.0, 148.0, 152.0, 151.0, 165.0, 164.0, 166.0, 204.0, 153.0], "value": 160.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 178.0, 177.0, 172.0, 178.0, 175.0, 173.0, 175.0, 172.0, 175.0], "value": 175.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 69.0, 69.0, 67.0, 71.0, 68.0, 69.0, 69.0, 68.0, 68.0], "value": 68.8, "unit": "score"}]}]} 12:21:05 INFO - Return code: 0 12:21:06 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:21:06 ERROR - # TBPL SUCCESS # 12:21:06 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:21:06 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:21:06 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:21:06 INFO - 2016-11-23 12:21:06.047 screenresolution[1654:10720] starting screenresolution argv=screenresolution get 12:21:06 INFO - 2016-11-23 12:21:06.049 screenresolution[1654:10720] Display 0: 1600x1200x32@60 12:21:06 INFO - 2016-11-23 12:21:06.055 screenresolution[1655:10723] starting screenresolution argv=screenresolution list 12:21:06 INFO - Available Modes on Display 0 12:21:06 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:21:06 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:21:06 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:21:06 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:21:06 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:21:06 INFO - Intel Iris: 12:21:06 INFO - Chipset Model: Intel Iris 12:21:06 INFO - Type: GPU 12:21:06 INFO - Bus: Built-In 12:21:06 INFO - VRAM (Dynamic, Max): 1536 MB 12:21:06 INFO - Vendor: Intel (0x8086) 12:21:06 INFO - Device ID: 0x0a2e 12:21:06 INFO - Revision ID: 0x0009 12:21:06 INFO - Displays: 12:21:06 INFO - Display: 12:21:06 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:21:06 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:21:06 INFO - Display Serial Number: 1600x1200 60 12:21:06 INFO - Main Display: Yes 12:21:06 INFO - Mirror: Off 12:21:06 INFO - Online: Yes 12:21:06 INFO - Rotation: Supported 12:21:06 INFO - Return code: 0 12:21:06 INFO - Running post-action listener: _resource_record_post_action 12:21:06 INFO - [mozharness: 2016-11-23 20:21:06.292665Z] Finished run-tests step (success) 12:21:06 INFO - Running post-run listener: _resource_record_post_run 12:21:06 INFO - Total resource usage - Wall time: 1213s; CPU: 55.0%; Read bytes: 38574592; Write bytes: 695296512; Read time: 244; Write time: 1996 12:21:06 INFO - TinderboxPrint: CPU usage
54.5% 12:21:06 INFO - TinderboxPrint: I/O read bytes / time
38,574,592 / 244 12:21:06 INFO - TinderboxPrint: I/O write bytes / time
695,296,512 / 1,996 12:21:06 INFO - TinderboxPrint: CPU idle
2,207.0 (45.5%) 12:21:06 INFO - TinderboxPrint: CPU system
982.2 (20.2%) 12:21:06 INFO - TinderboxPrint: CPU user
1,664.4 (34.3%) 12:21:06 INFO - TinderboxPrint: Swap in / out
422,064,128 / 0 12:21:06 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 370096640; Write bytes: 366794752; Read time: 28092; Write time: 771 12:21:06 INFO - run-tests - Wall time: 1180s; CPU: 55.0%; Read bytes: 36755968; Write bytes: 309058048; Read time: 229; Write time: 1176 12:21:06 INFO - Running post-run listener: _upload_blobber_files 12:21:06 INFO - Blob upload gear active. 12:21:06 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:21:06 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 12:21:06 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'] 12:21:06 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 12:21:07 INFO - (blobuploader) - INFO - Open directory for files ... 12:21:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:21:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:21:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:21:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:21:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:21:08 INFO - (blobuploader) - INFO - Done attempting. 12:21:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:21:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:21:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:21:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:21:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:21:09 INFO - (blobuploader) - INFO - Done attempting. 12:21:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:21:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:21:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:21:12 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:21:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:21:12 INFO - (blobuploader) - INFO - Done attempting. 12:21:12 INFO - (blobuploader) - INFO - Iteration through files over. 12:21:12 INFO - Return code: 0 12:21:12 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:21:12 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:21:12 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/b7864df82cbc037f0c6cd45603b9df4ef6cda2a1a2a7ff5cf0ac1985b71beb15664a3fc7b454bee5f1f2e7b523d9d08cea611b282af80b464900b98e55266146", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/d4cc805fc860687a73d080378fc0707485072e3a7f8f9684a6dab3faa7ce25bd54b831eeca2b68ce5c9405ba2048e3c5c7339de1e49f0b63e80c70d66af65559", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/7af3113345e53fc2a172316f9573824201b4d0864baf522a54784665dc0ccfe5d33e8d3986715bdfd910cd99e141dc7b5b21b920990dffd27fcdca87792393bd"} 12:21:12 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:21:12 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:21:12 INFO - Contents: 12:21:12 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/b7864df82cbc037f0c6cd45603b9df4ef6cda2a1a2a7ff5cf0ac1985b71beb15664a3fc7b454bee5f1f2e7b523d9d08cea611b282af80b464900b98e55266146", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/d4cc805fc860687a73d080378fc0707485072e3a7f8f9684a6dab3faa7ce25bd54b831eeca2b68ce5c9405ba2048e3c5c7339de1e49f0b63e80c70d66af65559", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/7af3113345e53fc2a172316f9573824201b4d0864baf522a54784665dc0ccfe5d33e8d3986715bdfd910cd99e141dc7b5b21b920990dffd27fcdca87792393bd"} 12:21:12 INFO - Running post-run listener: copy_logs_to_upload_dir 12:21:12 INFO - Copying logs to upload dir... 12:21:12 INFO - mkdir: /builds/slave/test/build/upload/logs 12:21:12 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.339587 ========= master_lag: 3.94 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 20 secs) (at 2016-11-23 12:21:16.404105) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-23 12:21:16.412009) ========= 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.6zs13kVB34/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.4Ye8jQ5W1O/Listeners TMPDIR=/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/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/b7864df82cbc037f0c6cd45603b9df4ef6cda2a1a2a7ff5cf0ac1985b71beb15664a3fc7b454bee5f1f2e7b523d9d08cea611b282af80b464900b98e55266146", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/d4cc805fc860687a73d080378fc0707485072e3a7f8f9684a6dab3faa7ce25bd54b831eeca2b68ce5c9405ba2048e3c5c7339de1e49f0b63e80c70d66af65559", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/7af3113345e53fc2a172316f9573824201b4d0864baf522a54784665dc0ccfe5d33e8d3986715bdfd910cd99e141dc7b5b21b920990dffd27fcdca87792393bd"} build_url:https://queue.taskcluster.net/v1/task/VIx2gix_QfO6j9SATyQQEA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011529 build_url: 'https://queue.taskcluster.net/v1/task/VIx2gix_QfO6j9SATyQQEA/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/b7864df82cbc037f0c6cd45603b9df4ef6cda2a1a2a7ff5cf0ac1985b71beb15664a3fc7b454bee5f1f2e7b523d9d08cea611b282af80b464900b98e55266146", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/d4cc805fc860687a73d080378fc0707485072e3a7f8f9684a6dab3faa7ce25bd54b831eeca2b68ce5c9405ba2048e3c5c7339de1e49f0b63e80c70d66af65559", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/7af3113345e53fc2a172316f9573824201b4d0864baf522a54784665dc0ccfe5d33e8d3986715bdfd910cd99e141dc7b5b21b920990dffd27fcdca87792393bd"}' ========= master_lag: 0.05 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-23 12:21:16.470681) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-23 12:21:16.471044) ========= 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.6zs13kVB34/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.4Ye8jQ5W1O/Listeners TMPDIR=/var/folders/jp/6dm4jt356msd6dqb5shhcwxm00000w/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.005253 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-23 12:21:16.519515) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-23 12:21:16.522881) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-23 12:21:16.523227) ========= ========= Total master_lag: 4.10 =========