builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0195 starttime: 1483009010.22 results: success (0) buildid: 20161229013937 builduid: f360bf03ea4e41b9ae05d6af79031c1b revision: 80e89a0b39c5c21de97eb9f1ad1c60fcdc618992 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-29 02:56:50.217219) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-29 02:56:50.217639) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-29 02:56:50.217931) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-29 02:56:50.244573) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 02:56:50.244883) ========= 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.uvissI46nZ/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.DeSysAXuQz/Listeners TMPDIR=/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-12-29 02:56:50-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 16.1M=0.001s 2016-12-29 02:56:50 (16.1 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.512081 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 02:56:50.780844) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 02:56:50.781163) ========= 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.uvissI46nZ/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.DeSysAXuQz/Listeners TMPDIR=/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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.032577 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 02:56:50.836020) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-12-29 02:56:50.836367) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 80e89a0b39c5c21de97eb9f1ad1c60fcdc618992 --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 80e89a0b39c5c21de97eb9f1ad1c60fcdc618992 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.uvissI46nZ/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.DeSysAXuQz/Listeners TMPDIR=/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-12-29 02:56:50,929 truncating revision to first 12 chars 2016-12-29 02:56:50,929 Setting DEBUG logging. 2016-12-29 02:56:50,929 attempt 1/10 2016-12-29 02:56:50,929 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/80e89a0b39c5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-29 02:56:51,684 unpacking tar archive at: mozilla-aurora-80e89a0b39c5/testing/mozharness/ program finished with exit code 0 elapsedTime=1.002753 ========= master_lag: 1.29 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-12-29 02:56:53.129324) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-29 02:56:53.129610) ========= script_repo_revision: 80e89a0b39c5c21de97eb9f1ad1c60fcdc618992 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-29 02:56:53.129979) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-29 02:56:53.130252) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-29 02:56:53.210121) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-12-29 02:56:53.210472) ========= /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.uvissI46nZ/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.DeSysAXuQz/Listeners TMPDIR=/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 02:56:53 INFO - MultiFileLogger online at 20161229 02:56:53 in /builds/slave/test 02:56:53 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 02:56:53 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:56:53 INFO - {'append_to_log': False, 02:56:53 INFO - 'base_work_dir': '/builds/slave/test', 02:56:53 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:56:53 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:56:53 INFO - 'branch': 'Mozilla-Aurora', 02:56:53 INFO - 'buildbot_json_path': 'buildprops.json', 02:56:53 INFO - 'config_files': ('talos/mac_config.py',), 02:56:53 INFO - 'default_actions': ('clobber', 02:56:53 INFO - 'read-buildbot-config', 02:56:53 INFO - 'download-and-extract', 02:56:53 INFO - 'populate-webroot', 02:56:53 INFO - 'create-virtualenv', 02:56:53 INFO - 'install', 02:56:53 INFO - 'run-tests'), 02:56:53 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:56:53 INFO - 'download_minidump_stackwalk': True, 02:56:53 INFO - 'download_symbols': 'ondemand', 02:56:53 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:56:53 INFO - 'tooltool.py': '/tools/tooltool.py', 02:56:53 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:56:53 INFO - '/tools/misc-python/virtualenv.py')}, 02:56:53 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:56:53 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:56:53 INFO - 'installer_path': 'installer.exe', 02:56:53 INFO - 'log_level': 'info', 02:56:53 INFO - 'log_name': 'talos', 02:56:53 INFO - 'log_to_console': True, 02:56:53 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:56:53 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:56:53 INFO - 'opt_config_files': (), 02:56:53 INFO - 'pip_index': False, 02:56:53 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:56:53 INFO - 'cmd': ('bash', 02:56:53 INFO - '-c', 02:56:53 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:56:53 INFO - 'enabled': True, 02:56:53 INFO - 'halt_on_failure': False, 02:56:53 INFO - 'name': 'check_screen_resolution'},), 02:56:53 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:56:53 INFO - 'cmd': ('bash', 02:56:53 INFO - '-c', 02:56:53 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:56:53 INFO - 'enabled': True, 02:56:53 INFO - 'halt_on_failure': False, 02:56:53 INFO - 'name': 'check_screen_resolution'},), 02:56:53 INFO - 'run_cmd_checks_enabled': True, 02:56:53 INFO - 'sps_profile': False, 02:56:53 INFO - 'sps_profile_interval': 0, 02:56:53 INFO - 'suite': 'dromaeojs', 02:56:53 INFO - 'system_bits': '32', 02:56:53 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:56:53 INFO - 'title': 't-yosemite-r7-0195', 02:56:53 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:56:53 INFO - 'use_talos_json': True, 02:56:53 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:56:53 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:56:53 INFO - 'work_dir': 'build'} 02:56:53 INFO - [mozharness: 2016-12-29 10:56:53.415853Z] Running clobber step. 02:56:53 INFO - Running pre-action listener: _resource_record_pre_action 02:56:53 INFO - Running main action method: clobber 02:56:53 INFO - rmtree: /builds/slave/test/build 02:56:53 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:56:55 INFO - Running post-action listener: _resource_record_post_action 02:56:55 INFO - [mozharness: 2016-12-29 10:56:55.835629Z] Finished clobber step (success) 02:56:55 INFO - [mozharness: 2016-12-29 10:56:55.835755Z] Running read-buildbot-config step. 02:56:55 INFO - Running pre-action listener: _resource_record_pre_action 02:56:55 INFO - Running main action method: read_buildbot_config 02:56:55 INFO - Using buildbot properties: 02:56:55 INFO - { 02:56:55 INFO - "project": "", 02:56:55 INFO - "product": "firefox", 02:56:55 INFO - "script_repo_revision": "production", 02:56:55 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 02:56:55 INFO - "repository": "", 02:56:55 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 02:56:55 INFO - "buildid": "20161229013937", 02:56:55 INFO - "pgo_build": "False", 02:56:55 INFO - "basedir": "/builds/slave/test", 02:56:55 INFO - "buildnumber": 22, 02:56:55 INFO - "slavename": "t-yosemite-r7-0195", 02:56:55 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 02:56:55 INFO - "platform": "yosemite_r7", 02:56:55 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 02:56:55 INFO - "branch": "mozilla-aurora", 02:56:55 INFO - "repo_path": "releases/mozilla-aurora", 02:56:55 INFO - "revision": "80e89a0b39c5c21de97eb9f1ad1c60fcdc618992", 02:56:55 INFO - "stage_platform": "macosx64", 02:56:55 INFO - "builduid": "f360bf03ea4e41b9ae05d6af79031c1b", 02:56:55 INFO - "slavebuilddir": "test" 02:56:55 INFO - } 02:56:55 INFO - Found installer url https://queue.taskcluster.net/v1/task/SzyL-sbPQImSl4jsyC132g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 02:56:55 INFO - Running post-action listener: _resource_record_post_action 02:56:55 INFO - [mozharness: 2016-12-29 10:56:55.838930Z] Finished read-buildbot-config step (success) 02:56:55 INFO - [mozharness: 2016-12-29 10:56:55.839060Z] Running download-and-extract step. 02:56:55 INFO - Running pre-action listener: _resource_record_pre_action 02:56:55 INFO - Running main action method: download_and_extract 02:56:55 INFO - mkdir: /builds/slave/test/build/tests 02:56:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:56:55 INFO - trying https://queue.taskcluster.net/v1/task/SzyL-sbPQImSl4jsyC132g/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 02:56:55 INFO - Downloading https://queue.taskcluster.net/v1/task/SzyL-sbPQImSl4jsyC132g/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 02:56:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SzyL-sbPQImSl4jsyC132g/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 02:56:57 INFO - Downloaded 1199 bytes. 02:56:57 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 02:56:57 INFO - Using the following test package requirements: 02:56:57 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 02:56:57 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:56:57 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 02:56:57 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:56:57 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 02:56:57 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:56:57 INFO - u'jsshell-mac.zip'], 02:56:57 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:56:57 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 02:56:57 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 02:56:57 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:56:57 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 02:56:57 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:56:57 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 02:56:57 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:56:57 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 02:56:57 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:56:57 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 02:56:57 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 02:56:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SzyL-sbPQImSl4jsyC132g/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 02:56:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SzyL-sbPQImSl4jsyC132g/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:56:57 INFO - Fetch https://queue.taskcluster.net/v1/task/SzyL-sbPQImSl4jsyC132g/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 02:56:57 INFO - Http code: 200 02:56:57 INFO - via: 1.1 6be093c16fa776bbd432bbe9bd03f6af.cloudfront.net (CloudFront) 02:56:57 INFO - x-amz-cf-id: oO_bD6KKDhlJPgDEA9TSduBpcz0WeyroutUH_yYUPCrAeO2cR40oJg== 02:56:57 INFO - x-amz-version-id: XfcifysCQT7UqvFMVZAKdHeygxa4dIok 02:56:58 INFO - Expected file size: 16268413 02:56:58 INFO - Obtained file size: 16268413 02:57:04 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 02:57:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SzyL-sbPQImSl4jsyC132g/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 02:57:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SzyL-sbPQImSl4jsyC132g/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:57:04 INFO - Fetch https://queue.taskcluster.net/v1/task/SzyL-sbPQImSl4jsyC132g/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 02:57:05 INFO - Http code: 200 02:57:05 INFO - via: 1.1 6e65abb04cb818a6ec78111935b507f7.cloudfront.net (CloudFront) 02:57:05 INFO - x-amz-cf-id: DyH3xfpsCSGKRxNI6uWn22UU9u_mab6kjeDoI2yglwYPNdMxUP28_g== 02:57:05 INFO - x-amz-version-id: XfcifysCQT7UqvFMVZAKdHeygxa4dIok 02:57:05 INFO - Expected file size: 16268413 02:57:05 INFO - Obtained file size: 16268413 02:57:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SzyL-sbPQImSl4jsyC132g/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 02:57:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SzyL-sbPQImSl4jsyC132g/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 02:57:12 INFO - Fetch https://queue.taskcluster.net/v1/task/SzyL-sbPQImSl4jsyC132g/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 02:57:12 INFO - Http code: 200 02:57:12 INFO - via: 1.1 6e65abb04cb818a6ec78111935b507f7.cloudfront.net (CloudFront) 02:57:12 INFO - x-amz-cf-id: KE-78oRjnlD62OXILe5QtuT0BWrcQGDR15e7R7D2anKbx-RYF6oR6A== 02:57:12 INFO - x-amz-version-id: j9MDkeizv4DdCTKrPLbyGBSg8IRDrpHF 02:57:12 INFO - Expected file size: 14409038 02:57:12 INFO - Obtained file size: 14409038 02:57:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:57:13 INFO - trying https://queue.taskcluster.net/v1/task/SzyL-sbPQImSl4jsyC132g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 02:57:13 INFO - Downloading https://queue.taskcluster.net/v1/task/SzyL-sbPQImSl4jsyC132g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 02:57:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SzyL-sbPQImSl4jsyC132g/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 02:57:15 INFO - Downloaded 109824693 bytes. 02:57:15 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/SzyL-sbPQImSl4jsyC132g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 02:57:15 INFO - mkdir: /builds/slave/test/properties 02:57:15 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:57:15 INFO - Writing to file /builds/slave/test/properties/build_url 02:57:15 INFO - Contents: 02:57:15 INFO - build_url:https://queue.taskcluster.net/v1/task/SzyL-sbPQImSl4jsyC132g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 02:57:16 INFO - Running post-action listener: _resource_record_post_action 02:57:16 INFO - Running post-action listener: set_extra_try_arguments 02:57:16 INFO - [mozharness: 2016-12-29 10:57:16.674446Z] Finished download-and-extract step (success) 02:57:16 INFO - [mozharness: 2016-12-29 10:57:16.674577Z] Running populate-webroot step. 02:57:16 INFO - Running pre-action listener: _resource_record_pre_action 02:57:16 INFO - Running main action method: populate_webroot 02:57:16 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:57:16 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:57:16 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:57:16 INFO - '--tppagecycles', 02:57:16 INFO - '7'], 02:57:16 INFO - 'tests': ['tsvgm']}}, 02:57:16 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:57:16 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:57:16 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:57:16 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:57:16 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:57:16 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:57:16 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:57:16 INFO - 'talos_options': ['--disable-e10s'], 02:57:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:57:16 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:57:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:57:16 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:57:16 INFO - 'talos_options': ['--disable-e10s'], 02:57:16 INFO - 'tests': ['damp', 'tps']}, 02:57:16 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:57:16 INFO - 'tests': ['damp', 'tps']}, 02:57:16 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:57:16 INFO - 'tests': ['dromaeo_dom']}, 02:57:16 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:57:16 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:57:16 INFO - 'tests': ['basic_compositor_video']}, 02:57:16 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:57:16 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:57:16 INFO - 'tests': ['a11yr', 02:57:16 INFO - 'ts_paint', 02:57:16 INFO - 'tpaint', 02:57:16 INFO - 'sessionrestore', 02:57:16 INFO - 'sessionrestore_no_auto_restore', 02:57:16 INFO - 'tabpaint']}, 02:57:16 INFO - 'other-e10s': {'tests': ['a11yr', 02:57:16 INFO - 'ts_paint', 02:57:16 INFO - 'tpaint', 02:57:16 INFO - 'sessionrestore', 02:57:16 INFO - 'sessionrestore_no_auto_restore', 02:57:16 INFO - 'tabpaint']}, 02:57:16 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:57:16 INFO - 'ts_paint', 02:57:16 INFO - 'tpaint', 02:57:16 INFO - 'sessionrestore', 02:57:16 INFO - 'sessionrestore_no_auto_restore', 02:57:16 INFO - 'tabpaint']}, 02:57:16 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:57:16 INFO - 'ts_paint', 02:57:16 INFO - 'tpaint', 02:57:16 INFO - 'sessionrestore', 02:57:16 INFO - 'sessionrestore_no_auto_restore', 02:57:16 INFO - 'tabpaint']}, 02:57:16 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:57:16 INFO - 'tests': ['a11yr', 02:57:16 INFO - 'ts_paint', 02:57:16 INFO - 'tpaint', 02:57:16 INFO - 'sessionrestore', 02:57:16 INFO - 'sessionrestore_no_auto_restore', 02:57:16 INFO - 'tabpaint']}, 02:57:16 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:57:16 INFO - 'tests': ['a11yr', 02:57:16 INFO - 'ts_paint', 02:57:16 INFO - 'tpaint', 02:57:16 INFO - 'sessionrestore', 02:57:16 INFO - 'sessionrestore_no_auto_restore', 02:57:16 INFO - 'tabpaint']}, 02:57:16 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:57:16 INFO - 'tests': ['tsvgx', 02:57:16 INFO - 'tsvgr_opacity', 02:57:16 INFO - 'tart', 02:57:16 INFO - 'tscrollx', 02:57:16 INFO - 'cart']}, 02:57:16 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:57:16 INFO - 'tsvgr_opacity', 02:57:16 INFO - 'tart', 02:57:16 INFO - 'tscrollx', 02:57:16 INFO - 'cart']}, 02:57:16 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:57:16 INFO - 'talos_options': ['--disable-e10s'], 02:57:16 INFO - 'tests': ['tp5o']}, 02:57:16 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:57:16 INFO - 'tests': ['tp5o']}, 02:57:16 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:57:16 INFO - 'talos_options': ['--disable-e10s', 02:57:16 INFO - '--xperf_path', 02:57:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:57:16 INFO - 'tests': ['tp5n']}, 02:57:16 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:57:16 INFO - 'talos_options': ['--xperf_path', 02:57:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:57:16 INFO - 'tests': ['tp5n']}}, 02:57:16 INFO - 'talos.zip': {'path': '', 02:57:16 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:57:16 INFO - Running post-action listener: _resource_record_post_action 02:57:16 INFO - [mozharness: 2016-12-29 10:57:16.693003Z] Finished populate-webroot step (success) 02:57:16 INFO - [mozharness: 2016-12-29 10:57:16.693089Z] Running create-virtualenv step. 02:57:16 INFO - Running pre-action listener: _resource_record_pre_action 02:57:16 INFO - Running main action method: create_virtualenv 02:57:16 INFO - Creating virtualenv /builds/slave/test/build/venv 02:57:16 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 02:57:16 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:57:16 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:57:16 INFO - Using real prefix '/tools/python27' 02:57:16 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:57:17 INFO - Installing distribute.............................................................................................................................................................................................done. 02:57:20 INFO - Installing pip.................done. 02:57:20 INFO - Return code: 0 02:57:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:57:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:57:20 INFO - Reading from file tmpfile_stdout 02:57:20 INFO - Output received: 02:57:20 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) 02:57:20 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:57:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:57:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:57:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:57:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:57:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57:20 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 0x102dc3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d39570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa36ada38b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102bd7d30>, '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 0x102a3ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102bcfb20>, '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.uvissI46nZ/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.DeSysAXuQz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 02:57:20 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 02:57:20 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 02:57:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uvissI46nZ/Render', 02:57:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:57:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:57:20 INFO - 'HOME': '/Users/cltbld', 02:57:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:57:20 INFO - 'LOGNAME': 'cltbld', 02:57:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:57:20 INFO - 'MOZ_NO_REMOTE': '1', 02:57:20 INFO - 'NO_EM_RESTART': '1', 02:57:20 INFO - 'PAGER': '/bin/cat', 02:57:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:57:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:57:20 INFO - 'PWD': '/builds/slave/test', 02:57:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:57:20 INFO - 'SHELL': '/bin/bash', 02:57:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DeSysAXuQz/Listeners', 02:57:20 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 02:57:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:57:20 INFO - 'USER': 'cltbld', 02:57:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:57:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:57:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:57:20 INFO - 'XPC_FLAGS': '0x0', 02:57:20 INFO - 'XPC_SERVICE_NAME': '0', 02:57:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:57:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:57:20 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 02:57:20 INFO - Cleaning up... 02:57:20 INFO - Return code: 0 02:57:20 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:57:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:57:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:57:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:57:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:57:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57:20 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 0x102dc3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d39570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa36ada38b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102bd7d30>, '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 0x102a3ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102bcfb20>, '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.uvissI46nZ/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.DeSysAXuQz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 02:57:20 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 02:57:20 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 02:57:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uvissI46nZ/Render', 02:57:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:57:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:57:20 INFO - 'HOME': '/Users/cltbld', 02:57:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:57:20 INFO - 'LOGNAME': 'cltbld', 02:57:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:57:20 INFO - 'MOZ_NO_REMOTE': '1', 02:57:20 INFO - 'NO_EM_RESTART': '1', 02:57:20 INFO - 'PAGER': '/bin/cat', 02:57:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:57:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:57:20 INFO - 'PWD': '/builds/slave/test', 02:57:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:57:20 INFO - 'SHELL': '/bin/bash', 02:57:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DeSysAXuQz/Listeners', 02:57:20 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 02:57:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:57:20 INFO - 'USER': 'cltbld', 02:57:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:57:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:57:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:57:20 INFO - 'XPC_FLAGS': '0x0', 02:57:20 INFO - 'XPC_SERVICE_NAME': '0', 02:57:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:57:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:57:21 INFO - Downloading/unpacking psutil>=3.1.1 02:57:21 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 02:57:21 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 02:57:21 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 02:57:21 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 02:57:21 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 02:57:21 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 02:57:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:57:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:57:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:57:24 INFO - Installing collected packages: psutil 02:57:24 INFO - Running setup.py install for psutil 02:57:25 INFO - building 'psutil._psutil_osx' extension 02:57:25 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 02:57:25 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 02:57:25 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 02:57:25 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 02:57:25 INFO - building 'psutil._psutil_posix' extension 02:57:25 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 02:57:25 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:57:25 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:57:25 INFO - ^ 02:57:25 INFO - 1 warning generated. 02:57:25 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 02:57:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:57:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:57:26 INFO - Successfully installed psutil 02:57:26 INFO - Cleaning up... 02:57:26 INFO - Return code: 0 02:57:26 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:57:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:57:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:57:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:57:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:57:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57:26 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 0x102dc3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d39570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa36ada38b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102bd7d30>, '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 0x102a3ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102bcfb20>, '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.uvissI46nZ/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.DeSysAXuQz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 02:57:26 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 02:57:26 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 02:57:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uvissI46nZ/Render', 02:57:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:57:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:57:26 INFO - 'HOME': '/Users/cltbld', 02:57:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:57:26 INFO - 'LOGNAME': 'cltbld', 02:57:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:57:26 INFO - 'MOZ_NO_REMOTE': '1', 02:57:26 INFO - 'NO_EM_RESTART': '1', 02:57:26 INFO - 'PAGER': '/bin/cat', 02:57:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:57:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:57:26 INFO - 'PWD': '/builds/slave/test', 02:57:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:57:26 INFO - 'SHELL': '/bin/bash', 02:57:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DeSysAXuQz/Listeners', 02:57:26 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 02:57:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:57:26 INFO - 'USER': 'cltbld', 02:57:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:57:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:57:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:57:26 INFO - 'XPC_FLAGS': '0x0', 02:57:26 INFO - 'XPC_SERVICE_NAME': '0', 02:57:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:57:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:57:26 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:57:26 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 02:57:26 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 02:57:26 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 02:57:26 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 02:57:26 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 02:57:26 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 02:57:29 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:57:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:57:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:57:29 INFO - Installing collected packages: mozsystemmonitor 02:57:29 INFO - Running setup.py install for mozsystemmonitor 02:57:29 INFO - Successfully installed mozsystemmonitor 02:57:29 INFO - Cleaning up... 02:57:29 INFO - Return code: 0 02:57:29 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:57:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:57:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:57:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:57:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:57:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x102dc3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d39570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa36ada38b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102bd7d30>, '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 0x102a3ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102bcfb20>, '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.uvissI46nZ/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.DeSysAXuQz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 02:57:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 02:57:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 02:57:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uvissI46nZ/Render', 02:57:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:57:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:57:29 INFO - 'HOME': '/Users/cltbld', 02:57:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:57:29 INFO - 'LOGNAME': 'cltbld', 02:57:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:57:29 INFO - 'MOZ_NO_REMOTE': '1', 02:57:29 INFO - 'NO_EM_RESTART': '1', 02:57:29 INFO - 'PAGER': '/bin/cat', 02:57:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:57:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:57:29 INFO - 'PWD': '/builds/slave/test', 02:57:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:57:29 INFO - 'SHELL': '/bin/bash', 02:57:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DeSysAXuQz/Listeners', 02:57:29 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 02:57:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:57:29 INFO - 'USER': 'cltbld', 02:57:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:57:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:57:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:57:29 INFO - 'XPC_FLAGS': '0x0', 02:57:29 INFO - 'XPC_SERVICE_NAME': '0', 02:57:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:57:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:57:29 INFO - Downloading/unpacking jsonschema==2.5.1 02:57:29 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 02:57:29 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 02:57:29 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 02:57:29 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 02:57:29 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 02:57:29 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 02:57:32 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:57:32 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:57:32 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 02:57:32 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 02:57:32 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 02:57:32 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 02:57:32 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 02:57:32 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 02:57:32 INFO - Downloading functools32-3.2.3-2.tar.gz 02:57:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:57:32 INFO - warning: no files found matching '*.txt' 02:57:32 INFO - no previously-included directories found matching 'build' 02:57:32 INFO - no previously-included directories found matching 'dist' 02:57:32 INFO - no previously-included directories found matching '.git*' 02:57:32 INFO - Installing collected packages: jsonschema, functools32 02:57:32 INFO - Running setup.py install for functools32 02:57:32 INFO - warning: no files found matching '*.txt' 02:57:32 INFO - no previously-included directories found matching 'build' 02:57:32 INFO - no previously-included directories found matching 'dist' 02:57:32 INFO - no previously-included directories found matching '.git*' 02:57:32 INFO - Successfully installed jsonschema functools32 02:57:32 INFO - Cleaning up... 02:57:33 INFO - Return code: 0 02:57:33 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:57:33 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')]} 02:57:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:57:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57:33 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')]} 02:57:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:57:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57:33 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 0x102dc3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d39570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa36ada38b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102bd7d30>, '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 0x102a3ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102bcfb20>, '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.uvissI46nZ/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.DeSysAXuQz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 02:57:33 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 02:57:33 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 02:57:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uvissI46nZ/Render', 02:57:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:57:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:57:33 INFO - 'HOME': '/Users/cltbld', 02:57:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:57:33 INFO - 'LOGNAME': 'cltbld', 02:57:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:57:33 INFO - 'MOZ_NO_REMOTE': '1', 02:57:33 INFO - 'NO_EM_RESTART': '1', 02:57:33 INFO - 'PAGER': '/bin/cat', 02:57:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:57:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:57:33 INFO - 'PWD': '/builds/slave/test', 02:57:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:57:33 INFO - 'SHELL': '/bin/bash', 02:57:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DeSysAXuQz/Listeners', 02:57:33 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 02:57:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:57:33 INFO - 'USER': 'cltbld', 02:57:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:57:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:57:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:57:33 INFO - 'XPC_FLAGS': '0x0', 02:57:33 INFO - 'XPC_SERVICE_NAME': '0', 02:57:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:57:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:57:33 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:57:33 INFO - Cleaning up... 02:57:33 INFO - Return code: 0 02:57:33 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:57:33 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')]} 02:57:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:57:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57:33 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')]} 02:57:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:57:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57:33 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 0x102dc3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d39570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa36ada38b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102bd7d30>, '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 0x102a3ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102bcfb20>, '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.uvissI46nZ/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.DeSysAXuQz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 02:57:33 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 02:57:33 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 02:57:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uvissI46nZ/Render', 02:57:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:57:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:57:33 INFO - 'HOME': '/Users/cltbld', 02:57:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:57:33 INFO - 'LOGNAME': 'cltbld', 02:57:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:57:33 INFO - 'MOZ_NO_REMOTE': '1', 02:57:33 INFO - 'NO_EM_RESTART': '1', 02:57:33 INFO - 'PAGER': '/bin/cat', 02:57:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:57:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:57:33 INFO - 'PWD': '/builds/slave/test', 02:57:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:57:33 INFO - 'SHELL': '/bin/bash', 02:57:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DeSysAXuQz/Listeners', 02:57:33 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 02:57:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:57:33 INFO - 'USER': 'cltbld', 02:57:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:57:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:57:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:57:33 INFO - 'XPC_FLAGS': '0x0', 02:57:33 INFO - 'XPC_SERVICE_NAME': '0', 02:57:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:57:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:57:33 INFO - Downloading/unpacking blobuploader==1.2.4 02:57:33 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 02:57:33 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 02:57:33 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 02:57:33 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 02:57:33 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 02:57:33 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 02:57:36 INFO - Downloading blobuploader-1.2.4.tar.gz 02:57:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:57:36 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:57:36 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 02:57:36 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 02:57:36 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 02:57:36 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 02:57:36 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 02:57:36 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 02:57:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:57:36 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:57:36 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 02:57:36 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 02:57:36 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 02:57:36 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 02:57:36 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 02:57:36 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 02:57:37 INFO - Downloading docopt-0.6.1.tar.gz 02:57:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:57:37 INFO - Installing collected packages: blobuploader, requests, docopt 02:57:37 INFO - Running setup.py install for blobuploader 02:57:37 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:57:37 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:57:37 INFO - Running setup.py install for requests 02:57:38 INFO - Running setup.py install for docopt 02:57:38 INFO - Successfully installed blobuploader requests docopt 02:57:38 INFO - Cleaning up... 02:57:38 INFO - Return code: 0 02:57:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:57:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:57:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:57:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:57:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:57:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57:38 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 0x102dc3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d39570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa36ada38b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102bd7d30>, '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 0x102a3ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102bcfb20>, '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.uvissI46nZ/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.DeSysAXuQz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 02:57:38 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 02:57:38 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 02:57:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uvissI46nZ/Render', 02:57:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:57:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:57:38 INFO - 'HOME': '/Users/cltbld', 02:57:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:57:38 INFO - 'LOGNAME': 'cltbld', 02:57:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:57:38 INFO - 'MOZ_NO_REMOTE': '1', 02:57:38 INFO - 'NO_EM_RESTART': '1', 02:57:38 INFO - 'PAGER': '/bin/cat', 02:57:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:57:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:57:38 INFO - 'PWD': '/builds/slave/test', 02:57:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:57:38 INFO - 'SHELL': '/bin/bash', 02:57:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DeSysAXuQz/Listeners', 02:57:38 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 02:57:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:57:38 INFO - 'USER': 'cltbld', 02:57:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:57:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:57:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:57:38 INFO - 'XPC_FLAGS': '0x0', 02:57:38 INFO - 'XPC_SERVICE_NAME': '0', 02:57:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:57:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:57:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:57:38 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-q194g6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:57:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:57:38 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-UsUjVk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:57:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:57:38 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-DDzbtS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:57:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:57:39 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-TA4IBR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:57:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:57:39 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-gYCf_l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:57:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:57:39 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-ayjtF9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:57:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:57:39 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-xJNFhy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:57:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:57:39 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-q44r8X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:57:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:57:39 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-R0pjXl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:57:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:57:39 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-AXoiU2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:57:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:57:39 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-k3ZlDy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:57:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:57:39 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-JmlsJF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:57:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:57:39 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-SmVsPQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:57:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:57:40 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-CzmWuS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:57:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:57:40 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-xammJ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:57:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:57:40 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-QxxU3m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:57:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:57:40 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-m5jUqL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:57:40 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:57:40 INFO - Running setup.py install for manifestparser 02:57:40 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:57:40 INFO - Running setup.py install for mozcrash 02:57:40 INFO - Running setup.py install for mozdebug 02:57:40 INFO - Running setup.py install for mozdevice 02:57:41 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:57:41 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:57:41 INFO - Running setup.py install for mozfile 02:57:41 INFO - Running setup.py install for mozhttpd 02:57:41 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:57:41 INFO - Running setup.py install for mozinfo 02:57:41 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:57:41 INFO - Running setup.py install for mozInstall 02:57:41 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:57:41 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:57:41 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:57:41 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:57:41 INFO - Running setup.py install for mozleak 02:57:41 INFO - Running setup.py install for mozlog 02:57:41 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:57:41 INFO - Running setup.py install for moznetwork 02:57:42 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:57:42 INFO - Running setup.py install for mozprocess 02:57:42 INFO - Running setup.py install for mozprofile 02:57:42 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:57:42 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:57:42 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:57:42 INFO - Running setup.py install for mozrunner 02:57:42 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:57:42 INFO - Running setup.py install for mozscreenshot 02:57:42 INFO - Running setup.py install for moztest 02:57:42 INFO - Running setup.py install for mozversion 02:57:43 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:57:43 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:57:43 INFO - Cleaning up... 02:57:43 INFO - Return code: 0 02:57:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:57:43 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')]} 02:57:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:57:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57:43 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')]} 02:57:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:57:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57:43 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 0x102dc3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d39570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa36ada38b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102bd7d30>, '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 0x102a3ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102bcfb20>, '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.uvissI46nZ/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.DeSysAXuQz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 02:57:43 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 02:57:43 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 02:57:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uvissI46nZ/Render', 02:57:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:57:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:57:43 INFO - 'HOME': '/Users/cltbld', 02:57:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:57:43 INFO - 'LOGNAME': 'cltbld', 02:57:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:57:43 INFO - 'MOZ_NO_REMOTE': '1', 02:57:43 INFO - 'NO_EM_RESTART': '1', 02:57:43 INFO - 'PAGER': '/bin/cat', 02:57:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:57:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:57:43 INFO - 'PWD': '/builds/slave/test', 02:57:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:57:43 INFO - 'SHELL': '/bin/bash', 02:57:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DeSysAXuQz/Listeners', 02:57:43 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 02:57:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:57:43 INFO - 'USER': 'cltbld', 02:57:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:57:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:57:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:57:43 INFO - 'XPC_FLAGS': '0x0', 02:57:43 INFO - 'XPC_SERVICE_NAME': '0', 02:57:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:57:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:57:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:57:43 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-7eof33-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:57:43 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)) 02:57:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:57:43 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-L0Ea7R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:57:43 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)) 02:57:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:57:43 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-cICGFV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:57:43 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)) 02:57:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:57:43 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-5hzAio-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:57:43 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)) 02:57:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:57:43 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-weP6IL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:57:43 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)) 02:57:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:57:43 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-93lpiR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:57:43 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)) 02:57:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:57:43 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-jweEux-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:57:44 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)) 02:57:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:57:44 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-gxgoS1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:57:44 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)) 02:57:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:57:44 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-5BXort-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:57:44 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)) 02:57:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:57:44 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-AwkWz5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:57:44 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)) 02:57:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:57:44 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-4UCCxt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:57:44 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)) 02:57:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:57:44 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-Vl7dFv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:57:44 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)) 02:57:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:57:44 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-Qp0KMm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:57:44 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)) 02:57:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:57:44 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-FTnOPw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:57:44 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)) 02:57:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:57:44 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-SuXMaC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:57:44 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)) 02:57:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:57:44 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-CAahh7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:57:45 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 02:57:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:57:45 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-l69nng-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:57:45 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)) 02:57:45 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)) 02:57:45 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)) 02:57:45 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)) 02:57:45 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)) 02:57:45 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)) 02:57:45 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:57:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:57:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:57:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:57:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:57:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:57:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:57:47 INFO - Downloading blessings-1.6.tar.gz 02:57:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:57:47 INFO - Installing collected packages: blessings 02:57:47 INFO - Running setup.py install for blessings 02:57:47 INFO - Successfully installed blessings 02:57:47 INFO - Cleaning up... 02:57:48 INFO - Return code: 0 02:57:48 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:57:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:57:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:57:48 INFO - Reading from file tmpfile_stdout 02:57:48 INFO - Current package versions: 02:57:48 INFO - blessings == 1.6 02:57:48 INFO - blobuploader == 1.2.4 02:57:48 INFO - docopt == 0.6.1 02:57:48 INFO - functools32 == 3.2.3-2 02:57:48 INFO - jsonschema == 2.5.1 02:57:48 INFO - manifestparser == 1.1 02:57:48 INFO - mozInstall == 1.12 02:57:48 INFO - mozcrash == 1.0 02:57:48 INFO - mozdebug == 0.1 02:57:48 INFO - mozdevice == 0.48 02:57:48 INFO - mozfile == 1.2 02:57:48 INFO - mozhttpd == 0.7 02:57:48 INFO - mozinfo == 0.9 02:57:48 INFO - mozleak == 0.1 02:57:48 INFO - mozlog == 3.4 02:57:48 INFO - moznetwork == 0.27 02:57:48 INFO - mozprocess == 0.23 02:57:48 INFO - mozprofile == 0.28 02:57:48 INFO - mozrunner == 6.13 02:57:48 INFO - mozscreenshot == 0.1 02:57:48 INFO - mozsystemmonitor == 0.3 02:57:48 INFO - moztest == 0.7 02:57:48 INFO - mozversion == 1.4 02:57:48 INFO - psutil == 3.1.1 02:57:48 INFO - requests == 1.2.3 02:57:48 INFO - wsgiref == 0.1.2 02:57:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:57:48 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')]} 02:57:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:57:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57:48 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')]} 02:57:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:57:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57:48 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 0x102dc3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d39570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa36ada38b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102bd7d30>, '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 0x102a3ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102bcfb20>, '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.uvissI46nZ/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.DeSysAXuQz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 02:57:48 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 02:57:48 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 02:57:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uvissI46nZ/Render', 02:57:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:57:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:57:48 INFO - 'HOME': '/Users/cltbld', 02:57:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:57:48 INFO - 'LOGNAME': 'cltbld', 02:57:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:57:48 INFO - 'MOZ_NO_REMOTE': '1', 02:57:48 INFO - 'NO_EM_RESTART': '1', 02:57:48 INFO - 'PAGER': '/bin/cat', 02:57:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:57:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:57:48 INFO - 'PWD': '/builds/slave/test', 02:57:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:57:48 INFO - 'SHELL': '/bin/bash', 02:57:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DeSysAXuQz/Listeners', 02:57:48 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 02:57:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:57:48 INFO - 'USER': 'cltbld', 02:57:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:57:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:57:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:57:48 INFO - 'XPC_FLAGS': '0x0', 02:57:48 INFO - 'XPC_SERVICE_NAME': '0', 02:57:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:57:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:57:48 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)) 02:57:48 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)) 02:57:48 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)) 02:57:48 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)) 02:57:48 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)) 02:57:48 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)) 02:57:48 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)) 02:57:48 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)) 02:57:48 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)) 02:57:48 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)) 02:57:48 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)) 02:57:48 INFO - Cleaning up... 02:57:48 INFO - Return code: 0 02:57:48 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:57:48 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')]} 02:57:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:57:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57:48 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')]} 02:57:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:57:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57:48 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 0x102dc3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d39570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa36ada38b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102bd7d30>, '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 0x102a3ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102bcfb20>, '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.uvissI46nZ/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.DeSysAXuQz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 02:57:48 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 02:57:48 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 02:57:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uvissI46nZ/Render', 02:57:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:57:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:57:48 INFO - 'HOME': '/Users/cltbld', 02:57:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:57:48 INFO - 'LOGNAME': 'cltbld', 02:57:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:57:48 INFO - 'MOZ_NO_REMOTE': '1', 02:57:48 INFO - 'NO_EM_RESTART': '1', 02:57:48 INFO - 'PAGER': '/bin/cat', 02:57:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:57:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:57:48 INFO - 'PWD': '/builds/slave/test', 02:57:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:57:48 INFO - 'SHELL': '/bin/bash', 02:57:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DeSysAXuQz/Listeners', 02:57:48 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 02:57:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:57:48 INFO - 'USER': 'cltbld', 02:57:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:57:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:57:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:57:48 INFO - 'XPC_FLAGS': '0x0', 02:57:48 INFO - 'XPC_SERVICE_NAME': '0', 02:57:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:57:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:57:48 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:57:48 INFO - Cleaning up... 02:57:48 INFO - Return code: 0 02:57:48 INFO - Running post-action listener: _resource_record_post_action 02:57:48 INFO - Running post-action listener: _start_resource_monitoring 02:57:48 INFO - Starting resource monitoring. 02:57:48 INFO - [mozharness: 2016-12-29 10:57:48.917819Z] Finished create-virtualenv step (success) 02:57:48 INFO - [mozharness: 2016-12-29 10:57:48.918797Z] Running install step. 02:57:48 INFO - Running pre-action listener: _resource_record_pre_action 02:57:48 INFO - Running main action method: install 02:57:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:57:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:57:49 INFO - Reading from file tmpfile_stdout 02:57:49 INFO - Detecting whether we're running mozinstall >=1.0... 02:57:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:57:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:57:49 INFO - Reading from file tmpfile_stdout 02:57:49 INFO - Output received: 02:57:49 INFO - Usage: mozinstall [options] installer 02:57:49 INFO - Options: 02:57:49 INFO - -h, --help show this help message and exit 02:57:49 INFO - -d DEST, --destination=DEST 02:57:49 INFO - Directory to install application into. [default: 02:57:49 INFO - "/builds/slave/test"] 02:57:49 INFO - --app=APP Application being installed. [default: firefox] 02:57:49 INFO - mkdir: /builds/slave/test/build/application 02:57:49 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'] 02:57:49 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 02:58:20 INFO - Reading from file tmpfile_stdout 02:58:20 INFO - Output received: 02:58:20 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 02:58:20 INFO - Running post-action listener: _resource_record_post_action 02:58:20 INFO - [mozharness: 2016-12-29 10:58:20.668473Z] Finished install step (success) 02:58:20 INFO - [mozharness: 2016-12-29 10:58:20.668615Z] Running run-tests step. 02:58:20 INFO - Running pre-action listener: _resource_record_pre_action 02:58:20 INFO - Running main action method: run_tests 02:58:20 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:58:20 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:58:20 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:58:20 INFO - 2016-12-29 02:58:20.683 screenresolution[1631:8981] starting screenresolution argv=screenresolution get 02:58:20 INFO - 2016-12-29 02:58:20.685 screenresolution[1631:8981] Display 0: 1600x1200x32@60 02:58:20 INFO - 2016-12-29 02:58:20.691 screenresolution[1632:8984] starting screenresolution argv=screenresolution list 02:58:20 INFO - Available Modes on Display 0 02:58:20 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:58:20 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:58:20 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:58:20 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:58:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:58:20 INFO - Intel Iris: 02:58:20 INFO - Chipset Model: Intel Iris 02:58:20 INFO - Type: GPU 02:58:20 INFO - Bus: Built-In 02:58:20 INFO - VRAM (Dynamic, Max): 1536 MB 02:58:20 INFO - Vendor: Intel (0x8086) 02:58:20 INFO - Device ID: 0x0a2e 02:58:20 INFO - Revision ID: 0x0009 02:58:20 INFO - Displays: 02:58:20 INFO - Display: 02:58:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:58:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:58:20 INFO - Display Serial Number: 1600x1200 60 02:58:20 INFO - Main Display: Yes 02:58:20 INFO - Mirror: Off 02:58:20 INFO - Online: Yes 02:58:20 INFO - Rotation: Supported 02:58:20 INFO - Return code: 0 02:58:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:58:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:58:20 INFO - Python 2.7.3 02:58:20 INFO - Return code: 0 02:58:20 INFO - grabbing minidump binary from tooltool 02:58:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:58:20 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 0x102bd7d30>, '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 0x102a3ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102bcfb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:58:20 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 02:58:20 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 02:58:20 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 02:58:21 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:58:21 INFO - Return code: 0 02:58:21 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:58:21 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:58:21 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:58:21 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:58:21 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:58:21 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/SzyL-sbPQImSl4jsyC132g/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0195', '--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 02:58:21 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/SzyL-sbPQImSl4jsyC132g/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0195 --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 02:58:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uvissI46nZ/Render', 02:58:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:58:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:58:21 INFO - 'HOME': '/Users/cltbld', 02:58:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:58:21 INFO - 'LOGNAME': 'cltbld', 02:58:21 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:58:21 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:58:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:58:21 INFO - 'MOZ_NO_REMOTE': '1', 02:58:21 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:58:21 INFO - 'NO_EM_RESTART': '1', 02:58:21 INFO - 'PAGER': '/bin/cat', 02:58:21 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:58:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:58:21 INFO - 'PWD': '/builds/slave/test', 02:58:21 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:58:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:58:21 INFO - 'SHELL': '/bin/bash', 02:58:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DeSysAXuQz/Listeners', 02:58:21 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 02:58:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:58:21 INFO - 'USER': 'cltbld', 02:58:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:58:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:58:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:58:21 INFO - 'XPC_FLAGS': '0x0', 02:58:21 INFO - 'XPC_SERVICE_NAME': '0', 02:58:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:58:21 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/SzyL-sbPQImSl4jsyC132g/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0195', '--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 02:58:21 INFO - mozversion application_buildid: 20161229013937 02:58:21 INFO - mozversion application_changeset: 80e89a0b39c5c21de97eb9f1ad1c60fcdc618992 02:58:21 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:58:21 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:58:21 INFO - mozversion application_name: Firefox 02:58:21 INFO - mozversion application_remotingname: firefox-dev 02:58:21 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:58:21 INFO - mozversion application_vendor: Mozilla 02:58:21 INFO - mozversion application_version: 52.0a2 02:58:21 INFO - mozversion platform_buildid: 20161229013937 02:58:21 INFO - mozversion platform_changeset: 80e89a0b39c5c21de97eb9f1ad1c60fcdc618992 02:58:21 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:58:21 INFO - mozversion platform_version: 52.0a2 02:58:21 INFO - using testdate: 1483009101 02:58:21 INFO - actual date: 1483009101 02:58:21 INFO - starting webserver on 'localhost:49249' 02:58:21 INFO - SUITE-START | Running 2 tests 02:58:21 INFO - TEST-START | dromaeo_css 02:58:21 INFO - operating with platform_type : mac_ 02:58:21 INFO - Initialising browser for dromaeo_css test... 02:58:21 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/tmpExUTU6/profile http://localhost:49249/getInfo.html) 02:58:24 INFO - PROCESS | 1642 | __metrics Screen width/height:1600/1200 02:58:24 INFO - PROCESS | 1642 | colorDepth:24 02:58:24 INFO - PROCESS | 1642 | Browser inner width/height: 1024/689 02:58:24 INFO - PROCESS | 1642 | __metrics 02:58:24 INFO - PROCESS | 1642 | 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" 02:58:24 INFO - PROCESS | 1642 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:58:25 INFO - TEST-INFO | 1642: exit 0 02:58:25 INFO - Browser initialized. 02:58:25 INFO - Running cycle 1/1 for dromaeo_css test... 02:58:25 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/tmpExUTU6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:58:37 INFO - PROCESS | 1645 | RSS: Main: 222932992 02:58:37 INFO - PROCESS | 1645 | 02:59:28 INFO - PROCESS | 1645 | 1483009168747 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 03:01:42 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49249/tests/dromaeo/cssquery-ext.html) 03:01:45 INFO - PROCESS | 1645 | RSS: Main: 426717184 03:01:45 INFO - PROCESS | 1645 | 03:04:48 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-ext.html (next: http://localhost:49249/tests/dromaeo/cssquery-jquery.html) 03:04:48 INFO - PROCESS | 1645 | RSS: Main: 348807168 03:04:48 INFO - PROCESS | 1645 | 03:07:51 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49249/tests/dromaeo/cssquery-mootools.html) 03:07:51 INFO - PROCESS | 1645 | RSS: Main: 314740736 03:07:51 INFO - PROCESS | 1645 | 03:10:54 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49249/tests/dromaeo/cssquery-prototype.html) 03:10:54 INFO - PROCESS | 1645 | RSS: Main: 335511552 03:10:54 INFO - PROCESS | 1645 | 03:13:58 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49249/tests/dromaeo/cssquery-yui.html) 03:13:58 INFO - PROCESS | 1645 | RSS: Main: 309432320 03:13:58 INFO - PROCESS | 1645 | 03:17:02 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-yui.html (next: http://localhost:49249/tests/dromaeo/cssquery-dojo.html) 03:17:02 INFO - PROCESS | 1645 | RSS: Main: 379162624 03:17:02 INFO - PROCESS | 1645 | 03:17:02 INFO - PROCESS | 1645 | __start_tp_report 03:17:02 INFO - PROCESS | 1645 | _x_x_mozilla_page_load 03:17:02 INFO - PROCESS | 1645 | _x_x_mozilla_page_load_details 03:17:02 INFO - PROCESS | 1645 | |i|pagename|runs| 03:17:02 INFO - PROCESS | 1645 | |0;dojo.html;3290.0129961011694;3298.2578919743055;3311.2549676322815;3335.916602084946;3367.1918739502526;5655.462731040549;5686.658800471964;5690.715464226792;5712.9428705713035;5747.885042299146;5643.322801263854;5715.3141623005195;5726.914096288559;5729.0833466645345;5745.080787074058;5180.922286165692;5205.453427390126;5214.713190774506;5226.869328266805;5266.973665131669;5153.433122356538;5177.068127737006;5177.87055323618;5283.735813209343;5284.577233821305;5248.5276325130735;5272.340957380327;5273.868153296179;5283.07546179419;5311.760970756306;5256.632035757499;5270.314859068335;5272.7100009499845;5274.868128296785;5290.1800220965815;5111.897762044739;5220.347456567929;5225.738713064369;5226.92159617606;5261.210818377236;5736.196932429463;5743.138529220609;5755.3381361143165;5758.74085666146;5759.712014399305;5870.970645146747;5914.319853216892;5916.674582897939;5922.644641321492;6109.2528441424865;6191.442770150663;6264.123022776815;6269;6277.2153480814895;6323.083152942843;5741.110127930177;5758.222639943638;5771.567132465082;5796.420357964212;5819.388964158745;5190.92213616796;6264.248290205204;6308.905366419507;6321.367863213642;6336.778212762623;5962.612430192024;6019.067044608005;6048.727807248684;6074.726637301332;6078.848028799315;6147.047207682816;6210.72051757672;6244.937550624527;6308.211473565804;6311.936880631227;6079.3008803988105;6212.627242365473;6215.844603884939;6218.471429928507;6233.283172435228;6108.463491842603;6117.644716737952;6148.661823599745;6177.135201071854;6201.224846894138;4930.433000204951;4934.851954441372;4937.604991600663;4942.802287908516;4948;4405.339199120158;4406.9779651101535;4408.338749187584;4418.690691651554;4438.556144385536;5575.944240557624;5624.0439125348785;5628.409017053233;5637.408072152447;5670.262865827416;6246.625202487865;6257.061440783918;6265.310815810257;6266.655333956585;6271.937280627227;5614.24207731951;5623.381428042912;5624.71876406178;5640.182173584889;5647.519960803378;4365.672574556857;5332.466753324714;5353.678779273256;5364.436734142859;5364.544013758796;6262.46769024638;6308.148399965946;6327.272363678236;6363;6370.617762934239;6131.356207598316;6287.30839607652;6316.368363163647;6323.841903952438;6324.209473815773;6199.690015499117;6303.46420554258;6349.07938348946;6357.777477788255;6360.713767880549;4589.885252868705;6083.361247069171;6115.6662951758;6122.581127188433;6168;6115.346784300343;6261.279952805486;6305.369463053841;6311.400416960381;6317.399847014526;6057.545684073801;6124.356942521149;6162.198914141133;6166.321704612399;6172.074188871705;5918.674472903859;5962.880742385215;5980.37206093354;6039.275286965592;6090.086487026982;5935.228420305332;5944.881102377842;5961.433663801932;5991.430814072656;6017.338092809699;5935.40645935403;5946.791862284799;5986.3115741688525;6108.419700128481;6111.816645500642;5821.912671310019;5904.763809447574;5907.084401917709;5909.645421274738;5910.379410161871 03:17:02 INFO - PROCESS | 1645 | |1;ext.html;460.2015503102119;511.6750863201866;527.955123814476;543.7036814935859;551.2585572405113;30139.945101921436;38734.61265387349;39793.80103099488;40624.39063414051;41488.79255603718;40370.394444083286;40974.59025409745;41491.17017659645;41513.7924310378;41649.7917510412;8405;9285.164335209829;9484.952575237114;9492.810143797155;9515.571799269015;8421.03158136816;11069.557217711323;11374.090072794197;11395.202335836495;11501.42492875353;11376.488058037408;11421.771564568746;11443.542258309657;11469.541218351256;11471.942640286787;8446.366522510774;8641.395102342838;11237.775244495146;11297.717557061098;11407.201495895291;10886.891131088667;11236.94381528087;11259.324440533512;11376.772464550746;11383.658490245305;39474.80262598669;39729.20541589152;39729.20541589152;39823.407063717415;40064.59935400638;3238.6437491875877;3251.9837400812844;3471.0801637566065;3508.5263489428858;3637.1816341323256;24038.278851634477;27609.723902760918;28407.147785566467;28420.857895710596;29513.557296640567;3152.4168028914723;3172.4448221561365;3266.183454136466;3298.538204651327;3303;490847.5457622725;492346;494942;497863.9999999964;499940;33192;57350.7132464335;57767.422325776206;57926.420735792104;58385.124223136685;33440.49839252413;35256.29487410237;35275;35300.82349588287;35309.47035794465;14195.574132776033;14340.784888226684;14346.354414051393;14353;14358.641033974234;10819.945900270606;11026.117910567136;11836.05311575072;11997.520099196108;12092.576759813364;7796.064472263251;7811.726589569338;7863.174366691415;7915.525068495794;7924.6433910473015;3239.935201296008;3253.2842774589553;3261.904761904762;3265.2816380396275;3274.279658475132;890.1943740914353;918.7289749523907;964.8986856379979;1029.4183786160795;1071.7534966957487;29354.559681604795;29544.852275738485;29594.556081658793;29953.850230748707;30161.849190753906;1046.534292239946;1068.6687126990657;1075.3279200499687;1082.355998181081;1118.6811758648746;14533.909956753287;14754.852451475563;15241.3903443861;15425;15586.454474093352;640199;640407.585734497;645099;645606;648125;55897.72051139718;56554.7172264136;56680;56681;56690;1362.2099182474142;1365.8565850585744;1402.9368678409496;1403.663120850968;1405.6415614018565;28530.85734571314;29018.41963160767;29023.12930612085;29070.854645726635;29487;48211.276830846866;48531.757341213066;48672.783180419356;48709.756451217516;48740.512594873595;485637;486356.56821715663;487190;487721;488397;21776.782232177477;22340.553188936454;22353.44116397038;26131.346716332242;26574;44987;45265.54734452613;46511.76744116258;46540.53459465362;48384.27423588579;36838.89483315505;37145.81427092847;37283;37568.060798480255;37950;37544;37766;38632.80683596564;39012.80493597627;39388;36600.901972940854;36818.89543313705;37067;37067.62932370642;37341;32111;34353.484697730055;34380.65619343874;34387.65612343844;34566.13584660303;24855.378615534755;25149.119780807585;25187.496250075263;25659.486810264065;25683.743162568884 03:17:02 INFO - PROCESS | 1645 | |2;jquery.html;354.20481020109855;367.5827935293442;377.4093543604261;387.6433681013467;405.1734461698136;14505.492307769231;14817.555473335804;15431.768523472158;19468.41594752156;20408.8979555102;14824.925875370607;19846.610737248397;20022.799772002276;20114.59770804583;20383.18467261308;5877.823665290048;6139.570230083874;6143;6147.446729794317;6153.107799369089;4717.882052948687;6019.879602407949;6092.72582733778;6093.969530152343;6161.784337548187;5707.229524014268;6036.547258955574;6041.848953776147;6042.909356359636;6047.758089676429;5881.617694849821;5969.611975221596;5971.014782560872;5985.760569577212;5994.010988186988;4988.052270068675;5166.095933211673;5214.791408343662;5219.295395121648;5256.448072952361;24897.25308240756;24914.750852491423;25131;25612.35969100769;25867.22398328053;2094.4240333908147;2191.3973657244387;2447.1801946347996;2948.9410211795857;2957.260684828793;10000.249981251363;10149.238807089498;10236.283460157792;10358;10635.361878287327;1853.8331550160551;1865.123392005755;1873.072828949665;1882.2941396976134;1931.1309910540172;106012.46993764982;119567.40216298863;124076.75923240742;125570.11644825243;126286.10570841444;9541.47521886303;11470.107432273748;11505.676847162516;11744.94127529374;11803.940980295043;14805.629859253604;14883.851161488463;14884.925575372054;14897.255137243095;14911.328990195467;8157.306628936607;8260.917390826135;8307;8340.958295208606;8517.659293628309;7443.962780186064;7956.562389068657;8146.918530814734;8160.67357305713;8315.750527484186;4521.796519156645;4533.750643714628;4545.113702827983;4547.295169248771;4617.8383756568355;1850.296887182889;1905.733197352344;1909.417627623588;1911.9904400477908;1912.254220853868;743.8363560016787;803.5901690138072;834.8288600836814;893.7988952485639;916.9679061232824;19134;20775.792242077387;20817.271395501084;21035.789642103384;21186.15255389767;733.3949491669288;937.2455173585329;949.715085474355;963.8698775665196;976.7069879036261;10118.08937195656;10204.744881377876;10556.577736890593;10810.459477026117;10844.403557804397;514584.5624631267;518365;520198;620432.7956720375;623991;131668;131745;133549;141152;147432.26283868725;914.8764916736288;951.3388195204404;982.749398903275;1017.3488967061087;1020.739711373595;14760.704785904436;18819.435416937515;19427.4171774847;19713.1129099188;19778.60442791165;52347.95304093973;56830;57294.427055728906;59078.704606478415;59849.70075149596;74380;78649.60675196587;80726.59636701779;81304.18695812966;84032.57983710042;7878;8178.83642327162;8198.959005204935;8425.957870210856;8552.332295202625;9178.036306187754;12477.188982716094;12766.936165319485;12805.423755931191;13216.66958326011;11051.281666691639;11067.335959842436;11181.385023823443;11483;11548.076153907836;9361.906380936103;10962.671119866416;11066.944665276622;11129.721756956142;11198.720031999264;10953.068989136013;11307.943460282975;11497.482613282255;11625.360605166798;11673.533058677898;8232.094469608459;8493.91506084956;8495.872561911452;8587.95706021466;8706.782330441789;11089.168312376443;11307.943460282646;11334.773304534028;11624.6512604622;11704.356260405759 03:17:02 INFO - PROCESS | 1645 | |3;mootools.html;1437.7052704195637;1479.1125324805118;1620.6920685069836;1790.0154914796858;1824.890506569606;7962.044554653443;8331.75004749857;8360.70737524186;8363.749087527383;8367.91632083679;7455.813604659887;7703.653335599897;7705.036870391201;7769.300762931335;8254.958725206381;2091.6444204485238;2135.850490465668;2249.403907964391;2307.8730669813153;2315.6642286868478;2325.7790509901615;2352.7882490575844;2366.3965688749345;2368.6683864259016;2377.6076947303673;2305.101010605865;2317.8377513574055;2318.038014224095;2327.104064934995;2327.8137748980125;2278.646809744484;2321.4312493439215;2326.7905888470036;2329.4060014696315;2329.7087864017;2312.8959196836013;2326.4532834783813;2326.9650955235684;2360.079568968104;2360.1621424394266;8265.669373225064;8353.540555269457;8362.958185209034;8369.58152092399;8378.874316885252;5076.898462030776;5236.6857988520815;5244.0036393085575;5253.658512196695;5264.184051472028;2539.352465121382;3288.2437039480815;3435.4675025371107;3538.0800991742076;3541.6458354164633;1012.9189664826796;1048.659185764622;1062.2033474893828;1065.0787069185099;1109.2734259060328;10983.670489885317;11033.393163376011;11034.393108379036;11063.612773552886;11178.27341222818;4082.1223436961195;4399.560043995639;4681.625469962463;4744.857654270378;4756.096341695067;9376.57805398745;9380.718578442658;9392.765180870396;9398.812023759487;9402.294827887936;4646.442426908792;4958.801647934042;5174.172132458789;5180.637355385087;5206.088934436459;3966.8809935701975;4613.5847773700525;4677.204875171229;4743.928841067387;4764.2615394613895;3635.2911182319194;3646.799426031541;3695.2978934002454;3729.5524537055726;3740.532433445819;1247.189326937498;1250.2810883741704;1257.7547378261334;1259.477316913489;1269.4795133995017;874.491502158771;877.6971944679076;887.4719541872576;895.632790555882;899.4917871402637;5337.466253374631;5421.512063914267;5603.495685388335;5626.805545837126;5701.942980570224;862.5385418800911;964.3972517176765;992.8659630949874;1020.2603112743202;1028.5114570578914;3346.9497957530657;3605.441156620728;3706.814659267026;3769.660730534265;3877.96122038776;9537.523123843781;10138.03688649577;10193.286469947032;10284.971502849656;10332.483375831178;10017.148542373834;10230.948845255725;10266;10268.332558383681;10289.897101028893;7234.457415693844;7426.9230961510275;7744.574048427278;7887.369010479148;7948.324392426593;5380.3274590676165;5475.534579560623;5490.780368785204;5583.8883222336135;5623.128415095667;9326.253899688145;9366.2038726709;9376.671816486663;9445.386049906734;9577.138057574573;9344.719658410258;9613.086756758097;9633.373830700983;9644.855327170239;9648.228141748503;5523.4200408956485;6247.375262473716;6447.0651755494255;6459;6751.426128778912;7869.960650196713;7986.081600616004;8065.153158918464;8072.636731346986;8083;7115.359617634438;7121.82195445118;7134;7216.097987751531;7281.708731650887;6919.654017299215;6950.165980082423;7035.437165026888;7038.085048943604;7068.929310706827;6633.668316584248;6777.762778302736;6785.321467853372;6785.89821152693;6810.31896810315;4733.431988161443;4780.95219047819;4839.225723884161;4849.58778503817;4939.827106051271;4773.570378665937;4867.853964381075;4869.561739443467;4975.527324904128;5024.84925452237 03:17:02 INFO - PROCESS | 1645 | |4;prototype.html;244.97305296417403;253.27058072750475;306.10616998364765;313.4014033196594;318.2997405707444;4654.3716598259225;4940.827071052514;5086.796528138874;5177.818776342829;5214.322138122044;4271.380649805777;4454.933176002362;5078.263651770491;5092.974535127329;5188.403333616635;3994.8801535953967;4005.5393629732516;4008.8797336079965;4010.7192496525254;4070.409790580364;3503.5970863350785;3774.849006039769;3852.3451013327804;4014.8959036265114;4016.196760647882;3512.3326567952136;3684.2263124743704;3896.6556537994347;3904.804759762015;3945.3687410014404;3255.853486593109;3900.7854567998747;3908.5700572936953;3915.8237879295502;3922.5881282465502;3004.722992728082;3288.5067239914206;3418.538497302882;3427.474773725693;3457.1529975155995;4524.434445694288;4541.977290113561;4988.6757360771435;4990.101781679296;5127.435982041972;3713.424419214999;3797.259534390795;4414.470263568361;4458.821647134111;4496.303073023687;4946;4968.776405061781;4998.325226094467;5374.011615702176;5418.566514678816;3037.7545206465243;3259.2992506610954;3719.590845007047;3736.7668669339323;3742.4094759727277;5546.528545073674;5653.378128405872;5670.886582268332;5680.4319568042865;5694.5159661428825;3599.9560127563236;3721.971525518599;3780.4518344839844;3797.088698712289;3818.961810381916;3583.928321433557;3685.981570092132;3846.249981253685;3846.8845934622004;3858.2476417098956;4211.410402543646;4429.402030725875;4490.708103973231;4491.214037543482;4519.638428925744;4421.5578442156175;4946.826861059845;5048.93972265821;5375.946240537544;5469.589780766459;1074.6722249713755;1186.4720199511307;1204.6085022367765;1225.8099994500867;1242.9005679545796;839.6977088248226;846.1411667157839;856.5631527920681;858.4891989266376;859.9852174434228;4062.9187416252103;4287.871363859089;4449.708400173913;4968.627352948543;5064.772085256172;4357.607815296638;4601.838935637236;5201.153590475326;5289.127293996447;5523.696196709139;4831;4842.176829938919;4872.756362181876;4879.170541008037;4916.926246106311;2784.1346297980404;2784.331760377495;2790.725558669678;2795.273228960485;2796.783399221345;4496.797644105957;4611.677182597186;4910.852674419773;5462.371827239839;5493.505584497414;4918.434380046269;5419.783208671688;5492.258545096441;5535.833924982257;5568.331800184003;5262.658022204313;5504.5963279364305;5591.608587398843;5597.272354593877;5635.4077124522355;4860.635452341018;4892.730899800546;4928.507149285043;5361.839144825662;5758.510526605118;4752.405949256343;4934.951994922068;5377.704226267437;5563.805266815642;5693.174489698888;5934;5946.224147666349;5948.494377977747;5952;5952.553558483218;2901.680815110294;3563.948635152688;3698.7041036717537;3712.9628703712615;3777.225668737957;4676.854170728194;5174.818881339135;5646.971765141148;5648.039833228278;5664.49019588239;4330.588594083557;5505.724713764376;5506.394296627288;5518.310211223597;5520.254765606753;4267.914641707086;5008.649394542347;5420.970015697005;5424.430434804289;5446.972765136149;5092.007058623681;5315.521603055744;5323.707196104251;5356.758945847524;5359.008583412081;4422.955770442254;5376.661152398719;5540.168974653834;5560.749766260447;5573.27547418833;4572.839950601713;4886.828960986349;5079.974600126976;5448.210009548672;5466.644668096561 03:17:02 INFO - PROCESS | 1645 | |5;yui.html;664.5215444879686;688.652230623535;697.0102454514592;745.027738800865;748.7080038784873;14176.50382236622;14655.340509677064;14792.482263120794;14800.555983320504;14810.629734256649;12723.809142862841;12766.55317063903;12807.487700491993;12821.102522823407;12823.487060517567;647.8833809914214;665.0456594786473;800.9839803203932;801.7674874286464;826.4462809917339;811.2860682599304;814.5357146426539;819.5861090149466;820.0978923184509;820.8522465956125;884.305819931354;886.5921676029035;887.8934527856634;888.2893685051965;889.0131953531575;681.7750142452978;703.7853454696316;706.131458306281;880.0891077734514;880.8634661627458;526.7024131365804;527.6148411659472;528.2894506888267;628.8176428835632;649.3895738006306;13814.516491922734;13872.514461993782;13919.58241252754;13932.860671393257;13938.93030534841;12549.874501254963;12627.810582841264;12793.232406055666;12865.871341286562;12971.286579238138;11504.827427588592;11874.940625296904;11956.222845515014;12197.268163910105;12377.071719621064;4328.5455027222315;4355.803988820479;4367.192069467159;4401.427814384109;4483.327500874862;83099.5845020777;88462.5576872118;88739;90833.54583227108;91689.54155229247;10208.234382421348;10222.48887555634;10290;10321.63874264397;10324.277300589038;19384.83690978546;19501.414957551297;19537;19551.706724399144;19552.217911283675;6953.200381956039;7111.502194846311;7260.128784545888;7302.3792977597495;7310.597917114504;4016.196760647882;4213.094184750294;4277.657787377002;4322.286822674287;4420.977895110504;3064.3258483133677;3172.841357932094;3187.2509960159346;3188.5695431116965;3207.6631953644996;629.754395785644;630.0989584893555;638.2022471910112;648.9805305840832;654.0875478707177;519.0864079220523;524.2032111190969;527.2723641374878;555.9045070798711;589.584343038157;8744.475331480131;8898.688545900863;9004.459732416077;9031.41295815783;9036.548172591343;624.9156363890908;656.5732274021924;670.9765158219438;673.515069150211;679.6262055869253;4224.197402493516;4262.616364527145;4433.401490798766;4475.619572336388;4628.097520983376;87347.1265287339;102071.44821379524;110548;112322.43838780918;115732;72448;87248.5637571808;88783;94129;97605;66663.33336666669;75879;80092;83682;85005;6977.476689248225;7169.605671687898;8515.446495977989;8523.701670441505;8764.605592748214;52923;54133.72933135467;54982.45017549773;56081.15878261748;56304;52606;55861;60967.695161525684;62345;62735.37264627478;12121.151519393557;12140.575079872162;12145.69635759113;12148.392580371123;12237.571684990982;13328.666783330495;13387.531436399679;13610.455581776618;13655.931720341334;13889.930550347588;12292.016638668707;12711.61865144047;12840.678983025498;12938.547150849676;13377.598672039854;12663.240205587694;12803.61589152327;12887.742245155232;12963.41664625113;13372.331383430985;12540.184887982252;12545.184563003375;12925;13062.804057938949;13068.01989850746;10466.895331046897;10660.786784264115;10831.079358254321;11110.000099991039;11145.888541114486;9852.753681158028;10251.897481025095;10300.845487317547;10894.945525272324;11049.502772375423 03:17:02 INFO - PROCESS | 1645 | __end_tp_report 03:17:02 INFO - PROCESS | 1645 | __start_cc_report 03:17:02 INFO - PROCESS | 1645 | _x_x_mozilla_cycle_collect,2732 03:17:02 INFO - PROCESS | 1645 | __end_cc_report 03:17:02 INFO - PROCESS | 1645 | __startTimestamp1483010222313__endTimestamp 03:17:02 INFO - PROCESS | 1645 | 03:17:02 INFO - PROCESS | 1645 | ------- Summary: start ------- 03:17:02 INFO - PROCESS | 1645 | Number of tests: 6 03:17:02 INFO - PROCESS | 1645 | 03:17:02 INFO - PROCESS | 1645 | [#0] dojo.html Cycles:165 Average:5709.43 Median:5910.38 stddev:645.91 (10.9%) stddev-sans-first:619.38 03:17:02 INFO - PROCESS | 1645 | Values: 3290.0 3298.3 3311.3 3335.9 3367.2 5655.5 5686.7 5690.7 5712.9 5747.9 5643.3 5715.3 5726.9 5729.1 5745.1 5180.9 5205.5 5214.7 5226.9 5267.0 5153.4 5177.1 5177.9 5283.7 5284.6 5248.5 5272.3 5273.9 5283.1 5311.8 5256.6 5270.3 5272.7 5274.9 5290.2 5111.9 5220.3 5225.7 5226.9 5261.2 5736.2 5743.1 5755.3 5758.7 5759.7 5871.0 5914.3 5916.7 5922.6 6109.3 6191.4 6264.1 6269.0 6277.2 6323.1 5741.1 5758.2 5771.6 5796.4 5819.4 5190.9 6264.2 6308.9 6321.4 6336.8 5962.6 6019.1 6048.7 6074.7 6078.8 6147.0 6210.7 6244.9 6308.2 6311.9 6079.3 6212.6 6215.8 6218.5 6233.3 6108.5 6117.6 6148.7 6177.1 6201.2 4930.4 4934.9 4937.6 4942.8 4948.0 4405.3 4407.0 4408.3 4418.7 4438.6 5575.9 5624.0 5628.4 5637.4 5670.3 6246.6 6257.1 6265.3 6266.7 6271.9 5614.2 5623.4 5624.7 5640.2 5647.5 4365.7 5332.5 5353.7 5364.4 5364.5 6262.5 6308.1 6327.3 6363.0 6370.6 6131.4 6287.3 6316.4 6323.8 6324.2 6199.7 6303.5 6349.1 6357.8 6360.7 4589.9 6083.4 6115.7 6122.6 6168.0 6115.3 6261.3 6305.4 6311.4 6317.4 6057.5 6124.4 6162.2 6166.3 6172.1 5918.7 5962.9 5980.4 6039.3 6090.1 5935.2 5944.9 5961.4 5991.4 6017.3 5935.4 5946.8 5986.3 6108.4 6111.8 5821.9 5904.8 5907.1 5909.6 5910.4 03:17:02 INFO - PROCESS | 1645 | 03:17:02 INFO - PROCESS | 1645 | [#1] ext.html Cycles:180 Average:66176.25 Median:19382.41 stddev:146304.65 (754.8%) stddev-sans-first:146631.87 03:17:02 INFO - PROCESS | 1645 | Values: 460.2 511.7 528.0 543.7 551.3 30139.9 38734.6 39793.8 40624.4 41488.8 40370.4 40974.6 41491.2 41513.8 41649.8 8405.0 9285.2 9485.0 9492.8 9515.6 8421.0 11069.6 11374.1 11395.2 11501.4 11376.5 11421.8 11443.5 11469.5 11471.9 8446.4 8641.4 11237.8 11297.7 11407.2 10886.9 11236.9 11259.3 11376.8 11383.7 39474.8 39729.2 39729.2 39823.4 40064.6 3238.6 3252.0 3471.1 3508.5 3637.2 24038.3 27609.7 28407.1 28420.9 29513.6 3152.4 3172.4 3266.2 3298.5 3303.0 490847.5 492346.0 494942.0 497864.0 499940.0 33192.0 57350.7 57767.4 57926.4 58385.1 33440.5 35256.3 35275.0 35300.8 35309.5 14195.6 14340.8 14346.4 14353.0 14358.6 10819.9 11026.1 11836.1 11997.5 12092.6 7796.1 7811.7 7863.2 7915.5 7924.6 3239.9 3253.3 3261.9 3265.3 3274.3 890.2 918.7 964.9 1029.4 1071.8 29354.6 29544.9 29594.6 29953.9 30161.8 1046.5 1068.7 1075.3 1082.4 1118.7 14533.9 14754.9 15241.4 15425.0 15586.5 640199.0 640407.6 645099.0 645606.0 648125.0 55897.7 56554.7 56680.0 56681.0 56690.0 1362.2 1365.9 1402.9 1403.7 1405.6 28530.9 29018.4 29023.1 29070.9 29487.0 48211.3 48531.8 48672.8 48709.8 48740.5 485637.0 486356.6 487190.0 487721.0 488397.0 21776.8 22340.6 22353.4 26131.3 26574.0 44987.0 45265.5 46511.8 46540.5 48384.3 36838.9 37145.8 37283.0 37568.1 37950.0 37544.0 37766.0 38632.8 39012.8 39388.0 36600.9 36818.9 37067.0 37067.6 37341.0 32111.0 34353.5 34380.7 34387.7 34566.1 24855.4 25149.1 25187.5 25659.5 25683.7 03:17:02 INFO - PROCESS | 1645 | 03:17:02 INFO - PROCESS | 1645 | [#2] jquery.html Cycles:180 Average:34278.10 Median:25739.79 stddev:94567.57 (367.4%) stddev-sans-first:94798.56 03:17:02 INFO - PROCESS | 1645 | Values: 354.2 367.6 377.4 387.6 405.2 14505.5 14817.6 15431.8 19468.4 20408.9 14824.9 19846.6 20022.8 20114.6 20383.2 5877.8 6139.6 6143.0 6147.4 6153.1 4717.9 6019.9 6092.7 6094.0 6161.8 5707.2 6036.5 6041.8 6042.9 6047.8 5881.6 5969.6 5971.0 5985.8 5994.0 4988.1 5166.1 5214.8 5219.3 5256.4 24897.3 24914.8 25131.0 25612.4 25867.2 2094.4 2191.4 2447.2 2948.9 2957.3 10000.2 10149.2 10236.3 10358.0 10635.4 1853.8 1865.1 1873.1 1882.3 1931.1 106012.5 119567.4 124076.8 125570.1 126286.1 9541.5 11470.1 11505.7 11744.9 11803.9 14805.6 14883.9 14884.9 14897.3 14911.3 8157.3 8260.9 8307.0 8341.0 8517.7 7444.0 7956.6 8146.9 8160.7 8315.8 4521.8 4533.8 4545.1 4547.3 4617.8 1850.3 1905.7 1909.4 1912.0 1912.3 743.8 803.6 834.8 893.8 917.0 19134.0 20775.8 20817.3 21035.8 21186.2 733.4 937.2 949.7 963.9 976.7 10118.1 10204.7 10556.6 10810.5 10844.4 514584.6 518365.0 520198.0 620432.8 623991.0 131668.0 131745.0 133549.0 141152.0 147432.3 914.9 951.3 982.7 1017.3 1020.7 14760.7 18819.4 19427.4 19713.1 19778.6 52348.0 56830.0 57294.4 59078.7 59849.7 74380.0 78649.6 80726.6 81304.2 84032.6 7878.0 8178.8 8199.0 8426.0 8552.3 9178.0 12477.2 12766.9 12805.4 13216.7 11051.3 11067.3 11181.4 11483.0 11548.1 9361.9 10962.7 11066.9 11129.7 11198.7 10953.1 11307.9 11497.5 11625.4 11673.5 8232.1 8493.9 8495.9 8588.0 8706.8 11089.2 11307.9 11334.8 11624.7 11704.4 03:17:02 INFO - PROCESS | 1645 | 03:17:02 INFO - PROCESS | 1645 | [#3] mootools.html Cycles:180 Average:5420.40 Median:4967.16 stddev:2995.35 (60.3%) stddev-sans-first:2988.79 03:17:02 INFO - PROCESS | 1645 | Values: 1437.7 1479.1 1620.7 1790.0 1824.9 7962.0 8331.8 8360.7 8363.7 8367.9 7455.8 7703.7 7705.0 7769.3 8255.0 2091.6 2135.9 2249.4 2307.9 2315.7 2325.8 2352.8 2366.4 2368.7 2377.6 2305.1 2317.8 2318.0 2327.1 2327.8 2278.6 2321.4 2326.8 2329.4 2329.7 2312.9 2326.5 2327.0 2360.1 2360.2 8265.7 8353.5 8363.0 8369.6 8378.9 5076.9 5236.7 5244.0 5253.7 5264.2 2539.4 3288.2 3435.5 3538.1 3541.6 1012.9 1048.7 1062.2 1065.1 1109.3 10983.7 11033.4 11034.4 11063.6 11178.3 4082.1 4399.6 4681.6 4744.9 4756.1 9376.6 9380.7 9392.8 9398.8 9402.3 4646.4 4958.8 5174.2 5180.6 5206.1 3966.9 4613.6 4677.2 4743.9 4764.3 3635.3 3646.8 3695.3 3729.6 3740.5 1247.2 1250.3 1257.8 1259.5 1269.5 874.5 877.7 887.5 895.6 899.5 5337.5 5421.5 5603.5 5626.8 5701.9 862.5 964.4 992.9 1020.3 1028.5 3346.9 3605.4 3706.8 3769.7 3878.0 9537.5 10138.0 10193.3 10285.0 10332.5 10017.1 10230.9 10266.0 10268.3 10289.9 7234.5 7426.9 7744.6 7887.4 7948.3 5380.3 5475.5 5490.8 5583.9 5623.1 9326.3 9366.2 9376.7 9445.4 9577.1 9344.7 9613.1 9633.4 9644.9 9648.2 5523.4 6247.4 6447.1 6459.0 6751.4 7870.0 7986.1 8065.2 8072.6 8083.0 7115.4 7121.8 7134.0 7216.1 7281.7 6919.7 6950.2 7035.4 7038.1 7068.9 6633.7 6777.8 6785.3 6785.9 6810.3 4733.4 4781.0 4839.2 4849.6 4939.8 4773.6 4867.9 4869.6 4975.5 5024.8 03:17:02 INFO - PROCESS | 1645 | 03:17:02 INFO - PROCESS | 1645 | [#4] prototype.html Cycles:180 Average:4323.24 Median:4851.41 stddev:1338.99 (27.6%) stddev-sans-first:1307.29 03:17:02 INFO - PROCESS | 1645 | Values: 245.0 253.3 306.1 313.4 318.3 4654.4 4940.8 5086.8 5177.8 5214.3 4271.4 4454.9 5078.3 5093.0 5188.4 3994.9 4005.5 4008.9 4010.7 4070.4 3503.6 3774.8 3852.3 4014.9 4016.2 3512.3 3684.2 3896.7 3904.8 3945.4 3255.9 3900.8 3908.6 3915.8 3922.6 3004.7 3288.5 3418.5 3427.5 3457.2 4524.4 4542.0 4988.7 4990.1 5127.4 3713.4 3797.3 4414.5 4458.8 4496.3 4946.0 4968.8 4998.3 5374.0 5418.6 3037.8 3259.3 3719.6 3736.8 3742.4 5546.5 5653.4 5670.9 5680.4 5694.5 3600.0 3722.0 3780.5 3797.1 3819.0 3583.9 3686.0 3846.2 3846.9 3858.2 4211.4 4429.4 4490.7 4491.2 4519.6 4421.6 4946.8 5048.9 5375.9 5469.6 1074.7 1186.5 1204.6 1225.8 1242.9 839.7 846.1 856.6 858.5 860.0 4062.9 4287.9 4449.7 4968.6 5064.8 4357.6 4601.8 5201.2 5289.1 5523.7 4831.0 4842.2 4872.8 4879.2 4916.9 2784.1 2784.3 2790.7 2795.3 2796.8 4496.8 4611.7 4910.9 5462.4 5493.5 4918.4 5419.8 5492.3 5535.8 5568.3 5262.7 5504.6 5591.6 5597.3 5635.4 4860.6 4892.7 4928.5 5361.8 5758.5 4752.4 4935.0 5377.7 5563.8 5693.2 5934.0 5946.2 5948.5 5952.0 5952.6 2901.7 3563.9 3698.7 3713.0 3777.2 4676.9 5174.8 5647.0 5648.0 5664.5 4330.6 5505.7 5506.4 5518.3 5520.3 4267.9 5008.6 5421.0 5424.4 5447.0 5092.0 5315.5 5323.7 5356.8 5359.0 4423.0 5376.7 5540.2 5560.7 5573.3 4572.8 4886.8 5080.0 5448.2 5466.6 03:17:02 INFO - PROCESS | 1645 | 03:17:02 INFO - PROCESS | 1645 | [#5] yui.html Cycles:180 Average:19512.17 Median:4479.47 stddev:28256.92 (630.8%) stddev-sans-first:28300.74 03:17:02 INFO - PROCESS | 1645 | Values: 664.5 688.7 697.0 745.0 748.7 14176.5 14655.3 14792.5 14800.6 14810.6 12723.8 12766.6 12807.5 12821.1 12823.5 647.9 665.0 801.0 801.8 826.4 811.3 814.5 819.6 820.1 820.9 884.3 886.6 887.9 888.3 889.0 681.8 703.8 706.1 880.1 880.9 526.7 527.6 528.3 628.8 649.4 13814.5 13872.5 13919.6 13932.9 13938.9 12549.9 12627.8 12793.2 12865.9 12971.3 11504.8 11874.9 11956.2 12197.3 12377.1 4328.5 4355.8 4367.2 4401.4 4483.3 83099.6 88462.6 88739.0 90833.5 91689.5 10208.2 10222.5 10290.0 10321.6 10324.3 19384.8 19501.4 19537.0 19551.7 19552.2 6953.2 7111.5 7260.1 7302.4 7310.6 4016.2 4213.1 4277.7 4322.3 4421.0 3064.3 3172.8 3187.3 3188.6 3207.7 629.8 630.1 638.2 649.0 654.1 519.1 524.2 527.3 555.9 589.6 8744.5 8898.7 9004.5 9031.4 9036.5 624.9 656.6 671.0 673.5 679.6 4224.2 4262.6 4433.4 4475.6 4628.1 87347.1 102071.4 110548.0 112322.4 115732.0 72448.0 87248.6 88783.0 94129.0 97605.0 66663.3 75879.0 80092.0 83682.0 85005.0 6977.5 7169.6 8515.4 8523.7 8764.6 52923.0 54133.7 54982.5 56081.2 56304.0 52606.0 55861.0 60967.7 62345.0 62735.4 12121.2 12140.6 12145.7 12148.4 12237.6 13328.7 13387.5 13610.5 13655.9 13889.9 12292.0 12711.6 12840.7 12938.5 13377.6 12663.2 12803.6 12887.7 12963.4 13372.3 12540.2 12545.2 12925.0 13062.8 13068.0 10466.9 10660.8 10831.1 11110.0 11145.9 9852.8 10251.9 10300.8 10894.9 11049.5 03:17:02 INFO - PROCESS | 1645 | -------- Summary: end -------- 03:17:02 INFO - PROCESS | 1645 | 03:17:03 INFO - TEST-INFO | 1645: exit 0 03:17:03 INFO - TEST-OK | dromaeo_css | took 1121758ms 03:17:03 INFO - TEST-START | kraken 03:17:03 INFO - operating with platform_type : mac_ 03:17:03 INFO - Initialising browser for kraken test... 03:17:03 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/tmpXwDzk1/profile http://localhost:49249/getInfo.html) 03:17:05 INFO - PROCESS | 1653 | __metrics Screen width/height:1600/1200 03:17:05 INFO - PROCESS | 1653 | colorDepth:24 03:17:05 INFO - PROCESS | 1653 | Browser inner width/height: 1024/689 03:17:05 INFO - PROCESS | 1653 | __metrics 03:17:05 INFO - PROCESS | 1653 | 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" 03:17:05 INFO - PROCESS | 1653 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:17:06 INFO - TEST-INFO | 1653: exit 0 03:17:06 INFO - Browser initialized. 03:17:06 INFO - Running cycle 1/1 for kraken test... 03:17:06 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/tmpXwDzk1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:17:18 INFO - PROCESS | 1654 | RSS: Main: 222896128 03:17:18 INFO - PROCESS | 1654 | 03:17:21 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-beat-detection) 03:17:22 INFO - PROCESS | 1654 | RSS: Main: 272494592 03:17:22 INFO - PROCESS | 1654 | 03:17:25 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-dft) 03:17:25 INFO - PROCESS | 1654 | RSS: Main: 283209728 03:17:25 INFO - PROCESS | 1654 | 03:17:28 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-fft) 03:17:29 INFO - PROCESS | 1654 | RSS: Main: 381386752 03:17:29 INFO - PROCESS | 1654 | 03:17:31 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-oscillator) 03:17:31 INFO - PROCESS | 1654 | RSS: Main: 284065792 03:17:31 INFO - PROCESS | 1654 | 03:17:34 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:17:34 INFO - PROCESS | 1654 | RSS: Main: 285184000 03:17:34 INFO - PROCESS | 1654 | 03:17:37 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-darkroom) 03:17:37 INFO - PROCESS | 1654 | RSS: Main: 325607424 03:17:37 INFO - PROCESS | 1654 | 03:17:40 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-desaturate) 03:17:41 INFO - PROCESS | 1654 | RSS: Main: 327753728 03:17:41 INFO - PROCESS | 1654 | 03:17:44 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49249/tests/kraken/driver.html?testName=json-parse-financial) 03:17:44 INFO - PROCESS | 1654 | RSS: Main: 326176768 03:17:44 INFO - PROCESS | 1654 | 03:17:46 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49249/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:17:47 INFO - PROCESS | 1654 | RSS: Main: 300625920 03:17:47 INFO - PROCESS | 1654 | 03:17:49 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:17:49 INFO - PROCESS | 1654 | RSS: Main: 302379008 03:17:49 INFO - PROCESS | 1654 | 03:17:51 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:17:52 INFO - PROCESS | 1654 | RSS: Main: 304832512 03:17:52 INFO - PROCESS | 1654 | 03:17:55 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:17:56 INFO - PROCESS | 1654 | RSS: Main: 316915712 03:17:56 INFO - PROCESS | 1654 | 03:17:58 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:17:59 INFO - PROCESS | 1654 | RSS: Main: 316551168 03:17:59 INFO - PROCESS | 1654 | 03:18:01 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49249/tests/kraken/driver.html?testName=ai-astar) 03:18:01 INFO - PROCESS | 1654 | RSS: Main: 360583168 03:18:01 INFO - PROCESS | 1654 | 03:18:01 INFO - PROCESS | 1654 | __start_tp_report 03:18:01 INFO - PROCESS | 1654 | _x_x_mozilla_page_load 03:18:01 INFO - PROCESS | 1654 | _x_x_mozilla_page_load_details 03:18:01 INFO - PROCESS | 1654 | |i|pagename|runs| 03:18:01 INFO - PROCESS | 1654 | |0;ai-astar;114;114;113;112;113;113;112;112;112;112 03:18:01 INFO - PROCESS | 1654 | |1;audio-beat-detection;145;111;109;145;111;107;109;110;143;135 03:18:01 INFO - PROCESS | 1654 | |2;audio-dft;184;183;183;185;183;184;184;184;183;185 03:18:01 INFO - PROCESS | 1654 | |3;audio-fft;81;58;58;89;61;58;60;59;84;58 03:18:01 INFO - PROCESS | 1654 | |4;audio-oscillator;89;70;95;70;68;70;90;68;69;93 03:18:01 INFO - PROCESS | 1654 | |5;imaging-gaussian-blur;87;85;86;82;86;86;89;83;86;83 03:18:01 INFO - PROCESS | 1654 | |6;imaging-darkroom;100;123;93;93;92;92;93;93;92;92 03:18:01 INFO - PROCESS | 1654 | |7;imaging-desaturate;81;83;82;79;81;83;84;86;82;84 03:18:01 INFO - PROCESS | 1654 | |8;json-parse-financial;54;53;55;55;57;58;58;57;57;57 03:18:01 INFO - PROCESS | 1654 | |9;json-stringify-tinderbox;65;68;77;75;72;70;71;74;104;105 03:18:01 INFO - PROCESS | 1654 | |10;stanford-crypto-aes;85;84;85;84;83;84;91;105;77;83 03:18:01 INFO - PROCESS | 1654 | |11;stanford-crypto-ccm;141;139;123;132;142;150;142;141;193;142 03:18:01 INFO - PROCESS | 1654 | |12;stanford-crypto-pbkdf2;165;163;165;170;166;165;166;165;165;164 03:18:01 INFO - PROCESS | 1654 | |13;stanford-crypto-sha256-iterative;67;67;68;67;66;66;67;67;67;69 03:18:01 INFO - PROCESS | 1654 | __end_tp_report 03:18:01 INFO - PROCESS | 1654 | __start_cc_report 03:18:01 INFO - PROCESS | 1654 | _x_x_mozilla_cycle_collect,1991 03:18:01 INFO - PROCESS | 1654 | __end_cc_report 03:18:01 INFO - PROCESS | 1654 | __startTimestamp1483010281583__endTimestamp 03:18:01 INFO - PROCESS | 1654 | 03:18:01 INFO - PROCESS | 1654 | ------- Summary: start ------- 03:18:01 INFO - PROCESS | 1654 | Number of tests: 14 03:18:01 INFO - PROCESS | 1654 | 03:18:01 INFO - PROCESS | 1654 | [#0] ai-astar Cycles:10 Average:112.70 Median:113.00 stddev:0.82 (0.7%) stddev-sans-first:0.73 03:18:01 INFO - PROCESS | 1654 | Values: 114.0 114.0 113.0 112.0 113.0 113.0 112.0 112.0 112.0 112.0 03:18:01 INFO - PROCESS | 1654 | 03:18:01 INFO - PROCESS | 1654 | [#1] audio-beat-detection Cycles:10 Average:122.50 Median:123.00 stddev:17.04 (13.9%) stddev-sans-first:16.02 03:18:01 INFO - PROCESS | 1654 | Values: 145.0 111.0 109.0 145.0 111.0 107.0 109.0 110.0 143.0 135.0 03:18:01 INFO - PROCESS | 1654 | 03:18:01 INFO - PROCESS | 1654 | [#2] audio-dft Cycles:10 Average:183.80 Median:184.00 stddev:0.79 (0.4%) stddev-sans-first:0.83 03:18:01 INFO - PROCESS | 1654 | Values: 184.0 183.0 183.0 185.0 183.0 184.0 184.0 184.0 183.0 185.0 03:18:01 INFO - PROCESS | 1654 | 03:18:01 INFO - PROCESS | 1654 | [#3] audio-fft Cycles:10 Average:66.60 Median:60.50 stddev:12.65 (20.9%) stddev-sans-first:12.30 03:18:01 INFO - PROCESS | 1654 | Values: 81.0 58.0 58.0 89.0 61.0 58.0 60.0 59.0 84.0 58.0 03:18:01 INFO - PROCESS | 1654 | 03:18:01 INFO - PROCESS | 1654 | [#4] audio-oscillator Cycles:10 Average:78.20 Median:79.50 stddev:11.79 (14.8%) stddev-sans-first:11.84 03:18:01 INFO - PROCESS | 1654 | Values: 89.0 70.0 95.0 70.0 68.0 70.0 90.0 68.0 69.0 93.0 03:18:01 INFO - PROCESS | 1654 | 03:18:01 INFO - PROCESS | 1654 | [#5] imaging-gaussian-blur Cycles:10 Average:85.30 Median:86.00 stddev:2.11 (2.5%) stddev-sans-first:2.15 03:18:01 INFO - PROCESS | 1654 | Values: 87.0 85.0 86.0 82.0 86.0 86.0 89.0 83.0 86.0 83.0 03:18:01 INFO - PROCESS | 1654 | 03:18:01 INFO - PROCESS | 1654 | [#6] imaging-darkroom Cycles:10 Average:96.30 Median:92.50 stddev:9.68 (10.5%) stddev-sans-first:10.18 03:18:01 INFO - PROCESS | 1654 | Values: 100.0 123.0 93.0 93.0 92.0 92.0 93.0 93.0 92.0 92.0 03:18:01 INFO - PROCESS | 1654 | 03:18:01 INFO - PROCESS | 1654 | [#7] imaging-desaturate Cycles:10 Average:82.50 Median:83.00 stddev:1.96 (2.4%) stddev-sans-first:2.00 03:18:01 INFO - PROCESS | 1654 | Values: 81.0 83.0 82.0 79.0 81.0 83.0 84.0 86.0 82.0 84.0 03:18:01 INFO - PROCESS | 1654 | 03:18:01 INFO - PROCESS | 1654 | [#8] json-parse-financial Cycles:10 Average:56.10 Median:57.00 stddev:1.73 (3.0%) stddev-sans-first:1.66 03:18:01 INFO - PROCESS | 1654 | Values: 54.0 53.0 55.0 55.0 57.0 58.0 58.0 57.0 57.0 57.0 03:18:01 INFO - PROCESS | 1654 | 03:18:01 INFO - PROCESS | 1654 | [#9] json-stringify-tinderbox Cycles:10 Average:78.10 Median:71.50 stddev:14.33 (20.0%) stddev-sans-first:14.40 03:18:01 INFO - PROCESS | 1654 | Values: 65.0 68.0 77.0 75.0 72.0 70.0 71.0 74.0 104.0 105.0 03:18:01 INFO - PROCESS | 1654 | 03:18:01 INFO - PROCESS | 1654 | [#10] stanford-crypto-aes Cycles:10 Average:86.10 Median:84.00 stddev:7.45 (8.9%) stddev-sans-first:7.89 03:18:01 INFO - PROCESS | 1654 | Values: 85.0 84.0 85.0 84.0 83.0 84.0 91.0 105.0 77.0 83.0 03:18:01 INFO - PROCESS | 1654 | 03:18:01 INFO - PROCESS | 1654 | [#11] stanford-crypto-ccm Cycles:10 Average:144.50 Median:142.00 stddev:18.48 (13.0%) stddev-sans-first:19.56 03:18:01 INFO - PROCESS | 1654 | Values: 141.0 139.0 123.0 132.0 142.0 150.0 142.0 141.0 193.0 142.0 03:18:01 INFO - PROCESS | 1654 | 03:18:01 INFO - PROCESS | 1654 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.40 Median:165.00 stddev:1.84 (1.1%) stddev-sans-first:1.94 03:18:01 INFO - PROCESS | 1654 | Values: 165.0 163.0 165.0 170.0 166.0 165.0 166.0 165.0 165.0 164.0 03:18:01 INFO - PROCESS | 1654 | 03:18:01 INFO - PROCESS | 1654 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.10 Median:67.00 stddev:0.88 (1.3%) stddev-sans-first:0.93 03:18:01 INFO - PROCESS | 1654 | Values: 67.0 67.0 68.0 67.0 66.0 66.0 67.0 67.0 67.0 69.0 03:18:01 INFO - PROCESS | 1654 | -------- Summary: end -------- 03:18:01 INFO - PROCESS | 1654 | 03:18:02 INFO - TEST-INFO | 1654: exit 0 03:18:02 INFO - TEST-OK | kraken | took 59612ms 03:18:02 INFO - SUITE-END | took 1181s 03:18:02 INFO - Completed test suite (00:19:41) 03:18:02 INFO - javascript benchmark 03:18:02 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3290.0129961011694, 3298.2578919743055, 3311.2549676322815, 3335.916602084946, 3367.1918739502526, 5655.462731040549, 5686.658800471964, 5690.715464226792, 5712.9428705713035, 5747.885042299146, 5643.322801263854, 5715.3141623005195, 5726.914096288559, 5729.0833466645345, 5745.080787074058, 5180.922286165692, 5205.453427390126, 5214.713190774506, 5226.869328266805, 5266.973665131669, 5153.433122356538, 5177.068127737006, 5177.87055323618, 5283.735813209343, 5284.577233821305, 5248.5276325130735, 5272.340957380327, 5273.868153296179, 5283.07546179419, 5311.760970756306, 5256.632035757499, 5270.314859068335, 5272.7100009499845, 5274.868128296785, 5290.1800220965815, 5111.897762044739, 5220.347456567929, 5225.738713064369, 5226.92159617606, 5261.210818377236, 5736.196932429463, 5743.138529220609, 5755.3381361143165, 5758.74085666146, 5759.712014399305, 5870.970645146747, 5914.319853216892, 5916.674582897939, 5922.644641321492, 6109.2528441424865, 6191.442770150663, 6264.123022776815, 6269.0, 6277.2153480814895, 6323.083152942843, 5741.110127930177, 5758.222639943638, 5771.567132465082, 5796.420357964212, 5819.388964158745, 5190.92213616796, 6264.248290205204, 6308.905366419507, 6321.367863213642, 6336.778212762623, 5962.612430192024, 6019.067044608005, 6048.727807248684, 6074.726637301332, 6078.848028799315, 6147.047207682816, 6210.72051757672, 6244.937550624527, 6308.211473565804, 6311.936880631227, 6079.3008803988105, 6212.627242365473, 6215.844603884939, 6218.471429928507, 6233.283172435228, 6108.463491842603, 6117.644716737952, 6148.661823599745, 6177.135201071854, 6201.224846894138, 4930.433000204951, 4934.851954441372, 4937.604991600663, 4942.802287908516, 4948.0, 4405.339199120158, 4406.9779651101535, 4408.338749187584, 4418.690691651554, 4438.556144385536, 5575.944240557624, 5624.0439125348785, 5628.409017053233, 5637.408072152447, 5670.262865827416, 6246.625202487865, 6257.061440783918, 6265.310815810257, 6266.655333956585, 6271.937280627227, 5614.24207731951, 5623.381428042912, 5624.71876406178, 5640.182173584889, 5647.519960803378, 4365.672574556857, 5332.466753324714, 5353.678779273256, 5364.436734142859, 5364.544013758796, 6262.46769024638, 6308.148399965946, 6327.272363678236, 6363.0, 6370.617762934239, 6131.356207598316, 6287.30839607652, 6316.368363163647, 6323.841903952438, 6324.209473815773, 6199.690015499117, 6303.46420554258, 6349.07938348946, 6357.777477788255, 6360.713767880549, 4589.885252868705, 6083.361247069171, 6115.6662951758, 6122.581127188433, 6168.0, 6115.346784300343, 6261.279952805486, 6305.369463053841, 6311.400416960381, 6317.399847014526, 6057.545684073801, 6124.356942521149, 6162.198914141133, 6166.321704612399, 6172.074188871705, 5918.674472903859, 5962.880742385215, 5980.37206093354, 6039.275286965592, 6090.086487026982, 5935.228420305332, 5944.881102377842, 5961.433663801932, 5991.430814072656, 6017.338092809699, 5935.40645935403, 5946.791862284799, 5986.3115741688525, 6108.419700128481, 6111.816645500642, 5821.912671310019, 5904.763809447574, 5907.084401917709, 5909.645421274738, 5910.379410161871], "value": 5668.45651149915, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [460.2015503102119, 511.6750863201866, 527.955123814476, 543.7036814935859, 551.2585572405113, 30139.945101921436, 38734.61265387349, 39793.80103099488, 40624.39063414051, 41488.79255603718, 40370.394444083286, 40974.59025409745, 41491.17017659645, 41513.7924310378, 41649.7917510412, 8405.0, 9285.164335209829, 9484.952575237114, 9492.810143797155, 9515.571799269015, 8421.03158136816, 11069.557217711323, 11374.090072794197, 11395.202335836495, 11501.42492875353, 11376.488058037408, 11421.771564568746, 11443.542258309657, 11469.541218351256, 11471.942640286787, 8446.366522510774, 8641.395102342838, 11237.775244495146, 11297.717557061098, 11407.201495895291, 10886.891131088667, 11236.94381528087, 11259.324440533512, 11376.772464550746, 11383.658490245305, 39474.80262598669, 39729.20541589152, 39729.20541589152, 39823.407063717415, 40064.59935400638, 3238.6437491875877, 3251.9837400812844, 3471.0801637566065, 3508.5263489428858, 3637.1816341323256, 24038.278851634477, 27609.723902760918, 28407.147785566467, 28420.857895710596, 29513.557296640567, 3152.4168028914723, 3172.4448221561365, 3266.183454136466, 3298.538204651327, 3303.0, 490847.5457622725, 492346.0, 494942.0, 497863.9999999964, 499940.0, 33192.0, 57350.7132464335, 57767.422325776206, 57926.420735792104, 58385.124223136685, 33440.49839252413, 35256.29487410237, 35275.0, 35300.82349588287, 35309.47035794465, 14195.574132776033, 14340.784888226684, 14346.354414051393, 14353.0, 14358.641033974234, 10819.945900270606, 11026.117910567136, 11836.05311575072, 11997.520099196108, 12092.576759813364, 7796.064472263251, 7811.726589569338, 7863.174366691415, 7915.525068495794, 7924.6433910473015, 3239.935201296008, 3253.2842774589553, 3261.904761904762, 3265.2816380396275, 3274.279658475132, 890.1943740914353, 918.7289749523907, 964.8986856379979, 1029.4183786160795, 1071.7534966957487, 29354.559681604795, 29544.852275738485, 29594.556081658793, 29953.850230748707, 30161.849190753906, 1046.534292239946, 1068.6687126990657, 1075.3279200499687, 1082.355998181081, 1118.6811758648746, 14533.909956753287, 14754.852451475563, 15241.3903443861, 15425.0, 15586.454474093352, 640199.0, 640407.585734497, 645099.0, 645606.0, 648125.0, 55897.72051139718, 56554.7172264136, 56680.0, 56681.0, 56690.0, 1362.2099182474142, 1365.8565850585744, 1402.9368678409496, 1403.663120850968, 1405.6415614018565, 28530.85734571314, 29018.41963160767, 29023.12930612085, 29070.854645726635, 29487.0, 48211.276830846866, 48531.757341213066, 48672.783180419356, 48709.756451217516, 48740.512594873595, 485637.0, 486356.56821715663, 487190.0, 487721.0, 488397.0, 21776.782232177477, 22340.553188936454, 22353.44116397038, 26131.346716332242, 26574.0, 44987.0, 45265.54734452613, 46511.76744116258, 46540.53459465362, 48384.27423588579, 36838.89483315505, 37145.81427092847, 37283.0, 37568.060798480255, 37950.0, 37544.0, 37766.0, 38632.80683596564, 39012.80493597627, 39388.0, 36600.901972940854, 36818.89543313705, 37067.0, 37067.62932370642, 37341.0, 32111.0, 34353.484697730055, 34380.65619343874, 34387.65612343844, 34566.13584660303, 24855.378615534755, 25149.119780807585, 25187.496250075263, 25659.486810264065, 25683.743162568884], "value": 18500.684758020434, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [354.20481020109855, 367.5827935293442, 377.4093543604261, 387.6433681013467, 405.1734461698136, 14505.492307769231, 14817.555473335804, 15431.768523472158, 19468.41594752156, 20408.8979555102, 14824.925875370607, 19846.610737248397, 20022.799772002276, 20114.59770804583, 20383.18467261308, 5877.823665290048, 6139.570230083874, 6143.0, 6147.446729794317, 6153.107799369089, 4717.882052948687, 6019.879602407949, 6092.72582733778, 6093.969530152343, 6161.784337548187, 5707.229524014268, 6036.547258955574, 6041.848953776147, 6042.909356359636, 6047.758089676429, 5881.617694849821, 5969.611975221596, 5971.014782560872, 5985.760569577212, 5994.010988186988, 4988.052270068675, 5166.095933211673, 5214.791408343662, 5219.295395121648, 5256.448072952361, 24897.25308240756, 24914.750852491423, 25131.0, 25612.35969100769, 25867.22398328053, 2094.4240333908147, 2191.3973657244387, 2447.1801946347996, 2948.9410211795857, 2957.260684828793, 10000.249981251363, 10149.238807089498, 10236.283460157792, 10358.0, 10635.361878287327, 1853.8331550160551, 1865.123392005755, 1873.072828949665, 1882.2941396976134, 1931.1309910540172, 106012.46993764982, 119567.40216298863, 124076.75923240742, 125570.11644825243, 126286.10570841444, 9541.47521886303, 11470.107432273748, 11505.676847162516, 11744.94127529374, 11803.940980295043, 14805.629859253604, 14883.851161488463, 14884.925575372054, 14897.255137243095, 14911.328990195467, 8157.306628936607, 8260.917390826135, 8307.0, 8340.958295208606, 8517.659293628309, 7443.962780186064, 7956.562389068657, 8146.918530814734, 8160.67357305713, 8315.750527484186, 4521.796519156645, 4533.750643714628, 4545.113702827983, 4547.295169248771, 4617.8383756568355, 1850.296887182889, 1905.733197352344, 1909.417627623588, 1911.9904400477908, 1912.254220853868, 743.8363560016787, 803.5901690138072, 834.8288600836814, 893.7988952485639, 916.9679061232824, 19134.0, 20775.792242077387, 20817.271395501084, 21035.789642103384, 21186.15255389767, 733.3949491669288, 937.2455173585329, 949.715085474355, 963.8698775665196, 976.7069879036261, 10118.08937195656, 10204.744881377876, 10556.577736890593, 10810.459477026117, 10844.403557804397, 514584.5624631267, 518365.0, 520198.0, 620432.7956720375, 623991.0, 131668.0, 131745.0, 133549.0, 141152.0, 147432.26283868725, 914.8764916736288, 951.3388195204404, 982.749398903275, 1017.3488967061087, 1020.739711373595, 14760.704785904436, 18819.435416937515, 19427.4171774847, 19713.1129099188, 19778.60442791165, 52347.95304093973, 56830.0, 57294.427055728906, 59078.704606478415, 59849.70075149596, 74380.0, 78649.60675196587, 80726.59636701779, 81304.18695812966, 84032.57983710042, 7878.0, 8178.83642327162, 8198.959005204935, 8425.957870210856, 8552.332295202625, 9178.036306187754, 12477.188982716094, 12766.936165319485, 12805.423755931191, 13216.66958326011, 11051.281666691639, 11067.335959842436, 11181.385023823443, 11483.0, 11548.076153907836, 9361.906380936103, 10962.671119866416, 11066.944665276622, 11129.721756956142, 11198.720031999264, 10953.068989136013, 11307.943460282975, 11497.482613282255, 11625.360605166798, 11673.533058677898, 8232.094469608459, 8493.91506084956, 8495.872561911452, 8587.95706021466, 8706.782330441789, 11089.168312376443, 11307.943460282646, 11334.773304534028, 11624.6512604622, 11704.356260405759], "value": 9503.02702532857, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1437.7052704195637, 1479.1125324805118, 1620.6920685069836, 1790.0154914796858, 1824.890506569606, 7962.044554653443, 8331.75004749857, 8360.70737524186, 8363.749087527383, 8367.91632083679, 7455.813604659887, 7703.653335599897, 7705.036870391201, 7769.300762931335, 8254.958725206381, 2091.6444204485238, 2135.850490465668, 2249.403907964391, 2307.8730669813153, 2315.6642286868478, 2325.7790509901615, 2352.7882490575844, 2366.3965688749345, 2368.6683864259016, 2377.6076947303673, 2305.101010605865, 2317.8377513574055, 2318.038014224095, 2327.104064934995, 2327.8137748980125, 2278.646809744484, 2321.4312493439215, 2326.7905888470036, 2329.4060014696315, 2329.7087864017, 2312.8959196836013, 2326.4532834783813, 2326.9650955235684, 2360.079568968104, 2360.1621424394266, 8265.669373225064, 8353.540555269457, 8362.958185209034, 8369.58152092399, 8378.874316885252, 5076.898462030776, 5236.6857988520815, 5244.0036393085575, 5253.658512196695, 5264.184051472028, 2539.352465121382, 3288.2437039480815, 3435.4675025371107, 3538.0800991742076, 3541.6458354164633, 1012.9189664826796, 1048.659185764622, 1062.2033474893828, 1065.0787069185099, 1109.2734259060328, 10983.670489885317, 11033.393163376011, 11034.393108379036, 11063.612773552886, 11178.27341222818, 4082.1223436961195, 4399.560043995639, 4681.625469962463, 4744.857654270378, 4756.096341695067, 9376.57805398745, 9380.718578442658, 9392.765180870396, 9398.812023759487, 9402.294827887936, 4646.442426908792, 4958.801647934042, 5174.172132458789, 5180.637355385087, 5206.088934436459, 3966.8809935701975, 4613.5847773700525, 4677.204875171229, 4743.928841067387, 4764.2615394613895, 3635.2911182319194, 3646.799426031541, 3695.2978934002454, 3729.5524537055726, 3740.532433445819, 1247.189326937498, 1250.2810883741704, 1257.7547378261334, 1259.477316913489, 1269.4795133995017, 874.491502158771, 877.6971944679076, 887.4719541872576, 895.632790555882, 899.4917871402637, 5337.466253374631, 5421.512063914267, 5603.495685388335, 5626.805545837126, 5701.942980570224, 862.5385418800911, 964.3972517176765, 992.8659630949874, 1020.2603112743202, 1028.5114570578914, 3346.9497957530657, 3605.441156620728, 3706.814659267026, 3769.660730534265, 3877.96122038776, 9537.523123843781, 10138.03688649577, 10193.286469947032, 10284.971502849656, 10332.483375831178, 10017.148542373834, 10230.948845255725, 10266.0, 10268.332558383681, 10289.897101028893, 7234.457415693844, 7426.9230961510275, 7744.574048427278, 7887.369010479148, 7948.324392426593, 5380.3274590676165, 5475.534579560623, 5490.780368785204, 5583.8883222336135, 5623.128415095667, 9326.253899688145, 9366.2038726709, 9376.671816486663, 9445.386049906734, 9577.138057574573, 9344.719658410258, 9613.086756758097, 9633.373830700983, 9644.855327170239, 9648.228141748503, 5523.4200408956485, 6247.375262473716, 6447.0651755494255, 6459.0, 6751.426128778912, 7869.960650196713, 7986.081600616004, 8065.153158918464, 8072.636731346986, 8083.0, 7115.359617634438, 7121.82195445118, 7134.0, 7216.097987751531, 7281.708731650887, 6919.654017299215, 6950.165980082423, 7035.437165026888, 7038.085048943604, 7068.929310706827, 6633.668316584248, 6777.762778302736, 6785.321467853372, 6785.89821152693, 6810.31896810315, 4733.431988161443, 4780.95219047819, 4839.225723884161, 4849.58778503817, 4939.827106051271, 4773.570378665937, 4867.853964381075, 4869.561739443467, 4975.527324904128, 5024.84925452237], "value": 4399.2778585254555, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [244.97305296417403, 253.27058072750475, 306.10616998364765, 313.4014033196594, 318.2997405707444, 4654.3716598259225, 4940.827071052514, 5086.796528138874, 5177.818776342829, 5214.322138122044, 4271.380649805777, 4454.933176002362, 5078.263651770491, 5092.974535127329, 5188.403333616635, 3994.8801535953967, 4005.5393629732516, 4008.8797336079965, 4010.7192496525254, 4070.409790580364, 3503.5970863350785, 3774.849006039769, 3852.3451013327804, 4014.8959036265114, 4016.196760647882, 3512.3326567952136, 3684.2263124743704, 3896.6556537994347, 3904.804759762015, 3945.3687410014404, 3255.853486593109, 3900.7854567998747, 3908.5700572936953, 3915.8237879295502, 3922.5881282465502, 3004.722992728082, 3288.5067239914206, 3418.538497302882, 3427.474773725693, 3457.1529975155995, 4524.434445694288, 4541.977290113561, 4988.6757360771435, 4990.101781679296, 5127.435982041972, 3713.424419214999, 3797.259534390795, 4414.470263568361, 4458.821647134111, 4496.303073023687, 4946.0, 4968.776405061781, 4998.325226094467, 5374.011615702176, 5418.566514678816, 3037.7545206465243, 3259.2992506610954, 3719.590845007047, 3736.7668669339323, 3742.4094759727277, 5546.528545073674, 5653.378128405872, 5670.886582268332, 5680.4319568042865, 5694.5159661428825, 3599.9560127563236, 3721.971525518599, 3780.4518344839844, 3797.088698712289, 3818.961810381916, 3583.928321433557, 3685.981570092132, 3846.249981253685, 3846.8845934622004, 3858.2476417098956, 4211.410402543646, 4429.402030725875, 4490.708103973231, 4491.214037543482, 4519.638428925744, 4421.5578442156175, 4946.826861059845, 5048.93972265821, 5375.946240537544, 5469.589780766459, 1074.6722249713755, 1186.4720199511307, 1204.6085022367765, 1225.8099994500867, 1242.9005679545796, 839.6977088248226, 846.1411667157839, 856.5631527920681, 858.4891989266376, 859.9852174434228, 4062.9187416252103, 4287.871363859089, 4449.708400173913, 4968.627352948543, 5064.772085256172, 4357.607815296638, 4601.838935637236, 5201.153590475326, 5289.127293996447, 5523.696196709139, 4831.0, 4842.176829938919, 4872.756362181876, 4879.170541008037, 4916.926246106311, 2784.1346297980404, 2784.331760377495, 2790.725558669678, 2795.273228960485, 2796.783399221345, 4496.797644105957, 4611.677182597186, 4910.852674419773, 5462.371827239839, 5493.505584497414, 4918.434380046269, 5419.783208671688, 5492.258545096441, 5535.833924982257, 5568.331800184003, 5262.658022204313, 5504.5963279364305, 5591.608587398843, 5597.272354593877, 5635.4077124522355, 4860.635452341018, 4892.730899800546, 4928.507149285043, 5361.839144825662, 5758.510526605118, 4752.405949256343, 4934.951994922068, 5377.704226267437, 5563.805266815642, 5693.174489698888, 5934.0, 5946.224147666349, 5948.494377977747, 5952.0, 5952.553558483218, 2901.680815110294, 3563.948635152688, 3698.7041036717537, 3712.9628703712615, 3777.225668737957, 4676.854170728194, 5174.818881339135, 5646.971765141148, 5648.039833228278, 5664.49019588239, 4330.588594083557, 5505.724713764376, 5506.394296627288, 5518.310211223597, 5520.254765606753, 4267.914641707086, 5008.649394542347, 5420.970015697005, 5424.430434804289, 5446.972765136149, 5092.007058623681, 5315.521603055744, 5323.707196104251, 5356.758945847524, 5359.008583412081, 4422.955770442254, 5376.661152398719, 5540.168974653834, 5560.749766260447, 5573.27547418833, 4572.839950601713, 4886.828960986349, 5079.974600126976, 5448.210009548672, 5466.644668096561], "value": 3894.6102865864086, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [664.5215444879686, 688.652230623535, 697.0102454514592, 745.027738800865, 748.7080038784873, 14176.50382236622, 14655.340509677064, 14792.482263120794, 14800.555983320504, 14810.629734256649, 12723.809142862841, 12766.55317063903, 12807.487700491993, 12821.102522823407, 12823.487060517567, 647.8833809914214, 665.0456594786473, 800.9839803203932, 801.7674874286464, 826.4462809917339, 811.2860682599304, 814.5357146426539, 819.5861090149466, 820.0978923184509, 820.8522465956125, 884.305819931354, 886.5921676029035, 887.8934527856634, 888.2893685051965, 889.0131953531575, 681.7750142452978, 703.7853454696316, 706.131458306281, 880.0891077734514, 880.8634661627458, 526.7024131365804, 527.6148411659472, 528.2894506888267, 628.8176428835632, 649.3895738006306, 13814.516491922734, 13872.514461993782, 13919.58241252754, 13932.860671393257, 13938.93030534841, 12549.874501254963, 12627.810582841264, 12793.232406055666, 12865.871341286562, 12971.286579238138, 11504.827427588592, 11874.940625296904, 11956.222845515014, 12197.268163910105, 12377.071719621064, 4328.5455027222315, 4355.803988820479, 4367.192069467159, 4401.427814384109, 4483.327500874862, 83099.5845020777, 88462.5576872118, 88739.0, 90833.54583227108, 91689.54155229247, 10208.234382421348, 10222.48887555634, 10290.0, 10321.63874264397, 10324.277300589038, 19384.83690978546, 19501.414957551297, 19537.0, 19551.706724399144, 19552.217911283675, 6953.200381956039, 7111.502194846311, 7260.128784545888, 7302.3792977597495, 7310.597917114504, 4016.196760647882, 4213.094184750294, 4277.657787377002, 4322.286822674287, 4420.977895110504, 3064.3258483133677, 3172.841357932094, 3187.2509960159346, 3188.5695431116965, 3207.6631953644996, 629.754395785644, 630.0989584893555, 638.2022471910112, 648.9805305840832, 654.0875478707177, 519.0864079220523, 524.2032111190969, 527.2723641374878, 555.9045070798711, 589.584343038157, 8744.475331480131, 8898.688545900863, 9004.459732416077, 9031.41295815783, 9036.548172591343, 624.9156363890908, 656.5732274021924, 670.9765158219438, 673.515069150211, 679.6262055869253, 4224.197402493516, 4262.616364527145, 4433.401490798766, 4475.619572336388, 4628.097520983376, 87347.1265287339, 102071.44821379524, 110548.0, 112322.43838780918, 115732.0, 72448.0, 87248.5637571808, 88783.0, 94129.0, 97605.0, 66663.33336666669, 75879.0, 80092.0, 83682.0, 85005.0, 6977.476689248225, 7169.605671687898, 8515.446495977989, 8523.701670441505, 8764.605592748214, 52923.0, 54133.72933135467, 54982.45017549773, 56081.15878261748, 56304.0, 52606.0, 55861.0, 60967.695161525684, 62345.0, 62735.37264627478, 12121.151519393557, 12140.575079872162, 12145.69635759113, 12148.392580371123, 12237.571684990982, 13328.666783330495, 13387.531436399679, 13610.455581776618, 13655.931720341334, 13889.930550347588, 12292.016638668707, 12711.61865144047, 12840.678983025498, 12938.547150849676, 13377.598672039854, 12663.240205587694, 12803.61589152327, 12887.742245155232, 12963.41664625113, 13372.331383430985, 12540.184887982252, 12545.184563003375, 12925.0, 13062.804057938949, 13068.01989850746, 10466.895331046897, 10660.786784264115, 10831.079358254321, 11110.000099991039, 11145.888541114486, 9852.753681158028, 10251.897481025095, 10300.845487317547, 10894.945525272324, 11049.502772375423], "value": 7053.464450347621, "unit": "score"}], "name": "dromaeo_css", "value": 7027.511132237491, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 113.0, 112.0, 113.0, 113.0, 112.0, 112.0, 112.0, 112.0], "value": 112.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 111.0, 109.0, 145.0, 111.0, 107.0, 109.0, 110.0, 143.0, 135.0], "value": 122.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 183.0, 183.0, 185.0, 183.0, 184.0, 184.0, 184.0, 183.0, 185.0], "value": 183.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 58.0, 58.0, 89.0, 61.0, 58.0, 60.0, 59.0, 84.0, 58.0], "value": 66.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 70.0, 95.0, 70.0, 68.0, 70.0, 90.0, 68.0, 69.0, 93.0], "value": 78.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 86.0, 82.0, 86.0, 86.0, 89.0, 83.0, 86.0, 83.0], "value": 85.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 123.0, 93.0, 93.0, 92.0, 92.0, 93.0, 93.0, 92.0, 92.0], "value": 96.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 83.0, 82.0, 79.0, 81.0, 83.0, 84.0, 86.0, 82.0, 84.0], "value": 82.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 53.0, 55.0, 55.0, 57.0, 58.0, 58.0, 57.0, 57.0, 57.0], "value": 56.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 68.0, 77.0, 75.0, 72.0, 70.0, 71.0, 74.0, 104.0, 105.0], "value": 78.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 85.0, 84.0, 83.0, 84.0, 91.0, 105.0, 77.0, 83.0], "value": 86.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 139.0, 123.0, 132.0, 142.0, 150.0, 142.0, 141.0, 193.0, 142.0], "value": 144.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 163.0, 165.0, 170.0, 166.0, 165.0, 166.0, 165.0, 165.0, 164.0], "value": 165.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 68.0, 67.0, 66.0, 66.0, 67.0, 67.0, 67.0, 69.0], "value": 67.1, "unit": "score"}], "name": "kraken", "value": 1425.2, "alertThreshold": 2.0}]} 03:18:02 INFO - Return code: 0 03:18:02 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:18:02 INFO - # TBPL SUCCESS # 03:18:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:18:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:18:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:18:02 INFO - 2016-12-29 03:18:02.875 screenresolution[1658:11398] starting screenresolution argv=screenresolution get 03:18:02 INFO - 2016-12-29 03:18:02.878 screenresolution[1658:11398] Display 0: 1600x1200x32@60 03:18:02 INFO - 2016-12-29 03:18:02.884 screenresolution[1659:11401] starting screenresolution argv=screenresolution list 03:18:02 INFO - Available Modes on Display 0 03:18:02 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:18:02 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:18:02 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:18:02 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:18:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:18:03 INFO - Intel Iris: 03:18:03 INFO - Chipset Model: Intel Iris 03:18:03 INFO - Type: GPU 03:18:03 INFO - Bus: Built-In 03:18:03 INFO - VRAM (Dynamic, Max): 1536 MB 03:18:03 INFO - Vendor: Intel (0x8086) 03:18:03 INFO - Device ID: 0x0a2e 03:18:03 INFO - Revision ID: 0x0009 03:18:03 INFO - Displays: 03:18:03 INFO - Display: 03:18:03 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:18:03 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:18:03 INFO - Display Serial Number: 1600x1200 60 03:18:03 INFO - Main Display: Yes 03:18:03 INFO - Mirror: Off 03:18:03 INFO - Online: Yes 03:18:03 INFO - Rotation: Supported 03:18:03 INFO - Return code: 0 03:18:03 INFO - Running post-action listener: _resource_record_post_action 03:18:03 INFO - [mozharness: 2016-12-29 11:18:03.130626Z] Finished run-tests step (success) 03:18:03 INFO - Running post-run listener: _resource_record_post_run 03:18:03 INFO - Total resource usage - Wall time: 1213s; CPU: 53.0%; Read bytes: 33393664; Write bytes: 658015232; Read time: 316; Write time: 2085 03:18:03 INFO - TinderboxPrint: CPU usage
53.4% 03:18:03 INFO - TinderboxPrint: I/O read bytes / time
33,393,664 / 316 03:18:03 INFO - TinderboxPrint: I/O write bytes / time
658,015,232 / 2,085 03:18:03 INFO - TinderboxPrint: CPU idle
2,262.5 (46.6%) 03:18:03 INFO - TinderboxPrint: CPU system
971.2 (20.0%) 03:18:03 INFO - TinderboxPrint: CPU user
1,620.1 (33.4%) 03:18:03 INFO - TinderboxPrint: Swap in / out
379,039,744 / 0 03:18:03 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 340906496; Write bytes: 335718400; Read time: 27390; Write time: 763 03:18:03 INFO - run-tests - Wall time: 1182s; CPU: 53.0%; Read bytes: 32414720; Write bytes: 305650688; Read time: 301; Write time: 1273 03:18:03 INFO - Running post-run listener: _upload_blobber_files 03:18:03 INFO - Blob upload gear active. 03:18:03 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:18:03 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 03:18:03 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'] 03:18:03 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 03:18:04 INFO - (blobuploader) - INFO - Open directory for files ... 03:18:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:18:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:18:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:18:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:18:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:18:04 INFO - (blobuploader) - INFO - Done attempting. 03:18:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:18:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:18:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:18:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:18:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:18:05 INFO - (blobuploader) - INFO - Done attempting. 03:18:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:18:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:18:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:18:07 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:18:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:18:07 INFO - (blobuploader) - INFO - Done attempting. 03:18:07 INFO - (blobuploader) - INFO - Iteration through files over. 03:18:07 INFO - Return code: 0 03:18:07 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:18:07 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:18:07 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/655563247298a55d302e1e53759099a43354cc8660a8a08397bd7b43060934bee21dc8c958747fce4690c443512196610af758c6e0106a4cc912974598f13af7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6b5ec857eaacda459c14277a722fcd7b234386b2f3d6c5ad68e26f1184a69e0955c31962841559a15c819f3479c49d94674a2334875a131472ac8def1fd78b79", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6d889e6aeda687ba6dd118c2873e54312171240ed504de82da1f994b06f7ad226c711731d51206c213fde983ebbc34232c89364f28048a72c112fc9cf884acc0"} 03:18:07 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:18:07 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:18:07 INFO - Contents: 03:18:07 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/655563247298a55d302e1e53759099a43354cc8660a8a08397bd7b43060934bee21dc8c958747fce4690c443512196610af758c6e0106a4cc912974598f13af7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6b5ec857eaacda459c14277a722fcd7b234386b2f3d6c5ad68e26f1184a69e0955c31962841559a15c819f3479c49d94674a2334875a131472ac8def1fd78b79", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6d889e6aeda687ba6dd118c2873e54312171240ed504de82da1f994b06f7ad226c711731d51206c213fde983ebbc34232c89364f28048a72c112fc9cf884acc0"} 03:18:07 INFO - Running post-run listener: copy_logs_to_upload_dir 03:18:07 INFO - Copying logs to upload dir... 03:18:07 INFO - mkdir: /builds/slave/test/build/upload/logs 03:18:07 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.759943 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-12-29 03:18:08.011484) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-29 03:18:08.015399) ========= 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.uvissI46nZ/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.DeSysAXuQz/Listeners TMPDIR=/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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/655563247298a55d302e1e53759099a43354cc8660a8a08397bd7b43060934bee21dc8c958747fce4690c443512196610af758c6e0106a4cc912974598f13af7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6b5ec857eaacda459c14277a722fcd7b234386b2f3d6c5ad68e26f1184a69e0955c31962841559a15c819f3479c49d94674a2334875a131472ac8def1fd78b79", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6d889e6aeda687ba6dd118c2873e54312171240ed504de82da1f994b06f7ad226c711731d51206c213fde983ebbc34232c89364f28048a72c112fc9cf884acc0"} build_url:https://queue.taskcluster.net/v1/task/SzyL-sbPQImSl4jsyC132g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011198 build_url: 'https://queue.taskcluster.net/v1/task/SzyL-sbPQImSl4jsyC132g/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/655563247298a55d302e1e53759099a43354cc8660a8a08397bd7b43060934bee21dc8c958747fce4690c443512196610af758c6e0106a4cc912974598f13af7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6b5ec857eaacda459c14277a722fcd7b234386b2f3d6c5ad68e26f1184a69e0955c31962841559a15c819f3479c49d94674a2334875a131472ac8def1fd78b79", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6d889e6aeda687ba6dd118c2873e54312171240ed504de82da1f994b06f7ad226c711731d51206c213fde983ebbc34232c89364f28048a72c112fc9cf884acc0"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-29 03:18:08.050578) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 03:18:08.051021) ========= 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.uvissI46nZ/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.DeSysAXuQz/Listeners TMPDIR=/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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.005162 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 03:18:08.099989) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-29 03:18:08.100331) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-29 03:18:08.100663) ========= ========= Total master_lag: 1.45 =========