builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0286 starttime: 1479930523.4 results: success (0) buildid: 20161123104536 builduid: c0172d0e94b549c1b2e9a78bc3f1c51e revision: 6824ecd27c27cb6194dbca2cef13d12e9ddb3603 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-23 11:48:43.398289) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-23 11:48:43.398866) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-23 11:48:43.399297) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-23 11:48:43.427387) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-23 11:48:43.427705) ========= 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.97aPFSMhdb/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.lUcdxCLlx0/Listeners TMPDIR=/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-11-23 11:48:43-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.1M=0.001s 2016-11-23 11:48:43 (18.1 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.130428 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-23 11:48:43.581319) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-23 11:48:43.581685) ========= 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.97aPFSMhdb/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.lUcdxCLlx0/Listeners TMPDIR=/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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.024644 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-23 11:48:43.630094) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-23 11:48:43.630480) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 6824ecd27c27cb6194dbca2cef13d12e9ddb3603 --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 6824ecd27c27cb6194dbca2cef13d12e9ddb3603 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.97aPFSMhdb/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.lUcdxCLlx0/Listeners TMPDIR=/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-11-23 11:48:43,727 truncating revision to first 12 chars 2016-11-23 11:48:43,727 Setting DEBUG logging. 2016-11-23 11:48:43,727 attempt 1/10 2016-11-23 11:48:43,727 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/6824ecd27c27?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-23 11:48:45,184 unpacking tar archive at: mozilla-aurora-6824ecd27c27/testing/mozharness/ program finished with exit code 0 elapsedTime=1.721846 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-23 11:48:45.375900) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-23 11:48:45.376268) ========= script_repo_revision: 6824ecd27c27cb6194dbca2cef13d12e9ddb3603 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-23 11:48:45.376690) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-23 11:48:45.376993) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-23 11:48:45.396872) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-11-23 11:48:45.397274) ========= /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.97aPFSMhdb/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.lUcdxCLlx0/Listeners TMPDIR=/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 11:48:45 INFO - MultiFileLogger online at 20161123 11:48:45 in /builds/slave/test 11:48:45 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 11:48:45 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:48:45 INFO - {'append_to_log': False, 11:48:45 INFO - 'base_work_dir': '/builds/slave/test', 11:48:45 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 11:48:45 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:48:45 INFO - 'branch': 'Mozilla-Aurora', 11:48:45 INFO - 'buildbot_json_path': 'buildprops.json', 11:48:45 INFO - 'config_files': ('talos/mac_config.py',), 11:48:45 INFO - 'default_actions': ('clobber', 11:48:45 INFO - 'read-buildbot-config', 11:48:45 INFO - 'download-and-extract', 11:48:45 INFO - 'populate-webroot', 11:48:45 INFO - 'create-virtualenv', 11:48:45 INFO - 'install', 11:48:45 INFO - 'run-tests'), 11:48:45 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:48:45 INFO - 'download_minidump_stackwalk': True, 11:48:45 INFO - 'download_symbols': 'ondemand', 11:48:45 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:48:45 INFO - 'tooltool.py': '/tools/tooltool.py', 11:48:45 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:48:45 INFO - '/tools/misc-python/virtualenv.py')}, 11:48:45 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:48:45 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:48:45 INFO - 'installer_path': 'installer.exe', 11:48:45 INFO - 'log_level': 'info', 11:48:45 INFO - 'log_name': 'talos', 11:48:45 INFO - 'log_to_console': True, 11:48:45 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:48:45 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:48:45 INFO - 'opt_config_files': (), 11:48:45 INFO - 'pip_index': False, 11:48:45 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:48:45 INFO - 'cmd': ('bash', 11:48:45 INFO - '-c', 11:48:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:48:45 INFO - 'enabled': True, 11:48:45 INFO - 'halt_on_failure': False, 11:48:45 INFO - 'name': 'check_screen_resolution'},), 11:48:45 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:48:45 INFO - 'cmd': ('bash', 11:48:45 INFO - '-c', 11:48:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:48:45 INFO - 'enabled': True, 11:48:45 INFO - 'halt_on_failure': False, 11:48:45 INFO - 'name': 'check_screen_resolution'},), 11:48:45 INFO - 'run_cmd_checks_enabled': True, 11:48:45 INFO - 'sps_profile': False, 11:48:45 INFO - 'sps_profile_interval': 0, 11:48:45 INFO - 'suite': 'dromaeojs', 11:48:45 INFO - 'system_bits': '32', 11:48:45 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:48:45 INFO - 'title': 't-yosemite-r7-0286', 11:48:45 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:48:45 INFO - 'use_talos_json': True, 11:48:45 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:48:45 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:48:45 INFO - 'work_dir': 'build'} 11:48:45 INFO - [mozharness: 2016-11-23 19:48:45.598132Z] Running clobber step. 11:48:45 INFO - Running pre-action listener: _resource_record_pre_action 11:48:45 INFO - Running main action method: clobber 11:48:45 INFO - rmtree: /builds/slave/test/build 11:48:45 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:48:48 INFO - Running post-action listener: _resource_record_post_action 11:48:48 INFO - [mozharness: 2016-11-23 19:48:48.636248Z] Finished clobber step (success) 11:48:48 INFO - [mozharness: 2016-11-23 19:48:48.636514Z] Running read-buildbot-config step. 11:48:48 INFO - Running pre-action listener: _resource_record_pre_action 11:48:48 INFO - Running main action method: read_buildbot_config 11:48:48 INFO - Using buildbot properties: 11:48:48 INFO - { 11:48:48 INFO - "project": "", 11:48:48 INFO - "product": "firefox", 11:48:48 INFO - "script_repo_revision": "production", 11:48:48 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 11:48:48 INFO - "repository": "", 11:48:48 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 11:48:48 INFO - "buildid": "20161123104536", 11:48:48 INFO - "pgo_build": "False", 11:48:48 INFO - "basedir": "/builds/slave/test", 11:48:48 INFO - "buildnumber": 37, 11:48:48 INFO - "slavename": "t-yosemite-r7-0286", 11:48:48 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 11:48:48 INFO - "platform": "yosemite_r7", 11:48:48 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 11:48:48 INFO - "branch": "mozilla-aurora", 11:48:48 INFO - "repo_path": "releases/mozilla-aurora", 11:48:48 INFO - "revision": "6824ecd27c27cb6194dbca2cef13d12e9ddb3603", 11:48:48 INFO - "stage_platform": "macosx64", 11:48:48 INFO - "builduid": "c0172d0e94b549c1b2e9a78bc3f1c51e", 11:48:48 INFO - "slavebuilddir": "test" 11:48:48 INFO - } 11:48:48 INFO - Found installer url https://queue.taskcluster.net/v1/task/A0Yk00slSpaI3PHRRjtxGg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 11:48:48 INFO - Running post-action listener: _resource_record_post_action 11:48:48 INFO - [mozharness: 2016-11-23 19:48:48.642107Z] Finished read-buildbot-config step (success) 11:48:48 INFO - [mozharness: 2016-11-23 19:48:48.642278Z] Running download-and-extract step. 11:48:48 INFO - Running pre-action listener: _resource_record_pre_action 11:48:48 INFO - Running main action method: download_and_extract 11:48:48 INFO - mkdir: /builds/slave/test/build/tests 11:48:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:48:48 INFO - trying https://queue.taskcluster.net/v1/task/A0Yk00slSpaI3PHRRjtxGg/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 11:48:48 INFO - Downloading https://queue.taskcluster.net/v1/task/A0Yk00slSpaI3PHRRjtxGg/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 11:48:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/A0Yk00slSpaI3PHRRjtxGg/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 11:48:49 INFO - Downloaded 1199 bytes. 11:48:49 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 11:48:49 INFO - Using the following test package requirements: 11:48:49 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 11:48:49 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 11:48:49 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 11:48:49 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 11:48:49 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 11:48:49 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 11:48:49 INFO - u'jsshell-mac.zip'], 11:48:49 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 11:48:49 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 11:48:49 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 11:48:49 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 11:48:49 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 11:48:49 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 11:48:49 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 11:48:49 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 11:48:49 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 11:48:49 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 11:48:49 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 11:48:49 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 11:48:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/A0Yk00slSpaI3PHRRjtxGg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 11:48:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/A0Yk00slSpaI3PHRRjtxGg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 11:48:49 INFO - Fetch https://queue.taskcluster.net/v1/task/A0Yk00slSpaI3PHRRjtxGg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 11:48:49 INFO - Http code: 200 11:48:49 INFO - via: 1.1 bfd7406fdd888e34dfca79886cb7c964.cloudfront.net (CloudFront) 11:48:49 INFO - x-amz-cf-id: OWvH4GjzhV47Xmrkxj9neL7YgKn5E0c4E9gaDsfMBmpKWJ4igACh1A== 11:48:49 INFO - x-amz-version-id: 4A53cQOAjCMQXdBkeavet8_tx2onHj.o 11:48:50 INFO - Expected file size: 16277460 11:48:50 INFO - Obtained file size: 16277460 11:48:56 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 11:48:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/A0Yk00slSpaI3PHRRjtxGg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 11:48:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/A0Yk00slSpaI3PHRRjtxGg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 11:48:56 INFO - Fetch https://queue.taskcluster.net/v1/task/A0Yk00slSpaI3PHRRjtxGg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 11:48:57 INFO - Http code: 200 11:48:57 INFO - via: 1.1 ae162f6796e551002447afd7c07ec67a.cloudfront.net (CloudFront) 11:48:57 INFO - x-amz-cf-id: nxDHocyV1dhn0dpLBn1GkLCxDZTqsEVLhPhQUYyTp6NDkdN9b2HSGA== 11:48:57 INFO - x-amz-version-id: 4A53cQOAjCMQXdBkeavet8_tx2onHj.o 11:48:57 INFO - Expected file size: 16277460 11:48:57 INFO - Obtained file size: 16277460 11:49:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/A0Yk00slSpaI3PHRRjtxGg/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 11:49:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/A0Yk00slSpaI3PHRRjtxGg/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 11:49:04 INFO - Fetch https://queue.taskcluster.net/v1/task/A0Yk00slSpaI3PHRRjtxGg/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 11:49:04 INFO - Http code: 200 11:49:04 INFO - via: 1.1 24b0e5a3429d07ef12381da50e07f70f.cloudfront.net (CloudFront) 11:49:04 INFO - x-amz-cf-id: f5q7QvRIii6K8PccMOSJmihogwoHQzBq12XEpmiRS8WZQQpt7dVg-A== 11:49:04 INFO - x-amz-version-id: JUZEfQeNBsLEcpdxSFKJpg0cztrUQsWG 11:49:04 INFO - Expected file size: 14409038 11:49:04 INFO - Obtained file size: 14409038 11:49: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')]} 11:49:05 INFO - trying https://queue.taskcluster.net/v1/task/A0Yk00slSpaI3PHRRjtxGg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 11:49:05 INFO - Downloading https://queue.taskcluster.net/v1/task/A0Yk00slSpaI3PHRRjtxGg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 11:49:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/A0Yk00slSpaI3PHRRjtxGg/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 11:49:08 INFO - Downloaded 109658693 bytes. 11:49:08 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/A0Yk00slSpaI3PHRRjtxGg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 11:49:08 INFO - mkdir: /builds/slave/test/properties 11:49:08 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:49:08 INFO - Writing to file /builds/slave/test/properties/build_url 11:49:08 INFO - Contents: 11:49:08 INFO - build_url:https://queue.taskcluster.net/v1/task/A0Yk00slSpaI3PHRRjtxGg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 11:49:08 INFO - Running post-action listener: _resource_record_post_action 11:49:08 INFO - Running post-action listener: set_extra_try_arguments 11:49:08 INFO - [mozharness: 2016-11-23 19:49:08.792092Z] Finished download-and-extract step (success) 11:49:08 INFO - [mozharness: 2016-11-23 19:49:08.792187Z] Running populate-webroot step. 11:49:08 INFO - Running pre-action listener: _resource_record_pre_action 11:49:08 INFO - Running main action method: populate_webroot 11:49:08 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:49:08 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:49:08 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:49:08 INFO - '--tppagecycles', 11:49:08 INFO - '7'], 11:49:08 INFO - 'tests': ['tsvgm']}}, 11:49:08 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:49:08 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:49:08 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:49:08 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:49:08 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:49:08 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:49:08 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:49:08 INFO - 'talos_options': ['--disable-e10s'], 11:49:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:49:08 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:49:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:49:08 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:49:08 INFO - 'talos_options': ['--disable-e10s'], 11:49:08 INFO - 'tests': ['damp', 'tps']}, 11:49:08 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:49:08 INFO - 'tests': ['damp', 'tps']}, 11:49:08 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:49:08 INFO - 'tests': ['dromaeo_dom']}, 11:49:08 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:49:08 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:49:08 INFO - 'tests': ['basic_compositor_video']}, 11:49:08 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:49:08 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:49:08 INFO - 'tests': ['a11yr', 11:49:08 INFO - 'ts_paint', 11:49:08 INFO - 'tpaint', 11:49:08 INFO - 'sessionrestore', 11:49:08 INFO - 'sessionrestore_no_auto_restore', 11:49:08 INFO - 'tabpaint']}, 11:49:08 INFO - 'other-e10s': {'tests': ['a11yr', 11:49:08 INFO - 'ts_paint', 11:49:08 INFO - 'tpaint', 11:49:08 INFO - 'sessionrestore', 11:49:08 INFO - 'sessionrestore_no_auto_restore', 11:49:08 INFO - 'tabpaint']}, 11:49:08 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:49:08 INFO - 'ts_paint', 11:49:08 INFO - 'tpaint', 11:49:08 INFO - 'sessionrestore', 11:49:08 INFO - 'sessionrestore_no_auto_restore', 11:49:08 INFO - 'tabpaint']}, 11:49:08 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:49:08 INFO - 'ts_paint', 11:49:08 INFO - 'tpaint', 11:49:08 INFO - 'sessionrestore', 11:49:08 INFO - 'sessionrestore_no_auto_restore', 11:49:08 INFO - 'tabpaint']}, 11:49:08 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:49:08 INFO - 'tests': ['a11yr', 11:49:08 INFO - 'ts_paint', 11:49:08 INFO - 'tpaint', 11:49:08 INFO - 'sessionrestore', 11:49:08 INFO - 'sessionrestore_no_auto_restore', 11:49:08 INFO - 'tabpaint']}, 11:49:08 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:49:08 INFO - 'tests': ['a11yr', 11:49:08 INFO - 'ts_paint', 11:49:08 INFO - 'tpaint', 11:49:08 INFO - 'sessionrestore', 11:49:08 INFO - 'sessionrestore_no_auto_restore', 11:49:08 INFO - 'tabpaint']}, 11:49:08 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:49:08 INFO - 'tests': ['tsvgx', 11:49:08 INFO - 'tsvgr_opacity', 11:49:08 INFO - 'tart', 11:49:08 INFO - 'tscrollx', 11:49:08 INFO - 'cart']}, 11:49:08 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:49:08 INFO - 'tsvgr_opacity', 11:49:08 INFO - 'tart', 11:49:08 INFO - 'tscrollx', 11:49:08 INFO - 'cart']}, 11:49:08 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:49:08 INFO - 'talos_options': ['--disable-e10s'], 11:49:08 INFO - 'tests': ['tp5o']}, 11:49:08 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:49:08 INFO - 'tests': ['tp5o']}, 11:49:08 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:49:08 INFO - 'talos_options': ['--disable-e10s', 11:49:08 INFO - '--xperf_path', 11:49:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:49:08 INFO - 'tests': ['tp5n']}, 11:49:08 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:49:08 INFO - 'talos_options': ['--xperf_path', 11:49:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:49:08 INFO - 'tests': ['tp5n']}}, 11:49:08 INFO - 'talos.zip': {'path': '', 11:49:08 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:49:08 INFO - Running post-action listener: _resource_record_post_action 11:49:08 INFO - [mozharness: 2016-11-23 19:49:08.812009Z] Finished populate-webroot step (success) 11:49:08 INFO - [mozharness: 2016-11-23 19:49:08.812135Z] Running create-virtualenv step. 11:49:08 INFO - Running pre-action listener: _resource_record_pre_action 11:49:08 INFO - Running main action method: create_virtualenv 11:49:08 INFO - Creating virtualenv /builds/slave/test/build/venv 11:49:08 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 11:49:08 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:49:08 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:49:08 INFO - Using real prefix '/tools/python27' 11:49:08 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:49:09 INFO - Installing distribute.............................................................................................................................................................................................done. 11:49:12 INFO - Installing pip.................done. 11:49:12 INFO - Return code: 0 11:49:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:49:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:49:12 INFO - Reading from file tmpfile_stdout 11:49:12 INFO - Output received: 11:49:12 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) 11:49:12 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:49:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:49:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:49:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:49:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:49:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:49:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:49:12 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 0x10089a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100810570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fccf9d99950>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1006aed30>, '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 0x100514db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1006a6b20>, '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.97aPFSMhdb/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.lUcdxCLlx0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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 11:49:12 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 11:49:12 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 11:49:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.97aPFSMhdb/Render', 11:49:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:49:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:49:12 INFO - 'HOME': '/Users/cltbld', 11:49:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:49:12 INFO - 'LOGNAME': 'cltbld', 11:49:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:49:12 INFO - 'MOZ_NO_REMOTE': '1', 11:49:12 INFO - 'NO_EM_RESTART': '1', 11:49:12 INFO - 'PAGER': '/bin/cat', 11:49:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:49:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:49:12 INFO - 'PWD': '/builds/slave/test', 11:49:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:49:12 INFO - 'SHELL': '/bin/bash', 11:49:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lUcdxCLlx0/Listeners', 11:49:12 INFO - 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/', 11:49:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:49:12 INFO - 'USER': 'cltbld', 11:49:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:49:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:49:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:49:12 INFO - 'XPC_FLAGS': '0x0', 11:49:12 INFO - 'XPC_SERVICE_NAME': '0', 11:49:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:49:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:49:13 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 11:49:13 INFO - Cleaning up... 11:49:13 INFO - Return code: 0 11:49:13 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:49:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:49:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:49:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:49:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:49:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:49:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:49:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10089a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100810570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fccf9d99950>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1006aed30>, '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 0x100514db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1006a6b20>, '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.97aPFSMhdb/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.lUcdxCLlx0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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 11:49:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 11:49:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 11:49:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.97aPFSMhdb/Render', 11:49:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:49:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:49:13 INFO - 'HOME': '/Users/cltbld', 11:49:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:49:13 INFO - 'LOGNAME': 'cltbld', 11:49:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:49:13 INFO - 'MOZ_NO_REMOTE': '1', 11:49:13 INFO - 'NO_EM_RESTART': '1', 11:49:13 INFO - 'PAGER': '/bin/cat', 11:49:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:49:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:49:13 INFO - 'PWD': '/builds/slave/test', 11:49:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:49:13 INFO - 'SHELL': '/bin/bash', 11:49:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lUcdxCLlx0/Listeners', 11:49:13 INFO - 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/', 11:49:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:49:13 INFO - 'USER': 'cltbld', 11:49:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:49:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:49:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:49:13 INFO - 'XPC_FLAGS': '0x0', 11:49:13 INFO - 'XPC_SERVICE_NAME': '0', 11:49:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:49:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:49:13 INFO - Downloading/unpacking psutil>=3.1.1 11:49:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:49:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:49:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:49:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:49:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:49:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:49:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:49:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:49:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:49:16 INFO - Installing collected packages: psutil 11:49:16 INFO - Running setup.py install for psutil 11:49:17 INFO - building 'psutil._psutil_osx' extension 11:49:17 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 11:49:17 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 11:49:17 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 11:49:17 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 11:49:17 INFO - building 'psutil._psutil_posix' extension 11:49:17 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 11:49:17 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:49:17 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:49:17 INFO - ^ 11:49:17 INFO - 1 warning generated. 11:49:17 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 11:49:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:49:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:49:18 INFO - Successfully installed psutil 11:49:18 INFO - Cleaning up... 11:49:18 INFO - Return code: 0 11:49:18 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:49:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:49:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:49:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:49:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:49:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:49:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:49:18 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 0x10089a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100810570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fccf9d99950>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1006aed30>, '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 0x100514db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1006a6b20>, '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.97aPFSMhdb/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.lUcdxCLlx0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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 11:49:18 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 11:49:18 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 11:49:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.97aPFSMhdb/Render', 11:49:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:49:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:49:18 INFO - 'HOME': '/Users/cltbld', 11:49:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:49:18 INFO - 'LOGNAME': 'cltbld', 11:49:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:49:18 INFO - 'MOZ_NO_REMOTE': '1', 11:49:18 INFO - 'NO_EM_RESTART': '1', 11:49:18 INFO - 'PAGER': '/bin/cat', 11:49:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:49:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:49:18 INFO - 'PWD': '/builds/slave/test', 11:49:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:49:18 INFO - 'SHELL': '/bin/bash', 11:49:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lUcdxCLlx0/Listeners', 11:49:18 INFO - 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/', 11:49:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:49:18 INFO - 'USER': 'cltbld', 11:49:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:49:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:49:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:49:18 INFO - 'XPC_FLAGS': '0x0', 11:49:18 INFO - 'XPC_SERVICE_NAME': '0', 11:49:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:49:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:49:18 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:49:18 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 11:49:18 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 11:49:18 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 11:49:18 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 11:49:18 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 11:49:18 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 11:49:20 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:49:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:49:20 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:49:20 INFO - Installing collected packages: mozsystemmonitor 11:49:20 INFO - Running setup.py install for mozsystemmonitor 11:49:21 INFO - Successfully installed mozsystemmonitor 11:49:21 INFO - Cleaning up... 11:49:21 INFO - Return code: 0 11:49:21 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:49:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:49:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:49:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:49:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:49:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:49:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:49:21 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 0x10089a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100810570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fccf9d99950>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1006aed30>, '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 0x100514db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1006a6b20>, '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.97aPFSMhdb/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.lUcdxCLlx0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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 11:49:21 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 11:49:21 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 11:49:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.97aPFSMhdb/Render', 11:49:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:49:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:49:21 INFO - 'HOME': '/Users/cltbld', 11:49:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:49:21 INFO - 'LOGNAME': 'cltbld', 11:49:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:49:21 INFO - 'MOZ_NO_REMOTE': '1', 11:49:21 INFO - 'NO_EM_RESTART': '1', 11:49:21 INFO - 'PAGER': '/bin/cat', 11:49:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:49:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:49:21 INFO - 'PWD': '/builds/slave/test', 11:49:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:49:21 INFO - 'SHELL': '/bin/bash', 11:49:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lUcdxCLlx0/Listeners', 11:49:21 INFO - 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/', 11:49:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:49:21 INFO - 'USER': 'cltbld', 11:49:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:49:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:49:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:49:21 INFO - 'XPC_FLAGS': '0x0', 11:49:21 INFO - 'XPC_SERVICE_NAME': '0', 11:49:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:49:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:49:21 INFO - Downloading/unpacking jsonschema==2.5.1 11:49:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:49:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:49:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:49:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:49:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:49:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:49:23 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:49:23 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:49:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:49:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:49:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:49:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:49:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:49:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:49:24 INFO - Downloading functools32-3.2.3-2.tar.gz 11:49:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:49:24 INFO - warning: no files found matching '*.txt' 11:49:24 INFO - no previously-included directories found matching 'build' 11:49:24 INFO - no previously-included directories found matching 'dist' 11:49:24 INFO - no previously-included directories found matching '.git*' 11:49:24 INFO - Installing collected packages: jsonschema, functools32 11:49:24 INFO - Running setup.py install for functools32 11:49:24 INFO - warning: no files found matching '*.txt' 11:49:24 INFO - no previously-included directories found matching 'build' 11:49:24 INFO - no previously-included directories found matching 'dist' 11:49:24 INFO - no previously-included directories found matching '.git*' 11:49:24 INFO - Successfully installed jsonschema functools32 11:49:24 INFO - Cleaning up... 11:49:24 INFO - Return code: 0 11:49:24 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:49:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:49:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:49:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:49:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:49:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:49:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:49:24 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 0x10089a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100810570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fccf9d99950>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1006aed30>, '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 0x100514db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1006a6b20>, '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.97aPFSMhdb/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.lUcdxCLlx0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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 11:49:24 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 11:49:24 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 11:49:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.97aPFSMhdb/Render', 11:49:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:49:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:49:24 INFO - 'HOME': '/Users/cltbld', 11:49:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:49:24 INFO - 'LOGNAME': 'cltbld', 11:49:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:49:24 INFO - 'MOZ_NO_REMOTE': '1', 11:49:24 INFO - 'NO_EM_RESTART': '1', 11:49:24 INFO - 'PAGER': '/bin/cat', 11:49:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:49:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:49:24 INFO - 'PWD': '/builds/slave/test', 11:49:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:49:24 INFO - 'SHELL': '/bin/bash', 11:49:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lUcdxCLlx0/Listeners', 11:49:24 INFO - 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/', 11:49:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:49:24 INFO - 'USER': 'cltbld', 11:49:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:49:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:49:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:49:24 INFO - 'XPC_FLAGS': '0x0', 11:49:24 INFO - 'XPC_SERVICE_NAME': '0', 11:49:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:49:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:49:24 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:49:24 INFO - Cleaning up... 11:49:24 INFO - Return code: 0 11:49:24 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:49:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:49:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:49:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:49:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:49:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:49:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:49:24 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 0x10089a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100810570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fccf9d99950>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1006aed30>, '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 0x100514db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1006a6b20>, '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.97aPFSMhdb/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.lUcdxCLlx0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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 11:49:24 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 11:49:24 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 11:49:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.97aPFSMhdb/Render', 11:49:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:49:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:49:24 INFO - 'HOME': '/Users/cltbld', 11:49:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:49:24 INFO - 'LOGNAME': 'cltbld', 11:49:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:49:24 INFO - 'MOZ_NO_REMOTE': '1', 11:49:24 INFO - 'NO_EM_RESTART': '1', 11:49:24 INFO - 'PAGER': '/bin/cat', 11:49:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:49:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:49:24 INFO - 'PWD': '/builds/slave/test', 11:49:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:49:24 INFO - 'SHELL': '/bin/bash', 11:49:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lUcdxCLlx0/Listeners', 11:49:24 INFO - 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/', 11:49:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:49:24 INFO - 'USER': 'cltbld', 11:49:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:49:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:49:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:49:24 INFO - 'XPC_FLAGS': '0x0', 11:49:24 INFO - 'XPC_SERVICE_NAME': '0', 11:49:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:49:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:49:25 INFO - Downloading/unpacking blobuploader==1.2.4 11:49:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:49:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:49:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:49:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:49:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:49:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:49:27 INFO - Downloading blobuploader-1.2.4.tar.gz 11:49:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:49:27 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:49:27 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 11:49:27 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 11:49:27 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 11:49:27 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 11:49:27 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 11:49:27 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 11:49:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:49:28 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:49:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:49:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:49:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:49:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:49:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:49:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:49:28 INFO - Downloading docopt-0.6.1.tar.gz 11:49:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:49:29 INFO - Installing collected packages: blobuploader, requests, docopt 11:49:29 INFO - Running setup.py install for blobuploader 11:49:29 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:49:29 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:49:29 INFO - Running setup.py install for requests 11:49:29 INFO - Running setup.py install for docopt 11:49:29 INFO - Successfully installed blobuploader requests docopt 11:49:29 INFO - Cleaning up... 11:49:29 INFO - Return code: 0 11:49:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:49: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')]} 11:49:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:49:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:49: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')]} 11:49:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:49:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:49:29 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 0x10089a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100810570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fccf9d99950>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1006aed30>, '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 0x100514db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1006a6b20>, '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.97aPFSMhdb/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.lUcdxCLlx0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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 11:49:29 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 11:49:29 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 11:49:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.97aPFSMhdb/Render', 11:49:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:49:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:49:29 INFO - 'HOME': '/Users/cltbld', 11:49:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:49:29 INFO - 'LOGNAME': 'cltbld', 11:49:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:49:29 INFO - 'MOZ_NO_REMOTE': '1', 11:49:29 INFO - 'NO_EM_RESTART': '1', 11:49:29 INFO - 'PAGER': '/bin/cat', 11:49:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:49:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:49:29 INFO - 'PWD': '/builds/slave/test', 11:49:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:49:29 INFO - 'SHELL': '/bin/bash', 11:49:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lUcdxCLlx0/Listeners', 11:49:29 INFO - 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/', 11:49:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:49:29 INFO - 'USER': 'cltbld', 11:49:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:49:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:49:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:49:29 INFO - 'XPC_FLAGS': '0x0', 11:49:29 INFO - 'XPC_SERVICE_NAME': '0', 11:49:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:49:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:49:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:49:30 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-ahthHO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:49:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:49:30 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-kK_dIc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:49:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:49:30 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-Gf3jI4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:49:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:49:30 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-cwkX7E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:49:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:49:30 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-dKlEJG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:49:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:49:30 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-V4Kcw3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:49:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:49:30 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-bmD2Uu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:49:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:49:30 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-zivSYe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:49:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:49:31 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-QWlgRx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:49:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:49:31 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-DNKBM5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:49:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:49:31 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-fls9p3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:49:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:49:31 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-SdrBKp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:49:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:49:31 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-tjkiFg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:49:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:49:31 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-U53HEU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:49:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:49:31 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-9k_KoL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:49:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:49:31 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-MMDNrX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:49:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:49:31 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-hO0tl3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:49:31 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:49:31 INFO - Running setup.py install for manifestparser 11:49:32 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:49:32 INFO - Running setup.py install for mozcrash 11:49:32 INFO - Running setup.py install for mozdebug 11:49:32 INFO - Running setup.py install for mozdevice 11:49:32 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:49:32 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:49:32 INFO - Running setup.py install for mozfile 11:49:32 INFO - Running setup.py install for mozhttpd 11:49:32 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:49:32 INFO - Running setup.py install for mozinfo 11:49:33 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:49:33 INFO - Running setup.py install for mozInstall 11:49:33 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:49:33 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:49:33 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:49:33 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:49:33 INFO - Running setup.py install for mozleak 11:49:33 INFO - Running setup.py install for mozlog 11:49:33 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:49:33 INFO - Running setup.py install for moznetwork 11:49:33 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:49:33 INFO - Running setup.py install for mozprocess 11:49:33 INFO - Running setup.py install for mozprofile 11:49:33 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:49:33 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:49:33 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:49:33 INFO - Running setup.py install for mozrunner 11:49:34 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:49:34 INFO - Running setup.py install for mozscreenshot 11:49:34 INFO - Running setup.py install for moztest 11:49:34 INFO - Running setup.py install for mozversion 11:49:34 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:49:34 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:49:34 INFO - Cleaning up... 11:49:34 INFO - Return code: 0 11:49:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:49:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:49:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:49:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:49:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:49:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:49:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:49:34 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 0x10089a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100810570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fccf9d99950>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1006aed30>, '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 0x100514db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1006a6b20>, '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.97aPFSMhdb/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.lUcdxCLlx0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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 11:49:34 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 11:49:34 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 11:49:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.97aPFSMhdb/Render', 11:49:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:49:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:49:34 INFO - 'HOME': '/Users/cltbld', 11:49:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:49:34 INFO - 'LOGNAME': 'cltbld', 11:49:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:49:34 INFO - 'MOZ_NO_REMOTE': '1', 11:49:34 INFO - 'NO_EM_RESTART': '1', 11:49:34 INFO - 'PAGER': '/bin/cat', 11:49:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:49:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:49:34 INFO - 'PWD': '/builds/slave/test', 11:49:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:49:34 INFO - 'SHELL': '/bin/bash', 11:49:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lUcdxCLlx0/Listeners', 11:49:34 INFO - 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/', 11:49:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:49:34 INFO - 'USER': 'cltbld', 11:49:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:49:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:49:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:49:34 INFO - 'XPC_FLAGS': '0x0', 11:49:34 INFO - 'XPC_SERVICE_NAME': '0', 11:49:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:49:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:49:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:49:34 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-bWLM4r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:49:34 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)) 11:49:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:49:34 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-rUz5yl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:49:35 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)) 11:49:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:49:35 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-OTDL2z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:49:35 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)) 11:49:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:49:35 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-yt8zdH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:49:35 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)) 11:49:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:49:35 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-u_IKy0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:49:35 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)) 11:49:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:49:35 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-p8XOAu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:49:35 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)) 11:49:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:49:35 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-78JTpY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:49:35 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)) 11:49:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:49:35 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-ylaKzE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:49:35 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)) 11:49:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:49:35 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-TPfOYu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:49:35 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)) 11:49:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:49:35 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-til3j_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:49:35 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)) 11:49:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:49:35 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-9CuRaS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:49:36 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)) 11:49:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:49:36 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-LHq0pp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:49:36 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)) 11:49:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:49:36 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-dyhoCp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:49:36 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)) 11:49:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:49:36 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-3yzPcY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:49:36 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)) 11:49:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:49:36 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-cu9JWW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:49:36 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)) 11:49:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:49:36 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-8tq2vQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:49:36 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)) 11:49:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:49:36 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-LRBliV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:49:36 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)) 11:49:36 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)) 11:49:36 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)) 11:49:36 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)) 11:49:36 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)) 11:49:36 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)) 11:49:36 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:49:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:49:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:49:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:49:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:49:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:49:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:49:39 INFO - Downloading blessings-1.6.tar.gz 11:49:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:49:39 INFO - Installing collected packages: blessings 11:49:39 INFO - Running setup.py install for blessings 11:49:39 INFO - Successfully installed blessings 11:49:39 INFO - Cleaning up... 11:49:39 INFO - Return code: 0 11:49:39 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:49:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:49:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:49:39 INFO - Reading from file tmpfile_stdout 11:49:39 INFO - Current package versions: 11:49:39 INFO - blessings == 1.6 11:49:39 INFO - blobuploader == 1.2.4 11:49:39 INFO - docopt == 0.6.1 11:49:39 INFO - functools32 == 3.2.3-2 11:49:39 INFO - jsonschema == 2.5.1 11:49:39 INFO - manifestparser == 1.1 11:49:39 INFO - mozInstall == 1.12 11:49:39 INFO - mozcrash == 1.0 11:49:39 INFO - mozdebug == 0.1 11:49:39 INFO - mozdevice == 0.48 11:49:39 INFO - mozfile == 1.2 11:49:39 INFO - mozhttpd == 0.7 11:49:39 INFO - mozinfo == 0.9 11:49:39 INFO - mozleak == 0.1 11:49:39 INFO - mozlog == 3.4 11:49:39 INFO - moznetwork == 0.27 11:49:39 INFO - mozprocess == 0.23 11:49:39 INFO - mozprofile == 0.28 11:49:39 INFO - mozrunner == 6.13 11:49:39 INFO - mozscreenshot == 0.1 11:49:39 INFO - mozsystemmonitor == 0.3 11:49:39 INFO - moztest == 0.7 11:49:39 INFO - mozversion == 1.4 11:49:39 INFO - psutil == 3.1.1 11:49:39 INFO - requests == 1.2.3 11:49:39 INFO - wsgiref == 0.1.2 11:49:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:49:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:49:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:49:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:49:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:49:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:49:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:49:40 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 0x10089a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100810570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fccf9d99950>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1006aed30>, '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 0x100514db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1006a6b20>, '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.97aPFSMhdb/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.lUcdxCLlx0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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 11:49:40 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 11:49:40 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 11:49:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.97aPFSMhdb/Render', 11:49:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:49:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:49:40 INFO - 'HOME': '/Users/cltbld', 11:49:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:49:40 INFO - 'LOGNAME': 'cltbld', 11:49:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:49:40 INFO - 'MOZ_NO_REMOTE': '1', 11:49:40 INFO - 'NO_EM_RESTART': '1', 11:49:40 INFO - 'PAGER': '/bin/cat', 11:49:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:49:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:49:40 INFO - 'PWD': '/builds/slave/test', 11:49:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:49:40 INFO - 'SHELL': '/bin/bash', 11:49:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lUcdxCLlx0/Listeners', 11:49:40 INFO - 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/', 11:49:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:49:40 INFO - 'USER': 'cltbld', 11:49:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:49:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:49:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:49:40 INFO - 'XPC_FLAGS': '0x0', 11:49:40 INFO - 'XPC_SERVICE_NAME': '0', 11:49:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:49:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:49:41 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)) 11:49:41 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)) 11:49:41 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)) 11:49:41 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)) 11:49:41 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)) 11:49:41 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)) 11:49:41 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)) 11:49:41 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)) 11:49:41 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)) 11:49:41 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)) 11:49:41 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)) 11:49:41 INFO - Cleaning up... 11:49:41 INFO - Return code: 0 11:49:41 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:49:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:49:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:49:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:49:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:49:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:49:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:49:41 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 0x10089a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100810570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fccf9d99950>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1006aed30>, '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 0x100514db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1006a6b20>, '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.97aPFSMhdb/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.lUcdxCLlx0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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 11:49:41 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 11:49:41 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 11:49:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.97aPFSMhdb/Render', 11:49:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:49:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:49:41 INFO - 'HOME': '/Users/cltbld', 11:49:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:49:41 INFO - 'LOGNAME': 'cltbld', 11:49:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:49:41 INFO - 'MOZ_NO_REMOTE': '1', 11:49:41 INFO - 'NO_EM_RESTART': '1', 11:49:41 INFO - 'PAGER': '/bin/cat', 11:49:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:49:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:49:41 INFO - 'PWD': '/builds/slave/test', 11:49:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:49:41 INFO - 'SHELL': '/bin/bash', 11:49:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lUcdxCLlx0/Listeners', 11:49:41 INFO - 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/', 11:49:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:49:41 INFO - 'USER': 'cltbld', 11:49:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:49:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:49:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:49:41 INFO - 'XPC_FLAGS': '0x0', 11:49:41 INFO - 'XPC_SERVICE_NAME': '0', 11:49:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:49:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:49:41 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:49:41 INFO - Cleaning up... 11:49:41 INFO - Return code: 0 11:49:41 INFO - Running post-action listener: _resource_record_post_action 11:49:41 INFO - Running post-action listener: _start_resource_monitoring 11:49:41 INFO - Starting resource monitoring. 11:49:41 INFO - [mozharness: 2016-11-23 19:49:41.862506Z] Finished create-virtualenv step (success) 11:49:41 INFO - [mozharness: 2016-11-23 19:49:41.863555Z] Running install step. 11:49:41 INFO - Running pre-action listener: _resource_record_pre_action 11:49:41 INFO - Running main action method: install 11:49:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:49:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:49:42 INFO - Reading from file tmpfile_stdout 11:49:42 INFO - Detecting whether we're running mozinstall >=1.0... 11:49:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:49:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:49:42 INFO - Reading from file tmpfile_stdout 11:49:42 INFO - Output received: 11:49:42 INFO - Usage: mozinstall [options] installer 11:49:42 INFO - Options: 11:49:42 INFO - -h, --help show this help message and exit 11:49:42 INFO - -d DEST, --destination=DEST 11:49:42 INFO - Directory to install application into. [default: 11:49:42 INFO - "/builds/slave/test"] 11:49:42 INFO - --app=APP Application being installed. [default: firefox] 11:49:42 INFO - mkdir: /builds/slave/test/build/application 11:49:42 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'] 11:49:42 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 11:50:12 INFO - Reading from file tmpfile_stdout 11:50:12 INFO - Output received: 11:50:12 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 11:50:12 INFO - Running post-action listener: _resource_record_post_action 11:50:12 INFO - [mozharness: 2016-11-23 19:50:12.965312Z] Finished install step (success) 11:50:12 INFO - [mozharness: 2016-11-23 19:50:12.965496Z] Running run-tests step. 11:50:12 INFO - Running pre-action listener: _resource_record_pre_action 11:50:12 INFO - Running main action method: run_tests 11:50:12 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:50:12 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:50:12 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:50:12 INFO - 2016-11-23 11:50:12.980 screenresolution[1631:7356] starting screenresolution argv=screenresolution get 11:50:12 INFO - 2016-11-23 11:50:12.982 screenresolution[1631:7356] Display 0: 1600x1200x32@60 11:50:12 INFO - 2016-11-23 11:50:12.988 screenresolution[1632:7358] starting screenresolution argv=screenresolution list 11:50:12 INFO - Available Modes on Display 0 11:50:12 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:50:12 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:50:12 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:50:12 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:50:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:50:13 INFO - Intel Iris: 11:50:13 INFO - Chipset Model: Intel Iris 11:50:13 INFO - Type: GPU 11:50:13 INFO - Bus: Built-In 11:50:13 INFO - VRAM (Dynamic, Max): 1536 MB 11:50:13 INFO - Vendor: Intel (0x8086) 11:50:13 INFO - Device ID: 0x0a2e 11:50:13 INFO - Revision ID: 0x0009 11:50:13 INFO - Displays: 11:50:13 INFO - Display: 11:50:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:50:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:50:13 INFO - Display Serial Number: 1600x1200 60 11:50:13 INFO - Main Display: Yes 11:50:13 INFO - Mirror: Off 11:50:13 INFO - Online: Yes 11:50:13 INFO - Rotation: Supported 11:50:13 INFO - Return code: 0 11:50:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:50:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:50:13 INFO - Python 2.7.3 11:50:13 INFO - Return code: 0 11:50:13 INFO - grabbing minidump binary from tooltool 11:50:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:50:13 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 0x1006aed30>, '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 0x100514db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1006a6b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:50:13 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 11:50:13 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 11:50:13 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 11:50:13 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:50:13 INFO - Return code: 0 11:50:13 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:50:13 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:50:13 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:50:13 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:50:13 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:50:13 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/A0Yk00slSpaI3PHRRjtxGg/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0286', '--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 11:50:13 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/A0Yk00slSpaI3PHRRjtxGg/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0286 --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 11:50:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.97aPFSMhdb/Render', 11:50:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:50:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:50:13 INFO - 'HOME': '/Users/cltbld', 11:50:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:50:13 INFO - 'LOGNAME': 'cltbld', 11:50:13 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:50:13 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:50:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:50:13 INFO - 'MOZ_NO_REMOTE': '1', 11:50:13 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:50:13 INFO - 'NO_EM_RESTART': '1', 11:50:13 INFO - 'PAGER': '/bin/cat', 11:50:13 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:50:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:50:13 INFO - 'PWD': '/builds/slave/test', 11:50:13 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:50:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:50:13 INFO - 'SHELL': '/bin/bash', 11:50:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lUcdxCLlx0/Listeners', 11:50:13 INFO - 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/', 11:50:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:50:13 INFO - 'USER': 'cltbld', 11:50:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:50:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:50:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:50:13 INFO - 'XPC_FLAGS': '0x0', 11:50:13 INFO - 'XPC_SERVICE_NAME': '0', 11:50:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:50:13 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/A0Yk00slSpaI3PHRRjtxGg/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0286', '--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 11:50:13 INFO - mozversion application_buildid: 20161123104536 11:50:13 INFO - mozversion application_changeset: 6824ecd27c27cb6194dbca2cef13d12e9ddb3603 11:50:13 INFO - mozversion application_display_name: FirefoxDeveloperEdition 11:50:13 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:50:13 INFO - mozversion application_name: Firefox 11:50:13 INFO - mozversion application_remotingname: firefox-dev 11:50:13 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 11:50:13 INFO - mozversion application_vendor: Mozilla 11:50:13 INFO - mozversion application_version: 52.0a2 11:50:13 INFO - mozversion platform_buildid: 20161123104536 11:50:13 INFO - mozversion platform_changeset: 6824ecd27c27cb6194dbca2cef13d12e9ddb3603 11:50:13 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 11:50:13 INFO - mozversion platform_version: 52.0a2 11:50:13 INFO - using testdate: 1479930613 11:50:13 INFO - actual date: 1479930613 11:50:13 INFO - starting webserver on 'localhost:49241' 11:50:13 INFO - SUITE-START | Running 2 tests 11:50:13 INFO - TEST-START | dromaeo_css 11:50:13 INFO - operating with platform_type : mac_ 11:50:13 INFO - Initialising browser for dromaeo_css test... 11:50:13 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/tmp0SsVt5/profile http://localhost:49241/getInfo.html) 11:50:15 ERROR - PROCESS | 1642 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 5373: TypeError: parent.firstChild is null 11:50:16 INFO - PROCESS | 1642 | __metrics Screen width/height:1600/1200 11:50:16 INFO - PROCESS | 1642 | colorDepth:24 11:50:16 INFO - PROCESS | 1642 | Browser inner width/height: 1024/689 11:50:16 INFO - PROCESS | 1642 | __metrics 11:50:16 INFO - PROCESS | 1642 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 11:50:16 INFO - PROCESS | 1642 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:50:17 INFO - TEST-INFO | 1642: exit 0 11:50:17 INFO - Browser initialized. 11:50:17 INFO - Running cycle 1/1 for dromaeo_css test... 11:50:17 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/tmp0SsVt5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:50:29 INFO - PROCESS | 1644 | RSS: Main: 223006720 11:50:29 INFO - PROCESS | 1644 | 11:51:21 INFO - PROCESS | 1644 | 1479930681137 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] 11:53:34 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 11:53:36 INFO - PROCESS | 1644 | RSS: Main: 376868864 11:53:36 INFO - PROCESS | 1644 | 11:56:39 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 11:56:39 INFO - PROCESS | 1644 | RSS: Main: 363712512 11:56:39 INFO - PROCESS | 1644 | 11:59:42 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 11:59:42 INFO - PROCESS | 1644 | RSS: Main: 310366208 11:59:42 INFO - PROCESS | 1644 | 12:02:45 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 12:02:45 INFO - PROCESS | 1644 | RSS: Main: 320761856 12:02:45 INFO - PROCESS | 1644 | 12:05:49 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 12:05:49 INFO - PROCESS | 1644 | RSS: Main: 307769344 12:05:49 INFO - PROCESS | 1644 | 12:08:52 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 12:08:53 INFO - PROCESS | 1644 | RSS: Main: 358748160 12:08:53 INFO - PROCESS | 1644 | 12:08:53 INFO - PROCESS | 1644 | __start_tp_report 12:08:53 INFO - PROCESS | 1644 | _x_x_mozilla_page_load 12:08:53 INFO - PROCESS | 1644 | _x_x_mozilla_page_load_details 12:08:53 INFO - PROCESS | 1644 | |i|pagename|runs| 12:08:53 INFO - PROCESS | 1644 | |0;dojo.html;3309.8345082745855;3325.501174823777;3351.8156501392427;3372.123247955531;3394.1344957035953;4467.419235499388;5510.448955104487;5641.802536911209;5769.307683078031;5784.826455206346;5591.30108736408;5682.6306290091115;5686.63036902601;5714.200011998323;5721.456461636149;5115.2071428928575;5117.616178786588;5153.871153221162;5182.8704282393055;5191.325127733389;5149.279100925854;5149.665271757342;5167.431582525919;5243.3970093439175;5263.1578947368425;5166.863290076177;5214.452482489341;5225.425203227642;5234.162533994561;5234.319538459995;5204.29741984831;5226.660267082628;5263.763130659129;5282.0756367635895;5326.7869285228935;5059.139946209117;5135.4094279158;5223.955208958186;5233.528982391603;5255.921161182585;4426.336049592554;5586.301712285965;5590.301212348457;5607.214989901417;5613.915791263134;6148.077788331741;6167.167432396614;6201.6899155042065;6266.965950618142;6285.151504546919;6303.621782693007;6321.24145102586;6325.430711235965;6344.270408902989;6366.9681651591445;4755.904881902343;5672.602917795714;5729.742161602738;5739.225204597409;5744.971275143597;6250.593711408744;6273.560850740496;6281.654509002049;6284.497240220771;6294.590851594632;6139.355367686418;6158.199434073542;6171.012637977902;6189.6286222826775;6193.6903154842075;6129.325774164839;6135.9079613805825;6137.481585058268;6180.721867515973;6267.905981410283;6178.62928224308;6245.500359971192;6253.812385628438;6253.843653908689;6255.43701066897;6228.065790131516;6267.1539342188225;6279.277883043417;6281.434670879643;6302;4662.160811054043;4877.5366340197625;4915.754212289371;4937.456879743226;4953.1332016896895;4250.06498570314;4340.891477713082;4404.933925991113;4407.4050003249795;4439.622632076245;5759.596828222068;5760.971195143998;5769.53843692512;5773.538116950634;5777.768889244468;6139.9079013813935;6281;6289.245290565162;6290.874182516415;6310.274318453345;4636.281376386665;5618.887622247532;5669.6314739541795;5672.829815105553;5676.148577713294;5349.5720342372515;5375;5389.9191512127345;5404.135338345845;5437.809676661377;6257.530685198537;6262.3111457739615;6271.717772700148;6283.528735344776;6294.496440284858;4665.533446655308;6108.281362586102;6108.949222962717;6270.905936410866;6279.591826531261;6228.813135605939;6294.496440284858;6315.5894866833505;6325.304216536086;6334.049892516159;5980.760769569169;6009.90985135232;6043.425874541911;6048.36492168316;6090.7377302603045;5831.35855055952;5950.851228719317;6162.599431036968;6175.536834737323;6185.783497577563;5721.885562288648;6066.666333351708;6118.235220597426;6143.754249829957;6172.444479996642;6122.947834719419;6125.84596676928;6127.128444391175;6127.724252408563;6142.161149281699;5967.671778052249;5983.760649573968;6028.668423236764;6030.125631783455;6042.8791424170395;5954.196183515343;5990.760369585168;6025.246844144482;6032.577719559588;6106.267247930276;5093.541581257705;5836.941630583639;5927.851803704942;5999.280086389661;6009.2188015557695 12:08:53 INFO - PROCESS | 1644 | |1;ext.html;471.44605089020394;501.5736623869709;514.4778050278966;515.6029857010101;521.0438844719939;29226.12321630349;30759.99999999997;30897.536536951906;30968.690313096864;31006.379872402566;36848.26303473936;38523.614763852354;38699.419508707324;40613.99999999993;40789.59210407895;9158.725238242847;9320.673776417827;9322.86015709765;9325.860112098324;9331.580078896466;7910.4462687611895;9162.908370916306;9238.953805230964;9248.352615316931;9250.02874698157;9157.542122893861;9207.631694732203;9259.768505787342;9273.768155796091;9277.165055145035;9213.907860921405;9251.537423128817;9275.628974840998;9282.582283797245;9283.210927071206;7914.445988780788;9206.309526785519;9269.258459323239;9279.118483744034;9301.069893010712;37405.81297093524;37746.43380349297;37798.81100594507;37831.62168378309;37997.24005519902;3136.9215769605944;3276.836158192081;3284;3631.418972964313;3765.3410653135593;28296.858515707496;28422.14733557976;30039.699603004126;30740.077797666105;30932.381352372828;3147.8111313321274;3159.7314228290625;3166.794158379721;3199.63204231514;3249.122736861058;502684;503278;503470;505121;517736;32926.17684557857;33486.162845929044;33826;56708;56986.145207821915;34614.13464663403;34769;34806;34820;34862.82568587192;13848.09987350819;13856.376463059187;14247.501337453139;14382.424703011762;14406.919481038964;12057.698557535956;12180.512779488721;12185.268883866996;12203.02375809933;12241.449134788778;7669.5398276103615;7674.57789821554;7728.884066739003;7744.2642948919765;7761.495502792413;3184;3224.7742658013713;3225.080851957181;3230.4831227003565;3263.0047835409955;767.9731209407644;861.6424183963585;915.9221466175317;1000.5497526113278;1011.5903059261011;28436;28932.98734544281;29314.560281595794;29627.259318517208;29836;1052.547404616007;1096.703889949709;1103.3545375954996;1135.6422726841183;1156.7744289863397;14625.85374146245;14921.925390372979;15073.849261507245;15460.304286307142;15481.612959676098;679410;687300;688093;688225;688640.5567972128;51165;52642.683932901986;55953.44046559564;56356.15465768017;56434.15348769772;1315.7302752935625;1328.4022190014532;1344.7848344264871;1346.2393747532612;1360.7890776929592;28474.85762571174;28672.283192920342;28685.54175914319;28728.569071463528;29640.258993525335;52380.21429678634;53954;54218.728906355216;57458.42541574531;57580.56048598651;504171;507844;508251;510955;511068;39769.80115099522;40106.19787604173;40283.1943361137;40635.59364406318;40894;44813.551864480934;45078;45441.86395340143;45466.77266613646;45708;36251.09372265715;36643.63356366509;39532.60467395289;40656.59343406528;40767.1846563073;37587.436188456646;37775;37920.05199869915;38998.41502377523;39198.6080139195;31436.40641155624;31651.52522712115;32180.839095805306;36544;37253;25730.555597218234;30623.846880765454;34296.82851585726;34337.656623433446;34480.31039379148;24933.37666558278;25244;25374.74625253724;25878.353041174123;25903.611445828705 12:08:53 INFO - PROCESS | 1644 | |2;jquery.html;330.7040199021875;351.43945407075705;368.4031868373234;380.1484674329501;400.869717341864;14501.347439365227;14706.485273015447;15402.229888505586;19292.3247686331;19943.800561994376;17631.471055868344;18331.54171145722;19310.0344982751;20342.694859577117;20344.898275508604;5125.897482050356;5409.810656627019;5411.83764487066;5416.647917885344;5426.810061647843;4952.801887924478;5334.199870019509;5358.892822143554;5390.541803946649;5412.83761487156;5361.785528578852;5376.435474275204;5405.324334458192;5409.729513524328;5421.403645599001;5158.613104017214;5343.198520221998;5376.381716102659;5381.9461805382225;5412.2152287918225;5224.033553792523;5225.007248622749;5231.346081739783;5232.476752324776;5234.319538460014;12948;13536;21445.39159563039;21756.673649895107;24822.627660585105;2152.752433470155;2203.8787866667326;2291.3927809130596;2312.7571604981404;2408.5664580375524;9610.807783844284;9672.129508344213;9903.900960990442;10387.84418233727;10423.5830566778;1819.5360183153343;1909.0168563189968;1917.1851962915705;1917.4343568647282;1924.8363889069446;113851;115651;124863;126068.73931260662;126264.1060384095;11180.496877640526;11432;11445.656630300939;11522.078233741448;11707.180497365103;13889.861101389059;14162;14227.35976881043;14230.644233894029;14241.78637320441;7134.357907788324;8135.535603591292;8382.119877412906;8413.537255450887;8496.235338819535;8150.551719655357;8204.958975205205;8228.30059444942;8392.580370981548;8399;3774.226283611853;4489.124620698998;4502.189605870909;4503.527129651405;4552.590266875997;1622.1889055472263;1705.104819969526;1739.5911960689227;1826.5616252099667;1837.136545823461;700.8808502554577;760.9961950190213;970.7815741458115;999.9500024998721;1017.9694609161737;19122.71315930262;19808.20767169297;19815.702764458543;20755.68866467004;21210.15159393607;895.5029958373035;932.7807965128189;943.4575119306427;964.320154291223;1035.3115178406276;10389.9480502597;10724;10784.568617255221;10795.78408431827;10846.969537893887;536941;538774;539608;575873;608886;76307.61846190733;78846.21153788503;87137.12862871417;133166.33416832853;141165;932.7108596335158;983.9803203936025;1002.5288114586133;1002.5538635307219;1004.0511716428051;18221.726674100166;18611.81388186101;19057.523561911064;19138.330158444944;19272.22911083541;42053.579464204966;52168.73915630398;55793.047243347915;57931;59138.52153696192;80589.59705201436;83211.5839420799;84955;86773;86784.69822952534;7834.255745704149;7889.4082943780595;7913.881291780743;8163.183681631789;8164.510129392255;10831.783364332512;12132.939335303266;12216.083793715328;12241.877581224073;12253.652098269362;10300.948495257475;10364.274500784873;10715.464226788785;10885.238033337908;10891.237613366988;10399.116075133395;10627.521761520595;10630.84053739179;10841.728956775829;10867.402292873969;10615.575376984834;10719.892801072201;10919.617813376493;11035.944820275847;11037.503312351124;7817.5121777354725;7860.606969651609;7992.600369981361;8013.439059266028;8269.95865020695;7667.769966901002;10055.296129271173;10173.898261017592;10572.36565806054;11266.830997535208 12:08:53 INFO - PROCESS | 1644 | |3;mootools.html;1301.5054279373837;1304.9869501304986;1323.4772264955336;1575.5037163293562;1826.1964735516362;7560.017197764289;7976.242256985584;8148.59257037148;8475.364347673934;8492;7997.520148791076;8231.506109633427;8266.256036956673;8269.090400056004;8284.295834854045;2010.5576773109892;2301.5051763870765;2342.0982921575232;2345.179187284445;2348.0607756897207;2342.9531409371803;2382.452036031705;2392.3301475586863;2396.3050715292543;2398.1486572266854;2327.173853282086;2337.5441788851094;2370.146747171017;2374.3707917401903;2378.0368950575044;2319.814414846825;2327.243645815117;2352.2002519143402;2361.480474295652;2361.53949979754;2351.3651314145254;2378.988105059481;2379.785819276256;2381.4641705616273;2384.7376788553247;8251.587420628945;8385.874211886827;8452.83094338116;8464.44981076228;8469.534175620338;5096.923546146811;5257.053730328539;5259.447757985433;5259.500347466985;5264.394594621629;3482.582090149173;3618.493410922447;3655.6344365563496;3702.0559757261726;3706.09200745819;1076.8492411062457;1079.2121751121724;1100.301308668993;1110.8167152419842;1115.5649296774266;11096.500657470433;11157;11303.491342889589;11329.206955513117;11354;4517.932231016537;4686.718796872198;4719.716816990992;4777.593904518155;4787.138315103245;9626.90373096274;9629.422234665943;9633.662821801203;9636.26547221493;9655.131038206595;5274.630775845654;5277.815276465305;5279.973600132052;5282.894342113136;5287.947120528823;4177.373393990865;4336.934945975813;4340.2187606230555;4458.643308535375;4775.617950563947;3831.1379939513945;3841.8847434577006;3947.9013024674036;3950.861719839792;3956.9802150989062;1245.1532957588927;1259.703969567151;1261.1865346851414;1272.8981681465461;1277.1443133100845;817.6034623207739;873.4235404632911;878.7539488943106;898.4294972692256;915.5696822493418;4702.482726900038;5715.114157305624;5781.566382521328;5857.619254748428;5965.910511342333;897.9057198994142;908.6592527802075;910.5128756115579;912.885889263842;918.7473444802732;3673.4673472346362;3783.432485127253;3826.942595861064;3844.519435070616;3871.709621778378;9504.857427138599;10272.897271027194;10364.792704145875;10455.581776728846;10490.21323400748;10172.745681357877;10203.03071208249;10424.426656533811;10442.373457592568;10450.111740502143;7809.101953275446;8009.479383840032;8046.517208967363;8053.718119865776;8065.1934806518875;5544.3346798384455;5570.080936645408;5574.331080270394;5713.285839270091;5746.971265143815;9130.269578433577;9458.006909274427;9477.573509191965;9552.904470955202;9569;8889.288856891304;9274;9274.721758347261;9521.714348569554;9582.233421326417;6748.797536073926;7075.398591119607;7275.745348912763;7297.744578939712;7307.501962097864;7901.960490197513;8016.0380754307525;8114.391420643594;8115;8123.47197432165;7125.714971401086;7135.928640713526;7177.569345839266;7224.060872086595;7316.122065352191;7037.964810175917;7072.469564782559;7089.503734738519;7150.14198296187;7166.355028047501;6696.966515167393;6793.388595026274;6863.725450981905;6879.8968015480805;6885.070515480346;4818.253170758538;4850.1269771441475;4891.461939186615;4921.237208232729;4935.481774413778;4815.061063092803;4829.468758436639;4943.332650092191;4975.701457912537;5038.697678139323 12:08:53 INFO - PROCESS | 1644 | |4;prototype.html;302.5250356939605;306.9739072178866;310.69862233633376;319.5542218605046;321.96780321967793;4067.3288907330293;5171.534561889429;5191.428942816292;5325.4940780625875;5346.973265133679;4680.8234171110425;4929.668989372867;5130.02529519391;5134.409542902567;5246.606504512168;3122.703343182394;3772.358699021173;3875.399313106473;3962.1085255817425;3975.085730282037;3858.3633700439395;4006.73956192848;4012.739171953828;4037.9596204037875;4082.4896887889013;3513.5432393788774;3968.365061590146;3983.24318379507;4018.557958624549;4045.2516284487465;4018.1561872006914;4050.696197785178;4059.63463288304;4072.3687828386646;4082.836686532535;2965.7627389808763;3524.436090225577;3526.549048411355;3532.4877892705413;3536.2927414516944;4658.364926897759;4819.614430845524;5170.552245371302;5215.16557350822;5233.336664334981;4199.832006719727;4553.087703164679;4558.202314594932;4559.133764584752;4559.361689363492;5490;5498.945010549884;5529.281193444826;5543.223948647193;5544.889102217973;3674.577423596267;3783.3190025795348;3790.336691079078;3807.9619203807883;3823.0824602095577;5311.62818602698;5557.5831812613815;5587.44125587438;5842.561807864385;5874.530037596982;3566.983409728215;3662.9816850915577;3885.1646895917515;3913.8630147944687;3917.9020524486546;3912.8043597819997;3963.742356746802;3964.6035396460125;3981.422693709396;3988.5213774346685;3941.6649584785614;4391.836163416697;4579.90082380233;4592.793324300348;4634.513376095529;4845.830395936126;4921.729304888194;5517.78608706092;5631.099983508497;5632.9436705632415;1105.9778804423865;1135.9261647992853;1256.428325112072;1257.4215860703996;1272.3141837065925;806.5362416610473;827.1976183102381;830.5473516933286;849.8087930215652;867.2368315881984;4373.715708478939;4403.559644035571;5014.5708473085;5023.6483446159145;5034.572061374751;4668.8132474701315;5012.699238045656;5106.744662766847;5484.561235101182;5518.834434966958;4401.581849724271;4424.734515929054;4442.755648439303;4922.606191504671;4996.5003499650475;2650.8939642414475;2845.630068091135;2859.842708651044;2866.584345269924;2867.240181351943;4886.560209581155;5574.888502230014;5614.231517072191;5614.270144881221;5629.831105066854;5482.698451585121;5499.615026948155;5899.852503687356;5969.283975791005;5996.310424301174;5015.949840501548;6021.789237376758;6025.222559345091;6040.848978775566;6044.914504495942;5502.0921547944145;5504.752286147213;5715.657060576379;5789.1605717171615;5807.767689292381;5068.569171620454;5235.136202526541;5708.5147762439;5717.770069672914;5740.712964351682;5147.411313518702;5654.264945557051;5859.970700146472;5970.924713993071;5973;3330.2673411849355;3403.5405220294942;3642.3990041642833;3865.594112618247;3883.378659414593;5002.749862506787;5664.490195882226;5666.2801855108955;5688.089905615082;5688.40271771458;4470.754108524063;4764.618830493483;5057.747112644427;5127.027242949511;5517.613767036269;4920.72935988524;5405.324334458192;5538.667679939216;5607.383187849252;5622.734884650921;5257.37021523329;5492.995056804318;5498.395176530498;5529.4470552944385;5533.6403133796175;5402.540784033401;5466.562674986071;5640.956423061747;5642.040853055072;5661.46216109469;5552.222688823487;5555.777768889361;5561.943230786138;5563;5568.805091821767 12:08:53 INFO - PROCESS | 1644 | |5;yui.html;666.3669513961737;744.9180590135085;760.8097975506123;765.1010063175775;781.3202513812984;14404.567862964115;14753.26233688314;14759.778603320945;14765.409383624656;14772.852271477283;13103.54137605184;13163.934180329084;13173.538926137588;13185.077044606882;13230.669233269173;667.8063361625148;687.7386593094617;691.8720036793184;810.2788518218761;821.8561751693445;839.1524560194217;841.8947631546057;847.1316900177306;847.6863560482599;850.9446885952393;903.9548022598876;904.9954750226273;905.5608030105394;905.8278927003881;909.2816674826879;697.888337865939;719.9028131202273;896.5651658945407;900.4417261297972;903.0247332880506;634.6985182038541;658.2824721054026;663.7577284291199;664.2693037658585;665.6771465839064;14210.076345037643;14237.216953067576;14245.287735613178;14245.572632821031;14255.78616320756;13063.804042939364;13321.467141314335;13451.529196478174;13452.260125693083;13462.865371346259;11516.769664606745;11855.703607409798;11899.940500297529;12175.93912030443;12605.558805441857;4251.766152861592;4372.213001659732;4438.733675979419;4552.567506086916;4558.316252562109;88781;94524;95394.0460595392;96414;96716.51641741817;7857.332126769289;9840.70409880421;10121.949390253001;10133.863156587608;10295.124914382212;19192.616147677247;19494.415167544998;19497.317593884145;19516.12177452018;19527.609447810963;7201.243869393743;7204.92795072053;7217.92782072183;7500.924990750132;7515.774526764206;3761.6990640748672;3805.46723458716;4041.7574945502774;4094.877153685394;4216.978915105405;3304.339132173575;3397.575303087114;3417.6240613532987;3426.777259478126;3679.889603311905;653.1541653558653;669.464428457242;702.1714377035148;707.8548897476005;715.4848509073458;599.7884273766966;614.640435345319;621.6953692690549;631.1637080867869;657.1194599237045;8420.831583368421;9161.129692679186;9226.07739226072;9239.30705197113;9262;648.4844548583887;657.2179106862818;660.3165723476179;668.1881513960561;670.577536152221;3962.445257663929;4431.844885429059;4434.733915965053;4454.175977444183;4563.520830312769;121207;123523.3823830875;125027;125042;129175.35412322878;62630.37369626245;62824.68587657124;83713.58143209245;101871;106619.40070898943;88180;95033.52483237539;95637;97755;99422;8404.537750423786;8670.739877803677;8678.739637810875;8720.345974051845;8805.42764720291;55995.72002139963;60281.42673106372;60502.69748651228;61753.691231543555;62258;83621.58189209015;85384.57307713422;86293.56853215693;87262.56368718116;88848.5557572208;10942.835857462303;10965.451727413438;11276.7744645105;11315.321080735182;11785;11916.940415297868;12485.31330776816;12598.307093109966;12975.156614820007;13166.80249796273;11476.689217738072;11503;11795.11536634773;11982.760344792881;12022.338771367658;11529.596464123715;11640.592579259684;11661;11697.210045230624;12181.208221465577;11471.713207169887;11574.363410012531;11681.88318116808;11687.941560292144;11707.946284834406;10081.546330415296;10196.847047294148;10250.38497690141;10434.06093451593;10446.373217606968;9701.514924253617;10056.245781566266;10083.899161008296;10120.493975301353;10186.236032297458 12:08:53 INFO - PROCESS | 1644 | __end_tp_report 12:08:53 INFO - PROCESS | 1644 | __start_cc_report 12:08:53 INFO - PROCESS | 1644 | _x_x_mozilla_cycle_collect,1939 12:08:53 INFO - PROCESS | 1644 | __end_cc_report 12:08:53 INFO - PROCESS | 1644 | __startTimestamp1479931733202__endTimestamp 12:08:53 INFO - PROCESS | 1644 | 12:08:53 INFO - PROCESS | 1644 | ------- Summary: start ------- 12:08:53 INFO - PROCESS | 1644 | Number of tests: 6 12:08:53 INFO - PROCESS | 1644 | 12:08:53 INFO - PROCESS | 1644 | [#0] dojo.html Cycles:165 Average:5687.42 Median:5954.20 stddev:672.29 (11.3%) stddev-sans-first:647.96 12:08:53 INFO - PROCESS | 1644 | Values: 3309.8 3325.5 3351.8 3372.1 3394.1 4467.4 5510.4 5641.8 5769.3 5784.8 5591.3 5682.6 5686.6 5714.2 5721.5 5115.2 5117.6 5153.9 5182.9 5191.3 5149.3 5149.7 5167.4 5243.4 5263.2 5166.9 5214.5 5225.4 5234.2 5234.3 5204.3 5226.7 5263.8 5282.1 5326.8 5059.1 5135.4 5224.0 5233.5 5255.9 4426.3 5586.3 5590.3 5607.2 5613.9 6148.1 6167.2 6201.7 6267.0 6285.2 6303.6 6321.2 6325.4 6344.3 6367.0 4755.9 5672.6 5729.7 5739.2 5745.0 6250.6 6273.6 6281.7 6284.5 6294.6 6139.4 6158.2 6171.0 6189.6 6193.7 6129.3 6135.9 6137.5 6180.7 6267.9 6178.6 6245.5 6253.8 6253.8 6255.4 6228.1 6267.2 6279.3 6281.4 6302.0 4662.2 4877.5 4915.8 4937.5 4953.1 4250.1 4340.9 4404.9 4407.4 4439.6 5759.6 5761.0 5769.5 5773.5 5777.8 6139.9 6281.0 6289.2 6290.9 6310.3 4636.3 5618.9 5669.6 5672.8 5676.1 5349.6 5375.0 5389.9 5404.1 5437.8 6257.5 6262.3 6271.7 6283.5 6294.5 4665.5 6108.3 6108.9 6270.9 6279.6 6228.8 6294.5 6315.6 6325.3 6334.0 5980.8 6009.9 6043.4 6048.4 6090.7 5831.4 5950.9 6162.6 6175.5 6185.8 5721.9 6066.7 6118.2 6143.8 6172.4 6122.9 6125.8 6127.1 6127.7 6142.2 5967.7 5983.8 6028.7 6030.1 6042.9 5954.2 5990.8 6025.2 6032.6 6106.3 5093.5 5836.9 5927.9 5999.3 6009.2 12:08:53 INFO - PROCESS | 1644 | 12:08:53 INFO - PROCESS | 1644 | [#1] ext.html Cycles:180 Average:67946.81 Median:37760.72 stddev:153738.66 (407.1%) stddev-sans-first:154086.46 12:08:53 INFO - PROCESS | 1644 | Values: 471.4 501.6 514.5 515.6 521.0 29226.1 30760.0 30897.5 30968.7 31006.4 36848.3 38523.6 38699.4 40614.0 40789.6 9158.7 9320.7 9322.9 9325.9 9331.6 7910.4 9162.9 9239.0 9248.4 9250.0 9157.5 9207.6 9259.8 9273.8 9277.2 9213.9 9251.5 9275.6 9282.6 9283.2 7914.4 9206.3 9269.3 9279.1 9301.1 37405.8 37746.4 37798.8 37831.6 37997.2 3136.9 3276.8 3284.0 3631.4 3765.3 28296.9 28422.1 30039.7 30740.1 30932.4 3147.8 3159.7 3166.8 3199.6 3249.1 502684.0 503278.0 503470.0 505121.0 517736.0 32926.2 33486.2 33826.0 56708.0 56986.1 34614.1 34769.0 34806.0 34820.0 34862.8 13848.1 13856.4 14247.5 14382.4 14406.9 12057.7 12180.5 12185.3 12203.0 12241.4 7669.5 7674.6 7728.9 7744.3 7761.5 3184.0 3224.8 3225.1 3230.5 3263.0 768.0 861.6 915.9 1000.5 1011.6 28436.0 28933.0 29314.6 29627.3 29836.0 1052.5 1096.7 1103.4 1135.6 1156.8 14625.9 14921.9 15073.8 15460.3 15481.6 679410.0 687300.0 688093.0 688225.0 688640.6 51165.0 52642.7 55953.4 56356.2 56434.2 1315.7 1328.4 1344.8 1346.2 1360.8 28474.9 28672.3 28685.5 28728.6 29640.3 52380.2 53954.0 54218.7 57458.4 57580.6 504171.0 507844.0 508251.0 510955.0 511068.0 39769.8 40106.2 40283.2 40635.6 40894.0 44813.6 45078.0 45441.9 45466.8 45708.0 36251.1 36643.6 39532.6 40656.6 40767.2 37587.4 37775.0 37920.1 38998.4 39198.6 31436.4 31651.5 32180.8 36544.0 37253.0 25730.6 30623.8 34296.8 34337.7 34480.3 24933.4 25244.0 25374.7 25878.4 25903.6 12:08:53 INFO - PROCESS | 1644 | 12:08:53 INFO - PROCESS | 1644 | [#2] jquery.html Cycles:180 Average:33034.74 Median:2360.66 stddev:93765.55 (3972.0%) stddev-sans-first:93996.43 12:08:53 INFO - PROCESS | 1644 | Values: 330.7 351.4 368.4 380.1 400.9 14501.3 14706.5 15402.2 19292.3 19943.8 17631.5 18331.5 19310.0 20342.7 20344.9 5125.9 5409.8 5411.8 5416.6 5426.8 4952.8 5334.2 5358.9 5390.5 5412.8 5361.8 5376.4 5405.3 5409.7 5421.4 5158.6 5343.2 5376.4 5381.9 5412.2 5224.0 5225.0 5231.3 5232.5 5234.3 12948.0 13536.0 21445.4 21756.7 24822.6 2152.8 2203.9 2291.4 2312.8 2408.6 9610.8 9672.1 9903.9 10387.8 10423.6 1819.5 1909.0 1917.2 1917.4 1924.8 113851.0 115651.0 124863.0 126068.7 126264.1 11180.5 11432.0 11445.7 11522.1 11707.2 13889.9 14162.0 14227.4 14230.6 14241.8 7134.4 8135.5 8382.1 8413.5 8496.2 8150.6 8205.0 8228.3 8392.6 8399.0 3774.2 4489.1 4502.2 4503.5 4552.6 1622.2 1705.1 1739.6 1826.6 1837.1 700.9 761.0 970.8 1000.0 1018.0 19122.7 19808.2 19815.7 20755.7 21210.2 895.5 932.8 943.5 964.3 1035.3 10389.9 10724.0 10784.6 10795.8 10847.0 536941.0 538774.0 539608.0 575873.0 608886.0 76307.6 78846.2 87137.1 133166.3 141165.0 932.7 984.0 1002.5 1002.6 1004.1 18221.7 18611.8 19057.5 19138.3 19272.2 42053.6 52168.7 55793.0 57931.0 59138.5 80589.6 83211.6 84955.0 86773.0 86784.7 7834.3 7889.4 7913.9 8163.2 8164.5 10831.8 12132.9 12216.1 12241.9 12253.7 10300.9 10364.3 10715.5 10885.2 10891.2 10399.1 10627.5 10630.8 10841.7 10867.4 10615.6 10719.9 10919.6 11035.9 11037.5 7817.5 7860.6 7992.6 8013.4 8270.0 7667.8 10055.3 10173.9 10572.4 11266.8 12:08:53 INFO - PROCESS | 1644 | 12:08:53 INFO - PROCESS | 1644 | [#3] mootools.html Cycles:180 Average:5516.35 Median:5176.99 stddev:3033.27 (58.6%) stddev-sans-first:3025.24 12:08:53 INFO - PROCESS | 1644 | Values: 1301.5 1305.0 1323.5 1575.5 1826.2 7560.0 7976.2 8148.6 8475.4 8492.0 7997.5 8231.5 8266.3 8269.1 8284.3 2010.6 2301.5 2342.1 2345.2 2348.1 2343.0 2382.5 2392.3 2396.3 2398.1 2327.2 2337.5 2370.1 2374.4 2378.0 2319.8 2327.2 2352.2 2361.5 2361.5 2351.4 2379.0 2379.8 2381.5 2384.7 8251.6 8385.9 8452.8 8464.4 8469.5 5096.9 5257.1 5259.4 5259.5 5264.4 3482.6 3618.5 3655.6 3702.1 3706.1 1076.8 1079.2 1100.3 1110.8 1115.6 11096.5 11157.0 11303.5 11329.2 11354.0 4517.9 4686.7 4719.7 4777.6 4787.1 9626.9 9629.4 9633.7 9636.3 9655.1 5274.6 5277.8 5280.0 5282.9 5287.9 4177.4 4336.9 4340.2 4458.6 4775.6 3831.1 3841.9 3947.9 3950.9 3957.0 1245.2 1259.7 1261.2 1272.9 1277.1 817.6 873.4 878.8 898.4 915.6 4702.5 5715.1 5781.6 5857.6 5965.9 897.9 908.7 910.5 912.9 918.7 3673.5 3783.4 3826.9 3844.5 3871.7 9504.9 10272.9 10364.8 10455.6 10490.2 10172.7 10203.0 10424.4 10442.4 10450.1 7809.1 8009.5 8046.5 8053.7 8065.2 5544.3 5570.1 5574.3 5713.3 5747.0 9130.3 9458.0 9477.6 9552.9 9569.0 8889.3 9274.0 9274.7 9521.7 9582.2 6748.8 7075.4 7275.7 7297.7 7307.5 7902.0 8016.0 8114.4 8115.0 8123.5 7125.7 7135.9 7177.6 7224.1 7316.1 7038.0 7072.5 7089.5 7150.1 7166.4 6697.0 6793.4 6863.7 6879.9 6885.1 4818.3 4850.1 4891.5 4921.2 4935.5 4815.1 4829.5 4943.3 4975.7 5038.7 12:08:53 INFO - PROCESS | 1644 | 12:08:53 INFO - PROCESS | 1644 | [#4] prototype.html Cycles:180 Average:4470.34 Median:5013.64 stddev:1388.14 (27.7%) stddev-sans-first:1356.33 12:08:53 INFO - PROCESS | 1644 | Values: 302.5 307.0 310.7 319.6 322.0 4067.3 5171.5 5191.4 5325.5 5347.0 4680.8 4929.7 5130.0 5134.4 5246.6 3122.7 3772.4 3875.4 3962.1 3975.1 3858.4 4006.7 4012.7 4038.0 4082.5 3513.5 3968.4 3983.2 4018.6 4045.3 4018.2 4050.7 4059.6 4072.4 4082.8 2965.8 3524.4 3526.5 3532.5 3536.3 4658.4 4819.6 5170.6 5215.2 5233.3 4199.8 4553.1 4558.2 4559.1 4559.4 5490.0 5498.9 5529.3 5543.2 5544.9 3674.6 3783.3 3790.3 3808.0 3823.1 5311.6 5557.6 5587.4 5842.6 5874.5 3567.0 3663.0 3885.2 3913.9 3917.9 3912.8 3963.7 3964.6 3981.4 3988.5 3941.7 4391.8 4579.9 4592.8 4634.5 4845.8 4921.7 5517.8 5631.1 5632.9 1106.0 1135.9 1256.4 1257.4 1272.3 806.5 827.2 830.5 849.8 867.2 4373.7 4403.6 5014.6 5023.6 5034.6 4668.8 5012.7 5106.7 5484.6 5518.8 4401.6 4424.7 4442.8 4922.6 4996.5 2650.9 2845.6 2859.8 2866.6 2867.2 4886.6 5574.9 5614.2 5614.3 5629.8 5482.7 5499.6 5899.9 5969.3 5996.3 5015.9 6021.8 6025.2 6040.8 6044.9 5502.1 5504.8 5715.7 5789.2 5807.8 5068.6 5235.1 5708.5 5717.8 5740.7 5147.4 5654.3 5860.0 5970.9 5973.0 3330.3 3403.5 3642.4 3865.6 3883.4 5002.7 5664.5 5666.3 5688.1 5688.4 4470.8 4764.6 5057.7 5127.0 5517.6 4920.7 5405.3 5538.7 5607.4 5622.7 5257.4 5493.0 5498.4 5529.4 5533.6 5402.5 5466.6 5641.0 5642.0 5661.5 5552.2 5555.8 5561.9 5563.0 5568.8 12:08:53 INFO - PROCESS | 1644 | 12:08:53 INFO - PROCESS | 1644 | [#5] yui.html Cycles:180 Average:21338.42 Median:4503.37 stddev:32644.45 (724.9%) stddev-sans-first:32699.12 12:08:53 INFO - PROCESS | 1644 | Values: 666.4 744.9 760.8 765.1 781.3 14404.6 14753.3 14759.8 14765.4 14772.9 13103.5 13163.9 13173.5 13185.1 13230.7 667.8 687.7 691.9 810.3 821.9 839.2 841.9 847.1 847.7 850.9 904.0 905.0 905.6 905.8 909.3 697.9 719.9 896.6 900.4 903.0 634.7 658.3 663.8 664.3 665.7 14210.1 14237.2 14245.3 14245.6 14255.8 13063.8 13321.5 13451.5 13452.3 13462.9 11516.8 11855.7 11899.9 12175.9 12605.6 4251.8 4372.2 4438.7 4552.6 4558.3 88781.0 94524.0 95394.0 96414.0 96716.5 7857.3 9840.7 10121.9 10133.9 10295.1 19192.6 19494.4 19497.3 19516.1 19527.6 7201.2 7204.9 7217.9 7500.9 7515.8 3761.7 3805.5 4041.8 4094.9 4217.0 3304.3 3397.6 3417.6 3426.8 3679.9 653.2 669.5 702.2 707.9 715.5 599.8 614.6 621.7 631.2 657.1 8420.8 9161.1 9226.1 9239.3 9262.0 648.5 657.2 660.3 668.2 670.6 3962.4 4431.8 4434.7 4454.2 4563.5 121207.0 123523.4 125027.0 125042.0 129175.4 62630.4 62824.7 83713.6 101871.0 106619.4 88180.0 95033.5 95637.0 97755.0 99422.0 8404.5 8670.7 8678.7 8720.3 8805.4 55995.7 60281.4 60502.7 61753.7 62258.0 83621.6 85384.6 86293.6 87262.6 88848.6 10942.8 10965.5 11276.8 11315.3 11785.0 11916.9 12485.3 12598.3 12975.2 13166.8 11476.7 11503.0 11795.1 11982.8 12022.3 11529.6 11640.6 11661.0 11697.2 12181.2 11471.7 11574.4 11681.9 11687.9 11707.9 10081.5 10196.8 10250.4 10434.1 10446.4 9701.5 10056.2 10083.9 10120.5 10186.2 12:08:53 INFO - PROCESS | 1644 | -------- Summary: end -------- 12:08:53 INFO - PROCESS | 1644 | 12:08:53 INFO - TEST-INFO | 1644: exit 0 12:08:53 INFO - TEST-OK | dromaeo_css | took 1120284ms 12:08:53 INFO - TEST-START | kraken 12:08:53 INFO - operating with platform_type : mac_ 12:08:53 INFO - Initialising browser for kraken test... 12:08:53 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/tmpfChI5q/profile http://localhost:49241/getInfo.html) 12:08:55 ERROR - PROCESS | 1655 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 5373: TypeError: parent.firstChild is null 12:08:56 INFO - PROCESS | 1655 | __metrics Screen width/height:1600/1200 12:08:56 INFO - PROCESS | 1655 | colorDepth:24 12:08:56 INFO - PROCESS | 1655 | Browser inner width/height: 1024/689 12:08:56 INFO - PROCESS | 1655 | __metrics 12:08:56 INFO - PROCESS | 1655 | 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" 12:08:56 INFO - PROCESS | 1655 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:08:57 INFO - TEST-INFO | 1655: exit 0 12:08:57 INFO - Browser initialized. 12:08:57 INFO - Running cycle 1/1 for kraken test... 12:08:57 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/tmpfChI5q/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:09:09 INFO - PROCESS | 1656 | RSS: Main: 222928896 12:09:09 INFO - PROCESS | 1656 | 12:09:12 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 12:09:13 INFO - PROCESS | 1656 | RSS: Main: 273588224 12:09:13 INFO - PROCESS | 1656 | 12:09:15 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 12:09:16 INFO - PROCESS | 1656 | RSS: Main: 284065792 12:09:16 INFO - PROCESS | 1656 | 12:09:19 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 12:09:20 INFO - PROCESS | 1656 | RSS: Main: 380211200 12:09:20 INFO - PROCESS | 1656 | 12:09:22 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 12:09:22 INFO - PROCESS | 1656 | RSS: Main: 283770880 12:09:22 INFO - PROCESS | 1656 | 12:09:24 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:09:25 INFO - PROCESS | 1656 | RSS: Main: 285184000 12:09:25 INFO - PROCESS | 1656 | 12:09:28 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 12:09:28 INFO - PROCESS | 1656 | RSS: Main: 327557120 12:09:28 INFO - PROCESS | 1656 | 12:09:31 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 12:09:32 INFO - PROCESS | 1656 | RSS: Main: 325324800 12:09:32 INFO - PROCESS | 1656 | 12:09:35 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 12:09:35 INFO - PROCESS | 1656 | RSS: Main: 322080768 12:09:35 INFO - PROCESS | 1656 | 12:09:37 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:09:37 INFO - PROCESS | 1656 | RSS: Main: 301703168 12:09:37 INFO - PROCESS | 1656 | 12:09:39 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:09:40 INFO - PROCESS | 1656 | RSS: Main: 301264896 12:09:40 INFO - PROCESS | 1656 | 12:09:42 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:09:43 INFO - PROCESS | 1656 | RSS: Main: 305025024 12:09:43 INFO - PROCESS | 1656 | 12:09:46 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:09:46 INFO - PROCESS | 1656 | RSS: Main: 316899328 12:09:46 INFO - PROCESS | 1656 | 12:09:49 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:09:50 INFO - PROCESS | 1656 | RSS: Main: 315555840 12:09:50 INFO - PROCESS | 1656 | 12:09:52 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 12:09:52 INFO - PROCESS | 1656 | RSS: Main: 359436288 12:09:52 INFO - PROCESS | 1656 | 12:09:52 INFO - PROCESS | 1656 | __start_tp_report 12:09:52 INFO - PROCESS | 1656 | _x_x_mozilla_page_load 12:09:52 INFO - PROCESS | 1656 | _x_x_mozilla_page_load_details 12:09:52 INFO - PROCESS | 1656 | |i|pagename|runs| 12:09:52 INFO - PROCESS | 1656 | |0;ai-astar;115;114;114;113;114;113;114;113;113;114 12:09:52 INFO - PROCESS | 1656 | |1;audio-beat-detection;151;115;114;148;115;114;113;111;142;113 12:09:52 INFO - PROCESS | 1656 | |2;audio-dft;184;182;181;183;187;182;184;184;183;182 12:09:52 INFO - PROCESS | 1656 | |3;audio-fft;79;60;59;89;62;59;59;60;83;57 12:09:52 INFO - PROCESS | 1656 | |4;audio-oscillator;89;71;92;69;68;68;90;66;68;94 12:09:52 INFO - PROCESS | 1656 | |5;imaging-gaussian-blur;87;83;94;130;86;104;114;85;87;84 12:09:52 INFO - PROCESS | 1656 | |6;imaging-darkroom;93;94;94;93;94;94;95;93;93;94 12:09:52 INFO - PROCESS | 1656 | |7;imaging-desaturate;85;81;79;80;81;81;84;86;85;81 12:09:52 INFO - PROCESS | 1656 | |8;json-parse-financial;54;52;51;53;56;57;57;56;56;56 12:09:52 INFO - PROCESS | 1656 | |9;json-stringify-tinderbox;71;69;65;74;70;68;70;69;70;69 12:09:52 INFO - PROCESS | 1656 | |10;stanford-crypto-aes;86;85;85;88;83;87;85;83;83;85 12:09:52 INFO - PROCESS | 1656 | |11;stanford-crypto-ccm;162;147;153;147;149;156;151;145;246;158 12:09:52 INFO - PROCESS | 1656 | |12;stanford-crypto-pbkdf2;170;166;170;170;169;168;167;168;167;175 12:09:52 INFO - PROCESS | 1656 | |13;stanford-crypto-sha256-iterative;69;65;67;64;67;68;67;66;66;66 12:09:52 INFO - PROCESS | 1656 | __end_tp_report 12:09:52 INFO - PROCESS | 1656 | __start_cc_report 12:09:52 INFO - PROCESS | 1656 | _x_x_mozilla_cycle_collect,2035 12:09:52 INFO - PROCESS | 1656 | __end_cc_report 12:09:52 INFO - PROCESS | 1656 | __startTimestamp1479931792468__endTimestamp 12:09:52 INFO - PROCESS | 1656 | 12:09:52 INFO - PROCESS | 1656 | ------- Summary: start ------- 12:09:52 INFO - PROCESS | 1656 | Number of tests: 14 12:09:52 INFO - PROCESS | 1656 | 12:09:52 INFO - PROCESS | 1656 | [#0] ai-astar Cycles:10 Average:113.70 Median:114.00 stddev:0.67 (0.6%) stddev-sans-first:0.53 12:09:52 INFO - PROCESS | 1656 | Values: 115.0 114.0 114.0 113.0 114.0 113.0 114.0 113.0 113.0 114.0 12:09:52 INFO - PROCESS | 1656 | 12:09:52 INFO - PROCESS | 1656 | [#1] audio-beat-detection Cycles:10 Average:123.60 Median:115.00 stddev:16.33 (14.2%) stddev-sans-first:13.99 12:09:52 INFO - PROCESS | 1656 | Values: 151.0 115.0 114.0 148.0 115.0 114.0 113.0 111.0 142.0 113.0 12:09:52 INFO - PROCESS | 1656 | 12:09:52 INFO - PROCESS | 1656 | [#2] audio-dft Cycles:10 Average:183.20 Median:183.50 stddev:1.69 (0.9%) stddev-sans-first:1.76 12:09:52 INFO - PROCESS | 1656 | Values: 184.0 182.0 181.0 183.0 187.0 182.0 184.0 184.0 183.0 182.0 12:09:52 INFO - PROCESS | 1656 | 12:09:52 INFO - PROCESS | 1656 | [#3] audio-fft Cycles:10 Average:66.70 Median:61.00 stddev:12.01 (19.7%) stddev-sans-first:11.88 12:09:52 INFO - PROCESS | 1656 | Values: 79.0 60.0 59.0 89.0 62.0 59.0 59.0 60.0 83.0 57.0 12:09:52 INFO - PROCESS | 1656 | 12:09:52 INFO - PROCESS | 1656 | [#4] audio-oscillator Cycles:10 Average:77.50 Median:80.00 stddev:11.97 (15.0%) stddev-sans-first:11.95 12:09:52 INFO - PROCESS | 1656 | Values: 89.0 71.0 92.0 69.0 68.0 68.0 90.0 66.0 68.0 94.0 12:09:52 INFO - PROCESS | 1656 | 12:09:52 INFO - PROCESS | 1656 | [#5] imaging-gaussian-blur Cycles:10 Average:95.40 Median:85.50 stddev:15.78 (18.5%) stddev-sans-first:16.44 12:09:52 INFO - PROCESS | 1656 | Values: 87.0 83.0 94.0 130.0 86.0 104.0 114.0 85.0 87.0 84.0 12:09:52 INFO - PROCESS | 1656 | 12:09:52 INFO - PROCESS | 1656 | [#6] imaging-darkroom Cycles:10 Average:93.70 Median:94.00 stddev:0.67 (0.7%) stddev-sans-first:0.67 12:09:52 INFO - PROCESS | 1656 | Values: 93.0 94.0 94.0 93.0 94.0 94.0 95.0 93.0 93.0 94.0 12:09:52 INFO - PROCESS | 1656 | 12:09:52 INFO - PROCESS | 1656 | [#7] imaging-desaturate Cycles:10 Average:82.30 Median:82.50 stddev:2.45 (3.0%) stddev-sans-first:2.40 12:09:52 INFO - PROCESS | 1656 | Values: 85.0 81.0 79.0 80.0 81.0 81.0 84.0 86.0 85.0 81.0 12:09:52 INFO - PROCESS | 1656 | 12:09:52 INFO - PROCESS | 1656 | [#8] json-parse-financial Cycles:10 Average:54.80 Median:56.00 stddev:2.15 (3.8%) stddev-sans-first:2.26 12:09:52 INFO - PROCESS | 1656 | Values: 54.0 52.0 51.0 53.0 56.0 57.0 57.0 56.0 56.0 56.0 12:09:52 INFO - PROCESS | 1656 | 12:09:52 INFO - PROCESS | 1656 | [#9] json-stringify-tinderbox Cycles:10 Average:69.50 Median:70.00 stddev:2.27 (3.2%) stddev-sans-first:2.35 12:09:52 INFO - PROCESS | 1656 | Values: 71.0 69.0 65.0 74.0 70.0 68.0 70.0 69.0 70.0 69.0 12:09:52 INFO - PROCESS | 1656 | 12:09:52 INFO - PROCESS | 1656 | [#10] stanford-crypto-aes Cycles:10 Average:85.00 Median:85.00 stddev:1.70 (2.0%) stddev-sans-first:1.76 12:09:52 INFO - PROCESS | 1656 | Values: 86.0 85.0 85.0 88.0 83.0 87.0 85.0 83.0 83.0 85.0 12:09:52 INFO - PROCESS | 1656 | 12:09:52 INFO - PROCESS | 1656 | [#11] stanford-crypto-ccm Cycles:10 Average:161.40 Median:154.50 stddev:30.21 (19.6%) stddev-sans-first:32.04 12:09:52 INFO - PROCESS | 1656 | Values: 162.0 147.0 153.0 147.0 149.0 156.0 151.0 145.0 246.0 158.0 12:09:52 INFO - PROCESS | 1656 | 12:09:52 INFO - PROCESS | 1656 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.00 Median:169.50 stddev:2.54 (1.5%) stddev-sans-first:2.67 12:09:52 INFO - PROCESS | 1656 | Values: 170.0 166.0 170.0 170.0 169.0 168.0 167.0 168.0 167.0 175.0 12:09:52 INFO - PROCESS | 1656 | 12:09:52 INFO - PROCESS | 1656 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.50 Median:67.00 stddev:1.43 (2.1%) stddev-sans-first:1.20 12:09:52 INFO - PROCESS | 1656 | Values: 69.0 65.0 67.0 64.0 67.0 68.0 67.0 66.0 66.0 66.0 12:09:52 INFO - PROCESS | 1656 | -------- Summary: end -------- 12:09:52 INFO - PROCESS | 1656 | 12:09:53 INFO - TEST-INFO | 1656: exit 0 12:09:53 INFO - TEST-OK | kraken | took 59686ms 12:09:53 INFO - SUITE-END | took 1179s 12:09:53 INFO - Completed test suite (00:19:40) 12:09:53 INFO - javascript benchmark 12:09:53 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3309.8345082745855, 3325.501174823777, 3351.8156501392427, 3372.123247955531, 3394.1344957035953, 4467.419235499388, 5510.448955104487, 5641.802536911209, 5769.307683078031, 5784.826455206346, 5591.30108736408, 5682.6306290091115, 5686.63036902601, 5714.200011998323, 5721.456461636149, 5115.2071428928575, 5117.616178786588, 5153.871153221162, 5182.8704282393055, 5191.325127733389, 5149.279100925854, 5149.665271757342, 5167.431582525919, 5243.3970093439175, 5263.1578947368425, 5166.863290076177, 5214.452482489341, 5225.425203227642, 5234.162533994561, 5234.319538459995, 5204.29741984831, 5226.660267082628, 5263.763130659129, 5282.0756367635895, 5326.7869285228935, 5059.139946209117, 5135.4094279158, 5223.955208958186, 5233.528982391603, 5255.921161182585, 4426.336049592554, 5586.301712285965, 5590.301212348457, 5607.214989901417, 5613.915791263134, 6148.077788331741, 6167.167432396614, 6201.6899155042065, 6266.965950618142, 6285.151504546919, 6303.621782693007, 6321.24145102586, 6325.430711235965, 6344.270408902989, 6366.9681651591445, 4755.904881902343, 5672.602917795714, 5729.742161602738, 5739.225204597409, 5744.971275143597, 6250.593711408744, 6273.560850740496, 6281.654509002049, 6284.497240220771, 6294.590851594632, 6139.355367686418, 6158.199434073542, 6171.012637977902, 6189.6286222826775, 6193.6903154842075, 6129.325774164839, 6135.9079613805825, 6137.481585058268, 6180.721867515973, 6267.905981410283, 6178.62928224308, 6245.500359971192, 6253.812385628438, 6253.843653908689, 6255.43701066897, 6228.065790131516, 6267.1539342188225, 6279.277883043417, 6281.434670879643, 6302.0, 4662.160811054043, 4877.5366340197625, 4915.754212289371, 4937.456879743226, 4953.1332016896895, 4250.06498570314, 4340.891477713082, 4404.933925991113, 4407.4050003249795, 4439.622632076245, 5759.596828222068, 5760.971195143998, 5769.53843692512, 5773.538116950634, 5777.768889244468, 6139.9079013813935, 6281.0, 6289.245290565162, 6290.874182516415, 6310.274318453345, 4636.281376386665, 5618.887622247532, 5669.6314739541795, 5672.829815105553, 5676.148577713294, 5349.5720342372515, 5375.0, 5389.9191512127345, 5404.135338345845, 5437.809676661377, 6257.530685198537, 6262.3111457739615, 6271.717772700148, 6283.528735344776, 6294.496440284858, 4665.533446655308, 6108.281362586102, 6108.949222962717, 6270.905936410866, 6279.591826531261, 6228.813135605939, 6294.496440284858, 6315.5894866833505, 6325.304216536086, 6334.049892516159, 5980.760769569169, 6009.90985135232, 6043.425874541911, 6048.36492168316, 6090.7377302603045, 5831.35855055952, 5950.851228719317, 6162.599431036968, 6175.536834737323, 6185.783497577563, 5721.885562288648, 6066.666333351708, 6118.235220597426, 6143.754249829957, 6172.444479996642, 6122.947834719419, 6125.84596676928, 6127.128444391175, 6127.724252408563, 6142.161149281699, 5967.671778052249, 5983.760649573968, 6028.668423236764, 6030.125631783455, 6042.8791424170395, 5954.196183515343, 5990.760369585168, 6025.246844144482, 6032.577719559588, 6106.267247930276, 5093.541581257705, 5836.941630583639, 5927.851803704942, 5999.280086389661, 6009.2188015557695], "value": 5645.267938923961, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [471.44605089020394, 501.5736623869709, 514.4778050278966, 515.6029857010101, 521.0438844719939, 29226.12321630349, 30759.99999999997, 30897.536536951906, 30968.690313096864, 31006.379872402566, 36848.26303473936, 38523.614763852354, 38699.419508707324, 40613.99999999993, 40789.59210407895, 9158.725238242847, 9320.673776417827, 9322.86015709765, 9325.860112098324, 9331.580078896466, 7910.4462687611895, 9162.908370916306, 9238.953805230964, 9248.352615316931, 9250.02874698157, 9157.542122893861, 9207.631694732203, 9259.768505787342, 9273.768155796091, 9277.165055145035, 9213.907860921405, 9251.537423128817, 9275.628974840998, 9282.582283797245, 9283.210927071206, 7914.445988780788, 9206.309526785519, 9269.258459323239, 9279.118483744034, 9301.069893010712, 37405.81297093524, 37746.43380349297, 37798.81100594507, 37831.62168378309, 37997.24005519902, 3136.9215769605944, 3276.836158192081, 3284.0, 3631.418972964313, 3765.3410653135593, 28296.858515707496, 28422.14733557976, 30039.699603004126, 30740.077797666105, 30932.381352372828, 3147.8111313321274, 3159.7314228290625, 3166.794158379721, 3199.63204231514, 3249.122736861058, 502684.0, 503278.0, 503470.0, 505121.0, 517736.0, 32926.17684557857, 33486.162845929044, 33826.0, 56708.0, 56986.145207821915, 34614.13464663403, 34769.0, 34806.0, 34820.0, 34862.82568587192, 13848.09987350819, 13856.376463059187, 14247.501337453139, 14382.424703011762, 14406.919481038964, 12057.698557535956, 12180.512779488721, 12185.268883866996, 12203.02375809933, 12241.449134788778, 7669.5398276103615, 7674.57789821554, 7728.884066739003, 7744.2642948919765, 7761.495502792413, 3184.0, 3224.7742658013713, 3225.080851957181, 3230.4831227003565, 3263.0047835409955, 767.9731209407644, 861.6424183963585, 915.9221466175317, 1000.5497526113278, 1011.5903059261011, 28436.0, 28932.98734544281, 29314.560281595794, 29627.259318517208, 29836.0, 1052.547404616007, 1096.703889949709, 1103.3545375954996, 1135.6422726841183, 1156.7744289863397, 14625.85374146245, 14921.925390372979, 15073.849261507245, 15460.304286307142, 15481.612959676098, 679410.0, 687300.0, 688093.0, 688225.0, 688640.5567972128, 51165.0, 52642.683932901986, 55953.44046559564, 56356.15465768017, 56434.15348769772, 1315.7302752935625, 1328.4022190014532, 1344.7848344264871, 1346.2393747532612, 1360.7890776929592, 28474.85762571174, 28672.283192920342, 28685.54175914319, 28728.569071463528, 29640.258993525335, 52380.21429678634, 53954.0, 54218.728906355216, 57458.42541574531, 57580.56048598651, 504171.0, 507844.0, 508251.0, 510955.0, 511068.0, 39769.80115099522, 40106.19787604173, 40283.1943361137, 40635.59364406318, 40894.0, 44813.551864480934, 45078.0, 45441.86395340143, 45466.77266613646, 45708.0, 36251.09372265715, 36643.63356366509, 39532.60467395289, 40656.59343406528, 40767.1846563073, 37587.436188456646, 37775.0, 37920.05199869915, 38998.41502377523, 39198.6080139195, 31436.40641155624, 31651.52522712115, 32180.839095805306, 36544.0, 37253.0, 25730.555597218234, 30623.846880765454, 34296.82851585726, 34337.656623433446, 34480.31039379148, 24933.37666558278, 25244.0, 25374.74625253724, 25878.353041174123, 25903.611445828705], "value": 18144.20993764857, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [330.7040199021875, 351.43945407075705, 368.4031868373234, 380.1484674329501, 400.869717341864, 14501.347439365227, 14706.485273015447, 15402.229888505586, 19292.3247686331, 19943.800561994376, 17631.471055868344, 18331.54171145722, 19310.0344982751, 20342.694859577117, 20344.898275508604, 5125.897482050356, 5409.810656627019, 5411.83764487066, 5416.647917885344, 5426.810061647843, 4952.801887924478, 5334.199870019509, 5358.892822143554, 5390.541803946649, 5412.83761487156, 5361.785528578852, 5376.435474275204, 5405.324334458192, 5409.729513524328, 5421.403645599001, 5158.613104017214, 5343.198520221998, 5376.381716102659, 5381.9461805382225, 5412.2152287918225, 5224.033553792523, 5225.007248622749, 5231.346081739783, 5232.476752324776, 5234.319538460014, 12948.0, 13536.0, 21445.39159563039, 21756.673649895107, 24822.627660585105, 2152.752433470155, 2203.8787866667326, 2291.3927809130596, 2312.7571604981404, 2408.5664580375524, 9610.807783844284, 9672.129508344213, 9903.900960990442, 10387.84418233727, 10423.5830566778, 1819.5360183153343, 1909.0168563189968, 1917.1851962915705, 1917.4343568647282, 1924.8363889069446, 113851.0, 115651.0, 124863.0, 126068.73931260662, 126264.1060384095, 11180.496877640526, 11432.0, 11445.656630300939, 11522.078233741448, 11707.180497365103, 13889.861101389059, 14162.0, 14227.35976881043, 14230.644233894029, 14241.78637320441, 7134.357907788324, 8135.535603591292, 8382.119877412906, 8413.537255450887, 8496.235338819535, 8150.551719655357, 8204.958975205205, 8228.30059444942, 8392.580370981548, 8399.0, 3774.226283611853, 4489.124620698998, 4502.189605870909, 4503.527129651405, 4552.590266875997, 1622.1889055472263, 1705.104819969526, 1739.5911960689227, 1826.5616252099667, 1837.136545823461, 700.8808502554577, 760.9961950190213, 970.7815741458115, 999.9500024998721, 1017.9694609161737, 19122.71315930262, 19808.20767169297, 19815.702764458543, 20755.68866467004, 21210.15159393607, 895.5029958373035, 932.7807965128189, 943.4575119306427, 964.320154291223, 1035.3115178406276, 10389.9480502597, 10724.0, 10784.568617255221, 10795.78408431827, 10846.969537893887, 536941.0, 538774.0, 539608.0, 575873.0, 608886.0, 76307.61846190733, 78846.21153788503, 87137.12862871417, 133166.33416832853, 141165.0, 932.7108596335158, 983.9803203936025, 1002.5288114586133, 1002.5538635307219, 1004.0511716428051, 18221.726674100166, 18611.81388186101, 19057.523561911064, 19138.330158444944, 19272.22911083541, 42053.579464204966, 52168.73915630398, 55793.047243347915, 57931.0, 59138.52153696192, 80589.59705201436, 83211.5839420799, 84955.0, 86773.0, 86784.69822952534, 7834.255745704149, 7889.4082943780595, 7913.881291780743, 8163.183681631789, 8164.510129392255, 10831.783364332512, 12132.939335303266, 12216.083793715328, 12241.877581224073, 12253.652098269362, 10300.948495257475, 10364.274500784873, 10715.464226788785, 10885.238033337908, 10891.237613366988, 10399.116075133395, 10627.521761520595, 10630.84053739179, 10841.728956775829, 10867.402292873969, 10615.575376984834, 10719.892801072201, 10919.617813376493, 11035.944820275847, 11037.503312351124, 7817.5121777354725, 7860.606969651609, 7992.600369981361, 8013.439059266028, 8269.95865020695, 7667.769966901002, 10055.296129271173, 10173.898261017592, 10572.36565806054, 11266.830997535208], "value": 9107.277755299563, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1301.5054279373837, 1304.9869501304986, 1323.4772264955336, 1575.5037163293562, 1826.1964735516362, 7560.017197764289, 7976.242256985584, 8148.59257037148, 8475.364347673934, 8492.0, 7997.520148791076, 8231.506109633427, 8266.256036956673, 8269.090400056004, 8284.295834854045, 2010.5576773109892, 2301.5051763870765, 2342.0982921575232, 2345.179187284445, 2348.0607756897207, 2342.9531409371803, 2382.452036031705, 2392.3301475586863, 2396.3050715292543, 2398.1486572266854, 2327.173853282086, 2337.5441788851094, 2370.146747171017, 2374.3707917401903, 2378.0368950575044, 2319.814414846825, 2327.243645815117, 2352.2002519143402, 2361.480474295652, 2361.53949979754, 2351.3651314145254, 2378.988105059481, 2379.785819276256, 2381.4641705616273, 2384.7376788553247, 8251.587420628945, 8385.874211886827, 8452.83094338116, 8464.44981076228, 8469.534175620338, 5096.923546146811, 5257.053730328539, 5259.447757985433, 5259.500347466985, 5264.394594621629, 3482.582090149173, 3618.493410922447, 3655.6344365563496, 3702.0559757261726, 3706.09200745819, 1076.8492411062457, 1079.2121751121724, 1100.301308668993, 1110.8167152419842, 1115.5649296774266, 11096.500657470433, 11157.0, 11303.491342889589, 11329.206955513117, 11354.0, 4517.932231016537, 4686.718796872198, 4719.716816990992, 4777.593904518155, 4787.138315103245, 9626.90373096274, 9629.422234665943, 9633.662821801203, 9636.26547221493, 9655.131038206595, 5274.630775845654, 5277.815276465305, 5279.973600132052, 5282.894342113136, 5287.947120528823, 4177.373393990865, 4336.934945975813, 4340.2187606230555, 4458.643308535375, 4775.617950563947, 3831.1379939513945, 3841.8847434577006, 3947.9013024674036, 3950.861719839792, 3956.9802150989062, 1245.1532957588927, 1259.703969567151, 1261.1865346851414, 1272.8981681465461, 1277.1443133100845, 817.6034623207739, 873.4235404632911, 878.7539488943106, 898.4294972692256, 915.5696822493418, 4702.482726900038, 5715.114157305624, 5781.566382521328, 5857.619254748428, 5965.910511342333, 897.9057198994142, 908.6592527802075, 910.5128756115579, 912.885889263842, 918.7473444802732, 3673.4673472346362, 3783.432485127253, 3826.942595861064, 3844.519435070616, 3871.709621778378, 9504.857427138599, 10272.897271027194, 10364.792704145875, 10455.581776728846, 10490.21323400748, 10172.745681357877, 10203.03071208249, 10424.426656533811, 10442.373457592568, 10450.111740502143, 7809.101953275446, 8009.479383840032, 8046.517208967363, 8053.718119865776, 8065.1934806518875, 5544.3346798384455, 5570.080936645408, 5574.331080270394, 5713.285839270091, 5746.971265143815, 9130.269578433577, 9458.006909274427, 9477.573509191965, 9552.904470955202, 9569.0, 8889.288856891304, 9274.0, 9274.721758347261, 9521.714348569554, 9582.233421326417, 6748.797536073926, 7075.398591119607, 7275.745348912763, 7297.744578939712, 7307.501962097864, 7901.960490197513, 8016.0380754307525, 8114.391420643594, 8115.0, 8123.47197432165, 7125.714971401086, 7135.928640713526, 7177.569345839266, 7224.060872086595, 7316.122065352191, 7037.964810175917, 7072.469564782559, 7089.503734738519, 7150.14198296187, 7166.355028047501, 6696.966515167393, 6793.388595026274, 6863.725450981905, 6879.8968015480805, 6885.070515480346, 4818.253170758538, 4850.1269771441475, 4891.461939186615, 4921.237208232729, 4935.481774413778, 4815.061063092803, 4829.468758436639, 4943.332650092191, 4975.701457912537, 5038.697678139323], "value": 4463.4869081742045, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [302.5250356939605, 306.9739072178866, 310.69862233633376, 319.5542218605046, 321.96780321967793, 4067.3288907330293, 5171.534561889429, 5191.428942816292, 5325.4940780625875, 5346.973265133679, 4680.8234171110425, 4929.668989372867, 5130.02529519391, 5134.409542902567, 5246.606504512168, 3122.703343182394, 3772.358699021173, 3875.399313106473, 3962.1085255817425, 3975.085730282037, 3858.3633700439395, 4006.73956192848, 4012.739171953828, 4037.9596204037875, 4082.4896887889013, 3513.5432393788774, 3968.365061590146, 3983.24318379507, 4018.557958624549, 4045.2516284487465, 4018.1561872006914, 4050.696197785178, 4059.63463288304, 4072.3687828386646, 4082.836686532535, 2965.7627389808763, 3524.436090225577, 3526.549048411355, 3532.4877892705413, 3536.2927414516944, 4658.364926897759, 4819.614430845524, 5170.552245371302, 5215.16557350822, 5233.336664334981, 4199.832006719727, 4553.087703164679, 4558.202314594932, 4559.133764584752, 4559.361689363492, 5490.0, 5498.945010549884, 5529.281193444826, 5543.223948647193, 5544.889102217973, 3674.577423596267, 3783.3190025795348, 3790.336691079078, 3807.9619203807883, 3823.0824602095577, 5311.62818602698, 5557.5831812613815, 5587.44125587438, 5842.561807864385, 5874.530037596982, 3566.983409728215, 3662.9816850915577, 3885.1646895917515, 3913.8630147944687, 3917.9020524486546, 3912.8043597819997, 3963.742356746802, 3964.6035396460125, 3981.422693709396, 3988.5213774346685, 3941.6649584785614, 4391.836163416697, 4579.90082380233, 4592.793324300348, 4634.513376095529, 4845.830395936126, 4921.729304888194, 5517.78608706092, 5631.099983508497, 5632.9436705632415, 1105.9778804423865, 1135.9261647992853, 1256.428325112072, 1257.4215860703996, 1272.3141837065925, 806.5362416610473, 827.1976183102381, 830.5473516933286, 849.8087930215652, 867.2368315881984, 4373.715708478939, 4403.559644035571, 5014.5708473085, 5023.6483446159145, 5034.572061374751, 4668.8132474701315, 5012.699238045656, 5106.744662766847, 5484.561235101182, 5518.834434966958, 4401.581849724271, 4424.734515929054, 4442.755648439303, 4922.606191504671, 4996.5003499650475, 2650.8939642414475, 2845.630068091135, 2859.842708651044, 2866.584345269924, 2867.240181351943, 4886.560209581155, 5574.888502230014, 5614.231517072191, 5614.270144881221, 5629.831105066854, 5482.698451585121, 5499.615026948155, 5899.852503687356, 5969.283975791005, 5996.310424301174, 5015.949840501548, 6021.789237376758, 6025.222559345091, 6040.848978775566, 6044.914504495942, 5502.0921547944145, 5504.752286147213, 5715.657060576379, 5789.1605717171615, 5807.767689292381, 5068.569171620454, 5235.136202526541, 5708.5147762439, 5717.770069672914, 5740.712964351682, 5147.411313518702, 5654.264945557051, 5859.970700146472, 5970.924713993071, 5973.0, 3330.2673411849355, 3403.5405220294942, 3642.3990041642833, 3865.594112618247, 3883.378659414593, 5002.749862506787, 5664.490195882226, 5666.2801855108955, 5688.089905615082, 5688.40271771458, 4470.754108524063, 4764.618830493483, 5057.747112644427, 5127.027242949511, 5517.613767036269, 4920.72935988524, 5405.324334458192, 5538.667679939216, 5607.383187849252, 5622.734884650921, 5257.37021523329, 5492.995056804318, 5498.395176530498, 5529.4470552944385, 5533.6403133796175, 5402.540784033401, 5466.562674986071, 5640.956423061747, 5642.040853055072, 5661.46216109469, 5552.222688823487, 5555.777768889361, 5561.943230786138, 5563.0, 5568.805091821767], "value": 4023.298636403516, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [666.3669513961737, 744.9180590135085, 760.8097975506123, 765.1010063175775, 781.3202513812984, 14404.567862964115, 14753.26233688314, 14759.778603320945, 14765.409383624656, 14772.852271477283, 13103.54137605184, 13163.934180329084, 13173.538926137588, 13185.077044606882, 13230.669233269173, 667.8063361625148, 687.7386593094617, 691.8720036793184, 810.2788518218761, 821.8561751693445, 839.1524560194217, 841.8947631546057, 847.1316900177306, 847.6863560482599, 850.9446885952393, 903.9548022598876, 904.9954750226273, 905.5608030105394, 905.8278927003881, 909.2816674826879, 697.888337865939, 719.9028131202273, 896.5651658945407, 900.4417261297972, 903.0247332880506, 634.6985182038541, 658.2824721054026, 663.7577284291199, 664.2693037658585, 665.6771465839064, 14210.076345037643, 14237.216953067576, 14245.287735613178, 14245.572632821031, 14255.78616320756, 13063.804042939364, 13321.467141314335, 13451.529196478174, 13452.260125693083, 13462.865371346259, 11516.769664606745, 11855.703607409798, 11899.940500297529, 12175.93912030443, 12605.558805441857, 4251.766152861592, 4372.213001659732, 4438.733675979419, 4552.567506086916, 4558.316252562109, 88781.0, 94524.0, 95394.0460595392, 96414.0, 96716.51641741817, 7857.332126769289, 9840.70409880421, 10121.949390253001, 10133.863156587608, 10295.124914382212, 19192.616147677247, 19494.415167544998, 19497.317593884145, 19516.12177452018, 19527.609447810963, 7201.243869393743, 7204.92795072053, 7217.92782072183, 7500.924990750132, 7515.774526764206, 3761.6990640748672, 3805.46723458716, 4041.7574945502774, 4094.877153685394, 4216.978915105405, 3304.339132173575, 3397.575303087114, 3417.6240613532987, 3426.777259478126, 3679.889603311905, 653.1541653558653, 669.464428457242, 702.1714377035148, 707.8548897476005, 715.4848509073458, 599.7884273766966, 614.640435345319, 621.6953692690549, 631.1637080867869, 657.1194599237045, 8420.831583368421, 9161.129692679186, 9226.07739226072, 9239.30705197113, 9262.0, 648.4844548583887, 657.2179106862818, 660.3165723476179, 668.1881513960561, 670.577536152221, 3962.445257663929, 4431.844885429059, 4434.733915965053, 4454.175977444183, 4563.520830312769, 121207.0, 123523.3823830875, 125027.0, 125042.0, 129175.35412322878, 62630.37369626245, 62824.68587657124, 83713.58143209245, 101871.0, 106619.40070898943, 88180.0, 95033.52483237539, 95637.0, 97755.0, 99422.0, 8404.537750423786, 8670.739877803677, 8678.739637810875, 8720.345974051845, 8805.42764720291, 55995.72002139963, 60281.42673106372, 60502.69748651228, 61753.691231543555, 62258.0, 83621.58189209015, 85384.57307713422, 86293.56853215693, 87262.56368718116, 88848.5557572208, 10942.835857462303, 10965.451727413438, 11276.7744645105, 11315.321080735182, 11785.0, 11916.940415297868, 12485.31330776816, 12598.307093109966, 12975.156614820007, 13166.80249796273, 11476.689217738072, 11503.0, 11795.11536634773, 11982.760344792881, 12022.338771367658, 11529.596464123715, 11640.592579259684, 11661.0, 11697.210045230624, 12181.208221465577, 11471.713207169887, 11574.363410012531, 11681.88318116808, 11687.941560292144, 11707.946284834406, 10081.546330415296, 10196.847047294148, 10250.38497690141, 10434.06093451593, 10446.373217606968, 9701.514924253617, 10056.245781566266, 10083.899161008296, 10120.493975301353, 10186.236032297458], "value": 7251.738596511027, "unit": "score"}], "name": "dromaeo_css", "value": 7037.615709066401, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 114.0, 114.0, 113.0, 114.0, 113.0, 114.0, 113.0, 113.0, 114.0], "value": 113.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 115.0, 114.0, 148.0, 115.0, 114.0, 113.0, 111.0, 142.0, 113.0], "value": 123.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 182.0, 181.0, 183.0, 187.0, 182.0, 184.0, 184.0, 183.0, 182.0], "value": 183.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 60.0, 59.0, 89.0, 62.0, 59.0, 59.0, 60.0, 83.0, 57.0], "value": 66.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 71.0, 92.0, 69.0, 68.0, 68.0, 90.0, 66.0, 68.0, 94.0], "value": 77.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 83.0, 94.0, 130.0, 86.0, 104.0, 114.0, 85.0, 87.0, 84.0], "value": 95.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 94.0, 93.0, 94.0, 94.0, 95.0, 93.0, 93.0, 94.0], "value": 93.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 81.0, 79.0, 80.0, 81.0, 81.0, 84.0, 86.0, 85.0, 81.0], "value": 82.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 52.0, 51.0, 53.0, 56.0, 57.0, 57.0, 56.0, 56.0, 56.0], "value": 54.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 69.0, 65.0, 74.0, 70.0, 68.0, 70.0, 69.0, 70.0, 69.0], "value": 69.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 85.0, 88.0, 83.0, 87.0, 85.0, 83.0, 83.0, 85.0], "value": 85.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 147.0, 153.0, 147.0, 149.0, 156.0, 151.0, 145.0, 246.0, 158.0], "value": 161.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 166.0, 170.0, 170.0, 169.0, 168.0, 167.0, 168.0, 167.0, 175.0], "value": 169.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 65.0, 67.0, 64.0, 67.0, 68.0, 67.0, 66.0, 66.0, 66.0], "value": 66.5, "unit": "score"}], "name": "kraken", "value": 1442.3000000000002, "alertThreshold": 2.0}]} 12:09:53 INFO - Return code: 0 12:09:53 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:09:53 ERROR - # TBPL SUCCESS # 12:09:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:09:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:09:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:09:53 INFO - 2016-11-23 12:09:53.800 screenresolution[1660:9837] starting screenresolution argv=screenresolution get 12:09:53 INFO - 2016-11-23 12:09:53.803 screenresolution[1660:9837] Display 0: 1600x1200x32@60 12:09:53 INFO - 2016-11-23 12:09:53.810 screenresolution[1661:9840] starting screenresolution argv=screenresolution list 12:09:53 INFO - Available Modes on Display 0 12:09:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:09:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:09:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:09:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:09:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:09:54 INFO - Intel Iris: 12:09:54 INFO - Chipset Model: Intel Iris 12:09:54 INFO - Type: GPU 12:09:54 INFO - Bus: Built-In 12:09:54 INFO - VRAM (Dynamic, Max): 1536 MB 12:09:54 INFO - Vendor: Intel (0x8086) 12:09:54 INFO - Device ID: 0x0a2e 12:09:54 INFO - Revision ID: 0x0009 12:09:54 INFO - Displays: 12:09:54 INFO - Display: 12:09:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:09:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:09:54 INFO - Display Serial Number: 1600x1200 60 12:09:54 INFO - Main Display: Yes 12:09:54 INFO - Mirror: Off 12:09:54 INFO - Online: Yes 12:09:54 INFO - Rotation: Supported 12:09:54 INFO - Return code: 0 12:09:54 INFO - Running post-action listener: _resource_record_post_action 12:09:54 INFO - [mozharness: 2016-11-23 20:09:54.057767Z] Finished run-tests step (success) 12:09:54 INFO - Running post-run listener: _resource_record_post_run 12:09:54 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 37532672; Write bytes: 656667136; Read time: 239; Write time: 1921 12:09:54 INFO - TinderboxPrint: CPU usage
53.5% 12:09:54 INFO - TinderboxPrint: I/O read bytes / time
37,532,672 / 239 12:09:54 INFO - TinderboxPrint: I/O write bytes / time
656,667,136 / 1,921 12:09:54 INFO - TinderboxPrint: CPU idle
2,251.0 (46.5%) 12:09:54 INFO - TinderboxPrint: CPU system
972.3 (20.1%) 12:09:54 INFO - TinderboxPrint: CPU user
1,622.4 (33.5%) 12:09:54 INFO - TinderboxPrint: Swap in / out
385,732,608 / 0 12:09:54 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 349372928; Write bytes: 352991232; Read time: 26777; Write time: 755 12:09:54 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 34432000; Write bytes: 302340608; Read time: 215; Write time: 1160 12:09:54 INFO - Running post-run listener: _upload_blobber_files 12:09:54 INFO - Blob upload gear active. 12:09:54 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:09:54 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 12:09:54 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'] 12:09:54 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 12:09:54 INFO - (blobuploader) - INFO - Open directory for files ... 12:09:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:09:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:09:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:09:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:09:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:09:56 INFO - (blobuploader) - INFO - Done attempting. 12:09:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:09:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:09:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:09:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:09:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:09:57 INFO - (blobuploader) - INFO - Done attempting. 12:09:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:09:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:09:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:09:59 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:09:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:09:59 INFO - (blobuploader) - INFO - Done attempting. 12:09:59 INFO - (blobuploader) - INFO - Iteration through files over. 12:09:59 INFO - Return code: 0 12:09:59 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:09:59 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:09:59 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/28a4a8b67097e4d76cdac2a2d4b61a97f1313629bfab518a491f5bf4dcbdc9377e3b2415a8fe7f36fe8ba1feaf173ce44fc9e349e7ef478465590b3a555c9de5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/01391ebfbdd2f1b4a8081532eba6170625838339f12caaa19721a5a9715bad8e5ef51fb3e7b56a09b9e685f55ca8e03f2bb8fc9a8f104379a899c7cc43810374", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ee8bc60d09fe48dcc42bd3f20820c9c9143d30858d28c2609d9249bee30eb616853c8a440a7bc7c4d639ff8476ce8ac14ffed15a354b60be741417381c214826"} 12:09:59 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:09:59 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:09:59 INFO - Contents: 12:09:59 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/28a4a8b67097e4d76cdac2a2d4b61a97f1313629bfab518a491f5bf4dcbdc9377e3b2415a8fe7f36fe8ba1feaf173ce44fc9e349e7ef478465590b3a555c9de5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/01391ebfbdd2f1b4a8081532eba6170625838339f12caaa19721a5a9715bad8e5ef51fb3e7b56a09b9e685f55ca8e03f2bb8fc9a8f104379a899c7cc43810374", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ee8bc60d09fe48dcc42bd3f20820c9c9143d30858d28c2609d9249bee30eb616853c8a440a7bc7c4d639ff8476ce8ac14ffed15a354b60be741417381c214826"} 12:09:59 INFO - Running post-run listener: copy_logs_to_upload_dir 12:09:59 INFO - Copying logs to upload dir... 12:09:59 INFO - mkdir: /builds/slave/test/build/upload/logs 12:09:59 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.998536 ========= master_lag: 3.56 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-11-23 12:10:02.953793) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-23 12:10:02.955152) ========= 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.97aPFSMhdb/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.lUcdxCLlx0/Listeners TMPDIR=/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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/28a4a8b67097e4d76cdac2a2d4b61a97f1313629bfab518a491f5bf4dcbdc9377e3b2415a8fe7f36fe8ba1feaf173ce44fc9e349e7ef478465590b3a555c9de5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/01391ebfbdd2f1b4a8081532eba6170625838339f12caaa19721a5a9715bad8e5ef51fb3e7b56a09b9e685f55ca8e03f2bb8fc9a8f104379a899c7cc43810374", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ee8bc60d09fe48dcc42bd3f20820c9c9143d30858d28c2609d9249bee30eb616853c8a440a7bc7c4d639ff8476ce8ac14ffed15a354b60be741417381c214826"} build_url:https://queue.taskcluster.net/v1/task/A0Yk00slSpaI3PHRRjtxGg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010846 build_url: 'https://queue.taskcluster.net/v1/task/A0Yk00slSpaI3PHRRjtxGg/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/28a4a8b67097e4d76cdac2a2d4b61a97f1313629bfab518a491f5bf4dcbdc9377e3b2415a8fe7f36fe8ba1feaf173ce44fc9e349e7ef478465590b3a555c9de5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/01391ebfbdd2f1b4a8081532eba6170625838339f12caaa19721a5a9715bad8e5ef51fb3e7b56a09b9e685f55ca8e03f2bb8fc9a8f104379a899c7cc43810374", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ee8bc60d09fe48dcc42bd3f20820c9c9143d30858d28c2609d9249bee30eb616853c8a440a7bc7c4d639ff8476ce8ac14ffed15a354b60be741417381c214826"}' ========= master_lag: 0.84 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-23 12:10:03.801618) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-23 12:10:03.801945) ========= 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.97aPFSMhdb/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.lUcdxCLlx0/Listeners TMPDIR=/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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.005350 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-23 12:10:03.830003) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-23 12:10:03.830318) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-23 12:10:03.830642) ========= ========= Total master_lag: 4.49 =========