builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0332 starttime: 1484159489.21 results: success (0) buildid: 20170111064204 builduid: e2833fa29e7542778d1a4e40f825fd53 revision: 490dfb0aefde5c625c34e70476a932f5a5a1c1c6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-11 10:31:29.211871) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-11 10:31:29.212478) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-11 10:31:29.212804) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-11 10:31:29.237680) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-11 10:31:29.238159) ========= 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.NPKYavLDyw/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.TU7LnxDPUj/Listeners TMPDIR=/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-01-11 10:31:29-- 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% 173M=0s 2017-01-11 10:31:29 (173 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.102871 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-11 10:31:29.366291) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-11 10:31:29.366644) ========= 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.NPKYavLDyw/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.TU7LnxDPUj/Listeners TMPDIR=/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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.035093 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-11 10:31:29.423132) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-01-11 10:31:29.423586) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 490dfb0aefde5c625c34e70476a932f5a5a1c1c6 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 490dfb0aefde5c625c34e70476a932f5a5a1c1c6 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.NPKYavLDyw/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.TU7LnxDPUj/Listeners TMPDIR=/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-01-11 10:31:29,523 truncating revision to first 12 chars 2017-01-11 10:31:29,523 Setting DEBUG logging. 2017-01-11 10:31:29,523 attempt 1/10 2017-01-11 10:31:29,523 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/490dfb0aefde?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-11 10:31:31,186 unpacking tar archive at: mozilla-aurora-490dfb0aefde/testing/mozharness/ program finished with exit code 0 elapsedTime=1.958216 ========= master_lag: 0.74 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-01-11 10:31:32.122899) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-11 10:31:32.123237) ========= script_repo_revision: 490dfb0aefde5c625c34e70476a932f5a5a1c1c6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-11 10:31:32.123684) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-11 10:31:32.123974) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-11 10:31:32.146900) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2017-01-11 10:31:32.147306) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.NPKYavLDyw/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.TU7LnxDPUj/Listeners TMPDIR=/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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 10:31:32 INFO - MultiFileLogger online at 20170111 10:31:32 in /builds/slave/test 10:31:32 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 10:31:32 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:31:32 INFO - {'append_to_log': False, 10:31:32 INFO - 'base_work_dir': '/builds/slave/test', 10:31:32 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 10:31:32 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:31:32 INFO - 'branch': 'Mozilla-Aurora', 10:31:32 INFO - 'buildbot_json_path': 'buildprops.json', 10:31:32 INFO - 'config_files': ('talos/mac_config.py',), 10:31:32 INFO - 'default_actions': ('clobber', 10:31:32 INFO - 'read-buildbot-config', 10:31:32 INFO - 'download-and-extract', 10:31:32 INFO - 'populate-webroot', 10:31:32 INFO - 'create-virtualenv', 10:31:32 INFO - 'install', 10:31:32 INFO - 'run-tests'), 10:31:32 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:31:32 INFO - 'download_minidump_stackwalk': True, 10:31:32 INFO - 'download_symbols': 'ondemand', 10:31:32 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:31:32 INFO - 'tooltool.py': '/tools/tooltool.py', 10:31:32 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:31:32 INFO - '/tools/misc-python/virtualenv.py')}, 10:31:32 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:31:32 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:31:32 INFO - 'installer_path': 'installer.exe', 10:31:32 INFO - 'log_level': 'info', 10:31:32 INFO - 'log_name': 'talos', 10:31:32 INFO - 'log_to_console': True, 10:31:32 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:31:32 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:31:32 INFO - 'opt_config_files': (), 10:31:32 INFO - 'pip_index': False, 10:31:32 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:31:32 INFO - 'cmd': ('bash', 10:31:32 INFO - '-c', 10:31:32 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:31:32 INFO - 'enabled': True, 10:31:32 INFO - 'halt_on_failure': False, 10:31:32 INFO - 'name': 'check_screen_resolution'},), 10:31:32 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:31:32 INFO - 'cmd': ('bash', 10:31:32 INFO - '-c', 10:31:32 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:31:32 INFO - 'enabled': True, 10:31:32 INFO - 'halt_on_failure': False, 10:31:32 INFO - 'name': 'check_screen_resolution'},), 10:31:32 INFO - 'run_cmd_checks_enabled': True, 10:31:32 INFO - 'sps_profile': False, 10:31:32 INFO - 'sps_profile_interval': 0, 10:31:32 INFO - 'suite': 'dromaeojs', 10:31:32 INFO - 'system_bits': '32', 10:31:32 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:31:32 INFO - 'title': 't-yosemite-r7-0332', 10:31:32 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:31:32 INFO - 'use_talos_json': True, 10:31:32 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:31:32 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:31:32 INFO - 'work_dir': 'build'} 10:31:32 INFO - [mozharness: 2017-01-11 18:31:32.355621Z] Running clobber step. 10:31:32 INFO - Running pre-action listener: _resource_record_pre_action 10:31:32 INFO - Running main action method: clobber 10:31:32 INFO - rmtree: /builds/slave/test/build 10:31:32 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:31:35 INFO - Running post-action listener: _resource_record_post_action 10:31:35 INFO - [mozharness: 2017-01-11 18:31:35.084646Z] Finished clobber step (success) 10:31:35 INFO - [mozharness: 2017-01-11 18:31:35.084802Z] Running read-buildbot-config step. 10:31:35 INFO - Running pre-action listener: _resource_record_pre_action 10:31:35 INFO - Running main action method: read_buildbot_config 10:31:35 INFO - Using buildbot properties: 10:31:35 INFO - { 10:31:35 INFO - "project": "", 10:31:35 INFO - "product": "firefox", 10:31:35 INFO - "script_repo_revision": "production", 10:31:35 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 10:31:35 INFO - "repository": "", 10:31:35 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 10:31:35 INFO - "buildid": "20170111064204", 10:31:35 INFO - "pgo_build": "False", 10:31:35 INFO - "basedir": "/builds/slave/test", 10:31:35 INFO - "buildnumber": 1, 10:31:35 INFO - "slavename": "t-yosemite-r7-0332", 10:31:35 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 10:31:35 INFO - "platform": "yosemite_r7", 10:31:35 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 10:31:35 INFO - "branch": "mozilla-aurora", 10:31:35 INFO - "repo_path": "releases/mozilla-aurora", 10:31:35 INFO - "revision": "490dfb0aefde5c625c34e70476a932f5a5a1c1c6", 10:31:35 INFO - "stage_platform": "macosx64", 10:31:35 INFO - "builduid": "e2833fa29e7542778d1a4e40f825fd53", 10:31:35 INFO - "slavebuilddir": "test" 10:31:35 INFO - } 10:31:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/GNWYyDg8SiqGD_Cu7YZThw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 10:31:35 INFO - Running post-action listener: _resource_record_post_action 10:31:35 INFO - [mozharness: 2017-01-11 18:31:35.088222Z] Finished read-buildbot-config step (success) 10:31:35 INFO - [mozharness: 2017-01-11 18:31:35.088321Z] Running download-and-extract step. 10:31:35 INFO - Running pre-action listener: _resource_record_pre_action 10:31:35 INFO - Running main action method: download_and_extract 10:31:35 INFO - mkdir: /builds/slave/test/build/tests 10:31: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')]} 10:31:35 INFO - trying https://queue.taskcluster.net/v1/task/GNWYyDg8SiqGD_Cu7YZThw/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 10:31:35 INFO - Downloading https://queue.taskcluster.net/v1/task/GNWYyDg8SiqGD_Cu7YZThw/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 10:31:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GNWYyDg8SiqGD_Cu7YZThw/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 10:31:36 INFO - Downloaded 1199 bytes. 10:31:36 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 10:31:36 INFO - Using the following test package requirements: 10:31:36 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 10:31:36 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:31:36 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 10:31:36 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:31:36 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 10:31:36 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:31:36 INFO - u'jsshell-mac.zip'], 10:31:36 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:31:36 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 10:31:36 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 10:31:36 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:31:36 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 10:31:36 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:31:36 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 10:31:36 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:31:36 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 10:31:36 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 10:31:36 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 10:31:36 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 10:31:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GNWYyDg8SiqGD_Cu7YZThw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 10:31:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GNWYyDg8SiqGD_Cu7YZThw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 10:31:36 INFO - Fetch https://queue.taskcluster.net/v1/task/GNWYyDg8SiqGD_Cu7YZThw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 10:31:36 INFO - Http code: 200 10:31:36 INFO - via: 1.1 23172d761378a3865feafcda78328053.cloudfront.net (CloudFront) 10:31:36 INFO - x-amz-cf-id: SoA_uuPCYoGv2Os91M8GvkQ0DYEDnyv1FvxRWTPEmKgDGlVMPz3cLQ== 10:31:36 INFO - x-amz-version-id: IsF67xIhBBFE204SIJn9p9oyNn96VkIE 10:31:37 INFO - Expected file size: 16271770 10:31:37 INFO - Obtained file size: 16271770 10:31:43 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 10:31:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GNWYyDg8SiqGD_Cu7YZThw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 10:31:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GNWYyDg8SiqGD_Cu7YZThw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 10:31:43 INFO - Fetch https://queue.taskcluster.net/v1/task/GNWYyDg8SiqGD_Cu7YZThw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 10:31:44 INFO - Http code: 200 10:31:44 INFO - via: 1.1 032d42677fbf05c24a4bb71369581429.cloudfront.net (CloudFront) 10:31:44 INFO - x-amz-cf-id: Xcbtvy0RRybdcz42B-q3TIyQfBg4EA0IjZZOl1V-7eKUt4PJ782yJA== 10:31:44 INFO - x-amz-version-id: IsF67xIhBBFE204SIJn9p9oyNn96VkIE 10:31:44 INFO - Expected file size: 16271770 10:31:44 INFO - Obtained file size: 16271770 10:31:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GNWYyDg8SiqGD_Cu7YZThw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 10:31:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GNWYyDg8SiqGD_Cu7YZThw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 10:31:50 INFO - Fetch https://queue.taskcluster.net/v1/task/GNWYyDg8SiqGD_Cu7YZThw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 10:31:51 INFO - Http code: 200 10:31:51 INFO - via: 1.1 93ab344e1a3fa44edbf3f1bc5729427a.cloudfront.net (CloudFront) 10:31:51 INFO - x-amz-cf-id: _Een19z121vrgy7dWJY9zvsNQgPk7TFU5StchoDcGYhCiOBHij6Pwg== 10:31:51 INFO - x-amz-version-id: 5u2p3aMIrJc.UWLtQJSVvaUrqDtHN0wL 10:31:51 INFO - Expected file size: 14409038 10:31:51 INFO - Obtained file size: 14409038 10:31:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:31:51 INFO - trying https://queue.taskcluster.net/v1/task/GNWYyDg8SiqGD_Cu7YZThw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 10:31:51 INFO - Downloading https://queue.taskcluster.net/v1/task/GNWYyDg8SiqGD_Cu7YZThw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 10:31:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GNWYyDg8SiqGD_Cu7YZThw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 10:31:54 INFO - Downloaded 109870527 bytes. 10:31:54 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GNWYyDg8SiqGD_Cu7YZThw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 10:31:54 INFO - mkdir: /builds/slave/test/properties 10:31:54 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:31:54 INFO - Writing to file /builds/slave/test/properties/build_url 10:31:54 INFO - Contents: 10:31:54 INFO - build_url:https://queue.taskcluster.net/v1/task/GNWYyDg8SiqGD_Cu7YZThw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 10:31:55 INFO - Running post-action listener: _resource_record_post_action 10:31:55 INFO - Running post-action listener: set_extra_try_arguments 10:31:55 INFO - [mozharness: 2017-01-11 18:31:55.416696Z] Finished download-and-extract step (success) 10:31:55 INFO - [mozharness: 2017-01-11 18:31:55.416836Z] Running populate-webroot step. 10:31:55 INFO - Running pre-action listener: _resource_record_pre_action 10:31:55 INFO - Running main action method: populate_webroot 10:31:55 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:31:55 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:31:55 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:31:55 INFO - '--tppagecycles', 10:31:55 INFO - '7'], 10:31:55 INFO - 'tests': ['tsvgm']}}, 10:31:55 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:31:55 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:31:55 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:31:55 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:31:55 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:31:55 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:31:55 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:31:55 INFO - 'talos_options': ['--disable-e10s'], 10:31:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:31:55 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:31:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:31:55 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:31:55 INFO - 'talos_options': ['--disable-e10s'], 10:31:55 INFO - 'tests': ['damp', 'tps']}, 10:31:55 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:31:55 INFO - 'tests': ['damp', 'tps']}, 10:31:55 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:31:55 INFO - 'tests': ['dromaeo_dom']}, 10:31:55 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:31:55 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:31:55 INFO - 'tests': ['basic_compositor_video']}, 10:31:55 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:31:55 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:31:55 INFO - 'tests': ['a11yr', 10:31:55 INFO - 'ts_paint', 10:31:55 INFO - 'tpaint', 10:31:55 INFO - 'sessionrestore', 10:31:55 INFO - 'sessionrestore_no_auto_restore', 10:31:55 INFO - 'tabpaint']}, 10:31:55 INFO - 'other-e10s': {'tests': ['a11yr', 10:31:55 INFO - 'ts_paint', 10:31:55 INFO - 'tpaint', 10:31:55 INFO - 'sessionrestore', 10:31:55 INFO - 'sessionrestore_no_auto_restore', 10:31:55 INFO - 'tabpaint']}, 10:31:55 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:31:55 INFO - 'ts_paint', 10:31:55 INFO - 'tpaint', 10:31:55 INFO - 'sessionrestore', 10:31:55 INFO - 'sessionrestore_no_auto_restore', 10:31:55 INFO - 'tabpaint']}, 10:31:55 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:31:55 INFO - 'ts_paint', 10:31:55 INFO - 'tpaint', 10:31:55 INFO - 'sessionrestore', 10:31:55 INFO - 'sessionrestore_no_auto_restore', 10:31:55 INFO - 'tabpaint']}, 10:31:55 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:31:55 INFO - 'tests': ['a11yr', 10:31:55 INFO - 'ts_paint', 10:31:55 INFO - 'tpaint', 10:31:55 INFO - 'sessionrestore', 10:31:55 INFO - 'sessionrestore_no_auto_restore', 10:31:55 INFO - 'tabpaint']}, 10:31:55 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:31:55 INFO - 'tests': ['a11yr', 10:31:55 INFO - 'ts_paint', 10:31:55 INFO - 'tpaint', 10:31:55 INFO - 'sessionrestore', 10:31:55 INFO - 'sessionrestore_no_auto_restore', 10:31:55 INFO - 'tabpaint']}, 10:31:55 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:31:55 INFO - 'tests': ['tsvgx', 10:31:55 INFO - 'tsvgr_opacity', 10:31:55 INFO - 'tart', 10:31:55 INFO - 'tscrollx', 10:31:55 INFO - 'cart']}, 10:31:55 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:31:55 INFO - 'tsvgr_opacity', 10:31:55 INFO - 'tart', 10:31:55 INFO - 'tscrollx', 10:31:55 INFO - 'cart']}, 10:31:55 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:31:55 INFO - 'talos_options': ['--disable-e10s'], 10:31:55 INFO - 'tests': ['tp5o']}, 10:31:55 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:31:55 INFO - 'tests': ['tp5o']}, 10:31:55 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:31:55 INFO - 'talos_options': ['--disable-e10s', 10:31:55 INFO - '--xperf_path', 10:31:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:31:55 INFO - 'tests': ['tp5n']}, 10:31:55 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:31:55 INFO - 'talos_options': ['--xperf_path', 10:31:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:31:55 INFO - 'tests': ['tp5n']}}, 10:31:55 INFO - 'talos.zip': {'path': '', 10:31:55 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:31:55 INFO - Running post-action listener: _resource_record_post_action 10:31:55 INFO - [mozharness: 2017-01-11 18:31:55.435350Z] Finished populate-webroot step (success) 10:31:55 INFO - [mozharness: 2017-01-11 18:31:55.435488Z] Running create-virtualenv step. 10:31:55 INFO - Running pre-action listener: _resource_record_pre_action 10:31:55 INFO - Running main action method: create_virtualenv 10:31:55 INFO - Creating virtualenv /builds/slave/test/build/venv 10:31:55 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 10:31:55 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:31:55 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:31:55 INFO - Using real prefix '/tools/python27' 10:31:55 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:31:56 INFO - Installing distribute.............................................................................................................................................................................................done. 10:31:59 INFO - Installing pip.................done. 10:31:59 INFO - Return code: 0 10:31:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:31:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:31:59 INFO - Reading from file tmpfile_stdout 10:31:59 INFO - Output received: 10:31:59 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) 10:31:59 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:31:59 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')]} 10:31:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:31:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:31:59 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')]} 10:31:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:31:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:31:59 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 0x105f26490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e9c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a3adcef10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d3ad30>, '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 0x105ba0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105d32b20>, '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.NPKYavLDyw/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.TU7LnxDPUj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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 10:31:59 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 10:31:59 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 10:31:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NPKYavLDyw/Render', 10:31:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:31:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:31:59 INFO - 'HOME': '/Users/cltbld', 10:31:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:31:59 INFO - 'LOGNAME': 'cltbld', 10:31:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:31:59 INFO - 'MOZ_NO_REMOTE': '1', 10:31:59 INFO - 'NO_EM_RESTART': '1', 10:31:59 INFO - 'PAGER': '/bin/cat', 10:31:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:31:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:31:59 INFO - 'PWD': '/builds/slave/test', 10:31:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:31:59 INFO - 'SHELL': '/bin/bash', 10:31:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TU7LnxDPUj/Listeners', 10:31:59 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 10:31:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:31:59 INFO - 'USER': 'cltbld', 10:31:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:31:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:31:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:31:59 INFO - 'XPC_FLAGS': '0x0', 10:31:59 INFO - 'XPC_SERVICE_NAME': '0', 10:31:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:31:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:31:59 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 10:31:59 INFO - Cleaning up... 10:31:59 INFO - Return code: 0 10:31:59 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:31:59 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')]} 10:31:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:31:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:31:59 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')]} 10:31:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:31:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:31:59 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 0x105f26490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e9c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a3adcef10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d3ad30>, '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 0x105ba0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105d32b20>, '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.NPKYavLDyw/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.TU7LnxDPUj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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 10:31:59 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 10:31:59 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 10:31:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NPKYavLDyw/Render', 10:31:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:31:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:31:59 INFO - 'HOME': '/Users/cltbld', 10:31:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:31:59 INFO - 'LOGNAME': 'cltbld', 10:31:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:31:59 INFO - 'MOZ_NO_REMOTE': '1', 10:31:59 INFO - 'NO_EM_RESTART': '1', 10:31:59 INFO - 'PAGER': '/bin/cat', 10:31:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:31:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:31:59 INFO - 'PWD': '/builds/slave/test', 10:31:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:31:59 INFO - 'SHELL': '/bin/bash', 10:31:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TU7LnxDPUj/Listeners', 10:31:59 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 10:31:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:31:59 INFO - 'USER': 'cltbld', 10:31:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:31:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:31:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:31:59 INFO - 'XPC_FLAGS': '0x0', 10:31:59 INFO - 'XPC_SERVICE_NAME': '0', 10:31:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:32:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:32:00 INFO - Downloading/unpacking psutil>=3.1.1 10:32:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:32:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:32:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:32:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:32:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:32:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:32:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:32:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:32:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:32:03 INFO - Installing collected packages: psutil 10:32:03 INFO - Running setup.py install for psutil 10:32:03 INFO - building 'psutil._psutil_osx' extension 10:32:03 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 10:32:04 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 10:32:04 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 10:32:04 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 10:32:04 INFO - building 'psutil._psutil_posix' extension 10:32:04 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 10:32:04 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:32:04 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:32:04 INFO - ^ 10:32:04 INFO - 1 warning generated. 10:32:04 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 10:32:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:32:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:32:04 INFO - Successfully installed psutil 10:32:04 INFO - Cleaning up... 10:32:04 INFO - Return code: 0 10:32:04 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:32:04 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')]} 10:32:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:32:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:32:04 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')]} 10:32:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:32:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:32:04 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 0x105f26490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e9c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a3adcef10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d3ad30>, '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 0x105ba0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105d32b20>, '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.NPKYavLDyw/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.TU7LnxDPUj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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 10:32:04 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 10:32:04 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 10:32:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NPKYavLDyw/Render', 10:32:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:32:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:32:04 INFO - 'HOME': '/Users/cltbld', 10:32:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:32:04 INFO - 'LOGNAME': 'cltbld', 10:32:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:32:04 INFO - 'MOZ_NO_REMOTE': '1', 10:32:04 INFO - 'NO_EM_RESTART': '1', 10:32:04 INFO - 'PAGER': '/bin/cat', 10:32:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:32:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:32:04 INFO - 'PWD': '/builds/slave/test', 10:32:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:32:04 INFO - 'SHELL': '/bin/bash', 10:32:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TU7LnxDPUj/Listeners', 10:32:04 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 10:32:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:32:04 INFO - 'USER': 'cltbld', 10:32:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:32:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:32:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:32:04 INFO - 'XPC_FLAGS': '0x0', 10:32:04 INFO - 'XPC_SERVICE_NAME': '0', 10:32:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:32:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:32:05 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:32:05 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 10:32:05 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 10:32:05 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 10:32:05 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 10:32:05 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 10:32:05 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 10:32:07 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:32:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:32:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:32:07 INFO - Installing collected packages: mozsystemmonitor 10:32:07 INFO - Running setup.py install for mozsystemmonitor 10:32:07 INFO - Successfully installed mozsystemmonitor 10:32:07 INFO - Cleaning up... 10:32:08 INFO - Return code: 0 10:32:08 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:32:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:32:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:32:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:32:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:32:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:32:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:32:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x105f26490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e9c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a3adcef10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d3ad30>, '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 0x105ba0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105d32b20>, '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.NPKYavLDyw/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.TU7LnxDPUj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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 10:32:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 10:32:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 10:32:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NPKYavLDyw/Render', 10:32:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:32:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:32:08 INFO - 'HOME': '/Users/cltbld', 10:32:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:32:08 INFO - 'LOGNAME': 'cltbld', 10:32:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:32:08 INFO - 'MOZ_NO_REMOTE': '1', 10:32:08 INFO - 'NO_EM_RESTART': '1', 10:32:08 INFO - 'PAGER': '/bin/cat', 10:32:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:32:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:32:08 INFO - 'PWD': '/builds/slave/test', 10:32:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:32:08 INFO - 'SHELL': '/bin/bash', 10:32:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TU7LnxDPUj/Listeners', 10:32:08 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 10:32:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:32:08 INFO - 'USER': 'cltbld', 10:32:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:32:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:32:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:32:08 INFO - 'XPC_FLAGS': '0x0', 10:32:08 INFO - 'XPC_SERVICE_NAME': '0', 10:32:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:32:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:32:08 INFO - Downloading/unpacking jsonschema==2.5.1 10:32:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:32:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:32:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:32:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:32:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:32:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:32:10 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:32:10 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:32:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:32:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:32:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:32:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:32:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:32:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:32:11 INFO - Downloading functools32-3.2.3-2.tar.gz 10:32:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:32:11 INFO - warning: no files found matching '*.txt' 10:32:11 INFO - no previously-included directories found matching 'build' 10:32:11 INFO - no previously-included directories found matching 'dist' 10:32:11 INFO - no previously-included directories found matching '.git*' 10:32:11 INFO - Installing collected packages: jsonschema, functools32 10:32:11 INFO - Running setup.py install for functools32 10:32:11 INFO - warning: no files found matching '*.txt' 10:32:11 INFO - no previously-included directories found matching 'build' 10:32:11 INFO - no previously-included directories found matching 'dist' 10:32:11 INFO - no previously-included directories found matching '.git*' 10:32:11 INFO - Successfully installed jsonschema functools32 10:32:11 INFO - Cleaning up... 10:32:11 INFO - Return code: 0 10:32:11 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:32:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:32:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:32:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:32:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:32:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:32:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:32:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x105f26490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e9c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a3adcef10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d3ad30>, '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 0x105ba0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105d32b20>, '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.NPKYavLDyw/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.TU7LnxDPUj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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 10:32:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 10:32:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 10:32:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NPKYavLDyw/Render', 10:32:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:32:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:32:11 INFO - 'HOME': '/Users/cltbld', 10:32:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:32:11 INFO - 'LOGNAME': 'cltbld', 10:32:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:32:11 INFO - 'MOZ_NO_REMOTE': '1', 10:32:11 INFO - 'NO_EM_RESTART': '1', 10:32:11 INFO - 'PAGER': '/bin/cat', 10:32:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:32:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:32:11 INFO - 'PWD': '/builds/slave/test', 10:32:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:32:11 INFO - 'SHELL': '/bin/bash', 10:32:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TU7LnxDPUj/Listeners', 10:32:11 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 10:32:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:32:11 INFO - 'USER': 'cltbld', 10:32:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:32:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:32:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:32:11 INFO - 'XPC_FLAGS': '0x0', 10:32:11 INFO - 'XPC_SERVICE_NAME': '0', 10:32:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:32:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:32:11 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:32:11 INFO - Cleaning up... 10:32:11 INFO - Return code: 0 10:32:11 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:32:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:32:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:32:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:32:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:32:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:32:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:32:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x105f26490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e9c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a3adcef10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d3ad30>, '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 0x105ba0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105d32b20>, '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.NPKYavLDyw/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.TU7LnxDPUj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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 10:32:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 10:32:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 10:32:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NPKYavLDyw/Render', 10:32:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:32:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:32:11 INFO - 'HOME': '/Users/cltbld', 10:32:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:32:11 INFO - 'LOGNAME': 'cltbld', 10:32:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:32:11 INFO - 'MOZ_NO_REMOTE': '1', 10:32:11 INFO - 'NO_EM_RESTART': '1', 10:32:11 INFO - 'PAGER': '/bin/cat', 10:32:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:32:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:32:11 INFO - 'PWD': '/builds/slave/test', 10:32:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:32:11 INFO - 'SHELL': '/bin/bash', 10:32:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TU7LnxDPUj/Listeners', 10:32:11 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 10:32:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:32:11 INFO - 'USER': 'cltbld', 10:32:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:32:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:32:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:32:11 INFO - 'XPC_FLAGS': '0x0', 10:32:11 INFO - 'XPC_SERVICE_NAME': '0', 10:32:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:32:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:32:12 INFO - Downloading/unpacking blobuploader==1.2.4 10:32:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:32:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:32:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:32:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:32:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:32:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:32:14 INFO - Downloading blobuploader-1.2.4.tar.gz 10:32:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:32:15 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:32:15 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 10:32:15 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 10:32:15 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 10:32:15 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 10:32:15 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 10:32:15 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 10:32:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:32:15 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:32:15 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 10:32:15 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 10:32:15 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 10:32:15 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 10:32:15 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 10:32:15 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 10:32:16 INFO - Downloading docopt-0.6.1.tar.gz 10:32:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:32:16 INFO - Installing collected packages: blobuploader, requests, docopt 10:32:16 INFO - Running setup.py install for blobuploader 10:32:16 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:32:16 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:32:16 INFO - Running setup.py install for requests 10:32:16 INFO - Running setup.py install for docopt 10:32:17 INFO - Successfully installed blobuploader requests docopt 10:32:17 INFO - Cleaning up... 10:32:17 INFO - Return code: 0 10:32:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:32:17 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')]} 10:32:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:32:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:32:17 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')]} 10:32:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:32:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:32:17 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 0x105f26490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e9c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a3adcef10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d3ad30>, '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 0x105ba0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105d32b20>, '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.NPKYavLDyw/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.TU7LnxDPUj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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 10:32:17 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 10:32:17 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 10:32:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NPKYavLDyw/Render', 10:32:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:32:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:32:17 INFO - 'HOME': '/Users/cltbld', 10:32:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:32:17 INFO - 'LOGNAME': 'cltbld', 10:32:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:32:17 INFO - 'MOZ_NO_REMOTE': '1', 10:32:17 INFO - 'NO_EM_RESTART': '1', 10:32:17 INFO - 'PAGER': '/bin/cat', 10:32:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:32:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:32:17 INFO - 'PWD': '/builds/slave/test', 10:32:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:32:17 INFO - 'SHELL': '/bin/bash', 10:32:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TU7LnxDPUj/Listeners', 10:32:17 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 10:32:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:32:17 INFO - 'USER': 'cltbld', 10:32:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:32:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:32:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:32:17 INFO - 'XPC_FLAGS': '0x0', 10:32:17 INFO - 'XPC_SERVICE_NAME': '0', 10:32:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:32:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:32:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:32:17 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-NbKwU6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:32:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:32:17 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-daT5rt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:32:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:32:17 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-OA2U9T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:32:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:32:17 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-klq_3Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:32:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:32:17 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-aWxezB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:32:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:32:17 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-LpH5X2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:32:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:32:18 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-UMxv__-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:32:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:32:18 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-M171U2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:32:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:32:18 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-RuqZGm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:32:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:32:18 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-hXmmhs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:32:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:32:18 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-ksgfpk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:32:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:32:18 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-Xtyidj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:32:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:32:18 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-q0iXCE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:32:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:32:18 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-S59ufp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:32:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:32:18 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-j08YSn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:32:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:32:19 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-2kRpt3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:32:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:32:19 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-CLlVjX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:32:19 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:32:19 INFO - Running setup.py install for manifestparser 10:32:19 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:32:19 INFO - Running setup.py install for mozcrash 10:32:19 INFO - Running setup.py install for mozdebug 10:32:19 INFO - Running setup.py install for mozdevice 10:32:19 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:32:19 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:32:19 INFO - Running setup.py install for mozfile 10:32:19 INFO - Running setup.py install for mozhttpd 10:32:20 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:32:20 INFO - Running setup.py install for mozinfo 10:32:20 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:32:20 INFO - Running setup.py install for mozInstall 10:32:20 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:32:20 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:32:20 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:32:20 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:32:20 INFO - Running setup.py install for mozleak 10:32:20 INFO - Running setup.py install for mozlog 10:32:20 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:32:20 INFO - Running setup.py install for moznetwork 10:32:20 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:32:20 INFO - Running setup.py install for mozprocess 10:32:21 INFO - Running setup.py install for mozprofile 10:32:21 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:32:21 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:32:21 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:32:21 INFO - Running setup.py install for mozrunner 10:32:21 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:32:21 INFO - Running setup.py install for mozscreenshot 10:32:21 INFO - Running setup.py install for moztest 10:32:21 INFO - Running setup.py install for mozversion 10:32:21 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:32:21 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:32:21 INFO - Cleaning up... 10:32:21 INFO - Return code: 0 10:32:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:32:21 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')]} 10:32:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:32:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:32:21 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')]} 10:32:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:32:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:32:21 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 0x105f26490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e9c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a3adcef10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d3ad30>, '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 0x105ba0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105d32b20>, '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.NPKYavLDyw/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.TU7LnxDPUj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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 10:32:21 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 10:32:21 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 10:32:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NPKYavLDyw/Render', 10:32:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:32:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:32:21 INFO - 'HOME': '/Users/cltbld', 10:32:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:32:21 INFO - 'LOGNAME': 'cltbld', 10:32:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:32:21 INFO - 'MOZ_NO_REMOTE': '1', 10:32:21 INFO - 'NO_EM_RESTART': '1', 10:32:21 INFO - 'PAGER': '/bin/cat', 10:32:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:32:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:32:21 INFO - 'PWD': '/builds/slave/test', 10:32:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:32:21 INFO - 'SHELL': '/bin/bash', 10:32:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TU7LnxDPUj/Listeners', 10:32:21 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 10:32:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:32:21 INFO - 'USER': 'cltbld', 10:32:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:32:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:32:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:32:21 INFO - 'XPC_FLAGS': '0x0', 10:32:21 INFO - 'XPC_SERVICE_NAME': '0', 10:32:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:32:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:32:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:32:22 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-66PHpr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:32:22 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)) 10:32:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:32:22 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-Uf2nZp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:32:22 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)) 10:32:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:32:22 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-oL_qvo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:32:22 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)) 10:32:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:32:22 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-KFEx65-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:32:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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)) 10:32:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:32:22 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-qdYo8V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:32:22 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)) 10:32:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:32:22 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-nLNR7l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:32:22 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)) 10:32:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:32:22 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-zYbNwT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:32:22 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)) 10:32:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:32:22 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-4nXMiR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:32:22 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)) 10:32:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:32:22 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-aKn4Ou-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:32:23 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)) 10:32:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:32:23 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-ZjcZ04-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:32:23 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)) 10:32:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:32:23 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-aIy141-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:32:23 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)) 10:32:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:32:23 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-k95wUw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:32:23 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)) 10:32:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:32:23 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-6uhAjb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:32:23 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)) 10:32:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:32:23 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-UVIXFR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:32:23 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)) 10:32:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:32:23 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-Djz8R8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:32:23 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)) 10:32:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:32:23 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-bwr7UB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:32:23 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 10:32:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:32:23 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-3ETYYQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:32:23 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)) 10:32:23 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)) 10:32:23 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)) 10:32:23 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)) 10:32:23 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:32:23 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:32:23 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:32: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 10:32: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 10:32: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 10:32: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 10:32: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 10:32: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 10:32:26 INFO - Downloading blessings-1.6.tar.gz 10:32:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:32:26 INFO - Installing collected packages: blessings 10:32:26 INFO - Running setup.py install for blessings 10:32:26 INFO - Successfully installed blessings 10:32:26 INFO - Cleaning up... 10:32:27 INFO - Return code: 0 10:32:27 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:32:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:32:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:32:27 INFO - Reading from file tmpfile_stdout 10:32:27 INFO - Current package versions: 10:32:27 INFO - blessings == 1.6 10:32:27 INFO - blobuploader == 1.2.4 10:32:27 INFO - docopt == 0.6.1 10:32:27 INFO - functools32 == 3.2.3-2 10:32:27 INFO - jsonschema == 2.5.1 10:32:27 INFO - manifestparser == 1.1 10:32:27 INFO - mozInstall == 1.12 10:32:27 INFO - mozcrash == 1.0 10:32:27 INFO - mozdebug == 0.1 10:32:27 INFO - mozdevice == 0.48 10:32:27 INFO - mozfile == 1.2 10:32:27 INFO - mozhttpd == 0.7 10:32:27 INFO - mozinfo == 0.9 10:32:27 INFO - mozleak == 0.1 10:32:27 INFO - mozlog == 3.4 10:32:27 INFO - moznetwork == 0.27 10:32:27 INFO - mozprocess == 0.23 10:32:27 INFO - mozprofile == 0.28 10:32:27 INFO - mozrunner == 6.13 10:32:27 INFO - mozscreenshot == 0.1 10:32:27 INFO - mozsystemmonitor == 0.3 10:32:27 INFO - moztest == 0.8 10:32:27 INFO - mozversion == 1.4 10:32:27 INFO - psutil == 3.1.1 10:32:27 INFO - requests == 1.2.3 10:32:27 INFO - wsgiref == 0.1.2 10:32:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:32:27 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')]} 10:32:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:32:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:32:27 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')]} 10:32:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:32:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:32:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105f26490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e9c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a3adcef10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d3ad30>, '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 0x105ba0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105d32b20>, '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.NPKYavLDyw/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.TU7LnxDPUj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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 10:32:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 10:32:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:32:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NPKYavLDyw/Render', 10:32:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:32:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:32:28 INFO - 'HOME': '/Users/cltbld', 10:32:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:32:28 INFO - 'LOGNAME': 'cltbld', 10:32:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:32:28 INFO - 'MOZ_NO_REMOTE': '1', 10:32:28 INFO - 'NO_EM_RESTART': '1', 10:32:28 INFO - 'PAGER': '/bin/cat', 10:32:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:32:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:32:28 INFO - 'PWD': '/builds/slave/test', 10:32:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:32:28 INFO - 'SHELL': '/bin/bash', 10:32:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TU7LnxDPUj/Listeners', 10:32:28 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 10:32:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:32:28 INFO - 'USER': 'cltbld', 10:32:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:32:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:32:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:32:28 INFO - 'XPC_FLAGS': '0x0', 10:32:28 INFO - 'XPC_SERVICE_NAME': '0', 10:32:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:32:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:32:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 10:32:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 10:32:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 10:32:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 10:32:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 10:32:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 10:32:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 10:32:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 10:32:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 10:32:28 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 10:32:28 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 10:32:28 INFO - Cleaning up... 10:32:28 INFO - Return code: 0 10:32:28 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:32: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')]} 10:32:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:32:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:32: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')]} 10:32:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:32:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:32:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105f26490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105e9c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a3adcef10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d3ad30>, '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 0x105ba0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105d32b20>, '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.NPKYavLDyw/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.TU7LnxDPUj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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 10:32:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 10:32:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 10:32:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NPKYavLDyw/Render', 10:32:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:32:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:32:28 INFO - 'HOME': '/Users/cltbld', 10:32:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:32:28 INFO - 'LOGNAME': 'cltbld', 10:32:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:32:28 INFO - 'MOZ_NO_REMOTE': '1', 10:32:28 INFO - 'NO_EM_RESTART': '1', 10:32:28 INFO - 'PAGER': '/bin/cat', 10:32:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:32:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:32:28 INFO - 'PWD': '/builds/slave/test', 10:32:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:32:28 INFO - 'SHELL': '/bin/bash', 10:32:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TU7LnxDPUj/Listeners', 10:32:28 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 10:32:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:32:28 INFO - 'USER': 'cltbld', 10:32:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:32:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:32:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:32:28 INFO - 'XPC_FLAGS': '0x0', 10:32:28 INFO - 'XPC_SERVICE_NAME': '0', 10:32:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:32:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:32:28 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:32:28 INFO - Cleaning up... 10:32:28 INFO - Return code: 0 10:32:28 INFO - Running post-action listener: _resource_record_post_action 10:32:28 INFO - Running post-action listener: _start_resource_monitoring 10:32:28 INFO - Starting resource monitoring. 10:32:28 INFO - [mozharness: 2017-01-11 18:32:28.893424Z] Finished create-virtualenv step (success) 10:32:28 INFO - [mozharness: 2017-01-11 18:32:28.894321Z] Running install step. 10:32:28 INFO - Running pre-action listener: _resource_record_pre_action 10:32:28 INFO - Running main action method: install 10:32:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:32:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:32:29 INFO - Reading from file tmpfile_stdout 10:32:29 INFO - Detecting whether we're running mozinstall >=1.0... 10:32:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:32:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:32:29 INFO - Reading from file tmpfile_stdout 10:32:29 INFO - Output received: 10:32:29 INFO - Usage: mozinstall [options] installer 10:32:29 INFO - Options: 10:32:29 INFO - -h, --help show this help message and exit 10:32:29 INFO - -d DEST, --destination=DEST 10:32:29 INFO - Directory to install application into. [default: 10:32:29 INFO - "/builds/slave/test"] 10:32:29 INFO - --app=APP Application being installed. [default: firefox] 10:32:29 INFO - mkdir: /builds/slave/test/build/application 10:32:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 10:32:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 10:33:00 INFO - Reading from file tmpfile_stdout 10:33:00 INFO - Output received: 10:33:00 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 10:33:00 INFO - Running post-action listener: _resource_record_post_action 10:33:00 INFO - [mozharness: 2017-01-11 18:33:00.434461Z] Finished install step (success) 10:33:00 INFO - [mozharness: 2017-01-11 18:33:00.434593Z] Running run-tests step. 10:33:00 INFO - Running pre-action listener: _resource_record_pre_action 10:33:00 INFO - Running main action method: run_tests 10:33:00 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:33:00 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:33:00 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:33:00 INFO - 2017-01-11 10:33:00.449 screenresolution[1626:7718] starting screenresolution argv=screenresolution get 10:33:00 INFO - 2017-01-11 10:33:00.452 screenresolution[1626:7718] Display 0: 1600x1200x32@60 10:33:00 INFO - 2017-01-11 10:33:00.457 screenresolution[1627:7723] starting screenresolution argv=screenresolution list 10:33:00 INFO - Available Modes on Display 0 10:33:00 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:33:00 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:33:00 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:33:00 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:33:00 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:33:00 INFO - Intel Iris: 10:33:00 INFO - Chipset Model: Intel Iris 10:33:00 INFO - Type: GPU 10:33:00 INFO - Bus: Built-In 10:33:00 INFO - VRAM (Dynamic, Max): 1536 MB 10:33:00 INFO - Vendor: Intel (0x8086) 10:33:00 INFO - Device ID: 0x0a2e 10:33:00 INFO - Revision ID: 0x0009 10:33:00 INFO - Displays: 10:33:00 INFO - Display: 10:33:00 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:33:00 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:33:00 INFO - Display Serial Number: 1600x1200 60 10:33:00 INFO - Main Display: Yes 10:33:00 INFO - Mirror: Off 10:33:00 INFO - Online: Yes 10:33:00 INFO - Rotation: Supported 10:33:00 INFO - Return code: 0 10:33:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:33:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:33:00 INFO - Python 2.7.3 10:33:00 INFO - Return code: 0 10:33:00 INFO - grabbing minidump binary from tooltool 10:33:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:33:00 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 0x105d3ad30>, '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 0x105ba0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105d32b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:33:00 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 10:33:00 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 10:33:00 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 10:33:00 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:33:00 INFO - Return code: 0 10:33:00 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:33:00 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:33:00 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:33:00 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:33:00 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:33:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GNWYyDg8SiqGD_Cu7YZThw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0332', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 10:33:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/GNWYyDg8SiqGD_Cu7YZThw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0332 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 10:33:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NPKYavLDyw/Render', 10:33:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:33:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:33:00 INFO - 'HOME': '/Users/cltbld', 10:33:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:33:00 INFO - 'LOGNAME': 'cltbld', 10:33:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:33:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:33:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:33:00 INFO - 'MOZ_NO_REMOTE': '1', 10:33:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:33:00 INFO - 'NO_EM_RESTART': '1', 10:33:00 INFO - 'PAGER': '/bin/cat', 10:33:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:33:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:33:00 INFO - 'PWD': '/builds/slave/test', 10:33:00 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:33:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:33:00 INFO - 'SHELL': '/bin/bash', 10:33:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TU7LnxDPUj/Listeners', 10:33:00 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 10:33:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:33:00 INFO - 'USER': 'cltbld', 10:33:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:33:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:33:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:33:00 INFO - 'XPC_FLAGS': '0x0', 10:33:00 INFO - 'XPC_SERVICE_NAME': '0', 10:33:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:33:00 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GNWYyDg8SiqGD_Cu7YZThw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0332', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 10:33:01 INFO - mozversion application_buildid: 20170111064204 10:33:01 INFO - mozversion application_changeset: 490dfb0aefde5c625c34e70476a932f5a5a1c1c6 10:33:01 INFO - mozversion application_display_name: FirefoxDeveloperEdition 10:33:01 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:33:01 INFO - mozversion application_name: Firefox 10:33:01 INFO - mozversion application_remotingname: firefox-dev 10:33:01 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 10:33:01 INFO - mozversion application_vendor: Mozilla 10:33:01 INFO - mozversion application_version: 52.0a2 10:33:01 INFO - mozversion platform_buildid: 20170111064204 10:33:01 INFO - mozversion platform_changeset: 490dfb0aefde5c625c34e70476a932f5a5a1c1c6 10:33:01 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 10:33:01 INFO - mozversion platform_version: 52.0a2 10:33:01 INFO - using testdate: 1484159581 10:33:01 INFO - actual date: 1484159581 10:33:01 INFO - starting webserver on 'localhost:49240' 10:33:01 INFO - SUITE-START | Running 2 tests 10:33:01 INFO - TEST-START | dromaeo_css 10:33:01 INFO - operating with platform_type : mac_ 10:33:01 INFO - Initialising browser for dromaeo_css test... 10:33:01 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/tmpHqgkk8/profile http://localhost:49240/getInfo.html) 10:33:03 INFO - PROCESS | 1637 | __metrics Screen width/height:1600/1200 10:33:03 INFO - PROCESS | 1637 | colorDepth:24 10:33:03 INFO - PROCESS | 1637 | Browser inner width/height: 1024/689 10:33:03 INFO - PROCESS | 1637 | __metrics 10:33:03 INFO - PROCESS | 1637 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:33:03 INFO - PROCESS | 1637 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:33:04 INFO - TEST-INFO | 1637: exit 0 10:33:04 INFO - Browser initialized. 10:33:04 INFO - Running cycle 1/1 for dromaeo_css test... 10:33:04 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/tmpHqgkk8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:33:16 INFO - PROCESS | 1639 | RSS: Main: 222240768 10:33:16 INFO - PROCESS | 1639 | 10:36:21 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49240/tests/dromaeo/cssquery-ext.html) 10:36:23 INFO - PROCESS | 1639 | RSS: Main: 350752768 10:36:23 INFO - PROCESS | 1639 | 10:39:25 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-ext.html (next: http://localhost:49240/tests/dromaeo/cssquery-jquery.html) 10:39:26 INFO - PROCESS | 1639 | RSS: Main: 345550848 10:39:26 INFO - PROCESS | 1639 | 10:42:28 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49240/tests/dromaeo/cssquery-mootools.html) 10:42:29 INFO - PROCESS | 1639 | RSS: Main: 327655424 10:42:29 INFO - PROCESS | 1639 | 10:45:32 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49240/tests/dromaeo/cssquery-prototype.html) 10:45:32 INFO - PROCESS | 1639 | RSS: Main: 320876544 10:45:32 INFO - PROCESS | 1639 | 10:48:36 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49240/tests/dromaeo/cssquery-yui.html) 10:48:36 INFO - PROCESS | 1639 | RSS: Main: 313561088 10:48:36 INFO - PROCESS | 1639 | 10:51:39 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-yui.html (next: http://localhost:49240/tests/dromaeo/cssquery-dojo.html) 10:51:39 INFO - PROCESS | 1639 | RSS: Main: 352550912 10:51:39 INFO - PROCESS | 1639 | 10:51:39 INFO - PROCESS | 1639 | __start_tp_report 10:51:39 INFO - PROCESS | 1639 | _x_x_mozilla_page_load 10:51:39 INFO - PROCESS | 1639 | _x_x_mozilla_page_load_details 10:51:39 INFO - PROCESS | 1639 | |i|pagename|runs| 10:51:39 INFO - PROCESS | 1639 | |0;dojo.html;3254.5931758530182;3290;3313.4698448248287;3314.486254630533;3372.56156699629;5519.530839878616;5577.8047768328115;5599.300087489064;5599.972000140004;5629.71851407429;5602.971985140069;5706.65760054397;5721.485066344038;5738.053221218504;5781.335146458158;4315.697901146921;5165.535101840825;5190.688558686472;5212.973935130319;5283.524482796547;5166.664166829163;5170.560502357304;5180.559652429548;5317.574594032488;5332.120200166968;4267;5206.349206349207;5255.605829562798;5261.657992230512;5310.415854256029;5217.243499692542;5246.134387826004;5253.4221235664045;5281.86795330116;5302;5150.948490515084;5225.817096401645;5245.842624721265;5250.3962044364625;5291.629585928987;5415.972920135374;5445;5466.808661696821;5585.497305242507;5659.660420374791;6136.028952951454;6174.96912515439;6177.289611694623;6181.165542651775;6197.287311959092;6307.5269354798575;6321.273053598849;6326.2092238470195;6335.018072198816;6343.936560634381;5681.204631351614;5726.427357264323;5749.655020698771;5777.797777077824;5814.418558144236;6254.749810007549;6273.749050038039;6281.937180628227;6284.340144284784;6284.497240220771;6158.168647232566;6172.321044685081;6173.135760993465;6180.536459765445;6187.010078387527;6108.706347747029;6130.551011451118;6188.659623720739;6298.08677741734;6304.275008374096;6141.762184092961;6150.846228844314;6157.9384206157365;6159.4456498914415;6163.9383606164265;6118.506865273355;6196.287426945959;6210.844728881723;6213.2233470816145;6262.499000079983;4329.220740266782;4696.342512048316;4935.728534930613;4937.604991600663;4990.50094990498;4379.934300985487;4440.800163992628;4444.2444784386735;4448.221561226837;4477.7313361197785;5647.491725744702;5649.661020338793;5701.71491425427;5721.57088218377;5729.341125770589;6234.844128896814;6286.308506064329;6288.717007734572;6298;6300.21247344082;5630.577706672016;5660.264165658438;5709.172170035405;5749.1663708762835;5754;5265.736713164327;5303.946960530346;5306.734663266821;5386.461353864583;5402.540784033323;6236.65698386575;6240.625562466266;6252.937470625327;6264.498840092872;6293.118963345227;5414.3232095988005;6188.81433556994;6286.6856657166045;6301.842453938688;6324.683765811783;6101.938980610137;6245.5628106032145;6277.591956522811;6279.874402511833;6306.905396418965;6019;6108.159949115366;6138.664296166039;6142.600730952474;6158.753649853956;5080.872978175576;6228.56400051992;6271.62370257786;6283.905741413974;6302.810915672537;5187.533122019036;6088.391160883876;6122.510199183966;6129.540284478593;6135.14108024868;5879.118132280192;5938.049912014057;6019.4883434908525;6078.969605151946;6136.570440069152;5943.821685349447;5950.375210602998;5953.017752071033;5965.373635768356;5989.1914591529585;6130.63216207029;6134.386561344008;6135.11040899076;6140;6145.9692701536205;5742.827715168552;5746;5885.55858310637;5899.793507227227;5931.1399847021075 10:51:39 INFO - PROCESS | 1639 | |1;ext.html;433.8177965254593;487.06240487062394;498.81294514557044;523.6779380680882;537.2719964448173;29359.825606975723;38110.23779524408;39335.803320983425;39366.2126757465;40609.39085913714;39438.80280598593;39751.801240993824;39755.20489590206;39809.601903980954;39845.402318965236;9176.67881624145;9371.25029997602;9372.95313523435;9375.718728438158;9376.624935002592;9193.494357810318;9294.674686385977;9295.860562091573;9299;9315.673951411702;8413.831723365529;8879.689210877621;9295.48874811885;9335.206507446876;9341.299402544802;9235.399699019543;9303.953480232556;9306.86039709405;9310.022447642968;9324.673636422693;9212.078792120801;9294.256459483226;9299.442033478013;9302.348835581512;9307.441553506811;37651.24697506062;38019.23961520754;38045.809770951244;38073.048173795876;38176.236475270336;3132.686731326849;3183.904482865518;3226.6612005739294;3327.73378129749;3666.468362087509;28368.574471382944;28619.279493665897;28860.98986535461;29012.83948642052;29256.414871702447;3048.7713421493477;3084.7223749862396;3111.766617503674;3153.8738450461956;3158.6209654841564;365518;445023;445052;445352;446164;59004.704976474844;59397.406025939184;59433;59465.81068378695;59474.40525594775;33279;33579.328413432086;33613.49579756305;34718;61854;15602.985805922579;15749.370025199092;15753.68492630141;15756.448524301595;15763.448279310169;12016.759664806656;12102.394880255952;12131.029517638568;12132.272063676208;12141.878581214252;16158.949668271525;16728.916355418143;17927.910360448375;17964.20357964193;18044.90977545104;7458.277728629011;7504.662290196954;7511.586862722603;7554.92239634094;7603.695852165962;836.8870202522703;972.4248107244626;1004.3220825942606;1014.2494554030112;1043.212374657129;29049.273768155967;29112.854435727684;29398.853005734836;29531.114066578037;29638.703612964026;681.6625770243761;992.831218692824;1066;1080.6379862745912;1081.648464249122;14610.415583376545;15062.171580563174;15078.698426031637;15434.228288585751;15487.303071361815;550260;639437;641443;642079;643051;53465.66335841635;54132.3760287192;54184.45815541873;54224;54224.915501690535;1194.964151075469;1220.7680540697238;1257.8742125787348;1288.8002359634459;1362.9386677599532;29241.561376579793;29887.804487820245;29958.850205748833;30084.24789380283;30534.931277405183;46038.539614603425;46618.76690616525;46705;46774.53225467702;46801.76599116983;545283;547005;547543;639585;661795;37124.44313335356;37962;38159.42760858534;38378.80810596041;38384.04039899025;42349.57650423457;42499.7250082498;42512.78743606262;42630.78684606557;43047;35757.64242357647;36186.27627447489;36334.45498317474;36355.90932272036;36468.635313646526;36584.08539786527;36958.815205924875;36994.89015329545;37911.81044094762;39567.40648890327;36612.45081323729;36871.26257474889;36993.81503092467;37353.6264637361;37647.24705505929;31754.523682144325;32919.17702057468;33456.33087338288;33591.32817343689;33708.83145584256;24906.003759849402;24993.375165620277;25116.62325065162;25297.62053569161;25364 10:51:39 INFO - PROCESS | 1639 | |2;jquery.html;343.8607364017574;361.4578132800799;363.7035815810115;375.24013871909386;395.33386244178564;15161.848381516182;15381.846181538182;15797.447089351876;20211.59576808463;20686.172553097895;19386.903065484654;19693.704594431096;19812.50468738282;20498.28256011036;20760.480987975272;5891.116332550119;6063;6085.78699745509;6092.177556029924;6100.267967843865;4714.528547145293;6666.9332906734935;6762.391384775369;6808.387245147936;6822.454203663695;5766.221560089397;6066.241719785027;6642.634655093968;6716.563423377489;6770.69531871067;6032.788852390167;6055.5761096723245;6062.545309101835;6072.392760723937;6072.908906366408;5752.654840709529;5756.251687280668;5976.9402305977255;6008.188894499231;6014.879702405928;13210;13418.932905335509;13469.595912122651;22730.318090457313;23703.644445333335;2119.279444988696;2121.5332626822074;2148.430665873545;2806.4527417153663;2885.177724348551;10018.849717254247;10134.290599658027;10202.33684810485;12772.425240864184;13916.737468139378;1601.4074792326796;1790.4002159276658;1884.2274667386305;1896.5922326699688;1917.51103468616;114238;115144.57060372915;118404;124540;124978;10185.643502477526;10558.577656893793;11457.427128643534;11510.30938143714;11679.124065695107;14080.859191407955;14523.419063237563;14568.27158642085;14585.197814120325;14605;6886.48351373639;8307.584620768937;8342.457740246864;8446.366522510836;8509.957450212833;8270.766892938873;8295.502269863708;8314.833703326021;8450.61972211252;8461.661533538589;4313.956860431418;4378.8029538670835;4379.802908869108;4415.161119387306;4506.797194126272;1821.4626685127919;1888.4712280561464;1891.877027993176;1893.4603637963114;1895.9715604265948;813.8575749243859;837.7235512280932;913.8949020862553;932.4684929590069;969.8496733006396;19993;20820.79179208219;20906.581868362548;21084.481725584305;21166.259180928595;916.6241840845355;919.2002957427081;950.8240975419569;967.8644989701447;1020.5968642386216;9040.909590904006;10225.539850706737;10510.632127875488;10944.343339399662;10962.616308429326;571293;585003;586587;603160;603502;82826.96340091969;106557;118015;126664;129044;786.9134395216522;894.6332003878379;930.5440334236175;930.9115634014893;935.3919952031234;18659.533511662317;19271.710924335865;19480;19534.706979395036;19569.70645440291;52180.47819521756;54560;54771.726141369036;54786;54868.72565637146;58135.837283253255;72423.63788181025;74144.62927685327;77274;77349.61325193338;7673.731419400294;7907.604619769104;7968.800779980547;8104.756857294291;8156.306713929383;10060.597576096874;12207.633770986884;12392.56626018085;12581.370931453574;12724.363781811058;8131.7560473185895;10814.080803131821;11062.115030797679;11203.271787333797;11225.943870280924;10614.787704246026;10669.839952400875;10703.411312377582;10802.89197108019;10882;10687.786244274916;10939.343639381344;11088.778224435304;11173.49719262619;11422.94288528552;6675.566088204251;7867.449278550446;7895.171007044178;8002.159773223728;8290.170982901662;10266.691999240034;10397.636082737068;10742.624008159677;11048.889511105108;11067.169962252703 10:51:39 INFO - PROCESS | 1639 | |3;mootools.html;1271.1610337177462;1431.59199628106;1532.3334349557942;1767.823217678233;1789.9194536245868;8044.195580441953;8486.278666313356;8486.95756521218;8495.915040849597;8501.744947651565;7414.5551266923885;7727.304542591166;7728.111267204274;7790.18203088676;7794.103678077023;2107.525806693493;2146.5599552091785;2259.29961711869;2337.906483740648;2342.7188737351544;2343.789058984691;2345.413646588353;2351.1653363056125;2353.7410884802657;2357.5874222011166;2280.0879648140713;2339.333290012347;2340.063974410241;2341.2507997440825;2341.7424083350816;2306.9884650576723;2309.307207837642;2332.8716920569364;2334.124703236286;2334.26470661741;2339.0760649543504;2339.6724458575814;2358.551875143717;2360.917367892133;2368.029108065685;8226.876596850992;8394.958025209835;8408.537530435824;8423.15768423159;8435.283000944926;4995.075910956485;5152.458991805844;5166.095933211711;5174.146265866165;5191.402988656315;3217.8551965161623;3411.4200585900458;3471.357798807229;3483.599386070609;3496.056064862473;926.3746970794687;969.3699095587854;1041.265907535186;1057.6351158850184;1070.0743856564093;10838.729031774268;10884.020438160604;10885.074768644596;10888.346699197995;10902.836457453144;4433.556644335541;4653.488116307203;4740.265258884879;4757.500462451462;4768.47546769855;9356.017618150132;9358.970513243674;9363;9366.765830854147;9402;5145.07388670043;5150.510701483428;5161.896762064737;5165.586753059746;5170;4534.523874993144;4704.082703872712;4711.882202944954;4726.26742854858;4766.142094423037;3499.685028347461;3652.342578335925;3662.560492740835;3719.516462859811;3888.4944957155953;1067.3062509368817;1254.2787896959285;1254.811778233254;1270.6950331920273;1271.879171478708;847.7626264645909;874.6151693254948;882.7748924024332;887.0109827542373;902.5126431726794;5571.164325351229;5638.633488823213;5689.089745640677;5749.741261643236;5913.231279933581;798.2416704130983;884.526778173674;910.4264313482464;921.5944984207061;957.5308099031564;3736.103335199587;3756.9248615027545;3771.321162190832;3795.76270765069;3868.168343806095;9665.468399237969;10164.440955747357;10191.745206369751;10197;10207.489625518694;10132.392056476636;10142.949285253673;10145.94927025375;10147.746306342253;10154.39073655583;7750.418718596128;7828.2954534092205;7919.406044546682;7933.286004259528;7950.522968621901;4806.351142595705;5437.564994800486;5529.281193444987;5556.777728890799;5791.449812267996;8989.865152022594;9267.258619310303;9289.72130836076;9540.427574345284;9555.856662150212;8080.868678385148;9386.718398448058;9411;9515.381500202464;9598.088181622734;7055.753048643272;7124.893126603209;7207.819804504929;7221.350078492961;7232.63836808147;7761.883571746541;7785.844283114419;7823.139454659869;7838.921610783819;7850.960745196466;6076.361981992004;6953.79138625842;7119.572825630479;7123.536970096927;7139.500234983501;6056.394360563909;7012.088428504262;7015.894761578478;7022.508424410242;7061.505694601328;6428.678566071584;6624.470042396693;6683.8997415039785;6685.298043705341;6699.229588597374;4736.052789442194;4753.47711751714;4772.737499437564;4905.705657660552;5009.398872135367;3610;4805.37530121082;4832.806687732451;4932.4327702314695;4991.900161996812 10:51:39 INFO - PROCESS | 1639 | |4;prototype.html;241.3844696025136;288.2447986275957;300.13261673762827;306.60601127551104;310.58071603335515;5291.523762861341;5332.386775520817;5376.489233522819;5397.136458166699;5423.918641220375;4984.825531106412;5068;5085.491450854913;5124.564412024983;5333.413324534308;3720.367537518615;3752.4746535485056;4029.3955906614096;4038.394240863879;4094.733842300241;3570.357335679577;3828.157805282833;4074.6129117733767;4084.326086195774;4124.855630052949;3784.3188226119287;3906.199229158016;4040.2121586290536;4056.797160141996;4060.2894493463673;3634.78191308521;3863.8794749522604;4042.150284049738;4076.979615101935;4096.893838663574;3212.4699424594914;3510.5611798525183;3525.453554699026;3542.3800834854055;3552.9111772205642;4689.8358557450665;4800.399950006249;5035.748212589356;5225.399079105913;5243.528082472596;3692.4276737105793;3763.6424539668687;3926.9018274543364;4098.405731168964;4541.364209010741;4252.447181866369;4849.951500485021;4911.8772030699165;5548.500634942834;5730.255066841326;3274.361499507597;3878.7866667333287;3888.0279930017496;3888.9027774305587;3903.8438462461468;5451.672899626035;5727.656340619576;5777.393373695743;5911.172435858984;5915.645061296336;3323.5513205717402;3437.4843773434186;3614.493970844084;3871.457995880579;3888.494495715539;3793.6016718244737;3928.980355098206;3933.724639275223;3938.487996560486;3942.64516193544;4324.913501729948;4560.954390456053;4595.425571803525;4632.814687412466;4635.860924172281;4622.907541849144;5550.833474995757;5619.719014049281;5664.980303545319;5672.461116193955;1102.8566286382718;1162.7035106047904;1260.4139075330015;1270.009392673716;1279.2899940533093;729.6680010595272;747.9453969902412;754.8867669849567;787.7321710618418;824.1634740738156;4312.956870431318;4551.8634440966825;4797.424309082862;4888.804447822118;5280.81517146898;5255.76349064293;5783.855403614859;5792.495540198203;5797.594168408171;5847.707614619252;4543.818247270138;4921.447660913712;5297.17893726473;5322.4411436799355;5396.325459317585;2222.866628002325;2420.8426452280546;2543.219746177683;2817.4646817104685;2859.4709978654014;4820.469748327681;5287.68273903567;5313.237116699294;5543.861403464945;5783.652980821164;5607.607467477319;5963.105534169909;6006.609570377911;6032.819015429544;6033.883731509684;5291.365036195682;6085.513158947274;6131.908021379772;6154.907676384858;6155.692215389213;5547.833564993057;5595.5803314752375;5800.897829412398;5842.269716285465;5874.735636896265;4908.533689299511;5500.844822587142;5783.884322313446;5802.564807639528;5825.213596164633;4987.501249875128;5415.025295446857;5834.474897259267;5995.550333725076;6064.605800622945;3775.981120094382;3879.6140373670555;4045.7774822383876;4056.4320995060125;4066.898327541835;5686.687232202268;5778.739956701875;5797.44900253316;5799.739011744566;5826.659868230416;5301.390340110783;5662.178984047372;5692.231548740867;5707.743151558107;5729.939961107209;4995.425526064549;5613.859653508532;5675.489205971482;5677.574181936288;5706.372299047019;5176.534111929794;5429.914017196497;5525.226468294361;5577;5586.441355864381;4527.637788976808;5020.924686129784;5023.769176551622;5282.498162674539;5554.861128471821;5000.449950505369;5040.941402305557;5587.860303492445;5631.943680563142;5639 10:51:39 INFO - PROCESS | 1639 | |5;yui.html;685.2941470285601;746.8207630168757;761.4213197969543;778.7585848387;793.9960300198499;13634.318284085793;14155.787663185061;14157.009009369336;14163.645908852284;14197.361118749655;12810.167339122974;12951.41718622662;12952.22286662801;12956.15784973976;12968;645.2225068792094;663.6350007495882;663.6383171171699;664.0073090729343;793.4604468961104;665.3246954341347;780.8321210939647;802.7190483330817;806.2501873257868;806.9717559885376;852.7953291210096;854.8717692346166;877.8595424732044;878.0692465986043;882.2192359761625;868.4138206710445;868.4875923205276;868.8957325120995;868.9261412779921;871.760265926872;501.70309749297746;520.5159201942192;613.9508839292846;643.237763250545;646.3375040583393;13435.26106064166;13819.654508637264;13861.4455421783;13911.443542258297;13937.58187254384;11456.369899655516;12874.485020599164;12942.676433089155;12994.243747518876;13315.933420332933;10405.9479702601;11614.767704645945;11679.357635330054;11787.469563869647;12094.758104837854;4347.652187824967;4406.65596992917;4409.977950110261;4414.073044660625;4569.405977222973;78138.6093069531;79174;79875.60062199652;80477;84850.57574712149;10274.434906080089;10310.639127630497;10331.535080921376;10612.363258204532;10654.200934929911;19718.01409929498;19727.013649317476;19943.202271909253;20023.399298021082;20305.086271117834;7632.46572739914;7650.617469126521;7678.884816727754;7697.345725613385;7913.723019694283;4129.772862492592;4200.7059505834295;4218.325067989168;4364.301711726109;4397.626201772821;3320.9501857472155;3420.931581368359;3441.3117376524297;3444.3283559705615;3449.6722811332884;587.2923127631194;587.7913340764053;599.179124599293;607.7234858139536;611.150500803883;523.8978399212193;535.4859335038407;577.6129992904764;604.1119554255238;611.3031144495279;7963.362930965509;8373.120822313691;8522.57387130641;8677.088905664941;9091.909080909238;561.348835350999;614.8124821929265;623.9001759718424;633.6071635585874;658.887989041864;4091.4272001919753;4371.846985355561;4374.4531933508315;4415.712978656377;4465.441819772528;95377;121291;121547.39226303811;121719;123390.38304808419;64091;69545.30454695388;98068;99724;101335;90128;91123.1775364489;92693;93893;94588.52705736428;8472.66109355619;8650.178233067849;8660.307175426076;8729.258013068706;8884.955575222082;57368.42631573631;57973.13040304314;58014.7099264501;59045;60389;81978.59010704908;82020;83123.58438207771;84612;84792;10735.182900131216;11055.22613417053;11218;11272.380019099028;11770.646880593597;11997.220180688228;12077.456514457046;12106.697332566757;12665.936670316589;12737;11638.941805290919;11844.170908036354;11917;11920.165588408729;11946.880531194576;8956.552172391484;11248.550057997589;12252.509899604273;12280.201786883825;12614.306213158365;12096.516139354328;12136;12278.938605306916;12689.8096528554;12951.740965180456;10449.738756531147;10622.256442048982;10775.730606734895;11198.496067677137;11312.321260724382;10650;11053.336799792038;11222.438878055902;11337.603183888525;11349.659510214708 10:51:39 INFO - PROCESS | 1639 | __end_tp_report 10:51:39 INFO - PROCESS | 1639 | __start_cc_report 10:51:39 INFO - PROCESS | 1639 | _x_x_mozilla_cycle_collect,1510 10:51:39 INFO - PROCESS | 1639 | __end_cc_report 10:51:39 INFO - PROCESS | 1639 | __startTimestamp1484160699945__endTimestamp 10:51:39 INFO - PROCESS | 1639 | 10:51:39 INFO - PROCESS | 1639 | ------- Summary: start ------- 10:51:39 INFO - PROCESS | 1639 | Number of tests: 6 10:51:39 INFO - PROCESS | 1639 | 10:51:39 INFO - PROCESS | 1639 | [#0] dojo.html Cycles:165 Average:5698.60 Median:5899.79 stddev:663.38 (11.2%) stddev-sans-first:637.11 10:51:39 INFO - PROCESS | 1639 | Values: 3254.6 3290.0 3313.5 3314.5 3372.6 5519.5 5577.8 5599.3 5600.0 5629.7 5603.0 5706.7 5721.5 5738.1 5781.3 4315.7 5165.5 5190.7 5213.0 5283.5 5166.7 5170.6 5180.6 5317.6 5332.1 4267.0 5206.3 5255.6 5261.7 5310.4 5217.2 5246.1 5253.4 5281.9 5302.0 5150.9 5225.8 5245.8 5250.4 5291.6 5416.0 5445.0 5466.8 5585.5 5659.7 6136.0 6175.0 6177.3 6181.2 6197.3 6307.5 6321.3 6326.2 6335.0 6343.9 5681.2 5726.4 5749.7 5777.8 5814.4 6254.7 6273.7 6281.9 6284.3 6284.5 6158.2 6172.3 6173.1 6180.5 6187.0 6108.7 6130.6 6188.7 6298.1 6304.3 6141.8 6150.8 6157.9 6159.4 6163.9 6118.5 6196.3 6210.8 6213.2 6262.5 4329.2 4696.3 4935.7 4937.6 4990.5 4379.9 4440.8 4444.2 4448.2 4477.7 5647.5 5649.7 5701.7 5721.6 5729.3 6234.8 6286.3 6288.7 6298.0 6300.2 5630.6 5660.3 5709.2 5749.2 5754.0 5265.7 5303.9 5306.7 5386.5 5402.5 6236.7 6240.6 6252.9 6264.5 6293.1 5414.3 6188.8 6286.7 6301.8 6324.7 6101.9 6245.6 6277.6 6279.9 6306.9 6019.0 6108.2 6138.7 6142.6 6158.8 5080.9 6228.6 6271.6 6283.9 6302.8 5187.5 6088.4 6122.5 6129.5 6135.1 5879.1 5938.0 6019.5 6079.0 6136.6 5943.8 5950.4 5953.0 5965.4 5989.2 6130.6 6134.4 6135.1 6140.0 6146.0 5742.8 5746.0 5885.6 5899.8 5931.1 10:51:39 INFO - PROCESS | 1639 | 10:51:39 INFO - PROCESS | 1639 | [#1] ext.html Cycles:180 Average:67152.38 Median:38059.43 stddev:148491.70 (390.2%) stddev-sans-first:148823.76 10:51:39 INFO - PROCESS | 1639 | Values: 433.8 487.1 498.8 523.7 537.3 29359.8 38110.2 39335.8 39366.2 40609.4 39438.8 39751.8 39755.2 39809.6 39845.4 9176.7 9371.3 9373.0 9375.7 9376.6 9193.5 9294.7 9295.9 9299.0 9315.7 8413.8 8879.7 9295.5 9335.2 9341.3 9235.4 9304.0 9306.9 9310.0 9324.7 9212.1 9294.3 9299.4 9302.3 9307.4 37651.2 38019.2 38045.8 38073.0 38176.2 3132.7 3183.9 3226.7 3327.7 3666.5 28368.6 28619.3 28861.0 29012.8 29256.4 3048.8 3084.7 3111.8 3153.9 3158.6 365518.0 445023.0 445052.0 445352.0 446164.0 59004.7 59397.4 59433.0 59465.8 59474.4 33279.0 33579.3 33613.5 34718.0 61854.0 15603.0 15749.4 15753.7 15756.4 15763.4 12016.8 12102.4 12131.0 12132.3 12141.9 16158.9 16728.9 17927.9 17964.2 18044.9 7458.3 7504.7 7511.6 7554.9 7603.7 836.9 972.4 1004.3 1014.2 1043.2 29049.3 29112.9 29398.9 29531.1 29638.7 681.7 992.8 1066.0 1080.6 1081.6 14610.4 15062.2 15078.7 15434.2 15487.3 550260.0 639437.0 641443.0 642079.0 643051.0 53465.7 54132.4 54184.5 54224.0 54224.9 1195.0 1220.8 1257.9 1288.8 1362.9 29241.6 29887.8 29958.9 30084.2 30534.9 46038.5 46618.8 46705.0 46774.5 46801.8 545283.0 547005.0 547543.0 639585.0 661795.0 37124.4 37962.0 38159.4 38378.8 38384.0 42349.6 42499.7 42512.8 42630.8 43047.0 35757.6 36186.3 36334.5 36355.9 36468.6 36584.1 36958.8 36994.9 37911.8 39567.4 36612.5 36871.3 36993.8 37353.6 37647.2 31754.5 32919.2 33456.3 33591.3 33708.8 24906.0 24993.4 25116.6 25297.6 25364.0 10:51:39 INFO - PROCESS | 1639 | 10:51:39 INFO - PROCESS | 1639 | [#2] jquery.html Cycles:180 Average:33938.75 Median:13255.05 stddev:98252.58 (741.2%) stddev-sans-first:98495.82 10:51:39 INFO - PROCESS | 1639 | Values: 343.9 361.5 363.7 375.2 395.3 15161.8 15381.8 15797.4 20211.6 20686.2 19386.9 19693.7 19812.5 20498.3 20760.5 5891.1 6063.0 6085.8 6092.2 6100.3 4714.5 6666.9 6762.4 6808.4 6822.5 5766.2 6066.2 6642.6 6716.6 6770.7 6032.8 6055.6 6062.5 6072.4 6072.9 5752.7 5756.3 5976.9 6008.2 6014.9 13210.0 13418.9 13469.6 22730.3 23703.6 2119.3 2121.5 2148.4 2806.5 2885.2 10018.8 10134.3 10202.3 12772.4 13916.7 1601.4 1790.4 1884.2 1896.6 1917.5 114238.0 115144.6 118404.0 124540.0 124978.0 10185.6 10558.6 11457.4 11510.3 11679.1 14080.9 14523.4 14568.3 14585.2 14605.0 6886.5 8307.6 8342.5 8446.4 8510.0 8270.8 8295.5 8314.8 8450.6 8461.7 4314.0 4378.8 4379.8 4415.2 4506.8 1821.5 1888.5 1891.9 1893.5 1896.0 813.9 837.7 913.9 932.5 969.8 19993.0 20820.8 20906.6 21084.5 21166.3 916.6 919.2 950.8 967.9 1020.6 9040.9 10225.5 10510.6 10944.3 10962.6 571293.0 585003.0 586587.0 603160.0 603502.0 82827.0 106557.0 118015.0 126664.0 129044.0 786.9 894.6 930.5 930.9 935.4 18659.5 19271.7 19480.0 19534.7 19569.7 52180.5 54560.0 54771.7 54786.0 54868.7 58135.8 72423.6 74144.6 77274.0 77349.6 7673.7 7907.6 7968.8 8104.8 8156.3 10060.6 12207.6 12392.6 12581.4 12724.4 8131.8 10814.1 11062.1 11203.3 11225.9 10614.8 10669.8 10703.4 10802.9 10882.0 10687.8 10939.3 11088.8 11173.5 11422.9 6675.6 7867.4 7895.2 8002.2 8290.2 10266.7 10397.6 10742.6 11048.9 11067.2 10:51:39 INFO - PROCESS | 1639 | 10:51:39 INFO - PROCESS | 1639 | [#3] mootools.html Cycles:180 Average:5416.25 Median:5151.48 stddev:2974.28 (57.7%) stddev-sans-first:2966.31 10:51:39 INFO - PROCESS | 1639 | Values: 1271.2 1431.6 1532.3 1767.8 1789.9 8044.2 8486.3 8487.0 8495.9 8501.7 7414.6 7727.3 7728.1 7790.2 7794.1 2107.5 2146.6 2259.3 2337.9 2342.7 2343.8 2345.4 2351.2 2353.7 2357.6 2280.1 2339.3 2340.1 2341.3 2341.7 2307.0 2309.3 2332.9 2334.1 2334.3 2339.1 2339.7 2358.6 2360.9 2368.0 8226.9 8395.0 8408.5 8423.2 8435.3 4995.1 5152.5 5166.1 5174.1 5191.4 3217.9 3411.4 3471.4 3483.6 3496.1 926.4 969.4 1041.3 1057.6 1070.1 10838.7 10884.0 10885.1 10888.3 10902.8 4433.6 4653.5 4740.3 4757.5 4768.5 9356.0 9359.0 9363.0 9366.8 9402.0 5145.1 5150.5 5161.9 5165.6 5170.0 4534.5 4704.1 4711.9 4726.3 4766.1 3499.7 3652.3 3662.6 3719.5 3888.5 1067.3 1254.3 1254.8 1270.7 1271.9 847.8 874.6 882.8 887.0 902.5 5571.2 5638.6 5689.1 5749.7 5913.2 798.2 884.5 910.4 921.6 957.5 3736.1 3756.9 3771.3 3795.8 3868.2 9665.5 10164.4 10191.7 10197.0 10207.5 10132.4 10142.9 10145.9 10147.7 10154.4 7750.4 7828.3 7919.4 7933.3 7950.5 4806.4 5437.6 5529.3 5556.8 5791.4 8989.9 9267.3 9289.7 9540.4 9555.9 8080.9 9386.7 9411.0 9515.4 9598.1 7055.8 7124.9 7207.8 7221.4 7232.6 7761.9 7785.8 7823.1 7838.9 7851.0 6076.4 6953.8 7119.6 7123.5 7139.5 6056.4 7012.1 7015.9 7022.5 7061.5 6428.7 6624.5 6683.9 6685.3 6699.2 4736.1 4753.5 4772.7 4905.7 5009.4 3610.0 4805.4 4832.8 4932.4 4991.9 10:51:39 INFO - PROCESS | 1639 | 10:51:39 INFO - PROCESS | 1639 | [#4] prototype.html Cycles:180 Average:4506.76 Median:5029.76 stddev:1433.90 (28.5%) stddev-sans-first:1401.73 10:51:39 INFO - PROCESS | 1639 | Values: 241.4 288.2 300.1 306.6 310.6 5291.5 5332.4 5376.5 5397.1 5423.9 4984.8 5068.0 5085.5 5124.6 5333.4 3720.4 3752.5 4029.4 4038.4 4094.7 3570.4 3828.2 4074.6 4084.3 4124.9 3784.3 3906.2 4040.2 4056.8 4060.3 3634.8 3863.9 4042.2 4077.0 4096.9 3212.5 3510.6 3525.5 3542.4 3552.9 4689.8 4800.4 5035.7 5225.4 5243.5 3692.4 3763.6 3926.9 4098.4 4541.4 4252.4 4850.0 4911.9 5548.5 5730.3 3274.4 3878.8 3888.0 3888.9 3903.8 5451.7 5727.7 5777.4 5911.2 5915.6 3323.6 3437.5 3614.5 3871.5 3888.5 3793.6 3929.0 3933.7 3938.5 3942.6 4324.9 4561.0 4595.4 4632.8 4635.9 4622.9 5550.8 5619.7 5665.0 5672.5 1102.9 1162.7 1260.4 1270.0 1279.3 729.7 747.9 754.9 787.7 824.2 4313.0 4551.9 4797.4 4888.8 5280.8 5255.8 5783.9 5792.5 5797.6 5847.7 4543.8 4921.4 5297.2 5322.4 5396.3 2222.9 2420.8 2543.2 2817.5 2859.5 4820.5 5287.7 5313.2 5543.9 5783.7 5607.6 5963.1 6006.6 6032.8 6033.9 5291.4 6085.5 6131.9 6154.9 6155.7 5547.8 5595.6 5800.9 5842.3 5874.7 4908.5 5500.8 5783.9 5802.6 5825.2 4987.5 5415.0 5834.5 5995.6 6064.6 3776.0 3879.6 4045.8 4056.4 4066.9 5686.7 5778.7 5797.4 5799.7 5826.7 5301.4 5662.2 5692.2 5707.7 5729.9 4995.4 5613.9 5675.5 5677.6 5706.4 5176.5 5429.9 5525.2 5577.0 5586.4 4527.6 5020.9 5023.8 5282.5 5554.9 5000.4 5040.9 5587.9 5631.9 5639.0 10:51:39 INFO - PROCESS | 1639 | 10:51:39 INFO - PROCESS | 1639 | [#5] yui.html Cycles:180 Average:20623.17 Median:4414.89 stddev:30893.51 (699.8%) stddev-sans-first:30943.90 10:51:39 INFO - PROCESS | 1639 | Values: 685.3 746.8 761.4 778.8 794.0 13634.3 14155.8 14157.0 14163.6 14197.4 12810.2 12951.4 12952.2 12956.2 12968.0 645.2 663.6 663.6 664.0 793.5 665.3 780.8 802.7 806.3 807.0 852.8 854.9 877.9 878.1 882.2 868.4 868.5 868.9 868.9 871.8 501.7 520.5 614.0 643.2 646.3 13435.3 13819.7 13861.4 13911.4 13937.6 11456.4 12874.5 12942.7 12994.2 13315.9 10405.9 11614.8 11679.4 11787.5 12094.8 4347.7 4406.7 4410.0 4414.1 4569.4 78138.6 79174.0 79875.6 80477.0 84850.6 10274.4 10310.6 10331.5 10612.4 10654.2 19718.0 19727.0 19943.2 20023.4 20305.1 7632.5 7650.6 7678.9 7697.3 7913.7 4129.8 4200.7 4218.3 4364.3 4397.6 3321.0 3420.9 3441.3 3444.3 3449.7 587.3 587.8 599.2 607.7 611.2 523.9 535.5 577.6 604.1 611.3 7963.4 8373.1 8522.6 8677.1 9091.9 561.3 614.8 623.9 633.6 658.9 4091.4 4371.8 4374.5 4415.7 4465.4 95377.0 121291.0 121547.4 121719.0 123390.4 64091.0 69545.3 98068.0 99724.0 101335.0 90128.0 91123.2 92693.0 93893.0 94588.5 8472.7 8650.2 8660.3 8729.3 8885.0 57368.4 57973.1 58014.7 59045.0 60389.0 81978.6 82020.0 83123.6 84612.0 84792.0 10735.2 11055.2 11218.0 11272.4 11770.6 11997.2 12077.5 12106.7 12665.9 12737.0 11638.9 11844.2 11917.0 11920.2 11946.9 8956.6 11248.6 12252.5 12280.2 12614.3 12096.5 12136.0 12278.9 12689.8 12951.7 10449.7 10622.3 10775.7 11198.5 11312.3 10650.0 11053.3 11222.4 11337.6 11349.7 10:51:39 INFO - PROCESS | 1639 | -------- Summary: end -------- 10:51:39 INFO - PROCESS | 1639 | 10:51:40 INFO - TEST-INFO | 1639: exit 0 10:51:40 INFO - TEST-OK | dromaeo_css | took 1119557ms 10:51:40 INFO - TEST-START | kraken 10:51:40 INFO - operating with platform_type : mac_ 10:51:40 INFO - Initialising browser for kraken test... 10:51:40 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/tmpboAjKd/profile http://localhost:49240/getInfo.html) 10:51:43 INFO - PROCESS | 1648 | __metrics Screen width/height:1600/1200 10:51:43 INFO - PROCESS | 1648 | colorDepth:24 10:51:43 INFO - PROCESS | 1648 | Browser inner width/height: 1024/689 10:51:43 INFO - PROCESS | 1648 | __metrics 10:51:43 INFO - PROCESS | 1648 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:51:43 INFO - PROCESS | 1648 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:51:44 INFO - TEST-INFO | 1648: exit 0 10:51:44 INFO - Browser initialized. 10:51:44 INFO - Running cycle 1/1 for kraken test... 10:51:44 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/tmpboAjKd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:51:56 INFO - PROCESS | 1649 | RSS: Main: 222154752 10:51:56 INFO - PROCESS | 1649 | 10:51:58 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection) 10:51:59 INFO - PROCESS | 1649 | RSS: Main: 272183296 10:51:59 INFO - PROCESS | 1649 | 10:52:02 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-dft) 10:52:03 INFO - PROCESS | 1649 | RSS: Main: 283209728 10:52:03 INFO - PROCESS | 1649 | 10:52:06 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-fft) 10:52:06 INFO - PROCESS | 1649 | RSS: Main: 381042688 10:52:06 INFO - PROCESS | 1649 | 10:52:08 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator) 10:52:09 INFO - PROCESS | 1649 | RSS: Main: 282370048 10:52:09 INFO - PROCESS | 1649 | 10:52:11 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:52:12 INFO - PROCESS | 1649 | RSS: Main: 283791360 10:52:12 INFO - PROCESS | 1649 | 10:52:14 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom) 10:52:15 INFO - PROCESS | 1649 | RSS: Main: 322457600 10:52:15 INFO - PROCESS | 1649 | 10:52:18 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate) 10:52:19 INFO - PROCESS | 1649 | RSS: Main: 324648960 10:52:19 INFO - PROCESS | 1649 | 10:52:21 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial) 10:52:22 INFO - PROCESS | 1649 | RSS: Main: 323174400 10:52:22 INFO - PROCESS | 1649 | 10:52:23 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:52:24 INFO - PROCESS | 1649 | RSS: Main: 300085248 10:52:24 INFO - PROCESS | 1649 | 10:52:26 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:52:27 INFO - PROCESS | 1649 | RSS: Main: 300085248 10:52:27 INFO - PROCESS | 1649 | 10:52:29 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:52:29 INFO - PROCESS | 1649 | RSS: Main: 302661632 10:52:29 INFO - PROCESS | 1649 | 10:52:32 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:52:33 INFO - PROCESS | 1649 | RSS: Main: 312995840 10:52:33 INFO - PROCESS | 1649 | 10:52:36 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:52:36 INFO - PROCESS | 1649 | RSS: Main: 311287808 10:52:36 INFO - PROCESS | 1649 | 10:52:38 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49240/tests/kraken/driver.html?testName=ai-astar) 10:52:38 INFO - PROCESS | 1649 | RSS: Main: 355454976 10:52:38 INFO - PROCESS | 1649 | 10:52:38 INFO - PROCESS | 1649 | __start_tp_report 10:52:38 INFO - PROCESS | 1649 | _x_x_mozilla_page_load 10:52:38 INFO - PROCESS | 1649 | _x_x_mozilla_page_load_details 10:52:38 INFO - PROCESS | 1649 | |i|pagename|runs| 10:52:38 INFO - PROCESS | 1649 | |0;ai-astar;115;115;115;113;114;115;113;114;115;115 10:52:38 INFO - PROCESS | 1649 | |1;audio-beat-detection;144;112;110;142;110;112;110;109;139;109 10:52:38 INFO - PROCESS | 1649 | |2;audio-dft;185;186;184;184;183;183;185;183;183;183 10:52:38 INFO - PROCESS | 1649 | |3;audio-fft;81;58;61;93;71;83;81;59;84;59 10:52:38 INFO - PROCESS | 1649 | |4;audio-oscillator;85;71;95;70;68;68;90;67;69;93 10:52:38 INFO - PROCESS | 1649 | |5;imaging-gaussian-blur;88;87;90;87;90;86;90;87;93;130 10:52:38 INFO - PROCESS | 1649 | |6;imaging-darkroom;98;97;98;98;98;99;98;97;98;97 10:52:38 INFO - PROCESS | 1649 | |7;imaging-desaturate;83;84;79;80;83;83;84;82;81;80 10:52:38 INFO - PROCESS | 1649 | |8;json-parse-financial;56;56;56;55;56;58;58;57;57;58 10:52:38 INFO - PROCESS | 1649 | |9;json-stringify-tinderbox;65;76;81;73;71;69;70;71;71;72 10:52:38 INFO - PROCESS | 1649 | |10;stanford-crypto-aes;84;84;84;84;84;83;82;83;84;83 10:52:38 INFO - PROCESS | 1649 | |11;stanford-crypto-ccm;139;139;144;152;153;142;144;142;231;138 10:52:38 INFO - PROCESS | 1649 | |12;stanford-crypto-pbkdf2;164;164;164;165;165;169;163;166;165;165 10:52:38 INFO - PROCESS | 1649 | |13;stanford-crypto-sha256-iterative;67;67;67;68;67;67;67;66;67;67 10:52:38 INFO - PROCESS | 1649 | __end_tp_report 10:52:38 INFO - PROCESS | 1649 | __start_cc_report 10:52:38 INFO - PROCESS | 1649 | _x_x_mozilla_cycle_collect,1975 10:52:38 INFO - PROCESS | 1649 | __end_cc_report 10:52:38 INFO - PROCESS | 1649 | __startTimestamp1484160758894__endTimestamp 10:52:38 INFO - PROCESS | 1649 | 10:52:38 INFO - PROCESS | 1649 | ------- Summary: start ------- 10:52:38 INFO - PROCESS | 1649 | Number of tests: 14 10:52:38 INFO - PROCESS | 1649 | 10:52:38 INFO - PROCESS | 1649 | [#0] ai-astar Cycles:10 Average:114.40 Median:115.00 stddev:0.84 (0.7%) stddev-sans-first:0.87 10:52:38 INFO - PROCESS | 1649 | Values: 115.0 115.0 115.0 113.0 114.0 115.0 113.0 114.0 115.0 115.0 10:52:38 INFO - PROCESS | 1649 | 10:52:38 INFO - PROCESS | 1649 | [#1] audio-beat-detection Cycles:10 Average:119.70 Median:112.00 stddev:15.24 (13.6%) stddev-sans-first:13.39 10:52:38 INFO - PROCESS | 1649 | Values: 144.0 112.0 110.0 142.0 110.0 112.0 110.0 109.0 139.0 109.0 10:52:38 INFO - PROCESS | 1649 | 10:52:38 INFO - PROCESS | 1649 | [#2] audio-dft Cycles:10 Average:183.90 Median:184.00 stddev:1.10 (0.6%) stddev-sans-first:1.09 10:52:38 INFO - PROCESS | 1649 | Values: 185.0 186.0 184.0 184.0 183.0 183.0 185.0 183.0 183.0 183.0 10:52:38 INFO - PROCESS | 1649 | 10:52:38 INFO - PROCESS | 1649 | [#3] audio-fft Cycles:10 Average:73.00 Median:81.00 stddev:12.97 (16.0%) stddev-sans-first:13.43 10:52:38 INFO - PROCESS | 1649 | Values: 81.0 58.0 61.0 93.0 71.0 83.0 81.0 59.0 84.0 59.0 10:52:38 INFO - PROCESS | 1649 | 10:52:38 INFO - PROCESS | 1649 | [#4] audio-oscillator Cycles:10 Average:77.60 Median:78.00 stddev:11.64 (14.9%) stddev-sans-first:12.04 10:52:38 INFO - PROCESS | 1649 | Values: 85.0 71.0 95.0 70.0 68.0 68.0 90.0 67.0 69.0 93.0 10:52:38 INFO - PROCESS | 1649 | 10:52:38 INFO - PROCESS | 1649 | [#5] imaging-gaussian-blur Cycles:10 Average:92.80 Median:89.00 stddev:13.24 (14.9%) stddev-sans-first:13.93 10:52:38 INFO - PROCESS | 1649 | Values: 88.0 87.0 90.0 87.0 90.0 86.0 90.0 87.0 93.0 130.0 10:52:38 INFO - PROCESS | 1649 | 10:52:38 INFO - PROCESS | 1649 | [#6] imaging-darkroom Cycles:10 Average:97.80 Median:98.00 stddev:0.63 (0.6%) stddev-sans-first:0.67 10:52:38 INFO - PROCESS | 1649 | Values: 98.0 97.0 98.0 98.0 98.0 99.0 98.0 97.0 98.0 97.0 10:52:38 INFO - PROCESS | 1649 | 10:52:38 INFO - PROCESS | 1649 | [#7] imaging-desaturate Cycles:10 Average:81.90 Median:83.00 stddev:1.79 (2.2%) stddev-sans-first:1.86 10:52:38 INFO - PROCESS | 1649 | Values: 83.0 84.0 79.0 80.0 83.0 83.0 84.0 82.0 81.0 80.0 10:52:38 INFO - PROCESS | 1649 | 10:52:38 INFO - PROCESS | 1649 | [#8] json-parse-financial Cycles:10 Average:56.70 Median:57.00 stddev:1.06 (1.9%) stddev-sans-first:1.09 10:52:38 INFO - PROCESS | 1649 | Values: 56.0 56.0 56.0 55.0 56.0 58.0 58.0 57.0 57.0 58.0 10:52:38 INFO - PROCESS | 1649 | 10:52:38 INFO - PROCESS | 1649 | [#9] json-stringify-tinderbox Cycles:10 Average:71.90 Median:71.50 stddev:4.25 (6.0%) stddev-sans-first:3.71 10:52:38 INFO - PROCESS | 1649 | Values: 65.0 76.0 81.0 73.0 71.0 69.0 70.0 71.0 71.0 72.0 10:52:38 INFO - PROCESS | 1649 | 10:52:38 INFO - PROCESS | 1649 | [#10] stanford-crypto-aes Cycles:10 Average:83.50 Median:84.00 stddev:0.71 (0.8%) stddev-sans-first:0.73 10:52:38 INFO - PROCESS | 1649 | Values: 84.0 84.0 84.0 84.0 84.0 83.0 82.0 83.0 84.0 83.0 10:52:38 INFO - PROCESS | 1649 | 10:52:38 INFO - PROCESS | 1649 | [#11] stanford-crypto-ccm Cycles:10 Average:152.40 Median:144.00 stddev:28.09 (19.5%) stddev-sans-first:29.37 10:52:38 INFO - PROCESS | 1649 | Values: 139.0 139.0 144.0 152.0 153.0 142.0 144.0 142.0 231.0 138.0 10:52:38 INFO - PROCESS | 1649 | 10:52:38 INFO - PROCESS | 1649 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.00 Median:165.00 stddev:1.63 (1.0%) stddev-sans-first:1.69 10:52:38 INFO - PROCESS | 1649 | Values: 164.0 164.0 164.0 165.0 165.0 169.0 163.0 166.0 165.0 165.0 10:52:38 INFO - PROCESS | 1649 | 10:52:38 INFO - PROCESS | 1649 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.00 Median:67.00 stddev:0.47 (0.7%) stddev-sans-first:0.50 10:52:38 INFO - PROCESS | 1649 | Values: 67.0 67.0 67.0 68.0 67.0 67.0 67.0 66.0 67.0 67.0 10:52:38 INFO - PROCESS | 1649 | -------- Summary: end -------- 10:52:38 INFO - PROCESS | 1649 | 10:52:39 INFO - TEST-INFO | 1649: exit 0 10:52:39 INFO - TEST-OK | kraken | took 59358ms 10:52:39 INFO - SUITE-END | took 1178s 10:52:40 INFO - Completed test suite (00:19:39) 10:52:40 INFO - javascript benchmark 10:52:40 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3254.5931758530182, 3290.0, 3313.4698448248287, 3314.486254630533, 3372.56156699629, 5519.530839878616, 5577.8047768328115, 5599.300087489064, 5599.972000140004, 5629.71851407429, 5602.971985140069, 5706.65760054397, 5721.485066344038, 5738.053221218504, 5781.335146458158, 4315.697901146921, 5165.535101840825, 5190.688558686472, 5212.973935130319, 5283.524482796547, 5166.664166829163, 5170.560502357304, 5180.559652429548, 5317.574594032488, 5332.120200166968, 4267.0, 5206.349206349207, 5255.605829562798, 5261.657992230512, 5310.415854256029, 5217.243499692542, 5246.134387826004, 5253.4221235664045, 5281.86795330116, 5302.0, 5150.948490515084, 5225.817096401645, 5245.842624721265, 5250.3962044364625, 5291.629585928987, 5415.972920135374, 5445.0, 5466.808661696821, 5585.497305242507, 5659.660420374791, 6136.028952951454, 6174.96912515439, 6177.289611694623, 6181.165542651775, 6197.287311959092, 6307.5269354798575, 6321.273053598849, 6326.2092238470195, 6335.018072198816, 6343.936560634381, 5681.204631351614, 5726.427357264323, 5749.655020698771, 5777.797777077824, 5814.418558144236, 6254.749810007549, 6273.749050038039, 6281.937180628227, 6284.340144284784, 6284.497240220771, 6158.168647232566, 6172.321044685081, 6173.135760993465, 6180.536459765445, 6187.010078387527, 6108.706347747029, 6130.551011451118, 6188.659623720739, 6298.08677741734, 6304.275008374096, 6141.762184092961, 6150.846228844314, 6157.9384206157365, 6159.4456498914415, 6163.9383606164265, 6118.506865273355, 6196.287426945959, 6210.844728881723, 6213.2233470816145, 6262.499000079983, 4329.220740266782, 4696.342512048316, 4935.728534930613, 4937.604991600663, 4990.50094990498, 4379.934300985487, 4440.800163992628, 4444.2444784386735, 4448.221561226837, 4477.7313361197785, 5647.491725744702, 5649.661020338793, 5701.71491425427, 5721.57088218377, 5729.341125770589, 6234.844128896814, 6286.308506064329, 6288.717007734572, 6298.0, 6300.21247344082, 5630.577706672016, 5660.264165658438, 5709.172170035405, 5749.1663708762835, 5754.0, 5265.736713164327, 5303.946960530346, 5306.734663266821, 5386.461353864583, 5402.540784033323, 6236.65698386575, 6240.625562466266, 6252.937470625327, 6264.498840092872, 6293.118963345227, 5414.3232095988005, 6188.81433556994, 6286.6856657166045, 6301.842453938688, 6324.683765811783, 6101.938980610137, 6245.5628106032145, 6277.591956522811, 6279.874402511833, 6306.905396418965, 6019.0, 6108.159949115366, 6138.664296166039, 6142.600730952474, 6158.753649853956, 5080.872978175576, 6228.56400051992, 6271.62370257786, 6283.905741413974, 6302.810915672537, 5187.533122019036, 6088.391160883876, 6122.510199183966, 6129.540284478593, 6135.14108024868, 5879.118132280192, 5938.049912014057, 6019.4883434908525, 6078.969605151946, 6136.570440069152, 5943.821685349447, 5950.375210602998, 5953.017752071033, 5965.373635768356, 5989.1914591529585, 6130.63216207029, 6134.386561344008, 6135.11040899076, 6140.0, 6145.9692701536205, 5742.827715168552, 5746.0, 5885.55858310637, 5899.793507227227, 5931.1399847021075], "value": 5655.743231658541, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [433.8177965254593, 487.06240487062394, 498.81294514557044, 523.6779380680882, 537.2719964448173, 29359.825606975723, 38110.23779524408, 39335.803320983425, 39366.2126757465, 40609.39085913714, 39438.80280598593, 39751.801240993824, 39755.20489590206, 39809.601903980954, 39845.402318965236, 9176.67881624145, 9371.25029997602, 9372.95313523435, 9375.718728438158, 9376.624935002592, 9193.494357810318, 9294.674686385977, 9295.860562091573, 9299.0, 9315.673951411702, 8413.831723365529, 8879.689210877621, 9295.48874811885, 9335.206507446876, 9341.299402544802, 9235.399699019543, 9303.953480232556, 9306.86039709405, 9310.022447642968, 9324.673636422693, 9212.078792120801, 9294.256459483226, 9299.442033478013, 9302.348835581512, 9307.441553506811, 37651.24697506062, 38019.23961520754, 38045.809770951244, 38073.048173795876, 38176.236475270336, 3132.686731326849, 3183.904482865518, 3226.6612005739294, 3327.73378129749, 3666.468362087509, 28368.574471382944, 28619.279493665897, 28860.98986535461, 29012.83948642052, 29256.414871702447, 3048.7713421493477, 3084.7223749862396, 3111.766617503674, 3153.8738450461956, 3158.6209654841564, 365518.0, 445023.0, 445052.0, 445352.0, 446164.0, 59004.704976474844, 59397.406025939184, 59433.0, 59465.81068378695, 59474.40525594775, 33279.0, 33579.328413432086, 33613.49579756305, 34718.0, 61854.0, 15602.985805922579, 15749.370025199092, 15753.68492630141, 15756.448524301595, 15763.448279310169, 12016.759664806656, 12102.394880255952, 12131.029517638568, 12132.272063676208, 12141.878581214252, 16158.949668271525, 16728.916355418143, 17927.910360448375, 17964.20357964193, 18044.90977545104, 7458.277728629011, 7504.662290196954, 7511.586862722603, 7554.92239634094, 7603.695852165962, 836.8870202522703, 972.4248107244626, 1004.3220825942606, 1014.2494554030112, 1043.212374657129, 29049.273768155967, 29112.854435727684, 29398.853005734836, 29531.114066578037, 29638.703612964026, 681.6625770243761, 992.831218692824, 1066.0, 1080.6379862745912, 1081.648464249122, 14610.415583376545, 15062.171580563174, 15078.698426031637, 15434.228288585751, 15487.303071361815, 550260.0, 639437.0, 641443.0, 642079.0, 643051.0, 53465.66335841635, 54132.3760287192, 54184.45815541873, 54224.0, 54224.915501690535, 1194.964151075469, 1220.7680540697238, 1257.8742125787348, 1288.8002359634459, 1362.9386677599532, 29241.561376579793, 29887.804487820245, 29958.850205748833, 30084.24789380283, 30534.931277405183, 46038.539614603425, 46618.76690616525, 46705.0, 46774.53225467702, 46801.76599116983, 545283.0, 547005.0, 547543.0, 639585.0, 661795.0, 37124.44313335356, 37962.0, 38159.42760858534, 38378.80810596041, 38384.04039899025, 42349.57650423457, 42499.7250082498, 42512.78743606262, 42630.78684606557, 43047.0, 35757.64242357647, 36186.27627447489, 36334.45498317474, 36355.90932272036, 36468.635313646526, 36584.08539786527, 36958.815205924875, 36994.89015329545, 37911.81044094762, 39567.40648890327, 36612.45081323729, 36871.26257474889, 36993.81503092467, 37353.6264637361, 37647.24705505929, 31754.523682144325, 32919.17702057468, 33456.33087338288, 33591.32817343689, 33708.83145584256, 24906.003759849402, 24993.375165620277, 25116.62325065162, 25297.62053569161, 25364.0], "value": 19144.1456990044, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [343.8607364017574, 361.4578132800799, 363.7035815810115, 375.24013871909386, 395.33386244178564, 15161.848381516182, 15381.846181538182, 15797.447089351876, 20211.59576808463, 20686.172553097895, 19386.903065484654, 19693.704594431096, 19812.50468738282, 20498.28256011036, 20760.480987975272, 5891.116332550119, 6063.0, 6085.78699745509, 6092.177556029924, 6100.267967843865, 4714.528547145293, 6666.9332906734935, 6762.391384775369, 6808.387245147936, 6822.454203663695, 5766.221560089397, 6066.241719785027, 6642.634655093968, 6716.563423377489, 6770.69531871067, 6032.788852390167, 6055.5761096723245, 6062.545309101835, 6072.392760723937, 6072.908906366408, 5752.654840709529, 5756.251687280668, 5976.9402305977255, 6008.188894499231, 6014.879702405928, 13210.0, 13418.932905335509, 13469.595912122651, 22730.318090457313, 23703.644445333335, 2119.279444988696, 2121.5332626822074, 2148.430665873545, 2806.4527417153663, 2885.177724348551, 10018.849717254247, 10134.290599658027, 10202.33684810485, 12772.425240864184, 13916.737468139378, 1601.4074792326796, 1790.4002159276658, 1884.2274667386305, 1896.5922326699688, 1917.51103468616, 114238.0, 115144.57060372915, 118404.0, 124540.0, 124978.0, 10185.643502477526, 10558.577656893793, 11457.427128643534, 11510.30938143714, 11679.124065695107, 14080.859191407955, 14523.419063237563, 14568.27158642085, 14585.197814120325, 14605.0, 6886.48351373639, 8307.584620768937, 8342.457740246864, 8446.366522510836, 8509.957450212833, 8270.766892938873, 8295.502269863708, 8314.833703326021, 8450.61972211252, 8461.661533538589, 4313.956860431418, 4378.8029538670835, 4379.802908869108, 4415.161119387306, 4506.797194126272, 1821.4626685127919, 1888.4712280561464, 1891.877027993176, 1893.4603637963114, 1895.9715604265948, 813.8575749243859, 837.7235512280932, 913.8949020862553, 932.4684929590069, 969.8496733006396, 19993.0, 20820.79179208219, 20906.581868362548, 21084.481725584305, 21166.259180928595, 916.6241840845355, 919.2002957427081, 950.8240975419569, 967.8644989701447, 1020.5968642386216, 9040.909590904006, 10225.539850706737, 10510.632127875488, 10944.343339399662, 10962.616308429326, 571293.0, 585003.0, 586587.0, 603160.0, 603502.0, 82826.96340091969, 106557.0, 118015.0, 126664.0, 129044.0, 786.9134395216522, 894.6332003878379, 930.5440334236175, 930.9115634014893, 935.3919952031234, 18659.533511662317, 19271.710924335865, 19480.0, 19534.706979395036, 19569.70645440291, 52180.47819521756, 54560.0, 54771.726141369036, 54786.0, 54868.72565637146, 58135.837283253255, 72423.63788181025, 74144.62927685327, 77274.0, 77349.61325193338, 7673.731419400294, 7907.604619769104, 7968.800779980547, 8104.756857294291, 8156.306713929383, 10060.597576096874, 12207.633770986884, 12392.56626018085, 12581.370931453574, 12724.363781811058, 8131.7560473185895, 10814.080803131821, 11062.115030797679, 11203.271787333797, 11225.943870280924, 10614.787704246026, 10669.839952400875, 10703.411312377582, 10802.89197108019, 10882.0, 10687.786244274916, 10939.343639381344, 11088.778224435304, 11173.49719262619, 11422.94288528552, 6675.566088204251, 7867.449278550446, 7895.171007044178, 8002.159773223728, 8290.170982901662, 10266.691999240034, 10397.636082737068, 10742.624008159677, 11048.889511105108, 11067.169962252703], "value": 9339.731713358977, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1271.1610337177462, 1431.59199628106, 1532.3334349557942, 1767.823217678233, 1789.9194536245868, 8044.195580441953, 8486.278666313356, 8486.95756521218, 8495.915040849597, 8501.744947651565, 7414.5551266923885, 7727.304542591166, 7728.111267204274, 7790.18203088676, 7794.103678077023, 2107.525806693493, 2146.5599552091785, 2259.29961711869, 2337.906483740648, 2342.7188737351544, 2343.789058984691, 2345.413646588353, 2351.1653363056125, 2353.7410884802657, 2357.5874222011166, 2280.0879648140713, 2339.333290012347, 2340.063974410241, 2341.2507997440825, 2341.7424083350816, 2306.9884650576723, 2309.307207837642, 2332.8716920569364, 2334.124703236286, 2334.26470661741, 2339.0760649543504, 2339.6724458575814, 2358.551875143717, 2360.917367892133, 2368.029108065685, 8226.876596850992, 8394.958025209835, 8408.537530435824, 8423.15768423159, 8435.283000944926, 4995.075910956485, 5152.458991805844, 5166.095933211711, 5174.146265866165, 5191.402988656315, 3217.8551965161623, 3411.4200585900458, 3471.357798807229, 3483.599386070609, 3496.056064862473, 926.3746970794687, 969.3699095587854, 1041.265907535186, 1057.6351158850184, 1070.0743856564093, 10838.729031774268, 10884.020438160604, 10885.074768644596, 10888.346699197995, 10902.836457453144, 4433.556644335541, 4653.488116307203, 4740.265258884879, 4757.500462451462, 4768.47546769855, 9356.017618150132, 9358.970513243674, 9363.0, 9366.765830854147, 9402.0, 5145.07388670043, 5150.510701483428, 5161.896762064737, 5165.586753059746, 5170.0, 4534.523874993144, 4704.082703872712, 4711.882202944954, 4726.26742854858, 4766.142094423037, 3499.685028347461, 3652.342578335925, 3662.560492740835, 3719.516462859811, 3888.4944957155953, 1067.3062509368817, 1254.2787896959285, 1254.811778233254, 1270.6950331920273, 1271.879171478708, 847.7626264645909, 874.6151693254948, 882.7748924024332, 887.0109827542373, 902.5126431726794, 5571.164325351229, 5638.633488823213, 5689.089745640677, 5749.741261643236, 5913.231279933581, 798.2416704130983, 884.526778173674, 910.4264313482464, 921.5944984207061, 957.5308099031564, 3736.103335199587, 3756.9248615027545, 3771.321162190832, 3795.76270765069, 3868.168343806095, 9665.468399237969, 10164.440955747357, 10191.745206369751, 10197.0, 10207.489625518694, 10132.392056476636, 10142.949285253673, 10145.94927025375, 10147.746306342253, 10154.39073655583, 7750.418718596128, 7828.2954534092205, 7919.406044546682, 7933.286004259528, 7950.522968621901, 4806.351142595705, 5437.564994800486, 5529.281193444987, 5556.777728890799, 5791.449812267996, 8989.865152022594, 9267.258619310303, 9289.72130836076, 9540.427574345284, 9555.856662150212, 8080.868678385148, 9386.718398448058, 9411.0, 9515.381500202464, 9598.088181622734, 7055.753048643272, 7124.893126603209, 7207.819804504929, 7221.350078492961, 7232.63836808147, 7761.883571746541, 7785.844283114419, 7823.139454659869, 7838.921610783819, 7850.960745196466, 6076.361981992004, 6953.79138625842, 7119.572825630479, 7123.536970096927, 7139.500234983501, 6056.394360563909, 7012.088428504262, 7015.894761578478, 7022.508424410242, 7061.505694601328, 6428.678566071584, 6624.470042396693, 6683.8997415039785, 6685.298043705341, 6699.229588597374, 4736.052789442194, 4753.47711751714, 4772.737499437564, 4905.705657660552, 5009.398872135367, 3610.0, 4805.37530121082, 4832.806687732451, 4932.4327702314695, 4991.900161996812], "value": 4386.270554474685, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [241.3844696025136, 288.2447986275957, 300.13261673762827, 306.60601127551104, 310.58071603335515, 5291.523762861341, 5332.386775520817, 5376.489233522819, 5397.136458166699, 5423.918641220375, 4984.825531106412, 5068.0, 5085.491450854913, 5124.564412024983, 5333.413324534308, 3720.367537518615, 3752.4746535485056, 4029.3955906614096, 4038.394240863879, 4094.733842300241, 3570.357335679577, 3828.157805282833, 4074.6129117733767, 4084.326086195774, 4124.855630052949, 3784.3188226119287, 3906.199229158016, 4040.2121586290536, 4056.797160141996, 4060.2894493463673, 3634.78191308521, 3863.8794749522604, 4042.150284049738, 4076.979615101935, 4096.893838663574, 3212.4699424594914, 3510.5611798525183, 3525.453554699026, 3542.3800834854055, 3552.9111772205642, 4689.8358557450665, 4800.399950006249, 5035.748212589356, 5225.399079105913, 5243.528082472596, 3692.4276737105793, 3763.6424539668687, 3926.9018274543364, 4098.405731168964, 4541.364209010741, 4252.447181866369, 4849.951500485021, 4911.8772030699165, 5548.500634942834, 5730.255066841326, 3274.361499507597, 3878.7866667333287, 3888.0279930017496, 3888.9027774305587, 3903.8438462461468, 5451.672899626035, 5727.656340619576, 5777.393373695743, 5911.172435858984, 5915.645061296336, 3323.5513205717402, 3437.4843773434186, 3614.493970844084, 3871.457995880579, 3888.494495715539, 3793.6016718244737, 3928.980355098206, 3933.724639275223, 3938.487996560486, 3942.64516193544, 4324.913501729948, 4560.954390456053, 4595.425571803525, 4632.814687412466, 4635.860924172281, 4622.907541849144, 5550.833474995757, 5619.719014049281, 5664.980303545319, 5672.461116193955, 1102.8566286382718, 1162.7035106047904, 1260.4139075330015, 1270.009392673716, 1279.2899940533093, 729.6680010595272, 747.9453969902412, 754.8867669849567, 787.7321710618418, 824.1634740738156, 4312.956870431318, 4551.8634440966825, 4797.424309082862, 4888.804447822118, 5280.81517146898, 5255.76349064293, 5783.855403614859, 5792.495540198203, 5797.594168408171, 5847.707614619252, 4543.818247270138, 4921.447660913712, 5297.17893726473, 5322.4411436799355, 5396.325459317585, 2222.866628002325, 2420.8426452280546, 2543.219746177683, 2817.4646817104685, 2859.4709978654014, 4820.469748327681, 5287.68273903567, 5313.237116699294, 5543.861403464945, 5783.652980821164, 5607.607467477319, 5963.105534169909, 6006.609570377911, 6032.819015429544, 6033.883731509684, 5291.365036195682, 6085.513158947274, 6131.908021379772, 6154.907676384858, 6155.692215389213, 5547.833564993057, 5595.5803314752375, 5800.897829412398, 5842.269716285465, 5874.735636896265, 4908.533689299511, 5500.844822587142, 5783.884322313446, 5802.564807639528, 5825.213596164633, 4987.501249875128, 5415.025295446857, 5834.474897259267, 5995.550333725076, 6064.605800622945, 3775.981120094382, 3879.6140373670555, 4045.7774822383876, 4056.4320995060125, 4066.898327541835, 5686.687232202268, 5778.739956701875, 5797.44900253316, 5799.739011744566, 5826.659868230416, 5301.390340110783, 5662.178984047372, 5692.231548740867, 5707.743151558107, 5729.939961107209, 4995.425526064549, 5613.859653508532, 5675.489205971482, 5677.574181936288, 5706.372299047019, 5176.534111929794, 5429.914017196497, 5525.226468294361, 5577.0, 5586.441355864381, 4527.637788976808, 5020.924686129784, 5023.769176551622, 5282.498162674539, 5554.861128471821, 5000.449950505369, 5040.941402305557, 5587.860303492445, 5631.943680563142, 5639.0], "value": 4030.532836516013, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [685.2941470285601, 746.8207630168757, 761.4213197969543, 778.7585848387, 793.9960300198499, 13634.318284085793, 14155.787663185061, 14157.009009369336, 14163.645908852284, 14197.361118749655, 12810.167339122974, 12951.41718622662, 12952.22286662801, 12956.15784973976, 12968.0, 645.2225068792094, 663.6350007495882, 663.6383171171699, 664.0073090729343, 793.4604468961104, 665.3246954341347, 780.8321210939647, 802.7190483330817, 806.2501873257868, 806.9717559885376, 852.7953291210096, 854.8717692346166, 877.8595424732044, 878.0692465986043, 882.2192359761625, 868.4138206710445, 868.4875923205276, 868.8957325120995, 868.9261412779921, 871.760265926872, 501.70309749297746, 520.5159201942192, 613.9508839292846, 643.237763250545, 646.3375040583393, 13435.26106064166, 13819.654508637264, 13861.4455421783, 13911.443542258297, 13937.58187254384, 11456.369899655516, 12874.485020599164, 12942.676433089155, 12994.243747518876, 13315.933420332933, 10405.9479702601, 11614.767704645945, 11679.357635330054, 11787.469563869647, 12094.758104837854, 4347.652187824967, 4406.65596992917, 4409.977950110261, 4414.073044660625, 4569.405977222973, 78138.6093069531, 79174.0, 79875.60062199652, 80477.0, 84850.57574712149, 10274.434906080089, 10310.639127630497, 10331.535080921376, 10612.363258204532, 10654.200934929911, 19718.01409929498, 19727.013649317476, 19943.202271909253, 20023.399298021082, 20305.086271117834, 7632.46572739914, 7650.617469126521, 7678.884816727754, 7697.345725613385, 7913.723019694283, 4129.772862492592, 4200.7059505834295, 4218.325067989168, 4364.301711726109, 4397.626201772821, 3320.9501857472155, 3420.931581368359, 3441.3117376524297, 3444.3283559705615, 3449.6722811332884, 587.2923127631194, 587.7913340764053, 599.179124599293, 607.7234858139536, 611.150500803883, 523.8978399212193, 535.4859335038407, 577.6129992904764, 604.1119554255238, 611.3031144495279, 7963.362930965509, 8373.120822313691, 8522.57387130641, 8677.088905664941, 9091.909080909238, 561.348835350999, 614.8124821929265, 623.9001759718424, 633.6071635585874, 658.887989041864, 4091.4272001919753, 4371.846985355561, 4374.4531933508315, 4415.712978656377, 4465.441819772528, 95377.0, 121291.0, 121547.39226303811, 121719.0, 123390.38304808419, 64091.0, 69545.30454695388, 98068.0, 99724.0, 101335.0, 90128.0, 91123.1775364489, 92693.0, 93893.0, 94588.52705736428, 8472.66109355619, 8650.178233067849, 8660.307175426076, 8729.258013068706, 8884.955575222082, 57368.42631573631, 57973.13040304314, 58014.7099264501, 59045.0, 60389.0, 81978.59010704908, 82020.0, 83123.58438207771, 84612.0, 84792.0, 10735.182900131216, 11055.22613417053, 11218.0, 11272.380019099028, 11770.646880593597, 11997.220180688228, 12077.456514457046, 12106.697332566757, 12665.936670316589, 12737.0, 11638.941805290919, 11844.170908036354, 11917.0, 11920.165588408729, 11946.880531194576, 8956.552172391484, 11248.550057997589, 12252.509899604273, 12280.201786883825, 12614.306213158365, 12096.516139354328, 12136.0, 12278.938605306916, 12689.8096528554, 12951.740965180456, 10449.738756531147, 10622.256442048982, 10775.730606734895, 11198.496067677137, 11312.321260724382, 10650.0, 11053.336799792038, 11222.438878055902, 11337.603183888525, 11349.659510214708], "value": 7114.870982138159, "unit": "score"}], "name": "dromaeo_css", "value": 7091.779422949291, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 115.0, 115.0, 113.0, 114.0, 115.0, 113.0, 114.0, 115.0, 115.0], "value": 114.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 112.0, 110.0, 142.0, 110.0, 112.0, 110.0, 109.0, 139.0, 109.0], "value": 119.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 186.0, 184.0, 184.0, 183.0, 183.0, 185.0, 183.0, 183.0, 183.0], "value": 183.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 58.0, 61.0, 93.0, 71.0, 83.0, 81.0, 59.0, 84.0, 59.0], "value": 73.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 71.0, 95.0, 70.0, 68.0, 68.0, 90.0, 67.0, 69.0, 93.0], "value": 77.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 87.0, 90.0, 87.0, 90.0, 86.0, 90.0, 87.0, 93.0, 130.0], "value": 92.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 97.0, 98.0, 98.0, 98.0, 99.0, 98.0, 97.0, 98.0, 97.0], "value": 97.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 79.0, 80.0, 83.0, 83.0, 84.0, 82.0, 81.0, 80.0], "value": 81.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 56.0, 55.0, 56.0, 58.0, 58.0, 57.0, 57.0, 58.0], "value": 56.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 76.0, 81.0, 73.0, 71.0, 69.0, 70.0, 71.0, 71.0, 72.0], "value": 71.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 84.0, 84.0, 84.0, 83.0, 82.0, 83.0, 84.0, 83.0], "value": 83.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 139.0, 144.0, 152.0, 153.0, 142.0, 144.0, 142.0, 231.0, 138.0], "value": 152.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 164.0, 164.0, 165.0, 165.0, 169.0, 163.0, 166.0, 165.0, 165.0], "value": 165.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 67.0, 68.0, 67.0, 67.0, 67.0, 66.0, 67.0, 67.0], "value": 67.0, "unit": "score"}], "name": "kraken", "value": 1437.6, "alertThreshold": 2.0}]} 10:52:40 INFO - Return code: 0 10:52:40 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:52:40 INFO - # TBPL SUCCESS # 10:52:40 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:52:40 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:52:40 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:52:40 INFO - 2017-01-11 10:52:40.202 screenresolution[1653:10030] starting screenresolution argv=screenresolution get 10:52:40 INFO - 2017-01-11 10:52:40.205 screenresolution[1653:10030] Display 0: 1600x1200x32@60 10:52:40 INFO - 2017-01-11 10:52:40.211 screenresolution[1654:10033] starting screenresolution argv=screenresolution list 10:52:40 INFO - Available Modes on Display 0 10:52:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:52:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:52:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:52:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:52:40 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:52:40 INFO - Intel Iris: 10:52:40 INFO - Chipset Model: Intel Iris 10:52:40 INFO - Type: GPU 10:52:40 INFO - Bus: Built-In 10:52:40 INFO - VRAM (Dynamic, Max): 1536 MB 10:52:40 INFO - Vendor: Intel (0x8086) 10:52:40 INFO - Device ID: 0x0a2e 10:52:40 INFO - Revision ID: 0x0009 10:52:40 INFO - Displays: 10:52:40 INFO - Display: 10:52:40 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:52:40 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:52:40 INFO - Display Serial Number: 1600x1200 60 10:52:40 INFO - Main Display: Yes 10:52:40 INFO - Mirror: Off 10:52:40 INFO - Online: Yes 10:52:40 INFO - Rotation: Supported 10:52:40 INFO - Return code: 0 10:52:40 INFO - Running post-action listener: _resource_record_post_action 10:52:40 INFO - [mozharness: 2017-01-11 18:52:40.461211Z] Finished run-tests step (success) 10:52:40 INFO - Running post-run listener: _resource_record_post_run 10:52:40 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 37848064; Write bytes: 656101888; Read time: 241; Write time: 1883 10:52:40 INFO - TinderboxPrint: CPU usage
53.4% 10:52:40 INFO - TinderboxPrint: I/O read bytes / time
37,848,064 / 241 10:52:40 INFO - TinderboxPrint: I/O write bytes / time
656,101,888 / 1,883 10:52:40 INFO - TinderboxPrint: CPU idle
2,259.1 (46.6%) 10:52:40 INFO - TinderboxPrint: CPU system
967.3 (20.0%) 10:52:40 INFO - TinderboxPrint: CPU user
1,619.2 (33.4%) 10:52:40 INFO - TinderboxPrint: Swap in / out
386,514,944 / 0 10:52:40 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 344791552; Write bytes: 345458176; Read time: 26832; Write time: 723 10:52:40 INFO - run-tests - Wall time: 1180s; CPU: 53.0%; Read bytes: 35775488; Write bytes: 303762432; Read time: 222; Write time: 1132 10:52:40 INFO - Running post-run listener: _upload_blobber_files 10:52:40 INFO - Blob upload gear active. 10:52:40 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:52:40 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 10:52:40 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:52:40 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:52:41 INFO - (blobuploader) - INFO - Open directory for files ... 10:52:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:52:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:52:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:52:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:52:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:52:42 INFO - (blobuploader) - INFO - Done attempting. 10:52:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:52:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:52:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:52:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:52:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:52:43 INFO - (blobuploader) - INFO - Done attempting. 10:52:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:52:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:52:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:52:46 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:52:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:52:46 INFO - (blobuploader) - INFO - Done attempting. 10:52:46 INFO - (blobuploader) - INFO - Iteration through files over. 10:52:46 INFO - Return code: 0 10:52:46 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:52:46 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:52:46 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a9d30a1630464ab2cf13ea85e20c68becffd3e8ceb6b185883edece8ae5d24f1fd651f3914e344143d240442668f1b7417d49dc65d790fe1587d2aa5446e8885", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9931f03abebc9bbbd28bd89014a7321e16a78110b33db92bccb8d565fc55030e289bffea30baf04389586202dda640c82fcff4b79a860360b000d973b92868c4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/88e2dfdcec400a16ad6dec64dde5d7767010029e2c7c7fb350760e7aa0db6f3a35bf043f17dfadf7658cda86afbbe21189e06a737ffc431931a7e016f1793525"} 10:52:46 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:52:46 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:52:46 INFO - Contents: 10:52:46 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a9d30a1630464ab2cf13ea85e20c68becffd3e8ceb6b185883edece8ae5d24f1fd651f3914e344143d240442668f1b7417d49dc65d790fe1587d2aa5446e8885", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9931f03abebc9bbbd28bd89014a7321e16a78110b33db92bccb8d565fc55030e289bffea30baf04389586202dda640c82fcff4b79a860360b000d973b92868c4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/88e2dfdcec400a16ad6dec64dde5d7767010029e2c7c7fb350760e7aa0db6f3a35bf043f17dfadf7658cda86afbbe21189e06a737ffc431931a7e016f1793525"} 10:52:46 INFO - Running post-run listener: copy_logs_to_upload_dir 10:52:46 INFO - Copying logs to upload dir... 10:52:46 INFO - mkdir: /builds/slave/test/build/upload/logs 10:52:46 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.799670 ========= master_lag: 0.10 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2017-01-11 10:52:47.050004) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-11 10:52:47.055301) ========= 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.NPKYavLDyw/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.TU7LnxDPUj/Listeners TMPDIR=/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a9d30a1630464ab2cf13ea85e20c68becffd3e8ceb6b185883edece8ae5d24f1fd651f3914e344143d240442668f1b7417d49dc65d790fe1587d2aa5446e8885", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9931f03abebc9bbbd28bd89014a7321e16a78110b33db92bccb8d565fc55030e289bffea30baf04389586202dda640c82fcff4b79a860360b000d973b92868c4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/88e2dfdcec400a16ad6dec64dde5d7767010029e2c7c7fb350760e7aa0db6f3a35bf043f17dfadf7658cda86afbbe21189e06a737ffc431931a7e016f1793525"} build_url:https://queue.taskcluster.net/v1/task/GNWYyDg8SiqGD_Cu7YZThw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011113 build_url: 'https://queue.taskcluster.net/v1/task/GNWYyDg8SiqGD_Cu7YZThw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a9d30a1630464ab2cf13ea85e20c68becffd3e8ceb6b185883edece8ae5d24f1fd651f3914e344143d240442668f1b7417d49dc65d790fe1587d2aa5446e8885", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9931f03abebc9bbbd28bd89014a7321e16a78110b33db92bccb8d565fc55030e289bffea30baf04389586202dda640c82fcff4b79a860360b000d973b92868c4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/88e2dfdcec400a16ad6dec64dde5d7767010029e2c7c7fb350760e7aa0db6f3a35bf043f17dfadf7658cda86afbbe21189e06a737ffc431931a7e016f1793525"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-11 10:52:47.089748) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-11 10:52:47.090113) ========= 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.NPKYavLDyw/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.TU7LnxDPUj/Listeners TMPDIR=/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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.005258 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-11 10:52:47.139218) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-11 10:52:47.139559) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-11 10:52:47.139961) ========= ========= Total master_lag: 0.96 =========