builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0342 starttime: 1482660480.91 results: success (0) buildid: 20161225004004 builduid: 2f625a92445c499c9fcd2258a8d681c0 revision: 1deac793ef1dc655f2095e1f8d15deb32e2f666f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-25 02:08:00.908343) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-25 02:08:00.908833) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-25 02:08:00.909140) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-25 02:08:00.988336) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-25 02:08:00.988737) ========= 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.bBTW7nuoo2/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.hS5VYaaqfj/Listeners TMPDIR=/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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-25 02:08:01-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 132M=0s 2016-12-25 02:08:01 (132 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.632884 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-25 02:08:01.645417) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-25 02:08:01.645747) ========= 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.bBTW7nuoo2/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.hS5VYaaqfj/Listeners TMPDIR=/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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.019879 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-25 02:08:01.695010) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-25 02:08:01.695370) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 1deac793ef1dc655f2095e1f8d15deb32e2f666f --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 1deac793ef1dc655f2095e1f8d15deb32e2f666f --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.bBTW7nuoo2/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.hS5VYaaqfj/Listeners TMPDIR=/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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-25 02:08:01,781 truncating revision to first 12 chars 2016-12-25 02:08:01,781 Setting DEBUG logging. 2016-12-25 02:08:01,781 attempt 1/10 2016-12-25 02:08:01,781 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/1deac793ef1d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-25 02:08:03,025 unpacking tar archive at: mozilla-aurora-1deac793ef1d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.505609 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-25 02:08:03.224447) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-25 02:08:03.224821) ========= script_repo_revision: 1deac793ef1dc655f2095e1f8d15deb32e2f666f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-25 02:08:03.225323) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-25 02:08:03.225597) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-25 02:08:03.245408) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-12-25 02:08:03.245802) ========= /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.bBTW7nuoo2/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.hS5VYaaqfj/Listeners TMPDIR=/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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:08:03 INFO - MultiFileLogger online at 20161225 02:08:03 in /builds/slave/test 02:08:03 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:08:03 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:08:03 INFO - {'append_to_log': False, 02:08:03 INFO - 'base_work_dir': '/builds/slave/test', 02:08:03 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:08:03 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:08:03 INFO - 'branch': 'Mozilla-Aurora', 02:08:03 INFO - 'buildbot_json_path': 'buildprops.json', 02:08:03 INFO - 'config_files': ('talos/mac_config.py',), 02:08:03 INFO - 'default_actions': ('clobber', 02:08:03 INFO - 'read-buildbot-config', 02:08:03 INFO - 'download-and-extract', 02:08:03 INFO - 'populate-webroot', 02:08:03 INFO - 'create-virtualenv', 02:08:03 INFO - 'install', 02:08:03 INFO - 'run-tests'), 02:08:03 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:08:03 INFO - 'download_minidump_stackwalk': True, 02:08:03 INFO - 'download_symbols': 'ondemand', 02:08:03 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:08:03 INFO - 'tooltool.py': '/tools/tooltool.py', 02:08:03 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:08:03 INFO - '/tools/misc-python/virtualenv.py')}, 02:08:03 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:08:03 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:08:03 INFO - 'installer_path': 'installer.exe', 02:08:03 INFO - 'log_level': 'info', 02:08:03 INFO - 'log_name': 'talos', 02:08:03 INFO - 'log_to_console': True, 02:08:03 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:08:03 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:08:03 INFO - 'opt_config_files': (), 02:08:03 INFO - 'pip_index': False, 02:08:03 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:08:03 INFO - 'cmd': ('bash', 02:08:03 INFO - '-c', 02:08:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:08:03 INFO - 'enabled': True, 02:08:03 INFO - 'halt_on_failure': False, 02:08:03 INFO - 'name': 'check_screen_resolution'},), 02:08:03 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:08:03 INFO - 'cmd': ('bash', 02:08:03 INFO - '-c', 02:08:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:08:03 INFO - 'enabled': True, 02:08:03 INFO - 'halt_on_failure': False, 02:08:03 INFO - 'name': 'check_screen_resolution'},), 02:08:03 INFO - 'run_cmd_checks_enabled': True, 02:08:03 INFO - 'sps_profile': False, 02:08:03 INFO - 'sps_profile_interval': 0, 02:08:03 INFO - 'suite': 'dromaeojs', 02:08:03 INFO - 'system_bits': '32', 02:08:03 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:08:03 INFO - 'title': 't-yosemite-r7-0342', 02:08:03 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:08:03 INFO - 'use_talos_json': True, 02:08:03 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:08:03 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:08:03 INFO - 'work_dir': 'build'} 02:08:03 INFO - [mozharness: 2016-12-25 10:08:03.447473Z] Running clobber step. 02:08:03 INFO - Running pre-action listener: _resource_record_pre_action 02:08:03 INFO - Running main action method: clobber 02:08:03 INFO - rmtree: /builds/slave/test/build 02:08:03 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:08:05 INFO - Running post-action listener: _resource_record_post_action 02:08:05 INFO - [mozharness: 2016-12-25 10:08:05.449283Z] Finished clobber step (success) 02:08:05 INFO - [mozharness: 2016-12-25 10:08:05.449414Z] Running read-buildbot-config step. 02:08:05 INFO - Running pre-action listener: _resource_record_pre_action 02:08:05 INFO - Running main action method: read_buildbot_config 02:08:05 INFO - Using buildbot properties: 02:08:05 INFO - { 02:08:05 INFO - "project": "", 02:08:05 INFO - "product": "firefox", 02:08:05 INFO - "script_repo_revision": "production", 02:08:05 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 02:08:05 INFO - "repository": "", 02:08:05 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 02:08:05 INFO - "buildid": "20161225004004", 02:08:05 INFO - "stage_platform": "macosx64", 02:08:05 INFO - "basedir": "/builds/slave/test", 02:08:05 INFO - "nightly_build": "True", 02:08:05 INFO - "buildnumber": 29, 02:08:05 INFO - "slavename": "t-yosemite-r7-0342", 02:08:05 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 02:08:05 INFO - "platform": "yosemite_r7", 02:08:05 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 02:08:05 INFO - "branch": "mozilla-aurora", 02:08:05 INFO - "repo_path": "releases/mozilla-aurora", 02:08:05 INFO - "revision": "1deac793ef1dc655f2095e1f8d15deb32e2f666f", 02:08:05 INFO - "pgo_build": "False", 02:08:05 INFO - "builduid": "2f625a92445c499c9fcd2258a8d681c0", 02:08:05 INFO - "slavebuilddir": "test" 02:08:05 INFO - } 02:08:05 INFO - Found installer url https://queue.taskcluster.net/v1/task/d5SBpli3TheMWlhInPIL_A/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 02:08:05 INFO - Running post-action listener: _resource_record_post_action 02:08:05 INFO - [mozharness: 2016-12-25 10:08:05.452675Z] Finished read-buildbot-config step (success) 02:08:05 INFO - [mozharness: 2016-12-25 10:08:05.452799Z] Running download-and-extract step. 02:08:05 INFO - Running pre-action listener: _resource_record_pre_action 02:08:05 INFO - Running main action method: download_and_extract 02:08:05 INFO - mkdir: /builds/slave/test/build/tests 02:08:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:08:05 INFO - trying https://queue.taskcluster.net/v1/task/d5SBpli3TheMWlhInPIL_A/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 02:08:05 INFO - Downloading https://queue.taskcluster.net/v1/task/d5SBpli3TheMWlhInPIL_A/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:08:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/d5SBpli3TheMWlhInPIL_A/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:08:06 INFO - Downloaded 1199 bytes. 02:08:06 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 02:08:06 INFO - Using the following test package requirements: 02:08:06 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 02:08:06 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:08:06 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 02:08:06 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:08:06 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 02:08:06 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:08:06 INFO - u'jsshell-mac.zip'], 02:08:06 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:08:06 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 02:08:06 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 02:08:06 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:08:06 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 02:08:06 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:08:06 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 02:08:06 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:08:06 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 02:08:06 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:08:06 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 02:08:06 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 02:08:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/d5SBpli3TheMWlhInPIL_A/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 02:08:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/d5SBpli3TheMWlhInPIL_A/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:08:06 INFO - Fetch https://queue.taskcluster.net/v1/task/d5SBpli3TheMWlhInPIL_A/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 02:08:07 INFO - Http code: 200 02:08:07 INFO - via: 1.1 49c80a47c1441dd194a8337982f1cd7e.cloudfront.net (CloudFront) 02:08:07 INFO - x-amz-cf-id: e-P9utR9FPqr3nFFfnc1fXe_cujT4rwjHRMs3v5NxKmfvjZ1fXG1Ew== 02:08:07 INFO - x-amz-version-id: 9fkajTwhHrVtWUvOMchjaH0dF0qZEzNJ 02:08:07 INFO - Expected file size: 16271166 02:08:07 INFO - Obtained file size: 16271166 02:08:13 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:08:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/d5SBpli3TheMWlhInPIL_A/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 02:08:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/d5SBpli3TheMWlhInPIL_A/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:08:13 INFO - Fetch https://queue.taskcluster.net/v1/task/d5SBpli3TheMWlhInPIL_A/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 02:08:14 INFO - Http code: 200 02:08:14 INFO - via: 1.1 73fa5a1ee49cf827e596b502b5927eab.cloudfront.net (CloudFront) 02:08:14 INFO - x-amz-cf-id: xpkw_iJg76X8ssNUUZMZS1EdkEmWxWAhlnaESEM6FSEnsFzGrEfXgw== 02:08:14 INFO - x-amz-version-id: 9fkajTwhHrVtWUvOMchjaH0dF0qZEzNJ 02:08:14 INFO - Expected file size: 16271166 02:08:14 INFO - Obtained file size: 16271166 02:08:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/d5SBpli3TheMWlhInPIL_A/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 02:08:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/d5SBpli3TheMWlhInPIL_A/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 02:08:21 INFO - Fetch https://queue.taskcluster.net/v1/task/d5SBpli3TheMWlhInPIL_A/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 02:08:21 INFO - Http code: 200 02:08:21 INFO - via: 1.1 76bce8bb4fbd102fc0b3aa2e41094b79.cloudfront.net (CloudFront) 02:08:21 INFO - x-amz-cf-id: Yw2wdAF85M6yJAbOv0WBiM6VGbJbk9kNhez9qHxv0_r32Bl_grC1LA== 02:08:21 INFO - x-amz-version-id: Ff_d_dRgdXL9N2dXRJwyICzbsH8P9KMX 02:08:21 INFO - Expected file size: 14409038 02:08:21 INFO - Obtained file size: 14409038 02:08:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:08:22 INFO - trying https://queue.taskcluster.net/v1/task/d5SBpli3TheMWlhInPIL_A/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 02:08:22 INFO - Downloading https://queue.taskcluster.net/v1/task/d5SBpli3TheMWlhInPIL_A/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 02:08:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/d5SBpli3TheMWlhInPIL_A/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:08:25 INFO - Downloaded 109902678 bytes. 02:08:25 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/d5SBpli3TheMWlhInPIL_A/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 02:08:25 INFO - mkdir: /builds/slave/test/properties 02:08:25 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:08:25 INFO - Writing to file /builds/slave/test/properties/build_url 02:08:25 INFO - Contents: 02:08:25 INFO - build_url:https://queue.taskcluster.net/v1/task/d5SBpli3TheMWlhInPIL_A/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 02:08:25 INFO - Running post-action listener: _resource_record_post_action 02:08:25 INFO - Running post-action listener: set_extra_try_arguments 02:08:25 INFO - [mozharness: 2016-12-25 10:08:25.688554Z] Finished download-and-extract step (success) 02:08:25 INFO - [mozharness: 2016-12-25 10:08:25.688690Z] Running populate-webroot step. 02:08:25 INFO - Running pre-action listener: _resource_record_pre_action 02:08:25 INFO - Running main action method: populate_webroot 02:08:25 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:08:25 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:08:25 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:08:25 INFO - '--tppagecycles', 02:08:25 INFO - '7'], 02:08:25 INFO - 'tests': ['tsvgm']}}, 02:08:25 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:08:25 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:08:25 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:08:25 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:08:25 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:08:25 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:08:25 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:08:25 INFO - 'talos_options': ['--disable-e10s'], 02:08:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:08:25 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:08:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:08:25 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:08:25 INFO - 'talos_options': ['--disable-e10s'], 02:08:25 INFO - 'tests': ['damp', 'tps']}, 02:08:25 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:08:25 INFO - 'tests': ['damp', 'tps']}, 02:08:25 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:08:25 INFO - 'tests': ['dromaeo_dom']}, 02:08:25 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:08:25 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:08:25 INFO - 'tests': ['basic_compositor_video']}, 02:08:25 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:08:25 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:08:25 INFO - 'tests': ['a11yr', 02:08:25 INFO - 'ts_paint', 02:08:25 INFO - 'tpaint', 02:08:25 INFO - 'sessionrestore', 02:08:25 INFO - 'sessionrestore_no_auto_restore', 02:08:25 INFO - 'tabpaint']}, 02:08:25 INFO - 'other-e10s': {'tests': ['a11yr', 02:08:25 INFO - 'ts_paint', 02:08:25 INFO - 'tpaint', 02:08:25 INFO - 'sessionrestore', 02:08:25 INFO - 'sessionrestore_no_auto_restore', 02:08:25 INFO - 'tabpaint']}, 02:08:25 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:08:25 INFO - 'ts_paint', 02:08:25 INFO - 'tpaint', 02:08:25 INFO - 'sessionrestore', 02:08:25 INFO - 'sessionrestore_no_auto_restore', 02:08:25 INFO - 'tabpaint']}, 02:08:25 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:08:25 INFO - 'ts_paint', 02:08:25 INFO - 'tpaint', 02:08:25 INFO - 'sessionrestore', 02:08:25 INFO - 'sessionrestore_no_auto_restore', 02:08:25 INFO - 'tabpaint']}, 02:08:25 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:08:25 INFO - 'tests': ['a11yr', 02:08:25 INFO - 'ts_paint', 02:08:25 INFO - 'tpaint', 02:08:25 INFO - 'sessionrestore', 02:08:25 INFO - 'sessionrestore_no_auto_restore', 02:08:25 INFO - 'tabpaint']}, 02:08:25 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:08:25 INFO - 'tests': ['a11yr', 02:08:25 INFO - 'ts_paint', 02:08:25 INFO - 'tpaint', 02:08:25 INFO - 'sessionrestore', 02:08:25 INFO - 'sessionrestore_no_auto_restore', 02:08:25 INFO - 'tabpaint']}, 02:08:25 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:08:25 INFO - 'tests': ['tsvgx', 02:08:25 INFO - 'tsvgr_opacity', 02:08:25 INFO - 'tart', 02:08:25 INFO - 'tscrollx', 02:08:25 INFO - 'cart']}, 02:08:25 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:08:25 INFO - 'tsvgr_opacity', 02:08:25 INFO - 'tart', 02:08:25 INFO - 'tscrollx', 02:08:25 INFO - 'cart']}, 02:08:25 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:08:25 INFO - 'talos_options': ['--disable-e10s'], 02:08:25 INFO - 'tests': ['tp5o']}, 02:08:25 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:08:25 INFO - 'tests': ['tp5o']}, 02:08:25 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:08:25 INFO - 'talos_options': ['--disable-e10s', 02:08:25 INFO - '--xperf_path', 02:08:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:08:25 INFO - 'tests': ['tp5n']}, 02:08:25 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:08:25 INFO - 'talos_options': ['--xperf_path', 02:08:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:08:25 INFO - 'tests': ['tp5n']}}, 02:08:25 INFO - 'talos.zip': {'path': '', 02:08:25 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:08:25 INFO - Running post-action listener: _resource_record_post_action 02:08:25 INFO - [mozharness: 2016-12-25 10:08:25.707270Z] Finished populate-webroot step (success) 02:08:25 INFO - [mozharness: 2016-12-25 10:08:25.707401Z] Running create-virtualenv step. 02:08:25 INFO - Running pre-action listener: _resource_record_pre_action 02:08:25 INFO - Running main action method: create_virtualenv 02:08:25 INFO - Creating virtualenv /builds/slave/test/build/venv 02:08:25 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 02:08:25 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:08:25 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:08:25 INFO - Using real prefix '/tools/python27' 02:08:25 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:08:26 INFO - Installing distribute.............................................................................................................................................................................................done. 02:08:29 INFO - Installing pip.................done. 02:08:29 INFO - Return code: 0 02:08:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:08:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:08:29 INFO - Reading from file tmpfile_stdout 02:08:29 INFO - Output received: 02:08:29 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 02:08:29 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:08: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:08:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:08:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:08: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:08:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:08:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:08:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109996490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10990c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ed34b8480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1097aad30>, '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 0x109610db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1097a2b20>, '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.bBTW7nuoo2/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.hS5VYaaqfj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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:08:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 02:08:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 02:08:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bBTW7nuoo2/Render', 02:08:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:08:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:08:29 INFO - 'HOME': '/Users/cltbld', 02:08:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:08:29 INFO - 'LOGNAME': 'cltbld', 02:08:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:08:29 INFO - 'MOZ_NO_REMOTE': '1', 02:08:29 INFO - 'NO_EM_RESTART': '1', 02:08:29 INFO - 'PAGER': '/bin/cat', 02:08:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:08:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:08:29 INFO - 'PWD': '/builds/slave/test', 02:08:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:08:29 INFO - 'SHELL': '/bin/bash', 02:08:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hS5VYaaqfj/Listeners', 02:08:29 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 02:08:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:08:29 INFO - 'USER': 'cltbld', 02:08:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:08:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:08:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:08:29 INFO - 'XPC_FLAGS': '0x0', 02:08:29 INFO - 'XPC_SERVICE_NAME': '0', 02:08:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:08:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:08:29 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 02:08:29 INFO - Cleaning up... 02:08:29 INFO - Return code: 0 02:08:29 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:08: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:08:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:08:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:08: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:08:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:08:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:08:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109996490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10990c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ed34b8480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1097aad30>, '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 0x109610db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1097a2b20>, '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.bBTW7nuoo2/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.hS5VYaaqfj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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:08:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 02:08:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 02:08:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bBTW7nuoo2/Render', 02:08:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:08:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:08:29 INFO - 'HOME': '/Users/cltbld', 02:08:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:08:29 INFO - 'LOGNAME': 'cltbld', 02:08:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:08:29 INFO - 'MOZ_NO_REMOTE': '1', 02:08:29 INFO - 'NO_EM_RESTART': '1', 02:08:29 INFO - 'PAGER': '/bin/cat', 02:08:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:08:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:08:29 INFO - 'PWD': '/builds/slave/test', 02:08:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:08:29 INFO - 'SHELL': '/bin/bash', 02:08:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hS5VYaaqfj/Listeners', 02:08:29 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 02:08:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:08:29 INFO - 'USER': 'cltbld', 02:08:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:08:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:08:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:08:29 INFO - 'XPC_FLAGS': '0x0', 02:08:29 INFO - 'XPC_SERVICE_NAME': '0', 02:08:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:08:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:08:30 INFO - Downloading/unpacking psutil>=3.1.1 02:08:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:08:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:08:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:08:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:08:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:08:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:08:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:08:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:08:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:08:34 INFO - Installing collected packages: psutil 02:08:34 INFO - Running setup.py install for psutil 02:08:34 INFO - building 'psutil._psutil_osx' extension 02:08:34 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 02:08:34 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 02:08:34 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 02:08:34 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 02:08:34 INFO - building 'psutil._psutil_posix' extension 02:08:34 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 02:08:34 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:08:34 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:08:34 INFO - ^ 02:08:34 INFO - 1 warning generated. 02:08:34 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:08:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:08:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:08:35 INFO - Successfully installed psutil 02:08:35 INFO - Cleaning up... 02:08:35 INFO - Return code: 0 02:08:35 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:08:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:08:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:08:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:08:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:08:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:08:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:08:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109996490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10990c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ed34b8480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1097aad30>, '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 0x109610db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1097a2b20>, '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.bBTW7nuoo2/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.hS5VYaaqfj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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:08:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 02:08:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 02:08:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bBTW7nuoo2/Render', 02:08:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:08:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:08:35 INFO - 'HOME': '/Users/cltbld', 02:08:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:08:35 INFO - 'LOGNAME': 'cltbld', 02:08:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:08:35 INFO - 'MOZ_NO_REMOTE': '1', 02:08:35 INFO - 'NO_EM_RESTART': '1', 02:08:35 INFO - 'PAGER': '/bin/cat', 02:08:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:08:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:08:35 INFO - 'PWD': '/builds/slave/test', 02:08:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:08:35 INFO - 'SHELL': '/bin/bash', 02:08:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hS5VYaaqfj/Listeners', 02:08:35 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 02:08:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:08:35 INFO - 'USER': 'cltbld', 02:08:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:08:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:08:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:08:35 INFO - 'XPC_FLAGS': '0x0', 02:08:35 INFO - 'XPC_SERVICE_NAME': '0', 02:08:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:08:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:08:35 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:08:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:08:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:08:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:08:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:08:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:08:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:08:38 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:08:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:08:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:08:38 INFO - Installing collected packages: mozsystemmonitor 02:08:38 INFO - Running setup.py install for mozsystemmonitor 02:08:38 INFO - Successfully installed mozsystemmonitor 02:08:38 INFO - Cleaning up... 02:08:38 INFO - Return code: 0 02:08:38 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:08: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:08:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:08:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:08: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:08:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:08:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:08:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109996490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10990c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ed34b8480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1097aad30>, '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 0x109610db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1097a2b20>, '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.bBTW7nuoo2/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.hS5VYaaqfj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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:08:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 02:08:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 02:08:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bBTW7nuoo2/Render', 02:08:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:08:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:08:38 INFO - 'HOME': '/Users/cltbld', 02:08:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:08:38 INFO - 'LOGNAME': 'cltbld', 02:08:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:08:38 INFO - 'MOZ_NO_REMOTE': '1', 02:08:38 INFO - 'NO_EM_RESTART': '1', 02:08:38 INFO - 'PAGER': '/bin/cat', 02:08:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:08:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:08:38 INFO - 'PWD': '/builds/slave/test', 02:08:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:08:38 INFO - 'SHELL': '/bin/bash', 02:08:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hS5VYaaqfj/Listeners', 02:08:38 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 02:08:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:08:38 INFO - 'USER': 'cltbld', 02:08:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:08:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:08:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:08:38 INFO - 'XPC_FLAGS': '0x0', 02:08:38 INFO - 'XPC_SERVICE_NAME': '0', 02:08:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:08:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:08:38 INFO - Downloading/unpacking jsonschema==2.5.1 02:08:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:08:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:08:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:08:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:08:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:08:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:08:41 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:08:41 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:08:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:08:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:08:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:08:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:08:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:08:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:08:41 INFO - Downloading functools32-3.2.3-2.tar.gz 02:08:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:08:41 INFO - warning: no files found matching '*.txt' 02:08:41 INFO - no previously-included directories found matching 'build' 02:08:41 INFO - no previously-included directories found matching 'dist' 02:08:41 INFO - no previously-included directories found matching '.git*' 02:08:41 INFO - Installing collected packages: jsonschema, functools32 02:08:41 INFO - Running setup.py install for functools32 02:08:41 INFO - warning: no files found matching '*.txt' 02:08:41 INFO - no previously-included directories found matching 'build' 02:08:41 INFO - no previously-included directories found matching 'dist' 02:08:41 INFO - no previously-included directories found matching '.git*' 02:08:41 INFO - Successfully installed jsonschema functools32 02:08:41 INFO - Cleaning up... 02:08:42 INFO - Return code: 0 02:08:42 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:08:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:08:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:08:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:08:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:08:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:08:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:08:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109996490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10990c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ed34b8480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1097aad30>, '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 0x109610db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1097a2b20>, '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.bBTW7nuoo2/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.hS5VYaaqfj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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:08:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 02:08:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 02:08:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bBTW7nuoo2/Render', 02:08:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:08:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:08:42 INFO - 'HOME': '/Users/cltbld', 02:08:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:08:42 INFO - 'LOGNAME': 'cltbld', 02:08:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:08:42 INFO - 'MOZ_NO_REMOTE': '1', 02:08:42 INFO - 'NO_EM_RESTART': '1', 02:08:42 INFO - 'PAGER': '/bin/cat', 02:08:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:08:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:08:42 INFO - 'PWD': '/builds/slave/test', 02:08:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:08:42 INFO - 'SHELL': '/bin/bash', 02:08:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hS5VYaaqfj/Listeners', 02:08:42 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 02:08:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:08:42 INFO - 'USER': 'cltbld', 02:08:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:08:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:08:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:08:42 INFO - 'XPC_FLAGS': '0x0', 02:08:42 INFO - 'XPC_SERVICE_NAME': '0', 02:08:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:08:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:08:42 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:08:42 INFO - Cleaning up... 02:08:42 INFO - Return code: 0 02:08:42 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:08:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:08:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:08:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:08:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:08:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:08:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:08:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109996490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10990c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ed34b8480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1097aad30>, '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 0x109610db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1097a2b20>, '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.bBTW7nuoo2/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.hS5VYaaqfj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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:08:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 02:08:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 02:08:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bBTW7nuoo2/Render', 02:08:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:08:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:08:42 INFO - 'HOME': '/Users/cltbld', 02:08:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:08:42 INFO - 'LOGNAME': 'cltbld', 02:08:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:08:42 INFO - 'MOZ_NO_REMOTE': '1', 02:08:42 INFO - 'NO_EM_RESTART': '1', 02:08:42 INFO - 'PAGER': '/bin/cat', 02:08:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:08:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:08:42 INFO - 'PWD': '/builds/slave/test', 02:08:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:08:42 INFO - 'SHELL': '/bin/bash', 02:08:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hS5VYaaqfj/Listeners', 02:08:42 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 02:08:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:08:42 INFO - 'USER': 'cltbld', 02:08:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:08:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:08:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:08:42 INFO - 'XPC_FLAGS': '0x0', 02:08:42 INFO - 'XPC_SERVICE_NAME': '0', 02:08:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:08:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:08:42 INFO - Downloading/unpacking blobuploader==1.2.4 02:08:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:08:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:08:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:08:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:08:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:08:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:08:45 INFO - Downloading blobuploader-1.2.4.tar.gz 02:08:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:08:45 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:08: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:08: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:08: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:08: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:08: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:08: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:08:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:08:45 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:08: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:08: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:08: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:08: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:08: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:08: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:08:46 INFO - Downloading docopt-0.6.1.tar.gz 02:08:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:08:46 INFO - Installing collected packages: blobuploader, requests, docopt 02:08:46 INFO - Running setup.py install for blobuploader 02:08:46 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:08:46 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:08:46 INFO - Running setup.py install for requests 02:08:47 INFO - Running setup.py install for docopt 02:08:47 INFO - Successfully installed blobuploader requests docopt 02:08:47 INFO - Cleaning up... 02:08:47 INFO - Return code: 0 02:08:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:08:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:08:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:08:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:08:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:08:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:08:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:08:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109996490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10990c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ed34b8480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1097aad30>, '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 0x109610db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1097a2b20>, '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.bBTW7nuoo2/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.hS5VYaaqfj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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:08:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:08:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:08:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bBTW7nuoo2/Render', 02:08:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:08:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:08:47 INFO - 'HOME': '/Users/cltbld', 02:08:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:08:47 INFO - 'LOGNAME': 'cltbld', 02:08:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:08:47 INFO - 'MOZ_NO_REMOTE': '1', 02:08:47 INFO - 'NO_EM_RESTART': '1', 02:08:47 INFO - 'PAGER': '/bin/cat', 02:08:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:08:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:08:47 INFO - 'PWD': '/builds/slave/test', 02:08:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:08:47 INFO - 'SHELL': '/bin/bash', 02:08:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hS5VYaaqfj/Listeners', 02:08:47 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 02:08:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:08:47 INFO - 'USER': 'cltbld', 02:08:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:08:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:08:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:08:47 INFO - 'XPC_FLAGS': '0x0', 02:08:47 INFO - 'XPC_SERVICE_NAME': '0', 02:08:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:08:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:08:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:08:47 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-HQkV1x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:08:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:08:47 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-QNMta7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:08:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:08:47 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-1JgndN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:08:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:08:48 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-7vjVK6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:08:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:08:48 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-9eYm7Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:08:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:08:48 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-ftrm7y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:08:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:08:48 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-z2S9Xo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:08:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:08:48 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-cYd7WZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:08:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:08:48 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-9Nl6ID-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:08:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:08:48 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-eVpdIg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:08:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:08:48 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-GIaI1s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:08:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:08:48 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-IHJxXd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:08:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:08:48 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-jzFpge-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:08:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:08:49 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-5_YsEg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:08:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:08:49 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-9nhz49-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:08:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:08:49 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-XbNs1u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:08:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:08:49 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-Tb3XAZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:08:49 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:08:49 INFO - Running setup.py install for manifestparser 02:08:49 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:08:49 INFO - Running setup.py install for mozcrash 02:08:49 INFO - Running setup.py install for mozdebug 02:08:49 INFO - Running setup.py install for mozdevice 02:08:50 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:08:50 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:08:50 INFO - Running setup.py install for mozfile 02:08:50 INFO - Running setup.py install for mozhttpd 02:08:50 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:08:50 INFO - Running setup.py install for mozinfo 02:08:50 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:08:50 INFO - Running setup.py install for mozInstall 02:08:50 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:08:50 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:08:50 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:08:50 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:08:50 INFO - Running setup.py install for mozleak 02:08:50 INFO - Running setup.py install for mozlog 02:08:50 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:08:50 INFO - Running setup.py install for moznetwork 02:08:51 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:08:51 INFO - Running setup.py install for mozprocess 02:08:51 INFO - Running setup.py install for mozprofile 02:08:51 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:08:51 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:08:51 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:08:51 INFO - Running setup.py install for mozrunner 02:08:51 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:08:51 INFO - Running setup.py install for mozscreenshot 02:08:51 INFO - Running setup.py install for moztest 02:08:51 INFO - Running setup.py install for mozversion 02:08:52 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:08:52 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:08:52 INFO - Cleaning up... 02:08:52 INFO - Return code: 0 02:08:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:08:52 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:08:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:08:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:08:52 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:08:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:08:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:08:52 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 0x109996490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10990c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ed34b8480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1097aad30>, '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 0x109610db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1097a2b20>, '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.bBTW7nuoo2/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.hS5VYaaqfj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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:08:52 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:08:52 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:08:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bBTW7nuoo2/Render', 02:08:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:08:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:08:52 INFO - 'HOME': '/Users/cltbld', 02:08:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:08:52 INFO - 'LOGNAME': 'cltbld', 02:08:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:08:52 INFO - 'MOZ_NO_REMOTE': '1', 02:08:52 INFO - 'NO_EM_RESTART': '1', 02:08:52 INFO - 'PAGER': '/bin/cat', 02:08:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:08:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:08:52 INFO - 'PWD': '/builds/slave/test', 02:08:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:08:52 INFO - 'SHELL': '/bin/bash', 02:08:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hS5VYaaqfj/Listeners', 02:08:52 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 02:08:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:08:52 INFO - 'USER': 'cltbld', 02:08:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:08:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:08:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:08:52 INFO - 'XPC_FLAGS': '0x0', 02:08:52 INFO - 'XPC_SERVICE_NAME': '0', 02:08:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:08:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:08:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:08:52 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-KYyOFI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:08:52 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 02:08:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:08:52 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-lJ5JdK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:08:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:08:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:08:52 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-iFsYE_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:08:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:08:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:08:52 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-ls7hqi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:08:52 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:08:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:08:52 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-fgTLfa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:08:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 02:08:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:08:52 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-chOOoi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:08:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 02:08:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:08:52 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-Wsy_O4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:08:53 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:08:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:08:53 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-54jc5B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:08:53 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:08:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:08:53 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-tsv9_d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:08:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 02:08:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:08:53 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-jklx9H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:08:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:08:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:08:53 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-pb6kZV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:08:53 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 02:08:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:08:53 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-IGslBE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:08:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 02:08:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:08:53 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-GsZFlk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:08:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 02:08:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:08:53 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-hn62n8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:08:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 02:08:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:08:53 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-J0DjVp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:08:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 02:08:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:08:53 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-kS6nCC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:08:54 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:08:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:08:54 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-mbscJn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:08:54 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:08:54 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:08:54 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:08:54 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:08:54 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:08:54 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:08:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:08:54 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:08:54 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:08:54 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:08:54 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:08:54 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:08:54 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:08:56 INFO - Downloading blessings-1.6.tar.gz 02:08:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:08:56 INFO - Installing collected packages: blessings 02:08:56 INFO - Running setup.py install for blessings 02:08:57 INFO - Successfully installed blessings 02:08:57 INFO - Cleaning up... 02:08:57 INFO - Return code: 0 02:08:57 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:08:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:08:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:08:57 INFO - Reading from file tmpfile_stdout 02:08:57 INFO - Current package versions: 02:08:57 INFO - blessings == 1.6 02:08:57 INFO - blobuploader == 1.2.4 02:08:57 INFO - docopt == 0.6.1 02:08:57 INFO - functools32 == 3.2.3-2 02:08:57 INFO - jsonschema == 2.5.1 02:08:57 INFO - manifestparser == 1.1 02:08:57 INFO - mozInstall == 1.12 02:08:57 INFO - mozcrash == 1.0 02:08:57 INFO - mozdebug == 0.1 02:08:57 INFO - mozdevice == 0.48 02:08:57 INFO - mozfile == 1.2 02:08:57 INFO - mozhttpd == 0.7 02:08:57 INFO - mozinfo == 0.9 02:08:57 INFO - mozleak == 0.1 02:08:57 INFO - mozlog == 3.4 02:08:57 INFO - moznetwork == 0.27 02:08:57 INFO - mozprocess == 0.23 02:08:57 INFO - mozprofile == 0.28 02:08:57 INFO - mozrunner == 6.13 02:08:57 INFO - mozscreenshot == 0.1 02:08:57 INFO - mozsystemmonitor == 0.3 02:08:57 INFO - moztest == 0.7 02:08:57 INFO - mozversion == 1.4 02:08:57 INFO - psutil == 3.1.1 02:08:57 INFO - requests == 1.2.3 02:08:57 INFO - wsgiref == 0.1.2 02:08:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:08:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:08:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:08:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:08:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:08:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:08:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:08:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109996490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10990c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ed34b8480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1097aad30>, '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 0x109610db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1097a2b20>, '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.bBTW7nuoo2/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.hS5VYaaqfj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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:08:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 02:08:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:08:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bBTW7nuoo2/Render', 02:08:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:08:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:08:57 INFO - 'HOME': '/Users/cltbld', 02:08:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:08:57 INFO - 'LOGNAME': 'cltbld', 02:08:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:08:57 INFO - 'MOZ_NO_REMOTE': '1', 02:08:57 INFO - 'NO_EM_RESTART': '1', 02:08:57 INFO - 'PAGER': '/bin/cat', 02:08:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:08:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:08:57 INFO - 'PWD': '/builds/slave/test', 02:08:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:08:57 INFO - 'SHELL': '/bin/bash', 02:08:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hS5VYaaqfj/Listeners', 02:08:57 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 02:08:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:08:57 INFO - 'USER': 'cltbld', 02:08:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:08:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:08:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:08:57 INFO - 'XPC_FLAGS': '0x0', 02:08:57 INFO - 'XPC_SERVICE_NAME': '0', 02:08:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:08:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:08:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:08:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 02:08:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 02:08:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:08:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 02:08:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 02:08:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 02:08:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 02:08:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 02:08:57 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:08:57 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:08:57 INFO - Cleaning up... 02:08:57 INFO - Return code: 0 02:08:57 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:08:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:08:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:08:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:08:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:08:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:08:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:08:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109996490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10990c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ed34b8480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1097aad30>, '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 0x109610db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1097a2b20>, '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.bBTW7nuoo2/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.hS5VYaaqfj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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:08:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 02:08:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 02:08:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bBTW7nuoo2/Render', 02:08:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:08:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:08:57 INFO - 'HOME': '/Users/cltbld', 02:08:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:08:57 INFO - 'LOGNAME': 'cltbld', 02:08:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:08:57 INFO - 'MOZ_NO_REMOTE': '1', 02:08:57 INFO - 'NO_EM_RESTART': '1', 02:08:57 INFO - 'PAGER': '/bin/cat', 02:08:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:08:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:08:57 INFO - 'PWD': '/builds/slave/test', 02:08:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:08:57 INFO - 'SHELL': '/bin/bash', 02:08:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hS5VYaaqfj/Listeners', 02:08:57 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 02:08:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:08:57 INFO - 'USER': 'cltbld', 02:08:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:08:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:08:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:08:57 INFO - 'XPC_FLAGS': '0x0', 02:08:57 INFO - 'XPC_SERVICE_NAME': '0', 02:08:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:08:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:08:57 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:08:57 INFO - Cleaning up... 02:08:57 INFO - Return code: 0 02:08:57 INFO - Running post-action listener: _resource_record_post_action 02:08:57 INFO - Running post-action listener: _start_resource_monitoring 02:08:57 INFO - Starting resource monitoring. 02:08:58 INFO - [mozharness: 2016-12-25 10:08:58.001357Z] Finished create-virtualenv step (success) 02:08:58 INFO - [mozharness: 2016-12-25 10:08:58.002416Z] Running install step. 02:08:58 INFO - Running pre-action listener: _resource_record_pre_action 02:08:58 INFO - Running main action method: install 02:08:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:08:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:08:58 INFO - Reading from file tmpfile_stdout 02:08:58 INFO - Detecting whether we're running mozinstall >=1.0... 02:08:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:08:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:08:58 INFO - Reading from file tmpfile_stdout 02:08:58 INFO - Output received: 02:08:58 INFO - Usage: mozinstall [options] installer 02:08:58 INFO - Options: 02:08:58 INFO - -h, --help show this help message and exit 02:08:58 INFO - -d DEST, --destination=DEST 02:08:58 INFO - Directory to install application into. [default: 02:08:58 INFO - "/builds/slave/test"] 02:08:58 INFO - --app=APP Application being installed. [default: firefox] 02:08:58 INFO - mkdir: /builds/slave/test/build/application 02:08:58 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:08:58 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:09:29 INFO - Reading from file tmpfile_stdout 02:09:29 INFO - Output received: 02:09:29 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 02:09:29 INFO - Running post-action listener: _resource_record_post_action 02:09:29 INFO - [mozharness: 2016-12-25 10:09:29.302713Z] Finished install step (success) 02:09:29 INFO - [mozharness: 2016-12-25 10:09:29.302831Z] Running run-tests step. 02:09:29 INFO - Running pre-action listener: _resource_record_pre_action 02:09:29 INFO - Running main action method: run_tests 02:09:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:09:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:09:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:09:29 INFO - 2016-12-25 02:09:29.317 screenresolution[2249:28350] starting screenresolution argv=screenresolution get 02:09:29 INFO - 2016-12-25 02:09:29.320 screenresolution[2249:28350] Display 0: 1600x1200x32@60 02:09:29 INFO - 2016-12-25 02:09:29.326 screenresolution[2250:28352] starting screenresolution argv=screenresolution list 02:09:29 INFO - Available Modes on Display 0 02:09:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:09:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:09:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:09:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:09:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:09:29 INFO - Intel Iris: 02:09:29 INFO - Chipset Model: Intel Iris 02:09:29 INFO - Type: GPU 02:09:29 INFO - Bus: Built-In 02:09:29 INFO - VRAM (Dynamic, Max): 1536 MB 02:09:29 INFO - Vendor: Intel (0x8086) 02:09:29 INFO - Device ID: 0x0a2e 02:09:29 INFO - Revision ID: 0x0009 02:09:29 INFO - Displays: 02:09:29 INFO - Display: 02:09:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:09:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:09:29 INFO - Display Serial Number: 1600x1200 60 02:09:29 INFO - Main Display: Yes 02:09:29 INFO - Mirror: Off 02:09:29 INFO - Online: Yes 02:09:29 INFO - Rotation: Supported 02:09:29 INFO - Return code: 0 02:09:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:09:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:09:29 INFO - Python 2.7.3 02:09:29 INFO - Return code: 0 02:09:29 INFO - grabbing minidump binary from tooltool 02:09: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:09:29 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 0x1097aad30>, '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 0x109610db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1097a2b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:09:29 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:09:29 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:09:29 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:09:29 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:09:29 INFO - Return code: 0 02:09:29 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:09:29 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:09:29 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:09:29 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:09:29 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:09:29 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/d5SBpli3TheMWlhInPIL_A/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0342', '--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:09:29 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/d5SBpli3TheMWlhInPIL_A/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0342 --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:09:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bBTW7nuoo2/Render', 02:09:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:09:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:09:29 INFO - 'HOME': '/Users/cltbld', 02:09:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:09:29 INFO - 'LOGNAME': 'cltbld', 02:09:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:09:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:09:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:09:29 INFO - 'MOZ_NO_REMOTE': '1', 02:09:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:09:29 INFO - 'NO_EM_RESTART': '1', 02:09:29 INFO - 'PAGER': '/bin/cat', 02:09:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:09:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:09:29 INFO - 'PWD': '/builds/slave/test', 02:09:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:09:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:09:29 INFO - 'SHELL': '/bin/bash', 02:09:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hS5VYaaqfj/Listeners', 02:09:29 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 02:09:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:09:29 INFO - 'USER': 'cltbld', 02:09:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:09:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:09:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:09:29 INFO - 'XPC_FLAGS': '0x0', 02:09:29 INFO - 'XPC_SERVICE_NAME': '0', 02:09:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:09:29 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/d5SBpli3TheMWlhInPIL_A/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0342', '--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:09:29 INFO - mozversion application_buildid: 20161225004004 02:09:29 INFO - mozversion application_changeset: 1deac793ef1dc655f2095e1f8d15deb32e2f666f 02:09:29 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:09:29 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:09:29 INFO - mozversion application_name: Firefox 02:09:29 INFO - mozversion application_remotingname: firefox-dev 02:09:29 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:09:29 INFO - mozversion application_vendor: Mozilla 02:09:29 INFO - mozversion application_version: 52.0a2 02:09:29 INFO - mozversion platform_buildid: 20161225004004 02:09:29 INFO - mozversion platform_changeset: 1deac793ef1dc655f2095e1f8d15deb32e2f666f 02:09:29 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:09:29 INFO - mozversion platform_version: 52.0a2 02:09:29 INFO - using testdate: 1482660569 02:09:29 INFO - actual date: 1482660569 02:09:29 INFO - starting webserver on 'localhost:49468' 02:09:29 INFO - SUITE-START | Running 2 tests 02:09:29 INFO - TEST-START | dromaeo_css 02:09:29 INFO - operating with platform_type : mac_ 02:09:29 INFO - Initialising browser for dromaeo_css test... 02:09:29 INFO - TEST-INFO | started process 2260 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/tmp4VCrGQ/profile http://localhost:49468/getInfo.html) 02:09:32 INFO - PROCESS | 2260 | __metrics Screen width/height:1600/1200 02:09:32 INFO - PROCESS | 2260 | colorDepth:24 02:09:32 INFO - PROCESS | 2260 | Browser inner width/height: 1024/689 02:09:32 INFO - PROCESS | 2260 | __metrics 02:09:32 INFO - PROCESS | 2260 | 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:09:32 INFO - PROCESS | 2260 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:09:33 INFO - TEST-INFO | 2260: exit 0 02:09:33 INFO - Browser initialized. 02:09:33 INFO - Running cycle 1/1 for dromaeo_css test... 02:09:33 INFO - TEST-INFO | started process 2263 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/tmp4VCrGQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:09:46 INFO - PROCESS | 2263 | RSS: Main: 222945280 02:09:46 INFO - PROCESS | 2263 | 02:10:37 INFO - PROCESS | 2263 | 1482660637250 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] 02:12:51 INFO - PROCESS | 2263 | Cycle 1(1): loaded http://localhost:49468/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49468/tests/dromaeo/cssquery-ext.html) 02:12:53 INFO - PROCESS | 2263 | RSS: Main: 375066624 02:12:53 INFO - PROCESS | 2263 | 02:15:55 INFO - PROCESS | 2263 | Cycle 1(1): loaded http://localhost:49468/tests/dromaeo/cssquery-ext.html (next: http://localhost:49468/tests/dromaeo/cssquery-jquery.html) 02:15:56 INFO - PROCESS | 2263 | RSS: Main: 349159424 02:15:56 INFO - PROCESS | 2263 | 02:18:58 INFO - PROCESS | 2263 | Cycle 1(1): loaded http://localhost:49468/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49468/tests/dromaeo/cssquery-mootools.html) 02:18:59 INFO - PROCESS | 2263 | RSS: Main: 313012224 02:18:59 INFO - PROCESS | 2263 | 02:22:01 INFO - PROCESS | 2263 | Cycle 1(1): loaded http://localhost:49468/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49468/tests/dromaeo/cssquery-prototype.html) 02:22:02 INFO - PROCESS | 2263 | RSS: Main: 315011072 02:22:02 INFO - PROCESS | 2263 | 02:25:05 INFO - PROCESS | 2263 | Cycle 1(1): loaded http://localhost:49468/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49468/tests/dromaeo/cssquery-yui.html) 02:25:06 INFO - PROCESS | 2263 | RSS: Main: 306913280 02:25:06 INFO - PROCESS | 2263 | 02:28:09 INFO - PROCESS | 2263 | Cycle 1(1): loaded http://localhost:49468/tests/dromaeo/cssquery-yui.html (next: http://localhost:49468/tests/dromaeo/cssquery-dojo.html) 02:28:09 INFO - PROCESS | 2263 | RSS: Main: 374231040 02:28:09 INFO - PROCESS | 2263 | 02:28:09 INFO - PROCESS | 2263 | __start_tp_report 02:28:09 INFO - PROCESS | 2263 | _x_x_mozilla_page_load 02:28:09 INFO - PROCESS | 2263 | _x_x_mozilla_page_load_details 02:28:09 INFO - PROCESS | 2263 | |i|pagename|runs| 02:28:09 INFO - PROCESS | 2263 | |0;dojo.html;3292.325073359961;3307.8676852925882;3317.0546394277626;3342.0976336389162;3375.578052743407;5629.549636029118;5659;5686.687232202233;5696.857578560528;5768.1059435787465;5629.408912064236;5710.971445142768;5718.485336319731;5722.456366645172;5747.511461525775;4271.316589345705;5173.689578625275;5198.2722418861385;5214.270002199704;5262;5158.097332966716;5177.48225177483;5179.974100129494;5182.8704282393055;5235.738213089349;5259.105951988152;5284.154535274357;5307.601929855257;5308.761105750231;5334.893302133955;5230.189320655286;5266.710330931797;5268.736563171864;5277.12927366984;5326.6803991760235;4985.950140498585;4995.950040499585;5051.1413059779925;5193.662411943212;5195.272661827347;5591.524720398771;5636.267285252891;5646.774129034834;5707.686077265748;5725.3988331225455;6152.107944348089;6195.504359651217;6204.565680402374;6265.7493700251935;6273.62358258506;6126.142340072393;6279.246490421133;6316.589421687576;6335.271443783931;6364.649944253064;5143.408508021588;5700.543956483471;5717.285339332583;5729.713514324267;5756.884862302731;6274.372562743689;6301.905471417841;6311.810645680637;6312.368763123651;6328.398802113792;6106.023036314168;6113.755449782047;6122.969385153046;6123.234595675541;6126.724297406628;6100.23747031621;6147.139400483936;6148.385161483905;6149.77004599082;6233.002719564848;6210.099535567318;6212.844678882973;6225.875482490415;6232.002879539252;6233.065040244;6101.969490152521;6224.9377506224355;6272.3100458949475;6275.843103922439;6279.654618995908;4800.855974320776;4860.80556777732;4863.586595139453;4879.4876537963;4913.803447862117;4341.739495630272;4407.360932664746;4414.779261036935;4425.822967081281;4501.167284052461;5724.370319264877;5748.913766293509;5762.423757624288;5763.020286551213;5780.421957804186;6002.789902353396;6249.562530622813;6250.968745156245;6277.686115694197;6289.779857704958;5603.5237004854225;5664.688442135722;5667.489925906688;5724.971375143181;5744.597878148573;5402.52708479521;5405.243265942771;5409.594280428983;5410.864728381822;5417.160340147283;6272.090546870678;6299.370062993848;6303.180586523723;6306.873862522815;6307.495400368051;6005.39946005396;6111.470193407107;6116.1836049259855;6155.784547540815;6258.812235632939;6273.686315684289;6275.843103922439;6307.180066591313;6309.1167236587835;6313.779017734358;6088.299845517822;6177.598456100339;6191.349908259568;6198.039303907902;6201.410865967726;6073.423024812636;6143.047827586731;6228.657423841732;6269.529785266214;6271.560990730787;5870.324912635102;5940.584159108994;6020.849478762891;6084.269887613515;6138.143176733788;6077.908831367445;6080.361562035922;6083.513318934386;6088.8173354797655;6113.724882380393;5855.795047173328;5880.382559831156;5912.201852749824;5922.851928701817;5929.9110513341475;5902.1441890926435;5958.910616340671;5964.7912323068485;6042.90935635957;6101.725422355916;5924.822255332347;5929.673867937305;5957.434043765836;5969.582129251085;5977.790777322773 02:28:09 INFO - PROCESS | 2263 | |1;ext.html;480.6755440077949;512.5924889712678;520.6641716093119;528.1734086155169;549.6454786662605;31402.842985785068;40128.1974360513;41454.37818432722;41540.58459415405;43158;41646.95882602937;42426;42748.78625606868;42773.57226427743;42936.35595466063;9257.305702072355;9435.952820235889;9450.716478505656;9452.62189512422;9453.385529940566;8107.513549187068;9451.054894510562;9555.856662150074;9561.28290378221;9561.426314421122;9521.761955951122;9593.376430532027;9599.376040557341;9601.711948641552;9616.759581010461;9509.80980380392;9536.666216682452;9558.235341172689;9562.90437095627;9565.904340956571;9375.906240937571;9442.905570944271;9466.810663786755;9473.526323683856;9484.05159484053;39904.40143397851;40126;40190;40193.79903100495;40248;3329.583802024747;3382.915427114342;3432.5022871683714;3453.3611281912927;3636.7817930923966;28559.428811423655;28729;29945.850270748506;30818.537721933964;31038.379232415453;3062.142600071961;3230.176305042223;3260.5924259467565;3298.472244440902;3337.8664853406076;496119;500464;501820;502430;503159.4842025803;57930.71034644884;58050;58057.419425806045;58077.4192258072;58090.12864807031;65098;65290.673546631966;65399;65415.34584654188;65444.34555654478;16274.942128761593;16472.42346517866;16490.422835200712;16537.586560336087;16554.751678724828;12718.55485058037;12780.61658150257;12788.9129424;12809.871901281054;12812.935935320264;17033.574160645836;17725;17801.46595602134;17955.192016359295;18010.729839052423;5048.72232027242;5403.13549832025;6056.242969628796;6091.8477038073515;6185.659788711664;898.8561830107283;906.8730377747121;969.743018100204;971.1939090554964;991.1020395941691;23863.880680596485;28438.431231375256;28463.146105616866;29181.83272669112;29925.40149197004;1011.2870426349406;1030.7991190263408;1047.617619568852;1088.1240601315858;1122.7754449110212;11729.941350293195;14859.108453492825;15217.467388641344;15247.618809529851;15840.76238856418;636444;641328;641664;643691;645873;58348;58550;58593.70703146457;58653.65385384622;58826.411735882946;1236.3818090954524;1291.5350473829415;1347.4408120630026;1359.2116572387793;1409.4996276321963;23907.701320041706;30211.848940755157;30340.54489182708;30626.387472250877;31002.844985774926;50218.49781502284;50570;50669.746651266505;50759.492405076955;50803.74598126986;545300;548548;549269;610839;639443;25161.119360822282;25426.618600720634;25486.617700734132;25713.614295785203;25741.613875792253;44659.55340446684;44711.55288447074;45181.0963780716;45301.546984529734;45398;31773.84113079512;34650;36624.63375366212;37504;37546.436803448516;37368.81315593405;37394.25211495809;37425;37478;37683;30417;36193.63806361903;36694.89915302545;37488.25023499569;37644;31864.522032169072;33396.66603334033;33538.83230583832;34651.4802277961;34703.826480867436;23511.41221469477;23583.29250122499;25029.74970250274;25515.234542963743;26032.479350413265 02:28:09 INFO - PROCESS | 2263 | |2;jquery.html;287.9755220806232;307.3024234986582;345.9636738142493;367.77381910125274;398.1241269207742;15147.621309467255;16167.514974550768;19493.512662183435;19936.90031549844;20649.690254646193;17071;19968.101435435405;20444.8977755111;20635.793642063574;20873.060712267947;5247.842564723055;5650.858728531778;5719.914201286984;5722;5723.771049158028;3623.3659109655837;4081.469408976829;5627.155926611;5722.341930677983;5737.655740655553;5596.524295434893;6303.275123360826;6339.683015849212;6387.169667943161;6389;6128.877422451548;6228.626282423069;6295.842603934938;6300.149479820211;6366.36336366362;5520.392756796749;5521.089020311644;5530.170474428868;5537.473939975695;5544.6118771686;23883.805809709444;24456.021759129613;24482.265532034067;24528.14151504689;24771.752282477304;1914.6936490161647;2308.319045881469;2325.5000174962297;2452.6198439241766;2487.738787427312;8843.69047083349;9209.861852072225;9338.719838404859;9444.150026497648;9561.378510396871;1480.7778833175003;1658.336665333864;1709.2906443825798;1733.2806885142777;1766.8586513078924;86698.56650716707;119097.8090219104;119137.8086219144;119149;120165.39917300445;9239.122283383067;9840;9933.602655893828;10524.736881578021;10738.9261073892;12248.38758062111;12288.938555307166;12355.69110772238;12379.876201238052;12394.380280985915;7003.774339490569;7719.266669666373;7902.604869756489;7977.3618110551015;8104.473209241381;6711.563748356341;6986;8333.166683331618;8464.915350846535;8495.490270583785;3805.162864169878;3838.251540949488;3858.7298889078056;3881.3983832506005;3959.6634286085427;1841.8066103058986;1895.5166432559886;1897.2221389230626;1912.1586501939103;1920.2511020701938;808.931240844523;876.9035406105323;973.8977407372124;979.2704435195825;1001.0690058245756;19909.502262443264;21192.470188245108;21274.042668079663;21373.46566335823;21393.25123620697;867.3104881619128;934;934.1825902335456;936.8782058332373;947.507296205969;9444.669436569824;10259.897401026043;10737.463126843782;11055.834162487568;11216.607418740305;571836;586904;588886;590566;596453;77562.2243777555;83340.58329708313;111699.44150279196;136775.31612341874;139264;997.3168379659957;999.9400035997863;1000.6647772996128;1002.1081237698454;1028.9485525723685;18582.25670973146;19085.141168647166;19429;19513.121909513822;19569.60860782805;50246;51297.743511282206;54321;56144.47349869347;58395.70802145962;72370;73356.53286934124;73451.8982215278;75348.86976695244;82435;6466.579672321284;7049.330313620198;7620.885686714593;7850.2542258485355;7927.563983980705;11396.886031139915;12203.816942746043;12516.624501264976;12563.87436125627;12606.621801345975;8613.612387442456;9692.46691431978;10353.534090966075;10450.47747612601;11121;8911.55442227873;10423.374597524173;10442.947785261025;10612.097971672494;10967.341959482457;8933.419327743675;9061.04858989823;10309.587616495257;10696.572137114428;10852.077573406348;7739.419543534144;7783.688652454065;8012.759617211493;8058.717944871902;8106.47307924983;8766.430182038148;10584.682459526226;10692.411917344309;11084.889151108386;11138.554457821596 02:28:09 INFO - PROCESS | 2263 | |3;mootools.html;1448.7681970884657;1471.9337629806662;1623.634682196506;1780.6438712257552;1809.4842969753622;7951.801204969878;8388.993320801505;8389.161083891608;8392.328613710904;8400.075991640915;7399.852002959938;7625.923740762591;7693.230676932304;7926.009248843895;8211.91788082119;2300.0454811253308;2344.15610380263;2351.706036745407;2352.764723527651;2353.52929414117;2344.9765502345012;2364.73987861336;2376.976230237701;2385.654080158376;2394.9281521554294;2128.4040468668795;2229.5285111826197;2322.779335963081;2347.6361164019604;2359.7876191142796;2314.6643736658175;2316.119874447699;2342.5080733046166;2342.9297121086393;2344.120954642009;2275.5790178816806;2292.025888997186;2304.781045800646;2307.411610039446;2307.699999000136;8139.552324622144;8324.542150181738;8325.000999879992;8327;8343.374246931444;5018;5194.324737784102;5199.168133098686;5199.584033277367;5201.9479805201845;3106.0371284901753;3219.098652377338;3536.8054756988354;3536.840842162109;3539.185987222953;966.3525437956586;1044.2846650044744;1078.412265315405;1083.0685610375072;1092.6940456672144;10799.892001079967;10825.729356766065;10959.671209863716;10971;10977.12183025356;4523.050159466549;4669.976650116728;4753.811547113221;4780.450248221429;4787.353707249476;9348.859767103499;9349.719508414759;9357.01751316102;9360.15758581731;9365.01667324921;5218.556422704112;5224.869378265574;5227.320448341768;5229.895402091937;5236.162214045735;4036.555978842325;4213.262679031157;4787.473377928425;4805;4810.59109975656;3528.329617372691;3547.0600290922926;3804.1250512382308;3880.689544836406;3909.9022524436546;1254.2537190371713;1261.8738126187309;1262.110212300326;1276.8084787281982;1278.2202856257675;819.2176471469851;892.9864603674746;901.8737376767258;915.3729695158837;918.963241470347;5383.246345511631;5565.471280228372;5598.692071936082;5619.8595035124445;5636.2391077204875;419.95590463000946;445.681337843437;447.8343013085179;451.0752956439352;460.31413194340195;3689.686376658014;3772.13240954582;3786.1859700164596;3788.431735239681;3860;9789.902100979041;10063.19494440443;10149.238807089498;10172.491375431198;10207.693769186937;9428;9661.855072173923;9934.900650993543;9939.155171810333;10109.545070471846;5905.527557795366;7527.849443011219;7764.767056988299;7780.455368124177;7871.370290376871;4513.729176249435;5420.13277875538;5566.777328906798;5617.073182924772;5657.9151312731165;8802.25180859634;9242.353035287466;9308.581113850028;9349.626014959325;9439.858402123837;9370.344075914893;9435.433873967584;9575.521223938636;9596.568154433207;9618.711438656852;5965.701714914324;6268.404501572343;6899.689513971984;7457.291557302047;7817.5309481431295;7733.806654833674;7922.841543169219;7928.524288542706;7934.880976785237;7943.523388596703;6993.125859267591;7129.001939728339;7131.964340178266;7170.498065135599;7224.819379515554;6848;6970.65146742671;6976.988336691253;7119.501634885508;7125.857482850418;6601.3728695773825;6612.933870661232;6706.530542861953;6710;6724.125863637696;4789.4971028041555;4802.687825291485;4842.539958703917;4845.370101886732;4867.245576935581;4691.460482044608;4729.290606408928;4795.95204047955;4933.4819843915875;4937.333839726651 02:28:09 INFO - PROCESS | 2263 | |4;prototype.html;293.1440194631675;298.1250031158551;302.3484391137099;308.56338281331915;312.8639042016724;4794.736289504076;4889.21772516397;5177.456367081459;5181.611379146565;5261.368635763709;4644.256918892969;4773.856784296468;5004.699718016922;5007.774650140744;5041;3408.0798184490172;3935.527736671589;3986.880393588197;4005.8983779460664;4055.4930633670792;3142.3401085772016;3203.599550056243;3832.13776900198;3945.4081887716784;4052.5136983562015;3444.2594842108942;3774.7735135891935;3970.3051965905997;3986.740861843971;4016.2770701273757;3954.457761473028;3963.861264855745;3983.900402489961;3983.924340428083;3993.9001524961964;3198.856051477666;3311.9503207451908;3418.3163367326633;3428.5657183399817;3446.4313388291152;5120.283160357553;5137.7944882204665;5153.252778347119;5174.353205849269;5177.611679124081;4316.077264210181;4474.239379305526;4487.057717879249;4516.841910533149;4558.951441168517;4589.829593453681;4755;5491.423400542925;5498.532624726903;5523.944760552383;3759.270735461691;3776.9622303776887;3780.710781619981;3788.3370410178386;3800.3159431302356;5379.596530260246;5394.056040192951;5500.972495137538;5751.856203594902;5794.652320860761;3829.751066180689;3845.6346647069045;3849.730518863653;3860.2279544091293;3875.8643447479767;3901.31726947783;3903.2583809076186;3904.90237744053;3908.687305015592;3915.451836742858;4286.849960251377;4497.977510112429;4569.680122391467;4579.152856721517;4580.450345958507;4678.415198100238;5487.451254874481;5488.012157811552;5531.8893622128135;5564.27664403633;1015.6800607808665;1166.1778446195412;1168.5384273212035;1256.3718140929536;1260.9558665446666;787.7203592724501;798.6964953317819;808.3250485844268;818.5211651184125;828.4615000249886;4179.307380510898;4879.04838064777;4905.288733133676;4926.802927882844;4933.421305182307;5315.441878602768;5374.489423504839;5388.568914486832;5393.730313484389;5435.315052333789;4598.793054312564;4665.786895407151;4877.707337559757;5302.70835104073;5310.92033619496;2397.6283676030243;2524.9873750631127;2639.973600263973;2778.902333578266;2786.70739572346;5140.25231421311;5438.401775804658;5518.503334699816;5537.197106419627;5560.860978475571;4567.566081222279;4780.832670856504;4809.519048095232;5556.6943818089585;5566.860828479239;4864.878378040577;4888.291197776302;5505.6696598204235;5582.692951887685;5638.462691788794;4441.667499749998;5000.874453466583;5300.549453296513;5433.12044616354;5481.835544933659;5127.077126117335;5211.583073354198;5253.001929633358;5323.467653234646;5376.650517716336;4730.9763451182525;5433.673979561239;5547.645098597582;5580.888382232251;5615.803446879339;3601.7658852174527;3604.4953706480587;3605.3149901518627;3621.0766254604905;3626.782393056425;5294.947050529446;5695.971520142539;5743.310802703702;5744.798932037525;5762.654240745569;5554.333479982428;5567.526760225426;5597.384287728428;5597.888042239214;5611.579131565068;4215.262329092458;4625.306204069417;5483.369412517612;5548.9722551386985;5611;4882.072406242803;5496.230527726041;5503.20203570472;5507.669539827623;5519.365272993497;3991.900202494961;4932.7533623319405;4948.703077815343;5420.810271640474;5426;3885.7862817545306;5342.519173274424;5366;5452.345718513804;5528.281323427928 02:28:09 INFO - PROCESS | 2263 | |5;yui.html;699.4055053204777;703.2580627438055;717.6555253478333;748.5994992678915;783.4437549339966;13765.51820686275;14501.70996580069;16084.919575402137;16091.436799711983;16119.355225790969;12247.571335003277;14660.780088298685;14770.704585908275;14773.48292809752;14774.261286935664;650;797.8244786147038;818.5784321074651;819.213554987213;820.1962077164383;678.233596036478;690.1786873620375;796.9402294827883;813.8250276190618;832.6419639554989;857.7855536115972;876.6712482818942;879.226280872831;879.2438502887512;879.9912000880005;873.5239294584435;874.9256313213384;875.8905136857893;876.8202518483696;877.5042101212795;509.04553961322523;525.4902744337986;627.6391075131818;644.4232411991242;651.1892693596456;14343.21112338821;14348.85651143496;14350.282485875665;14355.928220358832;14365;12562.246265224025;12776.744465110645;12822.871771282262;12893.548725794646;12992.805107923388;12004.2936075702;12015.219010764273;12169;12195;12227.633171004794;4202.684798640114;4217.177650358181;4303.182395344875;4357.498887627931;4444.511103778582;88349.55825220897;90719.54640226823;90924.54537727269;91920;92023;8583.35624828128;10126;10318.535665895053;10370.844437333446;10429.634962776418;17175.742363864552;18685.439436816916;19364;19368.612627747367;19454;7426.517276377126;7484.214157513492;7489.400847932152;7572.697092116364;7679.19368466303;4104.15864747726;4218.978905105516;4247.5752424757275;4321.459817522809;4430.911381772346;3229.1442767666585;3386.0857568456377;3393.440082386428;3394.4229480988292;3410.0963244740165;616.5961295351552;624.6970219443566;628.177088014693;637.1525870592102;642.8939225027818;590.6485641043572;591.7721677154241;599.9620656264599;605.2374008749008;634.9396807303299;8091;8572.61423235956;8637;8654.697085601974;9044.954775226082;485.56784461829;545.3074595263993;594.0851089322396;604.7036951893629;611.7002668692397;3287.474004159371;3990.481237439172;4032.5967403259438;4045.838166473367;4100.323446631272;72575.6371218151;104005.47997259966;107083.9291607074;115657.4217128909;116124;93485.59771603432;95926.52036739772;102214;102507;103661;86214.56892715496;87064.56467717495;88037;88365;88649.55675221582;7557.900473111931;7731.729389471341;7743.845123097394;7902.130765615892;7981.960090199512;50467;51113.744431277606;53074.73462632662;53376.73311633417;55086.173707395224;67658;73300.63349683218;73566.89649655366;75763;77725.61137194278;10257.28199026061;10357.792844143225;10517.684469465928;10523.315984460985;10550.630727924487;11058.502367393647;11327.207095503236;11762.235454695418;11908.880911191123;12032.15774895784;10437.478126093512;10507.527161277914;10895.782084358427;12497.437615307515;13185.670358241121;12813.846753792204;12945.482180712666;13158.407871645608;13347.86652133505;13376.866231337563;11781;12265.754684906431;12823.358832058546;12866.614001579968;12914.22514649124;10435.32170408921;10551.841722374016;11020.118390528898;11171;11235.045021173291;11060.557577696802;11111;11373.658790236306;11423.714407139889;11463.082953363544 02:28:09 INFO - PROCESS | 2263 | __end_tp_report 02:28:09 INFO - PROCESS | 2263 | __start_cc_report 02:28:09 INFO - PROCESS | 2263 | _x_x_mozilla_cycle_collect,1919 02:28:09 INFO - PROCESS | 2263 | __end_cc_report 02:28:09 INFO - PROCESS | 2263 | __startTimestamp1482661689658__endTimestamp 02:28:09 INFO - PROCESS | 2263 | 02:28:09 INFO - PROCESS | 2263 | ------- Summary: start ------- 02:28:09 INFO - PROCESS | 2263 | Number of tests: 6 02:28:09 INFO - PROCESS | 2263 | 02:28:09 INFO - PROCESS | 2263 | [#0] dojo.html Cycles:165 Average:5720.48 Median:5929.67 stddev:647.77 (10.9%) stddev-sans-first:621.12 02:28:09 INFO - PROCESS | 2263 | Values: 3292.3 3307.9 3317.1 3342.1 3375.6 5629.5 5659.0 5686.7 5696.9 5768.1 5629.4 5711.0 5718.5 5722.5 5747.5 4271.3 5173.7 5198.3 5214.3 5262.0 5158.1 5177.5 5180.0 5182.9 5235.7 5259.1 5284.2 5307.6 5308.8 5334.9 5230.2 5266.7 5268.7 5277.1 5326.7 4986.0 4996.0 5051.1 5193.7 5195.3 5591.5 5636.3 5646.8 5707.7 5725.4 6152.1 6195.5 6204.6 6265.7 6273.6 6126.1 6279.2 6316.6 6335.3 6364.6 5143.4 5700.5 5717.3 5729.7 5756.9 6274.4 6301.9 6311.8 6312.4 6328.4 6106.0 6113.8 6123.0 6123.2 6126.7 6100.2 6147.1 6148.4 6149.8 6233.0 6210.1 6212.8 6225.9 6232.0 6233.1 6102.0 6224.9 6272.3 6275.8 6279.7 4800.9 4860.8 4863.6 4879.5 4913.8 4341.7 4407.4 4414.8 4425.8 4501.2 5724.4 5748.9 5762.4 5763.0 5780.4 6002.8 6249.6 6251.0 6277.7 6289.8 5603.5 5664.7 5667.5 5725.0 5744.6 5402.5 5405.2 5409.6 5410.9 5417.2 6272.1 6299.4 6303.2 6306.9 6307.5 6005.4 6111.5 6116.2 6155.8 6258.8 6273.7 6275.8 6307.2 6309.1 6313.8 6088.3 6177.6 6191.3 6198.0 6201.4 6073.4 6143.0 6228.7 6269.5 6271.6 5870.3 5940.6 6020.8 6084.3 6138.1 6077.9 6080.4 6083.5 6088.8 6113.7 5855.8 5880.4 5912.2 5922.9 5929.9 5902.1 5958.9 5964.8 6042.9 6101.7 5924.8 5929.7 5957.4 5969.6 5977.8 02:28:09 INFO - PROCESS | 2263 | 02:28:09 INFO - PROCESS | 2263 | [#1] ext.html Cycles:180 Average:70161.15 Median:40220.90 stddev:154352.36 (383.8%) stddev-sans-first:154696.69 02:28:09 INFO - PROCESS | 2263 | Values: 480.7 512.6 520.7 528.2 549.6 31402.8 40128.2 41454.4 41540.6 43158.0 41647.0 42426.0 42748.8 42773.6 42936.4 9257.3 9436.0 9450.7 9452.6 9453.4 8107.5 9451.1 9555.9 9561.3 9561.4 9521.8 9593.4 9599.4 9601.7 9616.8 9509.8 9536.7 9558.2 9562.9 9565.9 9375.9 9442.9 9466.8 9473.5 9484.1 39904.4 40126.0 40190.0 40193.8 40248.0 3329.6 3382.9 3432.5 3453.4 3636.8 28559.4 28729.0 29945.9 30818.5 31038.4 3062.1 3230.2 3260.6 3298.5 3337.9 496119.0 500464.0 501820.0 502430.0 503159.5 57930.7 58050.0 58057.4 58077.4 58090.1 65098.0 65290.7 65399.0 65415.3 65444.3 16274.9 16472.4 16490.4 16537.6 16554.8 12718.6 12780.6 12788.9 12809.9 12812.9 17033.6 17725.0 17801.5 17955.2 18010.7 5048.7 5403.1 6056.2 6091.8 6185.7 898.9 906.9 969.7 971.2 991.1 23863.9 28438.4 28463.1 29181.8 29925.4 1011.3 1030.8 1047.6 1088.1 1122.8 11729.9 14859.1 15217.5 15247.6 15840.8 636444.0 641328.0 641664.0 643691.0 645873.0 58348.0 58550.0 58593.7 58653.7 58826.4 1236.4 1291.5 1347.4 1359.2 1409.5 23907.7 30211.8 30340.5 30626.4 31002.8 50218.5 50570.0 50669.7 50759.5 50803.7 545300.0 548548.0 549269.0 610839.0 639443.0 25161.1 25426.6 25486.6 25713.6 25741.6 44659.6 44711.6 45181.1 45301.5 45398.0 31773.8 34650.0 36624.6 37504.0 37546.4 37368.8 37394.3 37425.0 37478.0 37683.0 30417.0 36193.6 36694.9 37488.3 37644.0 31864.5 33396.7 33538.8 34651.5 34703.8 23511.4 23583.3 25029.7 25515.2 26032.5 02:28:09 INFO - PROCESS | 2263 | 02:28:09 INFO - PROCESS | 2263 | [#2] jquery.html Cycles:180 Average:33592.17 Median:2178.89 stddev:97707.02 (4484.2%) stddev-sans-first:97949.11 02:28:09 INFO - PROCESS | 2263 | Values: 288.0 307.3 346.0 367.8 398.1 15147.6 16167.5 19493.5 19936.9 20649.7 17071.0 19968.1 20444.9 20635.8 20873.1 5247.8 5650.9 5719.9 5722.0 5723.8 3623.4 4081.5 5627.2 5722.3 5737.7 5596.5 6303.3 6339.7 6387.2 6389.0 6128.9 6228.6 6295.8 6300.1 6366.4 5520.4 5521.1 5530.2 5537.5 5544.6 23883.8 24456.0 24482.3 24528.1 24771.8 1914.7 2308.3 2325.5 2452.6 2487.7 8843.7 9209.9 9338.7 9444.2 9561.4 1480.8 1658.3 1709.3 1733.3 1766.9 86698.6 119097.8 119137.8 119149.0 120165.4 9239.1 9840.0 9933.6 10524.7 10738.9 12248.4 12288.9 12355.7 12379.9 12394.4 7003.8 7719.3 7902.6 7977.4 8104.5 6711.6 6986.0 8333.2 8464.9 8495.5 3805.2 3838.3 3858.7 3881.4 3959.7 1841.8 1895.5 1897.2 1912.2 1920.3 808.9 876.9 973.9 979.3 1001.1 19909.5 21192.5 21274.0 21373.5 21393.3 867.3 934.0 934.2 936.9 947.5 9444.7 10259.9 10737.5 11055.8 11216.6 571836.0 586904.0 588886.0 590566.0 596453.0 77562.2 83340.6 111699.4 136775.3 139264.0 997.3 999.9 1000.7 1002.1 1028.9 18582.3 19085.1 19429.0 19513.1 19569.6 50246.0 51297.7 54321.0 56144.5 58395.7 72370.0 73356.5 73451.9 75348.9 82435.0 6466.6 7049.3 7620.9 7850.3 7927.6 11396.9 12203.8 12516.6 12563.9 12606.6 8613.6 9692.5 10353.5 10450.5 11121.0 8911.6 10423.4 10442.9 10612.1 10967.3 8933.4 9061.0 10309.6 10696.6 10852.1 7739.4 7783.7 8012.8 8058.7 8106.5 8766.4 10584.7 10692.4 11084.9 11138.6 02:28:09 INFO - PROCESS | 2263 | 02:28:09 INFO - PROCESS | 2263 | [#3] mootools.html Cycles:180 Average:5394.79 Median:5106.16 stddev:2973.39 (58.2%) stddev-sans-first:2966.94 02:28:09 INFO - PROCESS | 2263 | Values: 1448.8 1471.9 1623.6 1780.6 1809.5 7951.8 8389.0 8389.2 8392.3 8400.1 7399.9 7625.9 7693.2 7926.0 8211.9 2300.0 2344.2 2351.7 2352.8 2353.5 2345.0 2364.7 2377.0 2385.7 2394.9 2128.4 2229.5 2322.8 2347.6 2359.8 2314.7 2316.1 2342.5 2342.9 2344.1 2275.6 2292.0 2304.8 2307.4 2307.7 8139.6 8324.5 8325.0 8327.0 8343.4 5018.0 5194.3 5199.2 5199.6 5201.9 3106.0 3219.1 3536.8 3536.8 3539.2 966.4 1044.3 1078.4 1083.1 1092.7 10799.9 10825.7 10959.7 10971.0 10977.1 4523.1 4670.0 4753.8 4780.5 4787.4 9348.9 9349.7 9357.0 9360.2 9365.0 5218.6 5224.9 5227.3 5229.9 5236.2 4036.6 4213.3 4787.5 4805.0 4810.6 3528.3 3547.1 3804.1 3880.7 3909.9 1254.3 1261.9 1262.1 1276.8 1278.2 819.2 893.0 901.9 915.4 919.0 5383.2 5565.5 5598.7 5619.9 5636.2 420.0 445.7 447.8 451.1 460.3 3689.7 3772.1 3786.2 3788.4 3860.0 9789.9 10063.2 10149.2 10172.5 10207.7 9428.0 9661.9 9934.9 9939.2 10109.5 5905.5 7527.8 7764.8 7780.5 7871.4 4513.7 5420.1 5566.8 5617.1 5657.9 8802.3 9242.4 9308.6 9349.6 9439.9 9370.3 9435.4 9575.5 9596.6 9618.7 5965.7 6268.4 6899.7 7457.3 7817.5 7733.8 7922.8 7928.5 7934.9 7943.5 6993.1 7129.0 7132.0 7170.5 7224.8 6848.0 6970.7 6977.0 7119.5 7125.9 6601.4 6612.9 6706.5 6710.0 6724.1 4789.5 4802.7 4842.5 4845.4 4867.2 4691.5 4729.3 4796.0 4933.5 4937.3 02:28:09 INFO - PROCESS | 2263 | 02:28:09 INFO - PROCESS | 2263 | [#4] prototype.html Cycles:180 Average:4370.86 Median:4880.56 stddev:1347.46 (27.6%) stddev-sans-first:1316.03 02:28:09 INFO - PROCESS | 2263 | Values: 293.1 298.1 302.3 308.6 312.9 4794.7 4889.2 5177.5 5181.6 5261.4 4644.3 4773.9 5004.7 5007.8 5041.0 3408.1 3935.5 3986.9 4005.9 4055.5 3142.3 3203.6 3832.1 3945.4 4052.5 3444.3 3774.8 3970.3 3986.7 4016.3 3954.5 3963.9 3983.9 3983.9 3993.9 3198.9 3312.0 3418.3 3428.6 3446.4 5120.3 5137.8 5153.3 5174.4 5177.6 4316.1 4474.2 4487.1 4516.8 4559.0 4589.8 4755.0 5491.4 5498.5 5523.9 3759.3 3777.0 3780.7 3788.3 3800.3 5379.6 5394.1 5501.0 5751.9 5794.7 3829.8 3845.6 3849.7 3860.2 3875.9 3901.3 3903.3 3904.9 3908.7 3915.5 4286.8 4498.0 4569.7 4579.2 4580.5 4678.4 5487.5 5488.0 5531.9 5564.3 1015.7 1166.2 1168.5 1256.4 1261.0 787.7 798.7 808.3 818.5 828.5 4179.3 4879.0 4905.3 4926.8 4933.4 5315.4 5374.5 5388.6 5393.7 5435.3 4598.8 4665.8 4877.7 5302.7 5310.9 2397.6 2525.0 2640.0 2778.9 2786.7 5140.3 5438.4 5518.5 5537.2 5560.9 4567.6 4780.8 4809.5 5556.7 5566.9 4864.9 4888.3 5505.7 5582.7 5638.5 4441.7 5000.9 5300.5 5433.1 5481.8 5127.1 5211.6 5253.0 5323.5 5376.7 4731.0 5433.7 5547.6 5580.9 5615.8 3601.8 3604.5 3605.3 3621.1 3626.8 5294.9 5696.0 5743.3 5744.8 5762.7 5554.3 5567.5 5597.4 5597.9 5611.6 4215.3 4625.3 5483.4 5549.0 5611.0 4882.1 5496.2 5503.2 5507.7 5519.4 3991.9 4932.8 4948.7 5420.8 5426.0 3885.8 5342.5 5366.0 5452.3 5528.3 02:28:09 INFO - PROCESS | 2263 | 02:28:09 INFO - PROCESS | 2263 | [#5] yui.html Cycles:180 Average:20347.04 Median:4275.38 stddev:30204.84 (706.5%) stddev-sans-first:30253.55 02:28:09 INFO - PROCESS | 2263 | Values: 699.4 703.3 717.7 748.6 783.4 13765.5 14501.7 16084.9 16091.4 16119.4 12247.6 14660.8 14770.7 14773.5 14774.3 650.0 797.8 818.6 819.2 820.2 678.2 690.2 796.9 813.8 832.6 857.8 876.7 879.2 879.2 880.0 873.5 874.9 875.9 876.8 877.5 509.0 525.5 627.6 644.4 651.2 14343.2 14348.9 14350.3 14355.9 14365.0 12562.2 12776.7 12822.9 12893.5 12992.8 12004.3 12015.2 12169.0 12195.0 12227.6 4202.7 4217.2 4303.2 4357.5 4444.5 88349.6 90719.5 90924.5 91920.0 92023.0 8583.4 10126.0 10318.5 10370.8 10429.6 17175.7 18685.4 19364.0 19368.6 19454.0 7426.5 7484.2 7489.4 7572.7 7679.2 4104.2 4219.0 4247.6 4321.5 4430.9 3229.1 3386.1 3393.4 3394.4 3410.1 616.6 624.7 628.2 637.2 642.9 590.6 591.8 600.0 605.2 634.9 8091.0 8572.6 8637.0 8654.7 9045.0 485.6 545.3 594.1 604.7 611.7 3287.5 3990.5 4032.6 4045.8 4100.3 72575.6 104005.5 107083.9 115657.4 116124.0 93485.6 95926.5 102214.0 102507.0 103661.0 86214.6 87064.6 88037.0 88365.0 88649.6 7557.9 7731.7 7743.8 7902.1 7982.0 50467.0 51113.7 53074.7 53376.7 55086.2 67658.0 73300.6 73566.9 75763.0 77725.6 10257.3 10357.8 10517.7 10523.3 10550.6 11058.5 11327.2 11762.2 11908.9 12032.2 10437.5 10507.5 10895.8 12497.4 13185.7 12813.8 12945.5 13158.4 13347.9 13376.9 11781.0 12265.8 12823.4 12866.6 12914.2 10435.3 10551.8 11020.1 11171.0 11235.0 11060.6 11111.0 11373.7 11423.7 11463.1 02:28:09 INFO - PROCESS | 2263 | -------- Summary: end -------- 02:28:09 INFO - PROCESS | 2263 | 02:28:10 INFO - TEST-INFO | 2263: exit 0 02:28:10 INFO - TEST-OK | dromaeo_css | took 1120395ms 02:28:10 INFO - TEST-START | kraken 02:28:10 INFO - operating with platform_type : mac_ 02:28:10 INFO - Initialising browser for kraken test... 02:28:10 INFO - TEST-INFO | started process 2266 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/tmpuHfyis/profile http://localhost:49468/getInfo.html) 02:28:12 INFO - PROCESS | 2266 | __metrics Screen width/height:1600/1200 02:28:12 INFO - PROCESS | 2266 | colorDepth:24 02:28:12 INFO - PROCESS | 2266 | Browser inner width/height: 1024/689 02:28:12 INFO - PROCESS | 2266 | __metrics 02:28:13 INFO - PROCESS | 2266 | 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:28:13 INFO - PROCESS | 2266 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:28:13 INFO - TEST-INFO | 2266: exit 0 02:28:13 INFO - Browser initialized. 02:28:13 INFO - Running cycle 1/1 for kraken test... 02:28:13 INFO - TEST-INFO | started process 2268 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/tmpuHfyis/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:28:26 INFO - PROCESS | 2268 | RSS: Main: 222916608 02:28:26 INFO - PROCESS | 2268 | 02:28:29 INFO - PROCESS | 2268 | Cycle 1(1): loaded http://localhost:49468/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49468/tests/kraken/driver.html?testName=audio-beat-detection) 02:28:29 INFO - PROCESS | 2268 | RSS: Main: 270180352 02:28:29 INFO - PROCESS | 2268 | 02:28:32 INFO - PROCESS | 2268 | Cycle 1(1): loaded http://localhost:49468/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49468/tests/kraken/driver.html?testName=audio-dft) 02:28:33 INFO - PROCESS | 2268 | RSS: Main: 284508160 02:28:33 INFO - PROCESS | 2268 | 02:28:36 INFO - PROCESS | 2268 | Cycle 1(1): loaded http://localhost:49468/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49468/tests/kraken/driver.html?testName=audio-fft) 02:28:36 INFO - PROCESS | 2268 | RSS: Main: 382164992 02:28:36 INFO - PROCESS | 2268 | 02:28:38 INFO - PROCESS | 2268 | Cycle 1(1): loaded http://localhost:49468/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49468/tests/kraken/driver.html?testName=audio-oscillator) 02:28:39 INFO - PROCESS | 2268 | RSS: Main: 284717056 02:28:39 INFO - PROCESS | 2268 | 02:28:41 INFO - PROCESS | 2268 | Cycle 1(1): loaded http://localhost:49468/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49468/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:28:42 INFO - PROCESS | 2268 | RSS: Main: 286351360 02:28:42 INFO - PROCESS | 2268 | 02:28:44 INFO - PROCESS | 2268 | Cycle 1(1): loaded http://localhost:49468/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49468/tests/kraken/driver.html?testName=imaging-darkroom) 02:28:45 INFO - PROCESS | 2268 | RSS: Main: 326299648 02:28:45 INFO - PROCESS | 2268 | 02:28:48 INFO - PROCESS | 2268 | Cycle 1(1): loaded http://localhost:49468/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49468/tests/kraken/driver.html?testName=imaging-desaturate) 02:28:49 INFO - PROCESS | 2268 | RSS: Main: 326504448 02:28:49 INFO - PROCESS | 2268 | 02:28:51 INFO - PROCESS | 2268 | Cycle 1(1): loaded http://localhost:49468/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49468/tests/kraken/driver.html?testName=json-parse-financial) 02:28:52 INFO - PROCESS | 2268 | RSS: Main: 323137536 02:28:52 INFO - PROCESS | 2268 | 02:28:53 INFO - PROCESS | 2268 | Cycle 1(1): loaded http://localhost:49468/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49468/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:28:54 INFO - PROCESS | 2268 | RSS: Main: 299442176 02:28:54 INFO - PROCESS | 2268 | 02:28:56 INFO - PROCESS | 2268 | Cycle 1(1): loaded http://localhost:49468/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49468/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:28:57 INFO - PROCESS | 2268 | RSS: Main: 299556864 02:28:57 INFO - PROCESS | 2268 | 02:28:59 INFO - PROCESS | 2268 | Cycle 1(1): loaded http://localhost:49468/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49468/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:28:59 INFO - PROCESS | 2268 | RSS: Main: 301375488 02:28:59 INFO - PROCESS | 2268 | 02:29:02 INFO - PROCESS | 2268 | Cycle 1(1): loaded http://localhost:49468/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49468/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:29:03 INFO - PROCESS | 2268 | RSS: Main: 320892928 02:29:03 INFO - PROCESS | 2268 | 02:29:06 INFO - PROCESS | 2268 | Cycle 1(1): loaded http://localhost:49468/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49468/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:29:07 INFO - PROCESS | 2268 | RSS: Main: 314216448 02:29:07 INFO - PROCESS | 2268 | 02:29:08 INFO - PROCESS | 2268 | Cycle 1(1): loaded http://localhost:49468/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49468/tests/kraken/driver.html?testName=ai-astar) 02:29:09 INFO - PROCESS | 2268 | RSS: Main: 358260736 02:29:09 INFO - PROCESS | 2268 | 02:29:09 INFO - PROCESS | 2268 | __start_tp_report 02:29:09 INFO - PROCESS | 2268 | _x_x_mozilla_page_load 02:29:09 INFO - PROCESS | 2268 | _x_x_mozilla_page_load_details 02:29:09 INFO - PROCESS | 2268 | |i|pagename|runs| 02:29:09 INFO - PROCESS | 2268 | |0;ai-astar;105;116;115;115;115;115;115;115;113;115 02:29:09 INFO - PROCESS | 2268 | |1;audio-beat-detection;147;110;110;145;109;114;111;111;171;138 02:29:09 INFO - PROCESS | 2268 | |2;audio-dft;184;182;185;184;183;184;186;185;185;183 02:29:09 INFO - PROCESS | 2268 | |3;audio-fft;83;58;59;89;61;59;60;58;82;58 02:29:09 INFO - PROCESS | 2268 | |4;audio-oscillator;86;69;95;70;67;69;89;67;68;92 02:29:09 INFO - PROCESS | 2268 | |5;imaging-gaussian-blur;88;85;87;88;88;85;89;85;86;84 02:29:09 INFO - PROCESS | 2268 | |6;imaging-darkroom;95;102;97;98;96;95;97;97;95;96 02:29:09 INFO - PROCESS | 2268 | |7;imaging-desaturate;81;83;79;78;80;83;82;82;80;79 02:29:09 INFO - PROCESS | 2268 | |8;json-parse-financial;56;54;54;54;57;58;59;57;57;58 02:29:09 INFO - PROCESS | 2268 | |9;json-stringify-tinderbox;72;81;76;72;74;72;71;71;71;72 02:29:09 INFO - PROCESS | 2268 | |10;stanford-crypto-aes;84;84;85;83;84;84;85;82;81;84 02:29:09 INFO - PROCESS | 2268 | |11;stanford-crypto-ccm;147;152;154;141;140;144;156;154;182;141 02:29:09 INFO - PROCESS | 2268 | |12;stanford-crypto-pbkdf2;166;167;165;166;166;164;167;165;164;165 02:29:09 INFO - PROCESS | 2268 | |13;stanford-crypto-sha256-iterative;60;64;84;67;61;60;79;85;66;57 02:29:09 INFO - PROCESS | 2268 | __end_tp_report 02:29:09 INFO - PROCESS | 2268 | __start_cc_report 02:29:09 INFO - PROCESS | 2268 | _x_x_mozilla_cycle_collect,1995 02:29:09 INFO - PROCESS | 2268 | __end_cc_report 02:29:09 INFO - PROCESS | 2268 | __startTimestamp1482661749129__endTimestamp 02:29:09 INFO - PROCESS | 2268 | 02:29:09 INFO - PROCESS | 2268 | ------- Summary: start ------- 02:29:09 INFO - PROCESS | 2268 | Number of tests: 14 02:29:09 INFO - PROCESS | 2268 | 02:29:09 INFO - PROCESS | 2268 | [#0] ai-astar Cycles:10 Average:113.90 Median:115.00 stddev:3.21 (2.8%) stddev-sans-first:0.78 02:29:09 INFO - PROCESS | 2268 | Values: 105.0 116.0 115.0 115.0 115.0 115.0 115.0 115.0 113.0 115.0 02:29:09 INFO - PROCESS | 2268 | 02:29:09 INFO - PROCESS | 2268 | [#1] audio-beat-detection Cycles:10 Average:126.60 Median:126.00 stddev:22.02 (17.5%) stddev-sans-first:22.08 02:29:09 INFO - PROCESS | 2268 | Values: 147.0 110.0 110.0 145.0 109.0 114.0 111.0 111.0 171.0 138.0 02:29:09 INFO - PROCESS | 2268 | 02:29:09 INFO - PROCESS | 2268 | [#2] audio-dft Cycles:10 Average:184.10 Median:184.50 stddev:1.20 (0.6%) stddev-sans-first:1.27 02:29:09 INFO - PROCESS | 2268 | Values: 184.0 182.0 185.0 184.0 183.0 184.0 186.0 185.0 185.0 183.0 02:29:09 INFO - PROCESS | 2268 | 02:29:09 INFO - PROCESS | 2268 | [#3] audio-fft Cycles:10 Average:66.70 Median:60.50 stddev:12.56 (20.8%) stddev-sans-first:11.86 02:29:09 INFO - PROCESS | 2268 | Values: 83.0 58.0 59.0 89.0 61.0 59.0 60.0 58.0 82.0 58.0 02:29:09 INFO - PROCESS | 2268 | 02:29:09 INFO - PROCESS | 2268 | [#4] audio-oscillator Cycles:10 Average:77.20 Median:78.00 stddev:11.70 (15.0%) stddev-sans-first:11.97 02:29:09 INFO - PROCESS | 2268 | Values: 86.0 69.0 95.0 70.0 67.0 69.0 89.0 67.0 68.0 92.0 02:29:09 INFO - PROCESS | 2268 | 02:29:09 INFO - PROCESS | 2268 | [#5] imaging-gaussian-blur Cycles:10 Average:86.50 Median:87.50 stddev:1.72 (2.0%) stddev-sans-first:1.73 02:29:09 INFO - PROCESS | 2268 | Values: 88.0 85.0 87.0 88.0 88.0 85.0 89.0 85.0 86.0 84.0 02:29:09 INFO - PROCESS | 2268 | 02:29:09 INFO - PROCESS | 2268 | [#6] imaging-darkroom Cycles:10 Average:96.80 Median:96.50 stddev:2.10 (2.2%) stddev-sans-first:2.12 02:29:09 INFO - PROCESS | 2268 | Values: 95.0 102.0 97.0 98.0 96.0 95.0 97.0 97.0 95.0 96.0 02:29:09 INFO - PROCESS | 2268 | 02:29:09 INFO - PROCESS | 2268 | [#7] imaging-desaturate Cycles:10 Average:80.70 Median:81.50 stddev:1.77 (2.2%) stddev-sans-first:1.87 02:29:09 INFO - PROCESS | 2268 | Values: 81.0 83.0 79.0 78.0 80.0 83.0 82.0 82.0 80.0 79.0 02:29:09 INFO - PROCESS | 2268 | 02:29:09 INFO - PROCESS | 2268 | [#8] json-parse-financial Cycles:10 Average:56.40 Median:57.00 stddev:1.84 (3.2%) stddev-sans-first:1.94 02:29:09 INFO - PROCESS | 2268 | Values: 56.0 54.0 54.0 54.0 57.0 58.0 59.0 57.0 57.0 58.0 02:29:09 INFO - PROCESS | 2268 | 02:29:09 INFO - PROCESS | 2268 | [#9] json-stringify-tinderbox Cycles:10 Average:73.20 Median:72.00 stddev:3.16 (4.4%) stddev-sans-first:3.32 02:29:09 INFO - PROCESS | 2268 | Values: 72.0 81.0 76.0 72.0 74.0 72.0 71.0 71.0 71.0 72.0 02:29:09 INFO - PROCESS | 2268 | 02:29:09 INFO - PROCESS | 2268 | [#10] stanford-crypto-aes Cycles:10 Average:83.60 Median:84.00 stddev:1.26 (1.5%) stddev-sans-first:1.33 02:29:09 INFO - PROCESS | 2268 | Values: 84.0 84.0 85.0 83.0 84.0 84.0 85.0 82.0 81.0 84.0 02:29:09 INFO - PROCESS | 2268 | 02:29:09 INFO - PROCESS | 2268 | [#11] stanford-crypto-ccm Cycles:10 Average:151.10 Median:153.00 stddev:12.43 (8.1%) stddev-sans-first:13.10 02:29:09 INFO - PROCESS | 2268 | Values: 147.0 152.0 154.0 141.0 140.0 144.0 156.0 154.0 182.0 141.0 02:29:09 INFO - PROCESS | 2268 | 02:29:09 INFO - PROCESS | 2268 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.50 Median:166.00 stddev:1.08 (0.7%) stddev-sans-first:1.13 02:29:09 INFO - PROCESS | 2268 | Values: 166.0 167.0 165.0 166.0 166.0 164.0 167.0 165.0 164.0 165.0 02:29:09 INFO - PROCESS | 2268 | 02:29:09 INFO - PROCESS | 2268 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.30 Median:66.50 stddev:10.46 (15.7%) stddev-sans-first:10.65 02:29:09 INFO - PROCESS | 2268 | Values: 60.0 64.0 84.0 67.0 61.0 60.0 79.0 85.0 66.0 57.0 02:29:09 INFO - PROCESS | 2268 | -------- Summary: end -------- 02:29:09 INFO - PROCESS | 2268 | 02:29:10 INFO - TEST-INFO | 2268: exit 0 02:29:10 INFO - TEST-OK | kraken | took 60032ms 02:29:10 INFO - SUITE-END | took 1180s 02:29:10 INFO - Completed test suite (00:19:40) 02:29:10 INFO - javascript benchmark 02:29:10 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3292.325073359961, 3307.8676852925882, 3317.0546394277626, 3342.0976336389162, 3375.578052743407, 5629.549636029118, 5659.0, 5686.687232202233, 5696.857578560528, 5768.1059435787465, 5629.408912064236, 5710.971445142768, 5718.485336319731, 5722.456366645172, 5747.511461525775, 4271.316589345705, 5173.689578625275, 5198.2722418861385, 5214.270002199704, 5262.0, 5158.097332966716, 5177.48225177483, 5179.974100129494, 5182.8704282393055, 5235.738213089349, 5259.105951988152, 5284.154535274357, 5307.601929855257, 5308.761105750231, 5334.893302133955, 5230.189320655286, 5266.710330931797, 5268.736563171864, 5277.12927366984, 5326.6803991760235, 4985.950140498585, 4995.950040499585, 5051.1413059779925, 5193.662411943212, 5195.272661827347, 5591.524720398771, 5636.267285252891, 5646.774129034834, 5707.686077265748, 5725.3988331225455, 6152.107944348089, 6195.504359651217, 6204.565680402374, 6265.7493700251935, 6273.62358258506, 6126.142340072393, 6279.246490421133, 6316.589421687576, 6335.271443783931, 6364.649944253064, 5143.408508021588, 5700.543956483471, 5717.285339332583, 5729.713514324267, 5756.884862302731, 6274.372562743689, 6301.905471417841, 6311.810645680637, 6312.368763123651, 6328.398802113792, 6106.023036314168, 6113.755449782047, 6122.969385153046, 6123.234595675541, 6126.724297406628, 6100.23747031621, 6147.139400483936, 6148.385161483905, 6149.77004599082, 6233.002719564848, 6210.099535567318, 6212.844678882973, 6225.875482490415, 6232.002879539252, 6233.065040244, 6101.969490152521, 6224.9377506224355, 6272.3100458949475, 6275.843103922439, 6279.654618995908, 4800.855974320776, 4860.80556777732, 4863.586595139453, 4879.4876537963, 4913.803447862117, 4341.739495630272, 4407.360932664746, 4414.779261036935, 4425.822967081281, 4501.167284052461, 5724.370319264877, 5748.913766293509, 5762.423757624288, 5763.020286551213, 5780.421957804186, 6002.789902353396, 6249.562530622813, 6250.968745156245, 6277.686115694197, 6289.779857704958, 5603.5237004854225, 5664.688442135722, 5667.489925906688, 5724.971375143181, 5744.597878148573, 5402.52708479521, 5405.243265942771, 5409.594280428983, 5410.864728381822, 5417.160340147283, 6272.090546870678, 6299.370062993848, 6303.180586523723, 6306.873862522815, 6307.495400368051, 6005.39946005396, 6111.470193407107, 6116.1836049259855, 6155.784547540815, 6258.812235632939, 6273.686315684289, 6275.843103922439, 6307.180066591313, 6309.1167236587835, 6313.779017734358, 6088.299845517822, 6177.598456100339, 6191.349908259568, 6198.039303907902, 6201.410865967726, 6073.423024812636, 6143.047827586731, 6228.657423841732, 6269.529785266214, 6271.560990730787, 5870.324912635102, 5940.584159108994, 6020.849478762891, 6084.269887613515, 6138.143176733788, 6077.908831367445, 6080.361562035922, 6083.513318934386, 6088.8173354797655, 6113.724882380393, 5855.795047173328, 5880.382559831156, 5912.201852749824, 5922.851928701817, 5929.9110513341475, 5902.1441890926435, 5958.910616340671, 5964.7912323068485, 6042.90935635957, 6101.725422355916, 5924.822255332347, 5929.673867937305, 5957.434043765836, 5969.582129251085, 5977.790777322773], "value": 5677.851795506712, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [480.6755440077949, 512.5924889712678, 520.6641716093119, 528.1734086155169, 549.6454786662605, 31402.842985785068, 40128.1974360513, 41454.37818432722, 41540.58459415405, 43158.0, 41646.95882602937, 42426.0, 42748.78625606868, 42773.57226427743, 42936.35595466063, 9257.305702072355, 9435.952820235889, 9450.716478505656, 9452.62189512422, 9453.385529940566, 8107.513549187068, 9451.054894510562, 9555.856662150074, 9561.28290378221, 9561.426314421122, 9521.761955951122, 9593.376430532027, 9599.376040557341, 9601.711948641552, 9616.759581010461, 9509.80980380392, 9536.666216682452, 9558.235341172689, 9562.90437095627, 9565.904340956571, 9375.906240937571, 9442.905570944271, 9466.810663786755, 9473.526323683856, 9484.05159484053, 39904.40143397851, 40126.0, 40190.0, 40193.79903100495, 40248.0, 3329.583802024747, 3382.915427114342, 3432.5022871683714, 3453.3611281912927, 3636.7817930923966, 28559.428811423655, 28729.0, 29945.850270748506, 30818.537721933964, 31038.379232415453, 3062.142600071961, 3230.176305042223, 3260.5924259467565, 3298.472244440902, 3337.8664853406076, 496119.0, 500464.0, 501820.0, 502430.0, 503159.4842025803, 57930.71034644884, 58050.0, 58057.419425806045, 58077.4192258072, 58090.12864807031, 65098.0, 65290.673546631966, 65399.0, 65415.34584654188, 65444.34555654478, 16274.942128761593, 16472.42346517866, 16490.422835200712, 16537.586560336087, 16554.751678724828, 12718.55485058037, 12780.61658150257, 12788.9129424, 12809.871901281054, 12812.935935320264, 17033.574160645836, 17725.0, 17801.46595602134, 17955.192016359295, 18010.729839052423, 5048.72232027242, 5403.13549832025, 6056.242969628796, 6091.8477038073515, 6185.659788711664, 898.8561830107283, 906.8730377747121, 969.743018100204, 971.1939090554964, 991.1020395941691, 23863.880680596485, 28438.431231375256, 28463.146105616866, 29181.83272669112, 29925.40149197004, 1011.2870426349406, 1030.7991190263408, 1047.617619568852, 1088.1240601315858, 1122.7754449110212, 11729.941350293195, 14859.108453492825, 15217.467388641344, 15247.618809529851, 15840.76238856418, 636444.0, 641328.0, 641664.0, 643691.0, 645873.0, 58348.0, 58550.0, 58593.70703146457, 58653.65385384622, 58826.411735882946, 1236.3818090954524, 1291.5350473829415, 1347.4408120630026, 1359.2116572387793, 1409.4996276321963, 23907.701320041706, 30211.848940755157, 30340.54489182708, 30626.387472250877, 31002.844985774926, 50218.49781502284, 50570.0, 50669.746651266505, 50759.492405076955, 50803.74598126986, 545300.0, 548548.0, 549269.0, 610839.0, 639443.0, 25161.119360822282, 25426.618600720634, 25486.617700734132, 25713.614295785203, 25741.613875792253, 44659.55340446684, 44711.55288447074, 45181.0963780716, 45301.546984529734, 45398.0, 31773.84113079512, 34650.0, 36624.63375366212, 37504.0, 37546.436803448516, 37368.81315593405, 37394.25211495809, 37425.0, 37478.0, 37683.0, 30417.0, 36193.63806361903, 36694.89915302545, 37488.25023499569, 37644.0, 31864.522032169072, 33396.66603334033, 33538.83230583832, 34651.4802277961, 34703.826480867436, 23511.41221469477, 23583.29250122499, 25029.74970250274, 25515.234542963743, 26032.479350413265], "value": 19518.52295863713, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [287.9755220806232, 307.3024234986582, 345.9636738142493, 367.77381910125274, 398.1241269207742, 15147.621309467255, 16167.514974550768, 19493.512662183435, 19936.90031549844, 20649.690254646193, 17071.0, 19968.101435435405, 20444.8977755111, 20635.793642063574, 20873.060712267947, 5247.842564723055, 5650.858728531778, 5719.914201286984, 5722.0, 5723.771049158028, 3623.3659109655837, 4081.469408976829, 5627.155926611, 5722.341930677983, 5737.655740655553, 5596.524295434893, 6303.275123360826, 6339.683015849212, 6387.169667943161, 6389.0, 6128.877422451548, 6228.626282423069, 6295.842603934938, 6300.149479820211, 6366.36336366362, 5520.392756796749, 5521.089020311644, 5530.170474428868, 5537.473939975695, 5544.6118771686, 23883.805809709444, 24456.021759129613, 24482.265532034067, 24528.14151504689, 24771.752282477304, 1914.6936490161647, 2308.319045881469, 2325.5000174962297, 2452.6198439241766, 2487.738787427312, 8843.69047083349, 9209.861852072225, 9338.719838404859, 9444.150026497648, 9561.378510396871, 1480.7778833175003, 1658.336665333864, 1709.2906443825798, 1733.2806885142777, 1766.8586513078924, 86698.56650716707, 119097.8090219104, 119137.8086219144, 119149.0, 120165.39917300445, 9239.122283383067, 9840.0, 9933.602655893828, 10524.736881578021, 10738.9261073892, 12248.38758062111, 12288.938555307166, 12355.69110772238, 12379.876201238052, 12394.380280985915, 7003.774339490569, 7719.266669666373, 7902.604869756489, 7977.3618110551015, 8104.473209241381, 6711.563748356341, 6986.0, 8333.166683331618, 8464.915350846535, 8495.490270583785, 3805.162864169878, 3838.251540949488, 3858.7298889078056, 3881.3983832506005, 3959.6634286085427, 1841.8066103058986, 1895.5166432559886, 1897.2221389230626, 1912.1586501939103, 1920.2511020701938, 808.931240844523, 876.9035406105323, 973.8977407372124, 979.2704435195825, 1001.0690058245756, 19909.502262443264, 21192.470188245108, 21274.042668079663, 21373.46566335823, 21393.25123620697, 867.3104881619128, 934.0, 934.1825902335456, 936.8782058332373, 947.507296205969, 9444.669436569824, 10259.897401026043, 10737.463126843782, 11055.834162487568, 11216.607418740305, 571836.0, 586904.0, 588886.0, 590566.0, 596453.0, 77562.2243777555, 83340.58329708313, 111699.44150279196, 136775.31612341874, 139264.0, 997.3168379659957, 999.9400035997863, 1000.6647772996128, 1002.1081237698454, 1028.9485525723685, 18582.25670973146, 19085.141168647166, 19429.0, 19513.121909513822, 19569.60860782805, 50246.0, 51297.743511282206, 54321.0, 56144.47349869347, 58395.70802145962, 72370.0, 73356.53286934124, 73451.8982215278, 75348.86976695244, 82435.0, 6466.579672321284, 7049.330313620198, 7620.885686714593, 7850.2542258485355, 7927.563983980705, 11396.886031139915, 12203.816942746043, 12516.624501264976, 12563.87436125627, 12606.621801345975, 8613.612387442456, 9692.46691431978, 10353.534090966075, 10450.47747612601, 11121.0, 8911.55442227873, 10423.374597524173, 10442.947785261025, 10612.097971672494, 10967.341959482457, 8933.419327743675, 9061.04858989823, 10309.587616495257, 10696.572137114428, 10852.077573406348, 7739.419543534144, 7783.688652454065, 8012.759617211493, 8058.717944871902, 8106.47307924983, 8766.430182038148, 10584.682459526226, 10692.411917344309, 11084.889151108386, 11138.554457821596], "value": 9070.851019502079, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1448.7681970884657, 1471.9337629806662, 1623.634682196506, 1780.6438712257552, 1809.4842969753622, 7951.801204969878, 8388.993320801505, 8389.161083891608, 8392.328613710904, 8400.075991640915, 7399.852002959938, 7625.923740762591, 7693.230676932304, 7926.009248843895, 8211.91788082119, 2300.0454811253308, 2344.15610380263, 2351.706036745407, 2352.764723527651, 2353.52929414117, 2344.9765502345012, 2364.73987861336, 2376.976230237701, 2385.654080158376, 2394.9281521554294, 2128.4040468668795, 2229.5285111826197, 2322.779335963081, 2347.6361164019604, 2359.7876191142796, 2314.6643736658175, 2316.119874447699, 2342.5080733046166, 2342.9297121086393, 2344.120954642009, 2275.5790178816806, 2292.025888997186, 2304.781045800646, 2307.411610039446, 2307.699999000136, 8139.552324622144, 8324.542150181738, 8325.000999879992, 8327.0, 8343.374246931444, 5018.0, 5194.324737784102, 5199.168133098686, 5199.584033277367, 5201.9479805201845, 3106.0371284901753, 3219.098652377338, 3536.8054756988354, 3536.840842162109, 3539.185987222953, 966.3525437956586, 1044.2846650044744, 1078.412265315405, 1083.0685610375072, 1092.6940456672144, 10799.892001079967, 10825.729356766065, 10959.671209863716, 10971.0, 10977.12183025356, 4523.050159466549, 4669.976650116728, 4753.811547113221, 4780.450248221429, 4787.353707249476, 9348.859767103499, 9349.719508414759, 9357.01751316102, 9360.15758581731, 9365.01667324921, 5218.556422704112, 5224.869378265574, 5227.320448341768, 5229.895402091937, 5236.162214045735, 4036.555978842325, 4213.262679031157, 4787.473377928425, 4805.0, 4810.59109975656, 3528.329617372691, 3547.0600290922926, 3804.1250512382308, 3880.689544836406, 3909.9022524436546, 1254.2537190371713, 1261.8738126187309, 1262.110212300326, 1276.8084787281982, 1278.2202856257675, 819.2176471469851, 892.9864603674746, 901.8737376767258, 915.3729695158837, 918.963241470347, 5383.246345511631, 5565.471280228372, 5598.692071936082, 5619.8595035124445, 5636.2391077204875, 419.95590463000946, 445.681337843437, 447.8343013085179, 451.0752956439352, 460.31413194340195, 3689.686376658014, 3772.13240954582, 3786.1859700164596, 3788.431735239681, 3860.0, 9789.902100979041, 10063.19494440443, 10149.238807089498, 10172.491375431198, 10207.693769186937, 9428.0, 9661.855072173923, 9934.900650993543, 9939.155171810333, 10109.545070471846, 5905.527557795366, 7527.849443011219, 7764.767056988299, 7780.455368124177, 7871.370290376871, 4513.729176249435, 5420.13277875538, 5566.777328906798, 5617.073182924772, 5657.9151312731165, 8802.25180859634, 9242.353035287466, 9308.581113850028, 9349.626014959325, 9439.858402123837, 9370.344075914893, 9435.433873967584, 9575.521223938636, 9596.568154433207, 9618.711438656852, 5965.701714914324, 6268.404501572343, 6899.689513971984, 7457.291557302047, 7817.5309481431295, 7733.806654833674, 7922.841543169219, 7928.524288542706, 7934.880976785237, 7943.523388596703, 6993.125859267591, 7129.001939728339, 7131.964340178266, 7170.498065135599, 7224.819379515554, 6848.0, 6970.65146742671, 6976.988336691253, 7119.501634885508, 7125.857482850418, 6601.3728695773825, 6612.933870661232, 6706.530542861953, 6710.0, 6724.125863637696, 4789.4971028041555, 4802.687825291485, 4842.539958703917, 4845.370101886732, 4867.245576935581, 4691.460482044608, 4729.290606408928, 4795.95204047955, 4933.4819843915875, 4937.333839726651], "value": 4311.884440931412, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [293.1440194631675, 298.1250031158551, 302.3484391137099, 308.56338281331915, 312.8639042016724, 4794.736289504076, 4889.21772516397, 5177.456367081459, 5181.611379146565, 5261.368635763709, 4644.256918892969, 4773.856784296468, 5004.699718016922, 5007.774650140744, 5041.0, 3408.0798184490172, 3935.527736671589, 3986.880393588197, 4005.8983779460664, 4055.4930633670792, 3142.3401085772016, 3203.599550056243, 3832.13776900198, 3945.4081887716784, 4052.5136983562015, 3444.2594842108942, 3774.7735135891935, 3970.3051965905997, 3986.740861843971, 4016.2770701273757, 3954.457761473028, 3963.861264855745, 3983.900402489961, 3983.924340428083, 3993.9001524961964, 3198.856051477666, 3311.9503207451908, 3418.3163367326633, 3428.5657183399817, 3446.4313388291152, 5120.283160357553, 5137.7944882204665, 5153.252778347119, 5174.353205849269, 5177.611679124081, 4316.077264210181, 4474.239379305526, 4487.057717879249, 4516.841910533149, 4558.951441168517, 4589.829593453681, 4755.0, 5491.423400542925, 5498.532624726903, 5523.944760552383, 3759.270735461691, 3776.9622303776887, 3780.710781619981, 3788.3370410178386, 3800.3159431302356, 5379.596530260246, 5394.056040192951, 5500.972495137538, 5751.856203594902, 5794.652320860761, 3829.751066180689, 3845.6346647069045, 3849.730518863653, 3860.2279544091293, 3875.8643447479767, 3901.31726947783, 3903.2583809076186, 3904.90237744053, 3908.687305015592, 3915.451836742858, 4286.849960251377, 4497.977510112429, 4569.680122391467, 4579.152856721517, 4580.450345958507, 4678.415198100238, 5487.451254874481, 5488.012157811552, 5531.8893622128135, 5564.27664403633, 1015.6800607808665, 1166.1778446195412, 1168.5384273212035, 1256.3718140929536, 1260.9558665446666, 787.7203592724501, 798.6964953317819, 808.3250485844268, 818.5211651184125, 828.4615000249886, 4179.307380510898, 4879.04838064777, 4905.288733133676, 4926.802927882844, 4933.421305182307, 5315.441878602768, 5374.489423504839, 5388.568914486832, 5393.730313484389, 5435.315052333789, 4598.793054312564, 4665.786895407151, 4877.707337559757, 5302.70835104073, 5310.92033619496, 2397.6283676030243, 2524.9873750631127, 2639.973600263973, 2778.902333578266, 2786.70739572346, 5140.25231421311, 5438.401775804658, 5518.503334699816, 5537.197106419627, 5560.860978475571, 4567.566081222279, 4780.832670856504, 4809.519048095232, 5556.6943818089585, 5566.860828479239, 4864.878378040577, 4888.291197776302, 5505.6696598204235, 5582.692951887685, 5638.462691788794, 4441.667499749998, 5000.874453466583, 5300.549453296513, 5433.12044616354, 5481.835544933659, 5127.077126117335, 5211.583073354198, 5253.001929633358, 5323.467653234646, 5376.650517716336, 4730.9763451182525, 5433.673979561239, 5547.645098597582, 5580.888382232251, 5615.803446879339, 3601.7658852174527, 3604.4953706480587, 3605.3149901518627, 3621.0766254604905, 3626.782393056425, 5294.947050529446, 5695.971520142539, 5743.310802703702, 5744.798932037525, 5762.654240745569, 5554.333479982428, 5567.526760225426, 5597.384287728428, 5597.888042239214, 5611.579131565068, 4215.262329092458, 4625.306204069417, 5483.369412517612, 5548.9722551386985, 5611.0, 4882.072406242803, 5496.230527726041, 5503.20203570472, 5507.669539827623, 5519.365272993497, 3991.900202494961, 4932.7533623319405, 4948.703077815343, 5420.810271640474, 5426.0, 3885.7862817545306, 5342.519173274424, 5366.0, 5452.345718513804, 5528.281323427928], "value": 3934.9334103806386, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [699.4055053204777, 703.2580627438055, 717.6555253478333, 748.5994992678915, 783.4437549339966, 13765.51820686275, 14501.70996580069, 16084.919575402137, 16091.436799711983, 16119.355225790969, 12247.571335003277, 14660.780088298685, 14770.704585908275, 14773.48292809752, 14774.261286935664, 650.0, 797.8244786147038, 818.5784321074651, 819.213554987213, 820.1962077164383, 678.233596036478, 690.1786873620375, 796.9402294827883, 813.8250276190618, 832.6419639554989, 857.7855536115972, 876.6712482818942, 879.226280872831, 879.2438502887512, 879.9912000880005, 873.5239294584435, 874.9256313213384, 875.8905136857893, 876.8202518483696, 877.5042101212795, 509.04553961322523, 525.4902744337986, 627.6391075131818, 644.4232411991242, 651.1892693596456, 14343.21112338821, 14348.85651143496, 14350.282485875665, 14355.928220358832, 14365.0, 12562.246265224025, 12776.744465110645, 12822.871771282262, 12893.548725794646, 12992.805107923388, 12004.2936075702, 12015.219010764273, 12169.0, 12195.0, 12227.633171004794, 4202.684798640114, 4217.177650358181, 4303.182395344875, 4357.498887627931, 4444.511103778582, 88349.55825220897, 90719.54640226823, 90924.54537727269, 91920.0, 92023.0, 8583.35624828128, 10126.0, 10318.535665895053, 10370.844437333446, 10429.634962776418, 17175.742363864552, 18685.439436816916, 19364.0, 19368.612627747367, 19454.0, 7426.517276377126, 7484.214157513492, 7489.400847932152, 7572.697092116364, 7679.19368466303, 4104.15864747726, 4218.978905105516, 4247.5752424757275, 4321.459817522809, 4430.911381772346, 3229.1442767666585, 3386.0857568456377, 3393.440082386428, 3394.4229480988292, 3410.0963244740165, 616.5961295351552, 624.6970219443566, 628.177088014693, 637.1525870592102, 642.8939225027818, 590.6485641043572, 591.7721677154241, 599.9620656264599, 605.2374008749008, 634.9396807303299, 8091.0, 8572.61423235956, 8637.0, 8654.697085601974, 9044.954775226082, 485.56784461829, 545.3074595263993, 594.0851089322396, 604.7036951893629, 611.7002668692397, 3287.474004159371, 3990.481237439172, 4032.5967403259438, 4045.838166473367, 4100.323446631272, 72575.6371218151, 104005.47997259966, 107083.9291607074, 115657.4217128909, 116124.0, 93485.59771603432, 95926.52036739772, 102214.0, 102507.0, 103661.0, 86214.56892715496, 87064.56467717495, 88037.0, 88365.0, 88649.55675221582, 7557.900473111931, 7731.729389471341, 7743.845123097394, 7902.130765615892, 7981.960090199512, 50467.0, 51113.744431277606, 53074.73462632662, 53376.73311633417, 55086.173707395224, 67658.0, 73300.63349683218, 73566.89649655366, 75763.0, 77725.61137194278, 10257.28199026061, 10357.792844143225, 10517.684469465928, 10523.315984460985, 10550.630727924487, 11058.502367393647, 11327.207095503236, 11762.235454695418, 11908.880911191123, 12032.15774895784, 10437.478126093512, 10507.527161277914, 10895.782084358427, 12497.437615307515, 13185.670358241121, 12813.846753792204, 12945.482180712666, 13158.407871645608, 13347.86652133505, 13376.866231337563, 11781.0, 12265.754684906431, 12823.358832058546, 12866.614001579968, 12914.22514649124, 10435.32170408921, 10551.841722374016, 11020.118390528898, 11171.0, 11235.045021173291, 11060.557577696802, 11111.0, 11373.658790236306, 11423.714407139889, 11463.082953363544], "value": 7092.006213196225, "unit": "score"}], "name": "dromaeo_css", "value": 7032.614980491821, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 116.0, 115.0, 115.0, 115.0, 115.0, 115.0, 115.0, 113.0, 115.0], "value": 113.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 110.0, 110.0, 145.0, 109.0, 114.0, 111.0, 111.0, 171.0, 138.0], "value": 126.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 182.0, 185.0, 184.0, 183.0, 184.0, 186.0, 185.0, 185.0, 183.0], "value": 184.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 58.0, 59.0, 89.0, 61.0, 59.0, 60.0, 58.0, 82.0, 58.0], "value": 66.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 69.0, 95.0, 70.0, 67.0, 69.0, 89.0, 67.0, 68.0, 92.0], "value": 77.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 87.0, 88.0, 88.0, 85.0, 89.0, 85.0, 86.0, 84.0], "value": 86.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 102.0, 97.0, 98.0, 96.0, 95.0, 97.0, 97.0, 95.0, 96.0], "value": 96.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 83.0, 79.0, 78.0, 80.0, 83.0, 82.0, 82.0, 80.0, 79.0], "value": 80.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 54.0, 54.0, 54.0, 57.0, 58.0, 59.0, 57.0, 57.0, 58.0], "value": 56.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 81.0, 76.0, 72.0, 74.0, 72.0, 71.0, 71.0, 71.0, 72.0], "value": 73.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 85.0, 83.0, 84.0, 84.0, 85.0, 82.0, 81.0, 84.0], "value": 83.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 152.0, 154.0, 141.0, 140.0, 144.0, 156.0, 154.0, 182.0, 141.0], "value": 151.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 167.0, 165.0, 166.0, 166.0, 164.0, 167.0, 165.0, 164.0, 165.0], "value": 165.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 64.0, 84.0, 67.0, 61.0, 60.0, 79.0, 85.0, 66.0, 57.0], "value": 68.3, "unit": "score"}], "name": "kraken", "value": 1430.6, "alertThreshold": 2.0}]} 02:29:10 INFO - Return code: 0 02:29:10 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:29:10 INFO - # TBPL SUCCESS # 02:29:10 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:29:10 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:29:10 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:29:10 INFO - 2016-12-25 02:29:10.973 screenresolution[2272:30537] starting screenresolution argv=screenresolution get 02:29:10 INFO - 2016-12-25 02:29:10.976 screenresolution[2272:30537] Display 0: 1600x1200x32@60 02:29:10 INFO - 2016-12-25 02:29:10.982 screenresolution[2273:30540] starting screenresolution argv=screenresolution list 02:29:10 INFO - Available Modes on Display 0 02:29:10 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:29:10 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:29:10 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:29:10 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:29:11 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:29:11 INFO - Intel Iris: 02:29:11 INFO - Chipset Model: Intel Iris 02:29:11 INFO - Type: GPU 02:29:11 INFO - Bus: Built-In 02:29:11 INFO - VRAM (Dynamic, Max): 1536 MB 02:29:11 INFO - Vendor: Intel (0x8086) 02:29:11 INFO - Device ID: 0x0a2e 02:29:11 INFO - Revision ID: 0x0009 02:29:11 INFO - Displays: 02:29:11 INFO - Display: 02:29:11 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:29:11 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:29:11 INFO - Display Serial Number: 1600x1200 60 02:29:11 INFO - Main Display: Yes 02:29:11 INFO - Mirror: Off 02:29:11 INFO - Online: Yes 02:29:11 INFO - Rotation: Supported 02:29:11 INFO - Return code: 0 02:29:11 INFO - Running post-action listener: _resource_record_post_action 02:29:11 INFO - [mozharness: 2016-12-25 10:29:11.219630Z] Finished run-tests step (success) 02:29:11 INFO - Running post-run listener: _resource_record_post_run 02:29:11 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 245760; Write bytes: 660277248; Read time: 3; Write time: 1929 02:29:11 INFO - TinderboxPrint: CPU usage
53.4% 02:29:11 INFO - TinderboxPrint: I/O read bytes / time
245,760 / 3 02:29:11 INFO - TinderboxPrint: I/O write bytes / time
660,277,248 / 1,929 02:29:11 INFO - TinderboxPrint: CPU idle
2,258.5 (46.6%) 02:29:11 INFO - TinderboxPrint: CPU system
969.8 (20.0%) 02:29:11 INFO - TinderboxPrint: CPU user
1,621.4 (33.4%) 02:29:11 INFO - TinderboxPrint: Swap in / out
348,217,344 / 0 02:29:11 INFO - install - Wall time: 31s; CPU: 55.0%; Read bytes: 347709952; Write bytes: 346338304; Read time: 26821; Write time: 763 02:29:11 INFO - run-tests - Wall time: 1182s; CPU: 53.0%; Read bytes: 237568; Write bytes: 306791424; Read time: 3; Write time: 1139 02:29:11 INFO - Running post-run listener: _upload_blobber_files 02:29:11 INFO - Blob upload gear active. 02:29:11 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:29:11 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 02:29:11 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'] 02:29:11 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 02:29:12 INFO - (blobuploader) - INFO - Open directory for files ... 02:29:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 02:29:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:29:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:29:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 02:29:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:29:12 INFO - (blobuploader) - INFO - Done attempting. 02:29:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 02:29:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:29:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:29:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 02:29:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:29:13 INFO - (blobuploader) - INFO - Done attempting. 02:29:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:29:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:29:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:29:16 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:29:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:29:16 INFO - (blobuploader) - INFO - Done attempting. 02:29:16 INFO - (blobuploader) - INFO - Iteration through files over. 02:29:16 INFO - Return code: 0 02:29:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:29:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:29:16 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/229d96fc8a779ed6a8672210772985c4442019f46dd254b4be27a8854cbaabd4107cc278eb584c1437f9fae5ec8e16e0a523ba34bd0c9f24773bab9089d38a18", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a45e6f6c16bc09488ff9389e5c5bdc795414fe8aa9836781ed61b3d7adfbe49bf887f50e42342d265c4453ea0742760a8b56f16eacdbb327dbd88ef16c0f0e19", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cd54a4ebbfb7932a6bb8cd3ad6e527ac753ee7dc1156662179e0cc7049c8086cdcee9de0cd6cce945ae9676d7404f27e341c7b160095f6114037be1aec379b82"} 02:29:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:29:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:29:16 INFO - Contents: 02:29:16 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/229d96fc8a779ed6a8672210772985c4442019f46dd254b4be27a8854cbaabd4107cc278eb584c1437f9fae5ec8e16e0a523ba34bd0c9f24773bab9089d38a18", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a45e6f6c16bc09488ff9389e5c5bdc795414fe8aa9836781ed61b3d7adfbe49bf887f50e42342d265c4453ea0742760a8b56f16eacdbb327dbd88ef16c0f0e19", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cd54a4ebbfb7932a6bb8cd3ad6e527ac753ee7dc1156662179e0cc7049c8086cdcee9de0cd6cce945ae9676d7404f27e341c7b160095f6114037be1aec379b82"} 02:29:16 INFO - Running post-run listener: copy_logs_to_upload_dir 02:29:16 INFO - Copying logs to upload dir... 02:29:16 INFO - mkdir: /builds/slave/test/build/upload/logs 02:29:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.903671 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-12-25 02:29:16.187914) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-25 02:29:16.193429) ========= 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.bBTW7nuoo2/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.hS5VYaaqfj/Listeners TMPDIR=/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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/229d96fc8a779ed6a8672210772985c4442019f46dd254b4be27a8854cbaabd4107cc278eb584c1437f9fae5ec8e16e0a523ba34bd0c9f24773bab9089d38a18", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a45e6f6c16bc09488ff9389e5c5bdc795414fe8aa9836781ed61b3d7adfbe49bf887f50e42342d265c4453ea0742760a8b56f16eacdbb327dbd88ef16c0f0e19", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cd54a4ebbfb7932a6bb8cd3ad6e527ac753ee7dc1156662179e0cc7049c8086cdcee9de0cd6cce945ae9676d7404f27e341c7b160095f6114037be1aec379b82"} build_url:https://queue.taskcluster.net/v1/task/d5SBpli3TheMWlhInPIL_A/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011376 build_url: 'https://queue.taskcluster.net/v1/task/d5SBpli3TheMWlhInPIL_A/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/229d96fc8a779ed6a8672210772985c4442019f46dd254b4be27a8854cbaabd4107cc278eb584c1437f9fae5ec8e16e0a523ba34bd0c9f24773bab9089d38a18", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a45e6f6c16bc09488ff9389e5c5bdc795414fe8aa9836781ed61b3d7adfbe49bf887f50e42342d265c4453ea0742760a8b56f16eacdbb327dbd88ef16c0f0e19", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cd54a4ebbfb7932a6bb8cd3ad6e527ac753ee7dc1156662179e0cc7049c8086cdcee9de0cd6cce945ae9676d7404f27e341c7b160095f6114037be1aec379b82"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-25 02:29:16.227700) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-25 02:29:16.228048) ========= 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.bBTW7nuoo2/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.hS5VYaaqfj/Listeners TMPDIR=/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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.005304 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-25 02:29:16.278815) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-25 02:29:16.279385) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-25 02:29:16.279997) ========= ========= Total master_lag: 0.18 =========