builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0189 starttime: 1480262519.23 results: success (0) buildid: 20161127062019 builduid: 2805ce11bfd340a3a0860e2a3d8fff33 revision: d8e4710b55d980cb3a63f8bae224595d7ef256a0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-27 08:01:59.227051) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-27 08:01:59.227855) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-27 08:01:59.228176) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-27 08:01:59.250997) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-27 08:01:59.251345) ========= 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.olR8qxLACv/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.ofIbI9GY99/Listeners TMPDIR=/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/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-27 08:01:59-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 7.87M=0.001s 2016-11-27 08:01:59 (7.87 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.466658 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-27 08:01:59.768526) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-27 08:01:59.768802) ========= 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.olR8qxLACv/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.ofIbI9GY99/Listeners TMPDIR=/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/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.018866 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-27 08:01:59.825187) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-27 08:01:59.825504) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev d8e4710b55d980cb3a63f8bae224595d7ef256a0 --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 d8e4710b55d980cb3a63f8bae224595d7ef256a0 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.olR8qxLACv/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.ofIbI9GY99/Listeners TMPDIR=/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/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-27 08:01:59,895 truncating revision to first 12 chars 2016-11-27 08:01:59,895 Setting DEBUG logging. 2016-11-27 08:01:59,895 attempt 1/10 2016-11-27 08:01:59,895 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/d8e4710b55d9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-27 08:02:00,612 unpacking tar archive at: mozilla-aurora-d8e4710b55d9/testing/mozharness/ program finished with exit code 0 elapsedTime=0.975186 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-27 08:02:00.832427) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-27 08:02:00.832724) ========= script_repo_revision: d8e4710b55d980cb3a63f8bae224595d7ef256a0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-27 08:02:00.833107) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-27 08:02:00.833408) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-27 08:02:00.879819) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-11-27 08:02:00.880412) ========= /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.olR8qxLACv/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.ofIbI9GY99/Listeners TMPDIR=/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/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 08:02:01 INFO - MultiFileLogger online at 20161127 08:02:01 in /builds/slave/test 08:02:01 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 08:02:01 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:02:01 INFO - {'append_to_log': False, 08:02:01 INFO - 'base_work_dir': '/builds/slave/test', 08:02:01 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 08:02:01 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:02:01 INFO - 'branch': 'Mozilla-Aurora', 08:02:01 INFO - 'buildbot_json_path': 'buildprops.json', 08:02:01 INFO - 'config_files': ('talos/mac_config.py',), 08:02:01 INFO - 'default_actions': ('clobber', 08:02:01 INFO - 'read-buildbot-config', 08:02:01 INFO - 'download-and-extract', 08:02:01 INFO - 'populate-webroot', 08:02:01 INFO - 'create-virtualenv', 08:02:01 INFO - 'install', 08:02:01 INFO - 'run-tests'), 08:02:01 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:02:01 INFO - 'download_minidump_stackwalk': True, 08:02:01 INFO - 'download_symbols': 'ondemand', 08:02:01 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:02:01 INFO - 'tooltool.py': '/tools/tooltool.py', 08:02:01 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:02:01 INFO - '/tools/misc-python/virtualenv.py')}, 08:02:01 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:02:01 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:02:01 INFO - 'installer_path': 'installer.exe', 08:02:01 INFO - 'log_level': 'info', 08:02:01 INFO - 'log_name': 'talos', 08:02:01 INFO - 'log_to_console': True, 08:02:01 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:02:01 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:02:01 INFO - 'opt_config_files': (), 08:02:01 INFO - 'pip_index': False, 08:02:01 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:02:01 INFO - 'cmd': ('bash', 08:02:01 INFO - '-c', 08:02:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:02:01 INFO - 'enabled': True, 08:02:01 INFO - 'halt_on_failure': False, 08:02:01 INFO - 'name': 'check_screen_resolution'},), 08:02:01 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:02:01 INFO - 'cmd': ('bash', 08:02:01 INFO - '-c', 08:02:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:02:01 INFO - 'enabled': True, 08:02:01 INFO - 'halt_on_failure': False, 08:02:01 INFO - 'name': 'check_screen_resolution'},), 08:02:01 INFO - 'run_cmd_checks_enabled': True, 08:02:01 INFO - 'sps_profile': False, 08:02:01 INFO - 'sps_profile_interval': 0, 08:02:01 INFO - 'suite': 'dromaeojs', 08:02:01 INFO - 'system_bits': '32', 08:02:01 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:02:01 INFO - 'title': 't-yosemite-r7-0189', 08:02:01 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:02:01 INFO - 'use_talos_json': True, 08:02:01 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:02:01 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:02:01 INFO - 'work_dir': 'build'} 08:02:01 INFO - [mozharness: 2016-11-27 16:02:01.078108Z] Running clobber step. 08:02:01 INFO - Running pre-action listener: _resource_record_pre_action 08:02:01 INFO - Running main action method: clobber 08:02:01 INFO - rmtree: /builds/slave/test/build 08:02:01 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:02:02 INFO - Running post-action listener: _resource_record_post_action 08:02:02 INFO - [mozharness: 2016-11-27 16:02:02.692038Z] Finished clobber step (success) 08:02:02 INFO - [mozharness: 2016-11-27 16:02:02.692295Z] Running read-buildbot-config step. 08:02:02 INFO - Running pre-action listener: _resource_record_pre_action 08:02:02 INFO - Running main action method: read_buildbot_config 08:02:02 INFO - Using buildbot properties: 08:02:02 INFO - { 08:02:02 INFO - "project": "", 08:02:02 INFO - "product": "firefox", 08:02:02 INFO - "script_repo_revision": "production", 08:02:02 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 08:02:02 INFO - "repository": "", 08:02:02 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 08:02:02 INFO - "buildid": "20161127062019", 08:02:02 INFO - "pgo_build": "False", 08:02:02 INFO - "basedir": "/builds/slave/test", 08:02:02 INFO - "buildnumber": 0, 08:02:02 INFO - "slavename": "t-yosemite-r7-0189", 08:02:02 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 08:02:02 INFO - "platform": "yosemite_r7", 08:02:02 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 08:02:02 INFO - "branch": "mozilla-aurora", 08:02:02 INFO - "repo_path": "releases/mozilla-aurora", 08:02:02 INFO - "revision": "d8e4710b55d980cb3a63f8bae224595d7ef256a0", 08:02:02 INFO - "stage_platform": "macosx64", 08:02:02 INFO - "builduid": "2805ce11bfd340a3a0860e2a3d8fff33", 08:02:02 INFO - "slavebuilddir": "test" 08:02:02 INFO - } 08:02:02 INFO - Found installer url https://queue.taskcluster.net/v1/task/YkcB_iyAQaCz6jyl7d4McA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 08:02:02 INFO - Running post-action listener: _resource_record_post_action 08:02:02 INFO - [mozharness: 2016-11-27 16:02:02.697750Z] Finished read-buildbot-config step (success) 08:02:02 INFO - [mozharness: 2016-11-27 16:02:02.697981Z] Running download-and-extract step. 08:02:02 INFO - Running pre-action listener: _resource_record_pre_action 08:02:02 INFO - Running main action method: download_and_extract 08:02:02 INFO - mkdir: /builds/slave/test/build/tests 08:02:02 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')]} 08:02:02 INFO - trying https://queue.taskcluster.net/v1/task/YkcB_iyAQaCz6jyl7d4McA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 08:02:02 INFO - Downloading https://queue.taskcluster.net/v1/task/YkcB_iyAQaCz6jyl7d4McA/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 08:02:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YkcB_iyAQaCz6jyl7d4McA/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 08:02:04 INFO - Downloaded 1199 bytes. 08:02:04 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 08:02:04 INFO - Using the following test package requirements: 08:02:04 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 08:02:04 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:02:04 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 08:02:04 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:02:04 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 08:02:04 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:02:04 INFO - u'jsshell-mac.zip'], 08:02:04 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:02:04 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 08:02:04 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 08:02:04 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:02:04 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 08:02:04 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:02:04 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 08:02:04 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:02:04 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 08:02:04 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:02:04 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 08:02:04 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 08:02:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YkcB_iyAQaCz6jyl7d4McA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 08:02:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YkcB_iyAQaCz6jyl7d4McA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:02:04 INFO - Fetch https://queue.taskcluster.net/v1/task/YkcB_iyAQaCz6jyl7d4McA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 08:02:04 INFO - Http code: 200 08:02:04 INFO - via: 1.1 ba26a6eef2785fa2e20e601890b882b2.cloudfront.net (CloudFront) 08:02:04 INFO - x-amz-cf-id: QKwTbcrwtJDgp-TXxDFcFnMG5LmqmWOc-8DomZWcEYMKMmLOkgrcOA== 08:02:04 INFO - x-amz-version-id: vmwgPm21fucaJuZvTD2Wir_UI2bXi5Vq 08:02:04 INFO - Expected file size: 16278964 08:02:04 INFO - Obtained file size: 16278964 08:02:11 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 08:02:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YkcB_iyAQaCz6jyl7d4McA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 08:02:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YkcB_iyAQaCz6jyl7d4McA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:02:11 INFO - Fetch https://queue.taskcluster.net/v1/task/YkcB_iyAQaCz6jyl7d4McA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 08:02:12 INFO - Http code: 200 08:02:12 INFO - via: 1.1 a75342fa94ff4a9431476624fe74f02e.cloudfront.net (CloudFront) 08:02:12 INFO - x-amz-cf-id: O10F1s9uk2PSTSyhj_XO2oGbbOkvDHaK6Dwlo5IbFjUYYHFXobtUgg== 08:02:12 INFO - x-amz-version-id: vmwgPm21fucaJuZvTD2Wir_UI2bXi5Vq 08:02:12 INFO - Expected file size: 16278964 08:02:12 INFO - Obtained file size: 16278964 08:02:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YkcB_iyAQaCz6jyl7d4McA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 08:02:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YkcB_iyAQaCz6jyl7d4McA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 08:02:18 INFO - Fetch https://queue.taskcluster.net/v1/task/YkcB_iyAQaCz6jyl7d4McA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 08:02:19 INFO - Http code: 200 08:02:19 INFO - via: 1.1 93ab344e1a3fa44edbf3f1bc5729427a.cloudfront.net (CloudFront) 08:02:19 INFO - x-amz-cf-id: -czkvwt55RgsqfyHmr2XYVLybWF3ioUelg9xgT_LYzJ42WBXMKVPTg== 08:02:19 INFO - x-amz-version-id: jJCkugbfv3TWY2w2lkUHdZt0lFdmeOgm 08:02:20 INFO - Expected file size: 14409038 08:02:20 INFO - Obtained file size: 14409038 08:02:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:02:20 INFO - trying https://queue.taskcluster.net/v1/task/YkcB_iyAQaCz6jyl7d4McA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:02:20 INFO - Downloading https://queue.taskcluster.net/v1/task/YkcB_iyAQaCz6jyl7d4McA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 08:02:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YkcB_iyAQaCz6jyl7d4McA/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 08:02:24 INFO - Downloaded 109739337 bytes. 08:02:24 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/YkcB_iyAQaCz6jyl7d4McA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:02:24 INFO - mkdir: /builds/slave/test/properties 08:02:24 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:02:24 INFO - Writing to file /builds/slave/test/properties/build_url 08:02:24 INFO - Contents: 08:02:24 INFO - build_url:https://queue.taskcluster.net/v1/task/YkcB_iyAQaCz6jyl7d4McA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:02:25 INFO - Running post-action listener: _resource_record_post_action 08:02:25 INFO - Running post-action listener: set_extra_try_arguments 08:02:25 INFO - [mozharness: 2016-11-27 16:02:25.600290Z] Finished download-and-extract step (success) 08:02:25 INFO - [mozharness: 2016-11-27 16:02:25.600439Z] Running populate-webroot step. 08:02:25 INFO - Running pre-action listener: _resource_record_pre_action 08:02:25 INFO - Running main action method: populate_webroot 08:02:25 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:02:25 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:02:25 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:02:25 INFO - '--tppagecycles', 08:02:25 INFO - '7'], 08:02:25 INFO - 'tests': ['tsvgm']}}, 08:02:25 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:02:25 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:02:25 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:02:25 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:02:25 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:02:25 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:02:25 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:02:25 INFO - 'talos_options': ['--disable-e10s'], 08:02:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:02:25 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:02:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:02:25 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:02:25 INFO - 'talos_options': ['--disable-e10s'], 08:02:25 INFO - 'tests': ['damp', 'tps']}, 08:02:25 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:02:25 INFO - 'tests': ['damp', 'tps']}, 08:02:25 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:02:25 INFO - 'tests': ['dromaeo_dom']}, 08:02:25 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:02:25 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:02:25 INFO - 'tests': ['basic_compositor_video']}, 08:02:25 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:02:25 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:02:25 INFO - 'tests': ['a11yr', 08:02:25 INFO - 'ts_paint', 08:02:25 INFO - 'tpaint', 08:02:25 INFO - 'sessionrestore', 08:02:25 INFO - 'sessionrestore_no_auto_restore', 08:02:25 INFO - 'tabpaint']}, 08:02:25 INFO - 'other-e10s': {'tests': ['a11yr', 08:02:25 INFO - 'ts_paint', 08:02:25 INFO - 'tpaint', 08:02:25 INFO - 'sessionrestore', 08:02:25 INFO - 'sessionrestore_no_auto_restore', 08:02:25 INFO - 'tabpaint']}, 08:02:25 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:02:25 INFO - 'ts_paint', 08:02:25 INFO - 'tpaint', 08:02:25 INFO - 'sessionrestore', 08:02:25 INFO - 'sessionrestore_no_auto_restore', 08:02:25 INFO - 'tabpaint']}, 08:02:25 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:02:25 INFO - 'ts_paint', 08:02:25 INFO - 'tpaint', 08:02:25 INFO - 'sessionrestore', 08:02:25 INFO - 'sessionrestore_no_auto_restore', 08:02:25 INFO - 'tabpaint']}, 08:02:25 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:02:25 INFO - 'tests': ['a11yr', 08:02:25 INFO - 'ts_paint', 08:02:25 INFO - 'tpaint', 08:02:25 INFO - 'sessionrestore', 08:02:25 INFO - 'sessionrestore_no_auto_restore', 08:02:25 INFO - 'tabpaint']}, 08:02:25 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:02:25 INFO - 'tests': ['a11yr', 08:02:25 INFO - 'ts_paint', 08:02:25 INFO - 'tpaint', 08:02:25 INFO - 'sessionrestore', 08:02:25 INFO - 'sessionrestore_no_auto_restore', 08:02:25 INFO - 'tabpaint']}, 08:02:25 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:02:25 INFO - 'tests': ['tsvgx', 08:02:25 INFO - 'tsvgr_opacity', 08:02:25 INFO - 'tart', 08:02:25 INFO - 'tscrollx', 08:02:25 INFO - 'cart']}, 08:02:25 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:02:25 INFO - 'tsvgr_opacity', 08:02:25 INFO - 'tart', 08:02:25 INFO - 'tscrollx', 08:02:25 INFO - 'cart']}, 08:02:25 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:02:25 INFO - 'talos_options': ['--disable-e10s'], 08:02:25 INFO - 'tests': ['tp5o']}, 08:02:25 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:02:25 INFO - 'tests': ['tp5o']}, 08:02:25 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:02:25 INFO - 'talos_options': ['--disable-e10s', 08:02:25 INFO - '--xperf_path', 08:02:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:02:25 INFO - 'tests': ['tp5n']}, 08:02:25 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:02:25 INFO - 'talos_options': ['--xperf_path', 08:02:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:02:25 INFO - 'tests': ['tp5n']}}, 08:02:25 INFO - 'talos.zip': {'path': '', 08:02:25 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:02:25 INFO - Running post-action listener: _resource_record_post_action 08:02:25 INFO - [mozharness: 2016-11-27 16:02:25.618637Z] Finished populate-webroot step (success) 08:02:25 INFO - [mozharness: 2016-11-27 16:02:25.618768Z] Running create-virtualenv step. 08:02:25 INFO - Running pre-action listener: _resource_record_pre_action 08:02:25 INFO - Running main action method: create_virtualenv 08:02:25 INFO - Creating virtualenv /builds/slave/test/build/venv 08:02:25 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 08:02:25 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:02:25 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:02:25 INFO - Using real prefix '/tools/python27' 08:02:25 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:02:26 INFO - Installing distribute.............................................................................................................................................................................................done. 08:02:29 INFO - Installing pip.................done. 08:02:29 INFO - Return code: 0 08:02:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:02:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:02:29 INFO - Reading from file tmpfile_stdout 08:02:29 INFO - Output received: 08:02:29 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 08:02:29 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:02: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')]} 08:02:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:02:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02: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')]} 08:02:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:02:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104f46490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ebc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefdbc9a870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d5ad30>, '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 0x104bc0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104d52b20>, '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.olR8qxLACv/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.ofIbI9GY99/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/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 08:02:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:02:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:02:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.olR8qxLACv/Render', 08:02:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:02:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:02:29 INFO - 'HOME': '/Users/cltbld', 08:02:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:02:29 INFO - 'LOGNAME': 'cltbld', 08:02:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:02:29 INFO - 'MOZ_NO_REMOTE': '1', 08:02:29 INFO - 'NO_EM_RESTART': '1', 08:02:29 INFO - 'PAGER': '/bin/cat', 08:02:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:02:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:02:29 INFO - 'PWD': '/builds/slave/test', 08:02:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:02:29 INFO - 'SHELL': '/bin/bash', 08:02:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ofIbI9GY99/Listeners', 08:02:29 INFO - 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/', 08:02:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:02:29 INFO - 'USER': 'cltbld', 08:02:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:02:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:02:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:02:29 INFO - 'XPC_FLAGS': '0x0', 08:02:29 INFO - 'XPC_SERVICE_NAME': '0', 08:02:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:02:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:02:29 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 08:02:29 INFO - Cleaning up... 08:02:29 INFO - Return code: 0 08:02:29 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:02: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')]} 08:02:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:02:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02: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')]} 08:02:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:02:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104f46490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ebc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefdbc9a870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d5ad30>, '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 0x104bc0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104d52b20>, '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.olR8qxLACv/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.ofIbI9GY99/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/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 08:02:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:02:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:02:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.olR8qxLACv/Render', 08:02:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:02:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:02:29 INFO - 'HOME': '/Users/cltbld', 08:02:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:02:29 INFO - 'LOGNAME': 'cltbld', 08:02:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:02:29 INFO - 'MOZ_NO_REMOTE': '1', 08:02:29 INFO - 'NO_EM_RESTART': '1', 08:02:29 INFO - 'PAGER': '/bin/cat', 08:02:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:02:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:02:29 INFO - 'PWD': '/builds/slave/test', 08:02:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:02:29 INFO - 'SHELL': '/bin/bash', 08:02:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ofIbI9GY99/Listeners', 08:02:29 INFO - 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/', 08:02:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:02:29 INFO - 'USER': 'cltbld', 08:02:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:02:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:02:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:02:29 INFO - 'XPC_FLAGS': '0x0', 08:02:29 INFO - 'XPC_SERVICE_NAME': '0', 08:02:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:02:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:02:30 INFO - Downloading/unpacking psutil>=3.1.1 08:02:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:02:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:02:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:02:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:02:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:02:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:02:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:02:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:02:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:02:34 INFO - Installing collected packages: psutil 08:02:34 INFO - Running setup.py install for psutil 08:02:34 INFO - building 'psutil._psutil_osx' extension 08:02:34 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 08:02:34 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 08:02:34 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 08:02:34 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 08:02:34 INFO - building 'psutil._psutil_posix' extension 08:02:34 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 08:02:34 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:02:34 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:02:34 INFO - ^ 08:02:34 INFO - 1 warning generated. 08:02:34 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 08:02:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:02:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:02:34 INFO - Successfully installed psutil 08:02:34 INFO - Cleaning up... 08:02:34 INFO - Return code: 0 08:02:35 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:02:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:02:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:02:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:02:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:02:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104f46490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ebc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefdbc9a870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d5ad30>, '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 0x104bc0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104d52b20>, '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.olR8qxLACv/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.ofIbI9GY99/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/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 08:02:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:02:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 08:02:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.olR8qxLACv/Render', 08:02:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:02:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:02:35 INFO - 'HOME': '/Users/cltbld', 08:02:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:02:35 INFO - 'LOGNAME': 'cltbld', 08:02:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:02:35 INFO - 'MOZ_NO_REMOTE': '1', 08:02:35 INFO - 'NO_EM_RESTART': '1', 08:02:35 INFO - 'PAGER': '/bin/cat', 08:02:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:02:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:02:35 INFO - 'PWD': '/builds/slave/test', 08:02:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:02:35 INFO - 'SHELL': '/bin/bash', 08:02:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ofIbI9GY99/Listeners', 08:02:35 INFO - 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/', 08:02:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:02:35 INFO - 'USER': 'cltbld', 08:02:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:02:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:02:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:02:35 INFO - 'XPC_FLAGS': '0x0', 08:02:35 INFO - 'XPC_SERVICE_NAME': '0', 08:02:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:02:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:02:35 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:02:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:02:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:02:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:02:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:02:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:02:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:02:37 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:02:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:02:37 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:02:37 INFO - Installing collected packages: mozsystemmonitor 08:02:37 INFO - Running setup.py install for mozsystemmonitor 08:02:38 INFO - Successfully installed mozsystemmonitor 08:02:38 INFO - Cleaning up... 08:02:38 INFO - Return code: 0 08:02:38 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:02:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:02:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:02:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:02:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:02:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104f46490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ebc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefdbc9a870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d5ad30>, '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 0x104bc0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104d52b20>, '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.olR8qxLACv/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.ofIbI9GY99/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/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 08:02:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:02:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:02:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.olR8qxLACv/Render', 08:02:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:02:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:02:38 INFO - 'HOME': '/Users/cltbld', 08:02:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:02:38 INFO - 'LOGNAME': 'cltbld', 08:02:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:02:38 INFO - 'MOZ_NO_REMOTE': '1', 08:02:38 INFO - 'NO_EM_RESTART': '1', 08:02:38 INFO - 'PAGER': '/bin/cat', 08:02:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:02:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:02:38 INFO - 'PWD': '/builds/slave/test', 08:02:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:02:38 INFO - 'SHELL': '/bin/bash', 08:02:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ofIbI9GY99/Listeners', 08:02:38 INFO - 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/', 08:02:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:02:38 INFO - 'USER': 'cltbld', 08:02:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:02:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:02:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:02:38 INFO - 'XPC_FLAGS': '0x0', 08:02:38 INFO - 'XPC_SERVICE_NAME': '0', 08:02:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:02:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:02:38 INFO - Downloading/unpacking jsonschema==2.5.1 08:02:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:02:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:02:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:02:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:02:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:02:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:02:40 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:02:40 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:02:40 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 08:02:40 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 08:02:40 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 08:02:40 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 08:02:40 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 08:02:40 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 08:02:41 INFO - Downloading functools32-3.2.3-2.tar.gz 08:02:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:02:41 INFO - warning: no files found matching '*.txt' 08:02:41 INFO - no previously-included directories found matching 'build' 08:02:41 INFO - no previously-included directories found matching 'dist' 08:02:41 INFO - no previously-included directories found matching '.git*' 08:02:41 INFO - Installing collected packages: jsonschema, functools32 08:02:41 INFO - Running setup.py install for functools32 08:02:41 INFO - warning: no files found matching '*.txt' 08:02:41 INFO - no previously-included directories found matching 'build' 08:02:41 INFO - no previously-included directories found matching 'dist' 08:02:41 INFO - no previously-included directories found matching '.git*' 08:02:41 INFO - Successfully installed jsonschema functools32 08:02:41 INFO - Cleaning up... 08:02:41 INFO - Return code: 0 08:02:41 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:02: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')]} 08:02:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:02:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02: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')]} 08:02:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:02:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02: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', '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 0x104f46490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ebc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefdbc9a870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d5ad30>, '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 0x104bc0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104d52b20>, '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.olR8qxLACv/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.ofIbI9GY99/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/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 08:02: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:02: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 functools32==3.2.3-2 08:02:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.olR8qxLACv/Render', 08:02:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:02:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:02:41 INFO - 'HOME': '/Users/cltbld', 08:02:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:02:41 INFO - 'LOGNAME': 'cltbld', 08:02:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:02:41 INFO - 'MOZ_NO_REMOTE': '1', 08:02:41 INFO - 'NO_EM_RESTART': '1', 08:02:41 INFO - 'PAGER': '/bin/cat', 08:02:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:02:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:02:41 INFO - 'PWD': '/builds/slave/test', 08:02:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:02:41 INFO - 'SHELL': '/bin/bash', 08:02:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ofIbI9GY99/Listeners', 08:02:41 INFO - 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/', 08:02:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:02:41 INFO - 'USER': 'cltbld', 08:02:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:02:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:02:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:02:41 INFO - 'XPC_FLAGS': '0x0', 08:02:41 INFO - 'XPC_SERVICE_NAME': '0', 08:02:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:02:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:02:41 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:02:41 INFO - Cleaning up... 08:02:41 INFO - Return code: 0 08:02:41 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:02: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')]} 08:02:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:02:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02: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')]} 08:02:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:02:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02: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', '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 0x104f46490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ebc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefdbc9a870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d5ad30>, '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 0x104bc0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104d52b20>, '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.olR8qxLACv/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.ofIbI9GY99/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/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 08:02: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:02: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 blobuploader==1.2.4 08:02:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.olR8qxLACv/Render', 08:02:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:02:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:02:41 INFO - 'HOME': '/Users/cltbld', 08:02:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:02:41 INFO - 'LOGNAME': 'cltbld', 08:02:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:02:41 INFO - 'MOZ_NO_REMOTE': '1', 08:02:41 INFO - 'NO_EM_RESTART': '1', 08:02:41 INFO - 'PAGER': '/bin/cat', 08:02:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:02:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:02:41 INFO - 'PWD': '/builds/slave/test', 08:02:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:02:41 INFO - 'SHELL': '/bin/bash', 08:02:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ofIbI9GY99/Listeners', 08:02:41 INFO - 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/', 08:02:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:02:41 INFO - 'USER': 'cltbld', 08:02:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:02:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:02:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:02:41 INFO - 'XPC_FLAGS': '0x0', 08:02:41 INFO - 'XPC_SERVICE_NAME': '0', 08:02:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:02:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:02:42 INFO - Downloading/unpacking blobuploader==1.2.4 08:02:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:02:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:02:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:02:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:02:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:02:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:02:44 INFO - Downloading blobuploader-1.2.4.tar.gz 08:02:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:02:44 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:02:44 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 08:02:44 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 08:02:44 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 08:02:44 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 08:02:44 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 08:02:44 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 08:02:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:02:45 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:02:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:02:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:02:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:02:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:02:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:02:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:02:45 INFO - Downloading docopt-0.6.1.tar.gz 08:02:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:02:45 INFO - Installing collected packages: blobuploader, requests, docopt 08:02:45 INFO - Running setup.py install for blobuploader 08:02:46 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:02:46 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:02:46 INFO - Running setup.py install for requests 08:02:46 INFO - Running setup.py install for docopt 08:02:46 INFO - Successfully installed blobuploader requests docopt 08:02:46 INFO - Cleaning up... 08:02:46 INFO - Return code: 0 08:02:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:02:46 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')]} 08:02:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:02:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:46 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')]} 08:02:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:02:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:46 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 0x104f46490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ebc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefdbc9a870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d5ad30>, '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 0x104bc0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104d52b20>, '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.olR8qxLACv/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.ofIbI9GY99/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/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 08:02:46 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 08:02:46 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 08:02:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.olR8qxLACv/Render', 08:02:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:02:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:02:46 INFO - 'HOME': '/Users/cltbld', 08:02:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:02:46 INFO - 'LOGNAME': 'cltbld', 08:02:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:02:46 INFO - 'MOZ_NO_REMOTE': '1', 08:02:46 INFO - 'NO_EM_RESTART': '1', 08:02:46 INFO - 'PAGER': '/bin/cat', 08:02:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:02:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:02:46 INFO - 'PWD': '/builds/slave/test', 08:02:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:02:46 INFO - 'SHELL': '/bin/bash', 08:02:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ofIbI9GY99/Listeners', 08:02:46 INFO - 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/', 08:02:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:02:46 INFO - 'USER': 'cltbld', 08:02:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:02:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:02:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:02:46 INFO - 'XPC_FLAGS': '0x0', 08:02:46 INFO - 'XPC_SERVICE_NAME': '0', 08:02:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:02:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:02:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:02:47 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-uzBRgs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:02:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:02:47 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-DfhtHC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:02:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:02:47 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-nk0cl3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:02:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:02:47 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-JYuFfS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:02:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:02:47 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-rEK9Q7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:02:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:02:47 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-l_duwn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:02:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:02:47 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-aJcGkh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:02:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:02:47 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-gurA0n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:02:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:02:47 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-Sx4zO0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:02:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:02:48 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-NOHyEW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:02:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:02:48 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-7KxPD_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:02:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:02:48 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-QBNNXY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:02:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:02:48 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-zqf_2h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:02:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:02:48 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-FujQUO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:02:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:02:48 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-lyv1M_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:02:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:02:48 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-bGzwqm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:02:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:02:48 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-0rWdhp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:02:48 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:02:48 INFO - Running setup.py install for manifestparser 08:02:49 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:02:49 INFO - Running setup.py install for mozcrash 08:02:49 INFO - Running setup.py install for mozdebug 08:02:49 INFO - Running setup.py install for mozdevice 08:02:49 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:02:49 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:02:49 INFO - Running setup.py install for mozfile 08:02:49 INFO - Running setup.py install for mozhttpd 08:02:49 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:02:49 INFO - Running setup.py install for mozinfo 08:02:49 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:02:50 INFO - Running setup.py install for mozInstall 08:02:50 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:02:50 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:02:50 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:02:50 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:02:50 INFO - Running setup.py install for mozleak 08:02:50 INFO - Running setup.py install for mozlog 08:02:50 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:02:50 INFO - Running setup.py install for moznetwork 08:02:50 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:02:50 INFO - Running setup.py install for mozprocess 08:02:50 INFO - Running setup.py install for mozprofile 08:02:50 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:02:50 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:02:50 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:02:50 INFO - Running setup.py install for mozrunner 08:02:51 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:02:51 INFO - Running setup.py install for mozscreenshot 08:02:51 INFO - Running setup.py install for moztest 08:02:51 INFO - Running setup.py install for mozversion 08:02:51 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:02:51 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:02:51 INFO - Cleaning up... 08:02:51 INFO - Return code: 0 08:02:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:02:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:02:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:02:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:02:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:02:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:51 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 0x104f46490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ebc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefdbc9a870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d5ad30>, '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 0x104bc0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104d52b20>, '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.olR8qxLACv/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.ofIbI9GY99/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/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 08:02:51 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 08:02:51 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 08:02:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.olR8qxLACv/Render', 08:02:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:02:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:02:51 INFO - 'HOME': '/Users/cltbld', 08:02:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:02:51 INFO - 'LOGNAME': 'cltbld', 08:02:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:02:51 INFO - 'MOZ_NO_REMOTE': '1', 08:02:51 INFO - 'NO_EM_RESTART': '1', 08:02:51 INFO - 'PAGER': '/bin/cat', 08:02:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:02:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:02:51 INFO - 'PWD': '/builds/slave/test', 08:02:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:02:51 INFO - 'SHELL': '/bin/bash', 08:02:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ofIbI9GY99/Listeners', 08:02:51 INFO - 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/', 08:02:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:02:51 INFO - 'USER': 'cltbld', 08:02:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:02:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:02:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:02:51 INFO - 'XPC_FLAGS': '0x0', 08:02:51 INFO - 'XPC_SERVICE_NAME': '0', 08:02:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:02:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:02:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:02:51 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-3fTStp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:02:51 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)) 08:02:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:02:51 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-MtcQVh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:02:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:02:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:02:52 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-jA6uB7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:02:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:02:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:02:52 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-WJFNln-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:02:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:02:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:02:52 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-INPKX3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:02:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 08:02:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:02:52 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-4ObqgD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:02:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 08:02:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:02:52 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-EogFjJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:02:52 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)) 08:02:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:02:52 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-La_Jun-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:02:52 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)) 08:02:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:02:52 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-Z64eNo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:02:52 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)) 08:02:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:02:52 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-Cs9iqQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:02:52 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)) 08:02:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:02:52 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-voMIaF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:02:52 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)) 08:02:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:02:52 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-G_Jksc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:02:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 08:02:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:02:53 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-b7CE9z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:02:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 08:02:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:02:53 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-QEW3Qn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:02:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 08:02:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:02:53 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-_pp5p5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:02:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 08:02:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:02:53 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-5iq_bE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:02:53 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)) 08:02:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:02:53 INFO - Running setup.py (path:/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/pip-C31A_R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:02:53 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)) 08:02:53 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)) 08:02:53 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)) 08:02:53 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)) 08:02:53 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)) 08:02:53 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)) 08:02:53 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:02:53 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 08:02:53 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 08:02:53 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 08:02:53 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 08:02:53 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 08:02:53 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 08:02:56 INFO - Downloading blessings-1.6.tar.gz 08:02:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:02:56 INFO - Installing collected packages: blessings 08:02:56 INFO - Running setup.py install for blessings 08:02:56 INFO - Successfully installed blessings 08:02:56 INFO - Cleaning up... 08:02:56 INFO - Return code: 0 08:02:56 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:02:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:02:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:02:56 INFO - Reading from file tmpfile_stdout 08:02:56 INFO - Current package versions: 08:02:56 INFO - blessings == 1.6 08:02:56 INFO - blobuploader == 1.2.4 08:02:56 INFO - docopt == 0.6.1 08:02:56 INFO - functools32 == 3.2.3-2 08:02:56 INFO - jsonschema == 2.5.1 08:02:56 INFO - manifestparser == 1.1 08:02:56 INFO - mozInstall == 1.12 08:02:56 INFO - mozcrash == 1.0 08:02:56 INFO - mozdebug == 0.1 08:02:56 INFO - mozdevice == 0.48 08:02:56 INFO - mozfile == 1.2 08:02:56 INFO - mozhttpd == 0.7 08:02:56 INFO - mozinfo == 0.9 08:02:56 INFO - mozleak == 0.1 08:02:56 INFO - mozlog == 3.4 08:02:56 INFO - moznetwork == 0.27 08:02:56 INFO - mozprocess == 0.23 08:02:56 INFO - mozprofile == 0.28 08:02:56 INFO - mozrunner == 6.13 08:02:56 INFO - mozscreenshot == 0.1 08:02:56 INFO - mozsystemmonitor == 0.3 08:02:56 INFO - moztest == 0.7 08:02:56 INFO - mozversion == 1.4 08:02:56 INFO - psutil == 3.1.1 08:02:56 INFO - requests == 1.2.3 08:02:56 INFO - wsgiref == 0.1.2 08:02:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:02:56 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')]} 08:02:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:02:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:56 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')]} 08:02:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:02:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:56 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 0x104f46490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ebc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefdbc9a870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d5ad30>, '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 0x104bc0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104d52b20>, '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.olR8qxLACv/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.ofIbI9GY99/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/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 08:02:56 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 08:02:56 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 08:02:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.olR8qxLACv/Render', 08:02:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:02:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:02:56 INFO - 'HOME': '/Users/cltbld', 08:02:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:02:56 INFO - 'LOGNAME': 'cltbld', 08:02:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:02:56 INFO - 'MOZ_NO_REMOTE': '1', 08:02:56 INFO - 'NO_EM_RESTART': '1', 08:02:56 INFO - 'PAGER': '/bin/cat', 08:02:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:02:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:02:56 INFO - 'PWD': '/builds/slave/test', 08:02:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:02:56 INFO - 'SHELL': '/bin/bash', 08:02:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ofIbI9GY99/Listeners', 08:02:56 INFO - 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/', 08:02:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:02:56 INFO - 'USER': 'cltbld', 08:02:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:02:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:02:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:02:56 INFO - 'XPC_FLAGS': '0x0', 08:02:56 INFO - 'XPC_SERVICE_NAME': '0', 08:02:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:02:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:02:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:02:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 08:02:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 08:02:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:02:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 08:02:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 08:02:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 08:02:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 08:02:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 08:02:57 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:02:57 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:02:57 INFO - Cleaning up... 08:02:57 INFO - Return code: 0 08:02:57 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:02:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:02:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:02:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:02:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:02:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:02:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104f46490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ebc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefdbc9a870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d5ad30>, '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 0x104bc0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104d52b20>, '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.olR8qxLACv/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.ofIbI9GY99/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/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 08:02:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:02:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:02:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.olR8qxLACv/Render', 08:02:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:02:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:02:57 INFO - 'HOME': '/Users/cltbld', 08:02:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:02:57 INFO - 'LOGNAME': 'cltbld', 08:02:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:02:57 INFO - 'MOZ_NO_REMOTE': '1', 08:02:57 INFO - 'NO_EM_RESTART': '1', 08:02:57 INFO - 'PAGER': '/bin/cat', 08:02:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:02:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:02:57 INFO - 'PWD': '/builds/slave/test', 08:02:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:02:57 INFO - 'SHELL': '/bin/bash', 08:02:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ofIbI9GY99/Listeners', 08:02:57 INFO - 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/', 08:02:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:02:57 INFO - 'USER': 'cltbld', 08:02:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:02:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:02:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:02:57 INFO - 'XPC_FLAGS': '0x0', 08:02:57 INFO - 'XPC_SERVICE_NAME': '0', 08:02:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:02:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:02:57 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:02:57 INFO - Cleaning up... 08:02:57 INFO - Return code: 0 08:02:57 INFO - Running post-action listener: _resource_record_post_action 08:02:57 INFO - Running post-action listener: _start_resource_monitoring 08:02:57 INFO - Starting resource monitoring. 08:02:57 INFO - [mozharness: 2016-11-27 16:02:57.387293Z] Finished create-virtualenv step (success) 08:02:57 INFO - [mozharness: 2016-11-27 16:02:57.388383Z] Running install step. 08:02:57 INFO - Running pre-action listener: _resource_record_pre_action 08:02:57 INFO - Running main action method: install 08:02:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:02:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:02:57 INFO - Reading from file tmpfile_stdout 08:02:57 INFO - Detecting whether we're running mozinstall >=1.0... 08:02:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:02:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:02:57 INFO - Reading from file tmpfile_stdout 08:02:57 INFO - Output received: 08:02:57 INFO - Usage: mozinstall [options] installer 08:02:57 INFO - Options: 08:02:57 INFO - -h, --help show this help message and exit 08:02:57 INFO - -d DEST, --destination=DEST 08:02:57 INFO - Directory to install application into. [default: 08:02:57 INFO - "/builds/slave/test"] 08:02:57 INFO - --app=APP Application being installed. [default: firefox] 08:02:57 INFO - mkdir: /builds/slave/test/build/application 08:02:57 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'] 08:02:57 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 08:03:28 INFO - Reading from file tmpfile_stdout 08:03:28 INFO - Output received: 08:03:28 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 08:03:28 INFO - Running post-action listener: _resource_record_post_action 08:03:28 INFO - [mozharness: 2016-11-27 16:03:28.619204Z] Finished install step (success) 08:03:28 INFO - [mozharness: 2016-11-27 16:03:28.619346Z] Running run-tests step. 08:03:28 INFO - Running pre-action listener: _resource_record_pre_action 08:03:28 INFO - Running main action method: run_tests 08:03:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:03:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:03:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:03:28 INFO - 2016-11-27 08:03:28.634 screenresolution[1917:24644] starting screenresolution argv=screenresolution get 08:03:28 INFO - 2016-11-27 08:03:28.636 screenresolution[1917:24644] Display 0: 1600x1200x32@60 08:03:28 INFO - 2016-11-27 08:03:28.642 screenresolution[1918:24647] starting screenresolution argv=screenresolution list 08:03:28 INFO - Available Modes on Display 0 08:03:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:03:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:03:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:03:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:03:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:03:28 INFO - Intel Iris: 08:03:28 INFO - Chipset Model: Intel Iris 08:03:28 INFO - Type: GPU 08:03:28 INFO - Bus: Built-In 08:03:28 INFO - VRAM (Dynamic, Max): 1536 MB 08:03:28 INFO - Vendor: Intel (0x8086) 08:03:28 INFO - Device ID: 0x0a2e 08:03:28 INFO - Revision ID: 0x0009 08:03:28 INFO - Displays: 08:03:28 INFO - Display: 08:03:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:03:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:03:28 INFO - Display Serial Number: 1600x1200 60 08:03:28 INFO - Main Display: Yes 08:03:28 INFO - Mirror: Off 08:03:28 INFO - Online: Yes 08:03:28 INFO - Rotation: Supported 08:03:28 INFO - Return code: 0 08:03:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:03:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:03:28 INFO - Python 2.7.3 08:03:28 INFO - Return code: 0 08:03:28 INFO - grabbing minidump binary from tooltool 08:03:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:03:28 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 0x104d5ad30>, '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 0x104bc0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104d52b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:03:28 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 08:03:28 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 08:03:28 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 08:03:28 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:03:29 INFO - Return code: 0 08:03:29 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:03:29 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:03:29 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:03:29 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:03:29 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:03:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YkcB_iyAQaCz6jyl7d4McA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0189', '--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 08:03:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/YkcB_iyAQaCz6jyl7d4McA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0189 --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 08:03:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.olR8qxLACv/Render', 08:03:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:03:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:03:29 INFO - 'HOME': '/Users/cltbld', 08:03:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:03:29 INFO - 'LOGNAME': 'cltbld', 08:03:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:03:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:03:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:03:29 INFO - 'MOZ_NO_REMOTE': '1', 08:03:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:03:29 INFO - 'NO_EM_RESTART': '1', 08:03:29 INFO - 'PAGER': '/bin/cat', 08:03:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:03:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:03:29 INFO - 'PWD': '/builds/slave/test', 08:03:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:03:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:03:29 INFO - 'SHELL': '/bin/bash', 08:03:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ofIbI9GY99/Listeners', 08:03:29 INFO - 'TMPDIR': '/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/', 08:03:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:03:29 INFO - 'USER': 'cltbld', 08:03:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:03:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:03:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:03:29 INFO - 'XPC_FLAGS': '0x0', 08:03:29 INFO - 'XPC_SERVICE_NAME': '0', 08:03:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:03:29 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YkcB_iyAQaCz6jyl7d4McA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0189', '--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 08:03:29 INFO - mozversion application_buildid: 20161127062019 08:03:29 INFO - mozversion application_changeset: d8e4710b55d980cb3a63f8bae224595d7ef256a0 08:03:29 INFO - mozversion application_display_name: FirefoxDeveloperEdition 08:03:29 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:03:29 INFO - mozversion application_name: Firefox 08:03:29 INFO - mozversion application_remotingname: firefox-dev 08:03:29 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:03:29 INFO - mozversion application_vendor: Mozilla 08:03:29 INFO - mozversion application_version: 52.0a2 08:03:29 INFO - mozversion platform_buildid: 20161127062019 08:03:29 INFO - mozversion platform_changeset: d8e4710b55d980cb3a63f8bae224595d7ef256a0 08:03:29 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:03:29 INFO - mozversion platform_version: 52.0a2 08:03:29 INFO - using testdate: 1480262609 08:03:29 INFO - actual date: 1480262609 08:03:29 INFO - starting webserver on 'localhost:53628' 08:03:29 INFO - SUITE-START | Running 2 tests 08:03:29 INFO - TEST-START | dromaeo_css 08:03:29 INFO - operating with platform_type : mac_ 08:03:29 INFO - Initialising browser for dromaeo_css test... 08:03:29 INFO - TEST-INFO | started process 1928 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/tmpzUlMvw/profile http://localhost:53628/getInfo.html) 08:03:31 INFO - PROCESS | 1928 | __metrics Screen width/height:1600/1200 08:03:31 INFO - PROCESS | 1928 | colorDepth:24 08:03:31 INFO - PROCESS | 1928 | Browser inner width/height: 1024/689 08:03:31 INFO - PROCESS | 1928 | __metrics 08:03:31 INFO - PROCESS | 1928 | 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" 08:03:31 INFO - PROCESS | 1928 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:03:32 INFO - TEST-INFO | 1928: exit 0 08:03:32 INFO - Browser initialized. 08:03:32 INFO - Running cycle 1/1 for dromaeo_css test... 08:03:32 INFO - TEST-INFO | started process 1930 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/tmpzUlMvw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:03:44 INFO - PROCESS | 1930 | RSS: Main: 238608384 08:03:44 INFO - PROCESS | 1930 | 08:04:36 INFO - PROCESS | 1930 | 1480262676559 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] 08:06:49 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:53628/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53628/tests/dromaeo/cssquery-ext.html) 08:06:51 INFO - PROCESS | 1930 | RSS: Main: 348921856 08:06:51 INFO - PROCESS | 1930 | 08:09:54 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:53628/tests/dromaeo/cssquery-ext.html (next: http://localhost:53628/tests/dromaeo/cssquery-jquery.html) 08:09:54 INFO - PROCESS | 1930 | RSS: Main: 361951232 08:09:54 INFO - PROCESS | 1930 | 08:12:57 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:53628/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53628/tests/dromaeo/cssquery-mootools.html) 08:12:57 INFO - PROCESS | 1930 | RSS: Main: 328867840 08:12:57 INFO - PROCESS | 1930 | 08:16:00 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:53628/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53628/tests/dromaeo/cssquery-prototype.html) 08:16:00 INFO - PROCESS | 1930 | RSS: Main: 330735616 08:16:00 INFO - PROCESS | 1930 | 08:19:04 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:53628/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53628/tests/dromaeo/cssquery-yui.html) 08:19:04 INFO - PROCESS | 1930 | RSS: Main: 322228224 08:19:04 INFO - PROCESS | 1930 | 08:22:08 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:53628/tests/dromaeo/cssquery-yui.html (next: http://localhost:53628/tests/dromaeo/cssquery-dojo.html) 08:22:08 INFO - PROCESS | 1930 | RSS: Main: 407879680 08:22:08 INFO - PROCESS | 1930 | 08:22:08 INFO - PROCESS | 1930 | __start_tp_report 08:22:08 INFO - PROCESS | 1930 | _x_x_mozilla_page_load 08:22:08 INFO - PROCESS | 1930 | _x_x_mozilla_page_load_details 08:22:08 INFO - PROCESS | 1930 | |i|pagename|runs| 08:22:08 INFO - PROCESS | 1930 | |0;dojo.html;3351.983240083799;3354.3794398036366;3356.1777364545683;3367.6969072783477;3397.303552771682;5671.8298451046485;5686.829395118143;5811.331696854863;5816.389279125689;5826.504747096491;5690.715464226782;5713.400092990239;5744.597878148531;5788.160716696077;5792.073268277077;4360.738355698668;5224;5264.368275806909;5293.947060529403;5309.0709125903;5201.141811601081;5212.270282160501;5223.973880130594;5364.329458817648;5369.892602147955;5246.213068039787;5273.630845840793;5279.2872962150195;5314.388845282784;5339.22581225722;5295.947040529603;5300.310959575269;5314.627976041678;5317.680939143664;5364.034473794715;5225.686458812484;5319.654222475566;5321.467853214686;5337.786488540453;5343.145096784496;5606.831795046155;5619.129034999582;5625.38120806711;5628.212050312959;5762.250907382056;6195.690215489208;6237.844053898643;6266.279377871558;6269.059641053833;6280.937190628081;6318.368163183691;6321.336259692758;6333.619982801047;6342.460890824285;6346.333634968354;5654.15187721845;5713.714314284269;5720.1991721159075;5800.651960882318;5805.303363596396;6272.153259309935;6317;6334.493240540745;6348.555601107878;6365.204349456318;6119.357467465941;6122.072837224622;6156.78451254204;6162.969185154046;6173.722182501798;5721;5983.982722937111;6227.750889964442;6327.240731112296;6333.619982801047;6094.603850749687;6129.96935015331;6142.938570614237;6201.689021361152;6241.469475094577;6142.161149281699;6222.3466536013975;6229.626222426669;6293.402126797947;6300.905486417708;4319.676024298127;4883.169861123618;4944.9258261126115;4986;4987.825426110068;3859.1895701902354;4435.889102772457;4456.264716321835;4458.8439404620685;4462.152191083684;5671.8865622688145;5716.714164291769;5741.081426971665;5776.249087618583;5821.621594596338;6310.6213627182515;6315.5894866833505;6322.0200868865395;6329.7468101275435;6360.491160707133;5846.503047240947;5854.151148083589;5867.853303667357;5877.059670452707;5911.615744976562;5240;5326.387465441447;5359.624826262202;5435.1303791393975;5464.890702185934;6297.874042519215;6302.495800336053;6315.084312774714;6316.431521163117;6329.272133704499;6206.410391012847;6223.3465486125115;6265.498760099273;6289.150964619717;6319.557630965788;4906.509349065208;6273.372662733873;6331.841703957438;6343.207099112611;6346.397092276226;6202.596831205957;6240.656763878031;6246.937530624818;6256.718447669775;6256.937430625636;6140.109684095871;6150.815475535741;6163.3836616338;6171.197744293213;6204.038374052029;6081.908771368345;6113.327533971171;6131.682521312785;6160.7535698571555;6175.59858609189;5997.610155339889;6148.846278842886;6154.723037463235;6165.167702360124;6171.907421388593;5794.5364370851075;5833.533317334688;5860.120981852756;5890.970545147246;5951.672658003851;6158.353372895961;6169.01295792671;6172.783952561639;6185.969070154801;6191.442770150708;5955.761769529344;5966.820995370146;5981.952550746064;6015.819525414245;6017 08:22:08 INFO - PROCESS | 1930 | |1;ext.html;457.85119836053286;475.3725082890586;481.63877092180866;491.5354989534891;502.97895272596617;38199.99999999996;39657.99999999996;39733.40399894004;41752.79123604386;41907.95230119241;40680.796596017055;40693.59306406935;41034.79482602583;42126.36810447838;42273;9341.299402544802;9515;9523.761905952337;9532.14210721035;9538.90461095387;9431.386959847623;9497.050294970517;9546.379485333431;9557.474338911357;9564.37831540951;9534.856977145348;9556.331056826026;9565.66520171794;9596.472194029326;9604.903950960506;9468.053194680546;9514.666986655504;9535.618575256982;9554.713358599183;9556.761080972961;9280.303977201736;9353.906460935372;9363.76590585234;9373.109554592304;9387.202087822543;39465.802670986464;39783.60216397857;39869.00327491836;39876.20247595061;39971.40042899359;3062;3077.769167312438;3289.292802047571;3617.1318883468043;3885.844566217348;28713.56929646057;29500.70499295001;29505.262368440744;30227.848860755556;30832.229194270323;3131.827749473778;3184.188032051812;3214.4053350130303;3232.8060316381093;3256.3812875553567;431234;432290;432968;436468;436828;33020.66979330224;60506.697466512385;60808;60921.39078609246;61071.694641526505;34098.829505852314;34887.302253955284;35002.474962875574;35015.4747678785;35076.29847403038;14357.92821035909;14476.493322733864;14480.493182738554;14495.710085798224;14520.419183232552;12164.574239901562;12244.142909996215;12262.938685306517;12267.93866030682;12279.14046016788;7773.805654858674;7784.104827944859;7806.960965195251;7864.331531819745;7872.488288261245;2748.436570503082;3209.871605135815;3210.1172177650865;3216;3239.4492936200904;897.2059727141527;943.3820847345;1000.2847963705985;1022.192467950311;1036.1814166808288;28259.293517661812;29170.270743231165;29185.270368240963;30054.84972575167;30134.24664383364;698.9440844732409;794.8489786940463;860.9139086091341;989.747614358334;1039.8648175737105;14817.333220005126;15392.769108463383;15439.228038598025;15509.379624814881;15863.920680396524;643054;644010;644572;645004;646979;50640;57715.71142144346;58214.417855821746;58235.12647310294;58337.70831145817;1260.936953152357;1343.4021860272085;1351.7634413977712;1381.4128995177211;1382.7787553991313;29622.703772962857;30341.848290758404;30688.232794180323;31046.689533105284;31081.689183108785;49943.50056499388;50589.74705126451;50601.24098138457;50761.746191270286;50984.74507627438;403148;404187;408164;409371;454498;39292.410613841384;39612;40151.3977290335;40433.786986390456;40698.389524157756;43605;43886;44572.55427445814;44762.77618611886;44796;35680.10799729924;36903.630963691096;37174.6282537182;37356;38710.612893871825;38305.4254186193;38627.07250680525;38850.80574597109;39347.016324592114;39647;37116.443253350684;37768.811155944044;38011.809940950116;38046.858594242214;38400.6159938397;32581.34837303288;34115.97652070442;34654;34747.82626086853;34914.82542587271;21562.568748624624;23941;24878.751212487645;25412.983480661307;25852.482950341266 08:22:08 INFO - PROCESS | 1930 | |2;jquery.html;352.80595672380184;373.251630480886;373.9476473293739;385.69337376785455;404.4136002795945;14721.558353249406;14987.400503979841;15906.681866362665;19521;20411.183552657913;19282.035898205104;19844.107015184316;20279.087441065152;20413.693794593055;20455.693164602504;5631.662100273976;5931.080682494198;5936.257967754031;5944.4352786485215;5950.285965684124;5442.455754424545;5801.332846722617;5828.0966450200285;5835.270591176103;5839.386864379243;5553.805616803413;5695.5443564514735;5702.885942281173;5719.6854173020465;5803.158542011406;5658.886822263552;5794;5794.217780599628;5795.391483894215;5795.768169273224;4851.932574833531;5076.36545431821;5083.847484575469;5094.79620815167;5178.17149256121;24778.50442991148;25173.24480265595;25192.874035629888;25500;25503.362415939566;2278.624027035554;2379.703061831306;2382.892769825362;2638.1557901471538;2734.357426004887;9943.15483183923;10453.634122805664;10515.474226288656;10645.041946224877;14357.138571685628;1849.6670599292122;1852.3887117251275;1894.4979580411202;1902.4387805597382;1937.8643494955359;122739;123956.38021809924;127922.36038819839;129965;132833.33583332118;11216.327020378803;11640.417979101181;11663.708407289885;11713.648590542298;11886.34625095628;14312.85687143136;14337.928310358382;14631.92684036573;14675.853241467663;14750.926245368704;7013.438924885997;8323.875141872877;8487.363447741443;8669.436486628349;8713.3464990126;8254.463459875089;8379.874301885477;8384.119667434832;8422.410431269875;8509.872351914726;4434.55654434554;4577.198990176772;4578.313253012008;4589.54104589545;4654.534546545318;1854.2212270846278;1911.8852868827914;1917.309768483345;1917.68358220892;1918.7505624268756;797.7606717984581;835.8579041562949;836.4270474724833;883.4478450968145;892.1078921078921;19783.60432791365;20407.979601019888;20474.38576842697;20635.69046464304;20754.57549504345;910.1581037540382;910.9726708198632;923.072312326108;933.0995589256264;937.9953100234455;8571.957140214383;8796.284724478664;9950.552225149886;10912.890871091187;10952.890471095347;577778;587480.5680381156;590355;594709;598254;133288.3335583316;137788;138885;138959;142769;965.232640051147;974.91713204377;981.8969008254317;983.8179936711731;993.7813680990171;17788.288468461116;18139;18490.630187396448;18551.628967420846;18732.06339682983;51016.918646507445;52546;56055.43944560665;57155.42844571501;58140;67234.99147512889;80371;82783.34433311179;82952;84460.57769711112;6417.582857114273;7384.704611815468;7425.034745483053;7562.7353042645445;7978.202179781975;10927;12695.047871409866;12717.236965782082;12798.232106073665;12960.222386656453;8704.477731336141;10666.306690065121;10690.62582809629;10972.561097556008;11098.945600167972;10845.240833141605;10958.780824383628;11069.889301106887;11131.220814542905;11290.66128016128;10821.296615719953;10982;11032.338059716121;11062.280951738112;11160.330380177214;7215.783526494213;7362.7423040193335;7514.624268786648;7597.582132982739;7680.385569154342;9967.202623790225;10842.349459032483;10889.836652450062;10986.835197471884;11144.554217831195 08:22:08 INFO - PROCESS | 1930 | |3;mootools.html;1311.1936159262057;1466.083697688944;1506.4802643088135;1656.4699296225212;1849.3897013985395;7553.471257012005;8004.799880003003;8011.59942002899;8017;8026;7834.099078605962;7905.841883162333;8269.214424629665;8508.446950948199;8518.616662250199;2326.557953988741;2362.3857796972825;2362.9763702363016;2363.1020212319295;2372.240882917467;2348.941276468085;2395.9880200598973;2400.651905473714;2401.7478164792706;2405.1702162753822;2314.2363020203375;2319.675245465636;2344.8475849069755;2350.059976009601;2353.011735071274;2316.5830150572892;2321.2223904991847;2344.0975224538506;2350.612148995414;2354.4702441950594;2330.976690233093;2339.6139636959883;2366.491204391047;2374.085976898906;2376.9286921392386;8574.442661227062;8589.570521473901;8682.175193356621;8712;8712.390132690714;5093.337866077423;5160.974195129038;5332.386775520826;5340.385855626575;5345.064613692588;3572.392693242155;3662.981685091584;3666.17511060012;3667.743257971943;3679.0802299425145;1065.63235683689;1089.8256278995402;1104.7624760676413;1109.7503061811108;1119.9976021460748;10767.676969691;11221.887781122166;11324;11334.319940803578;11355.545778168864;4354.782260886943;4473.776311184428;4497.123061003073;4514.548545145459;4515.5484451554585;8680.392372533863;8683.956580217058;8684.131586841266;8689.6524139035;8703.43427677199;5083.771230294646;5092.312537807348;5092.872678183001;5093.439721630618;5104.591632669378;3821.3027655061424;4681.7425041622355;4749.4775574686755;4822.517748225219;4840.128776820206;3667.9266414671556;3690.7635941959743;3779.6787273081545;3808.790516521618;3835.6931445484292;1148.2536351371675;1151.4818331750746;1160.0951258018758;1169.976600467986;1170.6019953215948;764.9196834332315;864.7319331007408;878.7715194049465;911.6991392840347;913.5888850017386;5628.85927851807;5657.971710141505;5735.225744524436;5836.970815145982;6052.878942421127;864.2999170671776;894.5124906925611;915.2403505090758;929.707142250189;959.5058544849409;3706.7590606610484;3866.2847373235477;3894.4937158169255;3915.569287378386;3918.6081391861157;9952.203823694088;10288.897111028944;10446.268761186644;10458;10474.214433917487;9258.629654813732;10277.794444111076;10405;10406.739831504121;10423.843642345371;7142.57144571317;7696.11494678108;8033.718819841278;8042;8155.673773049011;5654.886902261849;5784.421557844182;5798;5958.404159584007;5979.760809567569;9769.218462522838;9774.902250977684;9795.118439340493;9799.167070799062;9805.362651427635;9477.336586438883;9752.658656946973;9775.21798256152;9855.605775769176;9969.102780749767;6368.0766288886925;6586.571872828251;6592.604443733391;6803.557768745016;7042.894356584553;7841.647125879464;8113.35093192555;8116.513009219466;8207.753767386748;8231.876521852057;7118.822029449305;7194.316539928698;7321.487495875238;7360.595167265852;7457.179710231979;5636.605437619492;5997.790077347272;7188.353048225685;7222.530535515385;7240.203577606564;6598.8350291243105;6729.5625784323865;6813.965930170318;6821.693023813841;6822.897656535255;4266.360045993126;4884.291777692144;4894;4978.576820970258;4990.6506544541535;4644.651651126112;4894.853154405374;4985.227289770091;5045;5110.080185566634 08:22:08 INFO - PROCESS | 1930 | |4;prototype.html;301.18529378032423;304.85367023828564;308.8949757082592;313.73332667232853;314.6114548532563;4336.414584031155;4437.2013037653205;5117.903668280688;5145.536901678848;5261.342332208474;4997.600191984641;5039.848804535861;5085.974570127153;5113.318410744268;5289;3912.2566712324638;4006.8998275043214;4041.2016652589464;4041.979790101045;4074.8981275468054;3496.108492334464;3957.4261732048835;4033.5159780826193;4045.4336392905016;4087.4686290782156;3634.4003239465455;3839.9808000959956;4034.354503279476;4037.051292946155;4046.676265898736;3540.451230059345;3974.701897357695;4013.19406267178;4031.5363733170766;4062.2484840304637;3275.082976766509;3354.798712077283;3535.628758980296;3550.5739311282555;3560.7151427885706;4664.300354946751;5085.79656813727;5164.992826398854;5201.205584073488;5205.869853253661;3251.674832516753;4454.955450445519;4530.501644819067;4540.409746732904;4547.431571053618;4843.273508973647;5514.558835293167;5522.806701765419;5529;5554.0835762099205;3341.398548261312;3757.6805971492454;3787.8484860605545;3790.639889210521;3794.658480736747;4821.397325334333;5389.568834493231;5411.106112860467;5776.42235776419;5814.47669709724;3376.4260075787174;3780.640839120279;3812.218495208476;3818.579956204815;3832.9616703833162;3852.3643598806443;3872.7482713623526;3888.6305800948867;3893.3186692328727;3909.843606255775;3860.749051311656;3921.7449516900906;4542.772861356919;4568.703034302826;4577.977110114428;5553.194786755978;5573.331200255995;5580.130656230208;5582.436917663061;5585.32440267918;1049.6588608702202;1091.1870656361061;1242.9005679545796;1256.7432567432568;1263.570386068741;702.9859402811915;799.9680412268109;804.7384600005022;849.218718778725;868.887044684187;4336.436263285816;4634.709356356957;4797.520247975174;4879.268109783561;4939.431965324034;4895.8911091457185;5310.734463276898;5311.920321195185;5331.333583302087;5403.729813509309;4171.0823618803815;4628.236341003697;4746.905061898743;4749;4829.5653391194965;2455.9754402455746;2541.567933451318;2724.9318767030586;2752.862356882148;2786.6945569095797;4492.0791237796175;4810.845397104669;5328.200769884548;5349.518543331119;5373.011985565366;5232.869178270574;5312.415634280226;5396.91904621431;5512.058877902319;5897.380775018647;5927.911081333784;5943.206692854352;5947.204813737537;5947.821565353046;5990.880182396415;4646.883827904329;5561.916571251353;5645.294338207724;5730.681943153015;5751.9137212941005;4696.976515117402;5021.698698077981;5578.525825304894;5661.037623604079;5674.460926212003;5380.973095134499;5600.927656767022;5624.7187640616985;5922.5261979040715;5944.64332140073;3399.8470068847455;3549.7337699673144;3776.4901738264984;3831.846726131035;3862.7102967277006;5516.420775818642;5551.417845913895;5555.472230138131;5560.749766260609;5561.804212094419;4265.552117027667;4712.575868171881;5292.576593872404;5299.49654782795;5356.544693701079;4385.758783266951;4640.862988567823;5441.639590102474;5481.917771233355;5534.833954981357;5156.716380599103;5324.725293944119;5351.331083614548;5414.187871819258;5489.615726899079;5024.593113928106;5335.86565373838;5415.48552887475;5431.266778984945;5565.7217139143695;5402.162664786964;5425.891482170414;5445.319335083114;5448.357546589821;5471.562275018068 08:22:08 INFO - PROCESS | 1930 | |5;yui.html;669.9564528305659;683.5112894280588;702.2240424331114;732.1616748822598;733.5232099135558;14291.213983230931;14884.404623815062;14915;14923.552293431201;14939.626509337271;12965.092443528956;13028.544000959944;13086.280254586018;13132.803007954864;13148.07963442557;659.7064306383661;678.1658559971238;679.1544527063812;800.7837883771393;804.798800299925;817.9509229446222;820.1839170025803;826.6734639817298;828.2421584250403;829.5022986208257;877.8814859993915;880.1770344727701;881.1100788203928;881.6297155194801;881.8148188880343;698.8637215742932;716.813628456605;737.3732327521617;877.8156587116713;878.4202426398579;623.1307326279859;643.4723526708101;644.2397970394932;646.024503000468;647.6891092275687;13847.930760346133;13888.791668124986;14088.929555352157;14213.78679319811;14235.928820355935;13049.412776425084;13229.140105893086;13282.933585332108;13337.666558336023;13381.598552043455;10331.27681062326;11255.774884502345;11729;11894.52421903123;12266.509339626493;4282.7169946403965;4304.80628371724;4423.557644235583;4468.0840427712565;4500.009997800478;77650.83523747092;87436;88496;89599;90454;9830.508474576243;9943.850842237372;10281.228907831794;10517.631882884063;10531.210159238088;17238;19206.231750730094;19316.517087072934;19467.415977520697;19557.119929603203;7216.278372162742;7220.9638951805955;7267.963660181666;7292.562446253241;7470.925290747132;4050.210209009215;4141.399497072907;4155.397467367215;4211.599898009684;4250.213710463574;3199.2961548459302;3304.8182349970984;3308.93382132356;3312.320974200283;3317.3696997570382;585.9443352881469;631.3749388105742;633.7718421368304;641.8908785506475;688.5868478912713;523.8637954131954;570.697530308937;637.1939496536885;647.0714524657133;668.5520701130255;8676.652933882575;9219.446833189895;9237;9262.166405023445;9412.717618471457;555.611072249427;563.4224919457507;598.6617561077977;605.4460168945366;662.4402379988902;4083.265012297815;4448.088141930896;4520.457545094544;4680.368150299666;4811.422629284439;71308.28691713017;93227.53386233025;97057;114947;123961.38019310027;66008.33991660118;66960;66972.33027669758;92401.07598923925;101628.49185754173;66393;88754.66867996985;89248;90342.09657903468;92694;8488.660453581788;8623.223909848144;9116.90883091187;9401.81196376055;9792.265580081379;54271.45728542664;54614;55610.88778224332;55714.442855570924;58311.125333120886;78043.82934255878;79094.20905790868;79723.00692482735;81458;84487;10148.340357876714;10182.592696292066;11782.587609433629;11877.762444750884;12746.42641081172;13588.59234222975;13655.453781849012;13787.724245514833;14551.199684017482;14658.853411466176;12693.619191424272;12878.671191426114;12891.742165156456;13162;13271.203727775977;12262.448189831657;12983.610491685264;13027.739445211233;13034.4134513949;13509.86490135086;11579.594714184963;11940.522379105087;12190.146689731633;12662.366881656064;13191.274479903313;9960.203183745429;10597.841032384365;10835.566577336818;11176.664700059011;11354.772904542027;9134.497602631918;10236.18110551169;10437.895621043996;10889.509972051119;11059.492442102255 08:22:08 INFO - PROCESS | 1930 | __end_tp_report 08:22:08 INFO - PROCESS | 1930 | __start_cc_report 08:22:08 INFO - PROCESS | 1930 | _x_x_mozilla_cycle_collect,1712 08:22:08 INFO - PROCESS | 1930 | __end_cc_report 08:22:08 INFO - PROCESS | 1930 | __startTimestamp1480263728375__endTimestamp 08:22:08 INFO - PROCESS | 1930 | 08:22:08 INFO - PROCESS | 1930 | ------- Summary: start ------- 08:22:08 INFO - PROCESS | 1930 | Number of tests: 6 08:22:08 INFO - PROCESS | 1930 | 08:22:08 INFO - PROCESS | 1930 | [#0] dojo.html Cycles:165 Average:5754.48 Median:5955.76 stddev:658.68 (11.1%) stddev-sans-first:633.16 08:22:08 INFO - PROCESS | 1930 | Values: 3352.0 3354.4 3356.2 3367.7 3397.3 5671.8 5686.8 5811.3 5816.4 5826.5 5690.7 5713.4 5744.6 5788.2 5792.1 4360.7 5224.0 5264.4 5293.9 5309.1 5201.1 5212.3 5224.0 5364.3 5369.9 5246.2 5273.6 5279.3 5314.4 5339.2 5295.9 5300.3 5314.6 5317.7 5364.0 5225.7 5319.7 5321.5 5337.8 5343.1 5606.8 5619.1 5625.4 5628.2 5762.3 6195.7 6237.8 6266.3 6269.1 6280.9 6318.4 6321.3 6333.6 6342.5 6346.3 5654.2 5713.7 5720.2 5800.7 5805.3 6272.2 6317.0 6334.5 6348.6 6365.2 6119.4 6122.1 6156.8 6163.0 6173.7 5721.0 5984.0 6227.8 6327.2 6333.6 6094.6 6130.0 6142.9 6201.7 6241.5 6142.2 6222.3 6229.6 6293.4 6300.9 4319.7 4883.2 4944.9 4986.0 4987.8 3859.2 4435.9 4456.3 4458.8 4462.2 5671.9 5716.7 5741.1 5776.2 5821.6 6310.6 6315.6 6322.0 6329.7 6360.5 5846.5 5854.2 5867.9 5877.1 5911.6 5240.0 5326.4 5359.6 5435.1 5464.9 6297.9 6302.5 6315.1 6316.4 6329.3 6206.4 6223.3 6265.5 6289.2 6319.6 4906.5 6273.4 6331.8 6343.2 6346.4 6202.6 6240.7 6246.9 6256.7 6256.9 6140.1 6150.8 6163.4 6171.2 6204.0 6081.9 6113.3 6131.7 6160.8 6175.6 5997.6 6148.8 6154.7 6165.2 6171.9 5794.5 5833.5 5860.1 5891.0 5951.7 6158.4 6169.0 6172.8 6186.0 6191.4 5955.8 5966.8 5982.0 6015.8 6017.0 08:22:08 INFO - PROCESS | 1930 | 08:22:08 INFO - PROCESS | 1930 | [#1] ext.html Cycles:180 Average:63072.08 Median:39758.50 stddev:136054.47 (342.2%) stddev-sans-first:136354.92 08:22:08 INFO - PROCESS | 1930 | Values: 457.9 475.4 481.6 491.5 503.0 38200.0 39658.0 39733.4 41752.8 41908.0 40680.8 40693.6 41034.8 42126.4 42273.0 9341.3 9515.0 9523.8 9532.1 9538.9 9431.4 9497.1 9546.4 9557.5 9564.4 9534.9 9556.3 9565.7 9596.5 9604.9 9468.1 9514.7 9535.6 9554.7 9556.8 9280.3 9353.9 9363.8 9373.1 9387.2 39465.8 39783.6 39869.0 39876.2 39971.4 3062.0 3077.8 3289.3 3617.1 3885.8 28713.6 29500.7 29505.3 30227.8 30832.2 3131.8 3184.2 3214.4 3232.8 3256.4 431234.0 432290.0 432968.0 436468.0 436828.0 33020.7 60506.7 60808.0 60921.4 61071.7 34098.8 34887.3 35002.5 35015.5 35076.3 14357.9 14476.5 14480.5 14495.7 14520.4 12164.6 12244.1 12262.9 12267.9 12279.1 7773.8 7784.1 7807.0 7864.3 7872.5 2748.4 3209.9 3210.1 3216.0 3239.4 897.2 943.4 1000.3 1022.2 1036.2 28259.3 29170.3 29185.3 30054.8 30134.2 698.9 794.8 860.9 989.7 1039.9 14817.3 15392.8 15439.2 15509.4 15863.9 643054.0 644010.0 644572.0 645004.0 646979.0 50640.0 57715.7 58214.4 58235.1 58337.7 1260.9 1343.4 1351.8 1381.4 1382.8 29622.7 30341.8 30688.2 31046.7 31081.7 49943.5 50589.7 50601.2 50761.7 50984.7 403148.0 404187.0 408164.0 409371.0 454498.0 39292.4 39612.0 40151.4 40433.8 40698.4 43605.0 43886.0 44572.6 44762.8 44796.0 35680.1 36903.6 37174.6 37356.0 38710.6 38305.4 38627.1 38850.8 39347.0 39647.0 37116.4 37768.8 38011.8 38046.9 38400.6 32581.3 34116.0 34654.0 34747.8 34914.8 21562.6 23941.0 24878.8 25413.0 25852.5 08:22:08 INFO - PROCESS | 1930 | 08:22:08 INFO - PROCESS | 1930 | [#2] jquery.html Cycles:180 Average:35213.12 Median:373.60 stddev:99118.85 (26530.8%) stddev-sans-first:99362.35 08:22:08 INFO - PROCESS | 1930 | Values: 352.8 373.3 373.9 385.7 404.4 14721.6 14987.4 15906.7 19521.0 20411.2 19282.0 19844.1 20279.1 20413.7 20455.7 5631.7 5931.1 5936.3 5944.4 5950.3 5442.5 5801.3 5828.1 5835.3 5839.4 5553.8 5695.5 5702.9 5719.7 5803.2 5658.9 5794.0 5794.2 5795.4 5795.8 4851.9 5076.4 5083.8 5094.8 5178.2 24778.5 25173.2 25192.9 25500.0 25503.4 2278.6 2379.7 2382.9 2638.2 2734.4 9943.2 10453.6 10515.5 10645.0 14357.1 1849.7 1852.4 1894.5 1902.4 1937.9 122739.0 123956.4 127922.4 129965.0 132833.3 11216.3 11640.4 11663.7 11713.6 11886.3 14312.9 14337.9 14631.9 14675.9 14750.9 7013.4 8323.9 8487.4 8669.4 8713.3 8254.5 8379.9 8384.1 8422.4 8509.9 4434.6 4577.2 4578.3 4589.5 4654.5 1854.2 1911.9 1917.3 1917.7 1918.8 797.8 835.9 836.4 883.4 892.1 19783.6 20408.0 20474.4 20635.7 20754.6 910.2 911.0 923.1 933.1 938.0 8572.0 8796.3 9950.6 10912.9 10952.9 577778.0 587480.6 590355.0 594709.0 598254.0 133288.3 137788.0 138885.0 138959.0 142769.0 965.2 974.9 981.9 983.8 993.8 17788.3 18139.0 18490.6 18551.6 18732.1 51016.9 52546.0 56055.4 57155.4 58140.0 67235.0 80371.0 82783.3 82952.0 84460.6 6417.6 7384.7 7425.0 7562.7 7978.2 10927.0 12695.0 12717.2 12798.2 12960.2 8704.5 10666.3 10690.6 10972.6 11098.9 10845.2 10958.8 11069.9 11131.2 11290.7 10821.3 10982.0 11032.3 11062.3 11160.3 7215.8 7362.7 7514.6 7597.6 7680.4 9967.2 10842.3 10889.8 10986.8 11144.6 08:22:08 INFO - PROCESS | 1930 | 08:22:08 INFO - PROCESS | 1930 | [#3] mootools.html Cycles:180 Average:5483.49 Median:5093.39 stddev:3019.67 (59.3%) stddev-sans-first:3011.86 08:22:08 INFO - PROCESS | 1930 | Values: 1311.2 1466.1 1506.5 1656.5 1849.4 7553.5 8004.8 8011.6 8017.0 8026.0 7834.1 7905.8 8269.2 8508.4 8518.6 2326.6 2362.4 2363.0 2363.1 2372.2 2348.9 2396.0 2400.7 2401.7 2405.2 2314.2 2319.7 2344.8 2350.1 2353.0 2316.6 2321.2 2344.1 2350.6 2354.5 2331.0 2339.6 2366.5 2374.1 2376.9 8574.4 8589.6 8682.2 8712.0 8712.4 5093.3 5161.0 5332.4 5340.4 5345.1 3572.4 3663.0 3666.2 3667.7 3679.1 1065.6 1089.8 1104.8 1109.8 1120.0 10767.7 11221.9 11324.0 11334.3 11355.5 4354.8 4473.8 4497.1 4514.5 4515.5 8680.4 8684.0 8684.1 8689.7 8703.4 5083.8 5092.3 5092.9 5093.4 5104.6 3821.3 4681.7 4749.5 4822.5 4840.1 3667.9 3690.8 3779.7 3808.8 3835.7 1148.3 1151.5 1160.1 1170.0 1170.6 764.9 864.7 878.8 911.7 913.6 5628.9 5658.0 5735.2 5837.0 6052.9 864.3 894.5 915.2 929.7 959.5 3706.8 3866.3 3894.5 3915.6 3918.6 9952.2 10288.9 10446.3 10458.0 10474.2 9258.6 10277.8 10405.0 10406.7 10423.8 7142.6 7696.1 8033.7 8042.0 8155.7 5654.9 5784.4 5798.0 5958.4 5979.8 9769.2 9774.9 9795.1 9799.2 9805.4 9477.3 9752.7 9775.2 9855.6 9969.1 6368.1 6586.6 6592.6 6803.6 7042.9 7841.6 8113.4 8116.5 8207.8 8231.9 7118.8 7194.3 7321.5 7360.6 7457.2 5636.6 5997.8 7188.4 7222.5 7240.2 6598.8 6729.6 6814.0 6821.7 6822.9 4266.4 4884.3 4894.0 4978.6 4990.7 4644.7 4894.9 4985.2 5045.0 5110.1 08:22:08 INFO - PROCESS | 1930 | 08:22:08 INFO - PROCESS | 1930 | [#4] prototype.html Cycles:180 Average:4403.22 Median:4861.27 stddev:1367.43 (28.1%) stddev-sans-first:1336.16 08:22:08 INFO - PROCESS | 1930 | Values: 301.2 304.9 308.9 313.7 314.6 4336.4 4437.2 5117.9 5145.5 5261.3 4997.6 5039.8 5086.0 5113.3 5289.0 3912.3 4006.9 4041.2 4042.0 4074.9 3496.1 3957.4 4033.5 4045.4 4087.5 3634.4 3840.0 4034.4 4037.1 4046.7 3540.5 3974.7 4013.2 4031.5 4062.2 3275.1 3354.8 3535.6 3550.6 3560.7 4664.3 5085.8 5165.0 5201.2 5205.9 3251.7 4455.0 4530.5 4540.4 4547.4 4843.3 5514.6 5522.8 5529.0 5554.1 3341.4 3757.7 3787.8 3790.6 3794.7 4821.4 5389.6 5411.1 5776.4 5814.5 3376.4 3780.6 3812.2 3818.6 3833.0 3852.4 3872.7 3888.6 3893.3 3909.8 3860.7 3921.7 4542.8 4568.7 4578.0 5553.2 5573.3 5580.1 5582.4 5585.3 1049.7 1091.2 1242.9 1256.7 1263.6 703.0 800.0 804.7 849.2 868.9 4336.4 4634.7 4797.5 4879.3 4939.4 4895.9 5310.7 5311.9 5331.3 5403.7 4171.1 4628.2 4746.9 4749.0 4829.6 2456.0 2541.6 2724.9 2752.9 2786.7 4492.1 4810.8 5328.2 5349.5 5373.0 5232.9 5312.4 5396.9 5512.1 5897.4 5927.9 5943.2 5947.2 5947.8 5990.9 4646.9 5561.9 5645.3 5730.7 5751.9 4697.0 5021.7 5578.5 5661.0 5674.5 5381.0 5600.9 5624.7 5922.5 5944.6 3399.8 3549.7 3776.5 3831.8 3862.7 5516.4 5551.4 5555.5 5560.7 5561.8 4265.6 4712.6 5292.6 5299.5 5356.5 4385.8 4640.9 5441.6 5481.9 5534.8 5156.7 5324.7 5351.3 5414.2 5489.6 5024.6 5335.9 5415.5 5431.3 5565.7 5402.2 5425.9 5445.3 5448.4 5471.6 08:22:08 INFO - PROCESS | 1930 | 08:22:08 INFO - PROCESS | 1930 | [#5] yui.html Cycles:180 Average:19897.32 Median:54442.73 stddev:28840.89 (53.0%) stddev-sans-first:28885.66 08:22:08 INFO - PROCESS | 1930 | Values: 670.0 683.5 702.2 732.2 733.5 14291.2 14884.4 14915.0 14923.6 14939.6 12965.1 13028.5 13086.3 13132.8 13148.1 659.7 678.2 679.2 800.8 804.8 818.0 820.2 826.7 828.2 829.5 877.9 880.2 881.1 881.6 881.8 698.9 716.8 737.4 877.8 878.4 623.1 643.5 644.2 646.0 647.7 13847.9 13888.8 14088.9 14213.8 14235.9 13049.4 13229.1 13282.9 13337.7 13381.6 10331.3 11255.8 11729.0 11894.5 12266.5 4282.7 4304.8 4423.6 4468.1 4500.0 77650.8 87436.0 88496.0 89599.0 90454.0 9830.5 9943.9 10281.2 10517.6 10531.2 17238.0 19206.2 19316.5 19467.4 19557.1 7216.3 7221.0 7268.0 7292.6 7470.9 4050.2 4141.4 4155.4 4211.6 4250.2 3199.3 3304.8 3308.9 3312.3 3317.4 585.9 631.4 633.8 641.9 688.6 523.9 570.7 637.2 647.1 668.6 8676.7 9219.4 9237.0 9262.2 9412.7 555.6 563.4 598.7 605.4 662.4 4083.3 4448.1 4520.5 4680.4 4811.4 71308.3 93227.5 97057.0 114947.0 123961.4 66008.3 66960.0 66972.3 92401.1 101628.5 66393.0 88754.7 89248.0 90342.1 92694.0 8488.7 8623.2 9116.9 9401.8 9792.3 54271.5 54614.0 55610.9 55714.4 58311.1 78043.8 79094.2 79723.0 81458.0 84487.0 10148.3 10182.6 11782.6 11877.8 12746.4 13588.6 13655.5 13787.7 14551.2 14658.9 12693.6 12878.7 12891.7 13162.0 13271.2 12262.4 12983.6 13027.7 13034.4 13509.9 11579.6 11940.5 12190.1 12662.4 13191.3 9960.2 10597.8 10835.6 11176.7 11354.8 9134.5 10236.2 10437.9 10889.5 11059.5 08:22:08 INFO - PROCESS | 1930 | -------- Summary: end -------- 08:22:08 INFO - PROCESS | 1930 | 08:22:09 INFO - TEST-INFO | 1930: exit 0 08:22:09 INFO - TEST-OK | dromaeo_css | took 1119800ms 08:22:09 INFO - TEST-START | kraken 08:22:09 INFO - operating with platform_type : mac_ 08:22:09 INFO - Initialising browser for kraken test... 08:22:09 INFO - TEST-INFO | started process 1933 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/tmptRpdxb/profile http://localhost:53628/getInfo.html) 08:22:11 INFO - PROCESS | 1933 | __metrics Screen width/height:1600/1200 08:22:11 INFO - PROCESS | 1933 | colorDepth:24 08:22:11 INFO - PROCESS | 1933 | Browser inner width/height: 1024/689 08:22:11 INFO - PROCESS | 1933 | __metrics 08:22:11 INFO - PROCESS | 1933 | 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" 08:22:11 INFO - PROCESS | 1933 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:22:12 INFO - TEST-INFO | 1933: exit 0 08:22:12 INFO - Browser initialized. 08:22:12 INFO - Running cycle 1/1 for kraken test... 08:22:12 INFO - TEST-INFO | started process 1934 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/T/tmptRpdxb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:22:24 INFO - PROCESS | 1934 | RSS: Main: 243044352 08:22:24 INFO - PROCESS | 1934 | 08:22:27 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:53628/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53628/tests/kraken/driver.html?testName=audio-beat-detection) 08:22:28 INFO - PROCESS | 1934 | RSS: Main: 289542144 08:22:28 INFO - PROCESS | 1934 | 08:22:30 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:53628/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53628/tests/kraken/driver.html?testName=audio-dft) 08:22:31 INFO - PROCESS | 1934 | RSS: Main: 300285952 08:22:31 INFO - PROCESS | 1934 | 08:22:34 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:53628/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53628/tests/kraken/driver.html?testName=audio-fft) 08:22:35 INFO - PROCESS | 1934 | RSS: Main: 395153408 08:22:35 INFO - PROCESS | 1934 | 08:22:37 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:53628/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53628/tests/kraken/driver.html?testName=audio-oscillator) 08:22:37 INFO - PROCESS | 1934 | RSS: Main: 297193472 08:22:37 INFO - PROCESS | 1934 | 08:22:39 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:53628/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53628/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:22:40 INFO - PROCESS | 1934 | RSS: Main: 298364928 08:22:40 INFO - PROCESS | 1934 | 08:22:43 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:53628/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53628/tests/kraken/driver.html?testName=imaging-darkroom) 08:22:43 INFO - PROCESS | 1934 | RSS: Main: 338243584 08:22:43 INFO - PROCESS | 1934 | 08:22:46 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:53628/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53628/tests/kraken/driver.html?testName=imaging-desaturate) 08:22:47 INFO - PROCESS | 1934 | RSS: Main: 338415616 08:22:47 INFO - PROCESS | 1934 | 08:22:50 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:53628/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53628/tests/kraken/driver.html?testName=json-parse-financial) 08:22:50 INFO - PROCESS | 1934 | RSS: Main: 337690624 08:22:50 INFO - PROCESS | 1934 | 08:22:52 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:53628/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53628/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:22:52 INFO - PROCESS | 1934 | RSS: Main: 313061376 08:22:52 INFO - PROCESS | 1934 | 08:22:54 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:53628/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53628/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:22:55 INFO - PROCESS | 1934 | RSS: Main: 313380864 08:22:55 INFO - PROCESS | 1934 | 08:22:57 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:53628/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53628/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:22:58 INFO - PROCESS | 1934 | RSS: Main: 315035648 08:22:58 INFO - PROCESS | 1934 | 08:23:01 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:53628/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53628/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:23:02 INFO - PROCESS | 1934 | RSS: Main: 332304384 08:23:02 INFO - PROCESS | 1934 | 08:23:05 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:53628/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53628/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:23:05 INFO - PROCESS | 1934 | RSS: Main: 325959680 08:23:05 INFO - PROCESS | 1934 | 08:23:07 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:53628/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53628/tests/kraken/driver.html?testName=ai-astar) 08:23:07 INFO - PROCESS | 1934 | RSS: Main: 370012160 08:23:07 INFO - PROCESS | 1934 | 08:23:07 INFO - PROCESS | 1934 | __start_tp_report 08:23:07 INFO - PROCESS | 1934 | _x_x_mozilla_page_load 08:23:07 INFO - PROCESS | 1934 | _x_x_mozilla_page_load_details 08:23:07 INFO - PROCESS | 1934 | |i|pagename|runs| 08:23:07 INFO - PROCESS | 1934 | |0;ai-astar;116;115;116;114;115;115;115;115;114;116 08:23:07 INFO - PROCESS | 1934 | |1;audio-beat-detection;146;116;113;147;114;113;114;112;143;114 08:23:07 INFO - PROCESS | 1934 | |2;audio-dft;184;185;184;186;183;182;184;188;223;183 08:23:07 INFO - PROCESS | 1934 | |3;audio-fft;81;58;60;88;57;56;58;59;83;58 08:23:07 INFO - PROCESS | 1934 | |4;audio-oscillator;90;69;94;69;67;69;89;69;69;91 08:23:07 INFO - PROCESS | 1934 | |5;imaging-gaussian-blur;89;84;89;85;87;83;88;84;87;85 08:23:07 INFO - PROCESS | 1934 | |6;imaging-darkroom;96;95;97;107;96;96;96;95;95;96 08:23:07 INFO - PROCESS | 1934 | |7;imaging-desaturate;81;81;79;99;65;122;130;139;80;82 08:23:07 INFO - PROCESS | 1934 | |8;json-parse-financial;55;55;55;53;56;56;57;56;56;57 08:23:07 INFO - PROCESS | 1934 | |9;json-stringify-tinderbox;64;69;79;80;73;70;73;70;72;72 08:23:07 INFO - PROCESS | 1934 | |10;stanford-crypto-aes;84;84;84;85;85;85;85;83;85;84 08:23:07 INFO - PROCESS | 1934 | |11;stanford-crypto-ccm;148;153;155;161;170;149;151;151;195;148 08:23:07 INFO - PROCESS | 1934 | |12;stanford-crypto-pbkdf2;169;168;170;169;169;182;155;214;228;221 08:23:07 INFO - PROCESS | 1934 | |13;stanford-crypto-sha256-iterative;67;66;68;67;66;68;80;87;66;66 08:23:07 INFO - PROCESS | 1934 | __end_tp_report 08:23:07 INFO - PROCESS | 1934 | __start_cc_report 08:23:07 INFO - PROCESS | 1934 | _x_x_mozilla_cycle_collect,2088 08:23:07 INFO - PROCESS | 1934 | __end_cc_report 08:23:07 INFO - PROCESS | 1934 | __startTimestamp1480263787890__endTimestamp 08:23:07 INFO - PROCESS | 1934 | 08:23:07 INFO - PROCESS | 1934 | ------- Summary: start ------- 08:23:07 INFO - PROCESS | 1934 | Number of tests: 14 08:23:07 INFO - PROCESS | 1934 | 08:23:07 INFO - PROCESS | 1934 | [#0] ai-astar Cycles:10 Average:115.10 Median:115.00 stddev:0.74 (0.6%) stddev-sans-first:0.71 08:23:07 INFO - PROCESS | 1934 | Values: 116.0 115.0 116.0 114.0 115.0 115.0 115.0 115.0 114.0 116.0 08:23:07 INFO - PROCESS | 1934 | 08:23:07 INFO - PROCESS | 1934 | [#1] audio-beat-detection Cycles:10 Average:123.20 Median:115.00 stddev:15.34 (13.3%) stddev-sans-first:13.87 08:23:07 INFO - PROCESS | 1934 | Values: 146.0 116.0 113.0 147.0 114.0 113.0 114.0 112.0 143.0 114.0 08:23:07 INFO - PROCESS | 1934 | 08:23:07 INFO - PROCESS | 1934 | [#2] audio-dft Cycles:10 Average:188.20 Median:184.50 stddev:12.35 (6.7%) stddev-sans-first:13.00 08:23:07 INFO - PROCESS | 1934 | Values: 184.0 185.0 184.0 186.0 183.0 182.0 184.0 188.0 223.0 183.0 08:23:07 INFO - PROCESS | 1934 | 08:23:07 INFO - PROCESS | 1934 | [#3] audio-fft Cycles:10 Average:65.80 Median:59.50 stddev:12.72 (21.4%) stddev-sans-first:12.24 08:23:07 INFO - PROCESS | 1934 | Values: 81.0 58.0 60.0 88.0 57.0 56.0 58.0 59.0 83.0 58.0 08:23:07 INFO - PROCESS | 1934 | 08:23:07 INFO - PROCESS | 1934 | [#4] audio-oscillator Cycles:10 Average:77.60 Median:79.00 stddev:11.62 (14.7%) stddev-sans-first:11.42 08:23:07 INFO - PROCESS | 1934 | Values: 90.0 69.0 94.0 69.0 67.0 69.0 89.0 69.0 69.0 91.0 08:23:07 INFO - PROCESS | 1934 | 08:23:07 INFO - PROCESS | 1934 | [#5] imaging-gaussian-blur Cycles:10 Average:86.10 Median:87.00 stddev:2.18 (2.5%) stddev-sans-first:2.05 08:23:07 INFO - PROCESS | 1934 | Values: 89.0 84.0 89.0 85.0 87.0 83.0 88.0 84.0 87.0 85.0 08:23:07 INFO - PROCESS | 1934 | 08:23:07 INFO - PROCESS | 1934 | [#6] imaging-darkroom Cycles:10 Average:96.90 Median:96.00 stddev:3.60 (3.8%) stddev-sans-first:3.81 08:23:07 INFO - PROCESS | 1934 | Values: 96.0 95.0 97.0 107.0 96.0 96.0 96.0 95.0 95.0 96.0 08:23:07 INFO - PROCESS | 1934 | 08:23:07 INFO - PROCESS | 1934 | [#7] imaging-desaturate Cycles:10 Average:95.80 Median:80.50 stddev:25.48 (31.6%) stddev-sans-first:26.45 08:23:07 INFO - PROCESS | 1934 | Values: 81.0 81.0 79.0 99.0 65.0 122.0 130.0 139.0 80.0 82.0 08:23:07 INFO - PROCESS | 1934 | 08:23:07 INFO - PROCESS | 1934 | [#8] json-parse-financial Cycles:10 Average:55.60 Median:56.00 stddev:1.17 (2.1%) stddev-sans-first:1.22 08:23:07 INFO - PROCESS | 1934 | Values: 55.0 55.0 55.0 53.0 56.0 56.0 57.0 56.0 56.0 57.0 08:23:07 INFO - PROCESS | 1934 | 08:23:07 INFO - PROCESS | 1934 | [#9] json-stringify-tinderbox Cycles:10 Average:72.20 Median:72.50 stddev:4.66 (6.4%) stddev-sans-first:3.89 08:23:07 INFO - PROCESS | 1934 | Values: 64.0 69.0 79.0 80.0 73.0 70.0 73.0 70.0 72.0 72.0 08:23:07 INFO - PROCESS | 1934 | 08:23:07 INFO - PROCESS | 1934 | [#10] stanford-crypto-aes Cycles:10 Average:84.40 Median:85.00 stddev:0.70 (0.8%) stddev-sans-first:0.73 08:23:07 INFO - PROCESS | 1934 | Values: 84.0 84.0 84.0 85.0 85.0 85.0 85.0 83.0 85.0 84.0 08:23:07 INFO - PROCESS | 1934 | 08:23:07 INFO - PROCESS | 1934 | [#11] stanford-crypto-ccm Cycles:10 Average:158.10 Median:154.00 stddev:14.66 (9.5%) stddev-sans-first:15.09 08:23:07 INFO - PROCESS | 1934 | Values: 148.0 153.0 155.0 161.0 170.0 149.0 151.0 151.0 195.0 148.0 08:23:07 INFO - PROCESS | 1934 | 08:23:07 INFO - PROCESS | 1934 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:184.50 Median:176.00 stddev:26.19 (14.9%) stddev-sans-first:27.17 08:23:07 INFO - PROCESS | 1934 | Values: 169.0 168.0 170.0 169.0 169.0 182.0 155.0 214.0 228.0 221.0 08:23:07 INFO - PROCESS | 1934 | 08:23:07 INFO - PROCESS | 1934 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.10 Median:67.50 stddev:7.29 (10.8%) stddev-sans-first:7.65 08:23:07 INFO - PROCESS | 1934 | Values: 67.0 66.0 68.0 67.0 66.0 68.0 80.0 87.0 66.0 66.0 08:23:07 INFO - PROCESS | 1934 | -------- Summary: end -------- 08:23:07 INFO - PROCESS | 1934 | 08:23:09 INFO - TEST-INFO | 1934: exit 0 08:23:09 INFO - TEST-OK | kraken | took 60128ms 08:23:09 INFO - SUITE-END | took 1179s 08:23:09 INFO - Completed test suite (00:19:40) 08:23:09 INFO - javascript benchmark 08:23:09 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3351.983240083799, 3354.3794398036366, 3356.1777364545683, 3367.6969072783477, 3397.303552771682, 5671.8298451046485, 5686.829395118143, 5811.331696854863, 5816.389279125689, 5826.504747096491, 5690.715464226782, 5713.400092990239, 5744.597878148531, 5788.160716696077, 5792.073268277077, 4360.738355698668, 5224.0, 5264.368275806909, 5293.947060529403, 5309.0709125903, 5201.141811601081, 5212.270282160501, 5223.973880130594, 5364.329458817648, 5369.892602147955, 5246.213068039787, 5273.630845840793, 5279.2872962150195, 5314.388845282784, 5339.22581225722, 5295.947040529603, 5300.310959575269, 5314.627976041678, 5317.680939143664, 5364.034473794715, 5225.686458812484, 5319.654222475566, 5321.467853214686, 5337.786488540453, 5343.145096784496, 5606.831795046155, 5619.129034999582, 5625.38120806711, 5628.212050312959, 5762.250907382056, 6195.690215489208, 6237.844053898643, 6266.279377871558, 6269.059641053833, 6280.937190628081, 6318.368163183691, 6321.336259692758, 6333.619982801047, 6342.460890824285, 6346.333634968354, 5654.15187721845, 5713.714314284269, 5720.1991721159075, 5800.651960882318, 5805.303363596396, 6272.153259309935, 6317.0, 6334.493240540745, 6348.555601107878, 6365.204349456318, 6119.357467465941, 6122.072837224622, 6156.78451254204, 6162.969185154046, 6173.722182501798, 5721.0, 5983.982722937111, 6227.750889964442, 6327.240731112296, 6333.619982801047, 6094.603850749687, 6129.96935015331, 6142.938570614237, 6201.689021361152, 6241.469475094577, 6142.161149281699, 6222.3466536013975, 6229.626222426669, 6293.402126797947, 6300.905486417708, 4319.676024298127, 4883.169861123618, 4944.9258261126115, 4986.0, 4987.825426110068, 3859.1895701902354, 4435.889102772457, 4456.264716321835, 4458.8439404620685, 4462.152191083684, 5671.8865622688145, 5716.714164291769, 5741.081426971665, 5776.249087618583, 5821.621594596338, 6310.6213627182515, 6315.5894866833505, 6322.0200868865395, 6329.7468101275435, 6360.491160707133, 5846.503047240947, 5854.151148083589, 5867.853303667357, 5877.059670452707, 5911.615744976562, 5240.0, 5326.387465441447, 5359.624826262202, 5435.1303791393975, 5464.890702185934, 6297.874042519215, 6302.495800336053, 6315.084312774714, 6316.431521163117, 6329.272133704499, 6206.410391012847, 6223.3465486125115, 6265.498760099273, 6289.150964619717, 6319.557630965788, 4906.509349065208, 6273.372662733873, 6331.841703957438, 6343.207099112611, 6346.397092276226, 6202.596831205957, 6240.656763878031, 6246.937530624818, 6256.718447669775, 6256.937430625636, 6140.109684095871, 6150.815475535741, 6163.3836616338, 6171.197744293213, 6204.038374052029, 6081.908771368345, 6113.327533971171, 6131.682521312785, 6160.7535698571555, 6175.59858609189, 5997.610155339889, 6148.846278842886, 6154.723037463235, 6165.167702360124, 6171.907421388593, 5794.5364370851075, 5833.533317334688, 5860.120981852756, 5890.970545147246, 5951.672658003851, 6158.353372895961, 6169.01295792671, 6172.783952561639, 6185.969070154801, 6191.442770150708, 5955.761769529344, 5966.820995370146, 5981.952550746064, 6015.819525414245, 6017.0], "value": 5711.757786270767, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [457.85119836053286, 475.3725082890586, 481.63877092180866, 491.5354989534891, 502.97895272596617, 38199.99999999996, 39657.99999999996, 39733.40399894004, 41752.79123604386, 41907.95230119241, 40680.796596017055, 40693.59306406935, 41034.79482602583, 42126.36810447838, 42273.0, 9341.299402544802, 9515.0, 9523.761905952337, 9532.14210721035, 9538.90461095387, 9431.386959847623, 9497.050294970517, 9546.379485333431, 9557.474338911357, 9564.37831540951, 9534.856977145348, 9556.331056826026, 9565.66520171794, 9596.472194029326, 9604.903950960506, 9468.053194680546, 9514.666986655504, 9535.618575256982, 9554.713358599183, 9556.761080972961, 9280.303977201736, 9353.906460935372, 9363.76590585234, 9373.109554592304, 9387.202087822543, 39465.802670986464, 39783.60216397857, 39869.00327491836, 39876.20247595061, 39971.40042899359, 3062.0, 3077.769167312438, 3289.292802047571, 3617.1318883468043, 3885.844566217348, 28713.56929646057, 29500.70499295001, 29505.262368440744, 30227.848860755556, 30832.229194270323, 3131.827749473778, 3184.188032051812, 3214.4053350130303, 3232.8060316381093, 3256.3812875553567, 431234.0, 432290.0, 432968.0, 436468.0, 436828.0, 33020.66979330224, 60506.697466512385, 60808.0, 60921.39078609246, 61071.694641526505, 34098.829505852314, 34887.302253955284, 35002.474962875574, 35015.4747678785, 35076.29847403038, 14357.92821035909, 14476.493322733864, 14480.493182738554, 14495.710085798224, 14520.419183232552, 12164.574239901562, 12244.142909996215, 12262.938685306517, 12267.93866030682, 12279.14046016788, 7773.805654858674, 7784.104827944859, 7806.960965195251, 7864.331531819745, 7872.488288261245, 2748.436570503082, 3209.871605135815, 3210.1172177650865, 3216.0, 3239.4492936200904, 897.2059727141527, 943.3820847345, 1000.2847963705985, 1022.192467950311, 1036.1814166808288, 28259.293517661812, 29170.270743231165, 29185.270368240963, 30054.84972575167, 30134.24664383364, 698.9440844732409, 794.8489786940463, 860.9139086091341, 989.747614358334, 1039.8648175737105, 14817.333220005126, 15392.769108463383, 15439.228038598025, 15509.379624814881, 15863.920680396524, 643054.0, 644010.0, 644572.0, 645004.0, 646979.0, 50640.0, 57715.71142144346, 58214.417855821746, 58235.12647310294, 58337.70831145817, 1260.936953152357, 1343.4021860272085, 1351.7634413977712, 1381.4128995177211, 1382.7787553991313, 29622.703772962857, 30341.848290758404, 30688.232794180323, 31046.689533105284, 31081.689183108785, 49943.50056499388, 50589.74705126451, 50601.24098138457, 50761.746191270286, 50984.74507627438, 403148.0, 404187.0, 408164.0, 409371.0, 454498.0, 39292.410613841384, 39612.0, 40151.3977290335, 40433.786986390456, 40698.389524157756, 43605.0, 43886.0, 44572.55427445814, 44762.77618611886, 44796.0, 35680.10799729924, 36903.630963691096, 37174.6282537182, 37356.0, 38710.612893871825, 38305.4254186193, 38627.07250680525, 38850.80574597109, 39347.016324592114, 39647.0, 37116.443253350684, 37768.811155944044, 38011.809940950116, 38046.858594242214, 38400.6159938397, 32581.34837303288, 34115.97652070442, 34654.0, 34747.82626086853, 34914.82542587271, 21562.568748624624, 23941.0, 24878.751212487645, 25412.983480661307, 25852.482950341266], "value": 18309.899083380955, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.80595672380184, 373.251630480886, 373.9476473293739, 385.69337376785455, 404.4136002795945, 14721.558353249406, 14987.400503979841, 15906.681866362665, 19521.0, 20411.183552657913, 19282.035898205104, 19844.107015184316, 20279.087441065152, 20413.693794593055, 20455.693164602504, 5631.662100273976, 5931.080682494198, 5936.257967754031, 5944.4352786485215, 5950.285965684124, 5442.455754424545, 5801.332846722617, 5828.0966450200285, 5835.270591176103, 5839.386864379243, 5553.805616803413, 5695.5443564514735, 5702.885942281173, 5719.6854173020465, 5803.158542011406, 5658.886822263552, 5794.0, 5794.217780599628, 5795.391483894215, 5795.768169273224, 4851.932574833531, 5076.36545431821, 5083.847484575469, 5094.79620815167, 5178.17149256121, 24778.50442991148, 25173.24480265595, 25192.874035629888, 25500.0, 25503.362415939566, 2278.624027035554, 2379.703061831306, 2382.892769825362, 2638.1557901471538, 2734.357426004887, 9943.15483183923, 10453.634122805664, 10515.474226288656, 10645.041946224877, 14357.138571685628, 1849.6670599292122, 1852.3887117251275, 1894.4979580411202, 1902.4387805597382, 1937.8643494955359, 122739.0, 123956.38021809924, 127922.36038819839, 129965.0, 132833.33583332118, 11216.327020378803, 11640.417979101181, 11663.708407289885, 11713.648590542298, 11886.34625095628, 14312.85687143136, 14337.928310358382, 14631.92684036573, 14675.853241467663, 14750.926245368704, 7013.438924885997, 8323.875141872877, 8487.363447741443, 8669.436486628349, 8713.3464990126, 8254.463459875089, 8379.874301885477, 8384.119667434832, 8422.410431269875, 8509.872351914726, 4434.55654434554, 4577.198990176772, 4578.313253012008, 4589.54104589545, 4654.534546545318, 1854.2212270846278, 1911.8852868827914, 1917.309768483345, 1917.68358220892, 1918.7505624268756, 797.7606717984581, 835.8579041562949, 836.4270474724833, 883.4478450968145, 892.1078921078921, 19783.60432791365, 20407.979601019888, 20474.38576842697, 20635.69046464304, 20754.57549504345, 910.1581037540382, 910.9726708198632, 923.072312326108, 933.0995589256264, 937.9953100234455, 8571.957140214383, 8796.284724478664, 9950.552225149886, 10912.890871091187, 10952.890471095347, 577778.0, 587480.5680381156, 590355.0, 594709.0, 598254.0, 133288.3335583316, 137788.0, 138885.0, 138959.0, 142769.0, 965.232640051147, 974.91713204377, 981.8969008254317, 983.8179936711731, 993.7813680990171, 17788.288468461116, 18139.0, 18490.630187396448, 18551.628967420846, 18732.06339682983, 51016.918646507445, 52546.0, 56055.43944560665, 57155.42844571501, 58140.0, 67234.99147512889, 80371.0, 82783.34433311179, 82952.0, 84460.57769711112, 6417.582857114273, 7384.704611815468, 7425.034745483053, 7562.7353042645445, 7978.202179781975, 10927.0, 12695.047871409866, 12717.236965782082, 12798.232106073665, 12960.222386656453, 8704.477731336141, 10666.306690065121, 10690.62582809629, 10972.561097556008, 11098.945600167972, 10845.240833141605, 10958.780824383628, 11069.889301106887, 11131.220814542905, 11290.66128016128, 10821.296615719953, 10982.0, 11032.338059716121, 11062.280951738112, 11160.330380177214, 7215.783526494213, 7362.7423040193335, 7514.624268786648, 7597.582132982739, 7680.385569154342, 9967.202623790225, 10842.349459032483, 10889.836652450062, 10986.835197471884, 11144.554217831195], "value": 9424.149002656184, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1311.1936159262057, 1466.083697688944, 1506.4802643088135, 1656.4699296225212, 1849.3897013985395, 7553.471257012005, 8004.799880003003, 8011.59942002899, 8017.0, 8026.0, 7834.099078605962, 7905.841883162333, 8269.214424629665, 8508.446950948199, 8518.616662250199, 2326.557953988741, 2362.3857796972825, 2362.9763702363016, 2363.1020212319295, 2372.240882917467, 2348.941276468085, 2395.9880200598973, 2400.651905473714, 2401.7478164792706, 2405.1702162753822, 2314.2363020203375, 2319.675245465636, 2344.8475849069755, 2350.059976009601, 2353.011735071274, 2316.5830150572892, 2321.2223904991847, 2344.0975224538506, 2350.612148995414, 2354.4702441950594, 2330.976690233093, 2339.6139636959883, 2366.491204391047, 2374.085976898906, 2376.9286921392386, 8574.442661227062, 8589.570521473901, 8682.175193356621, 8712.0, 8712.390132690714, 5093.337866077423, 5160.974195129038, 5332.386775520826, 5340.385855626575, 5345.064613692588, 3572.392693242155, 3662.981685091584, 3666.17511060012, 3667.743257971943, 3679.0802299425145, 1065.63235683689, 1089.8256278995402, 1104.7624760676413, 1109.7503061811108, 1119.9976021460748, 10767.676969691, 11221.887781122166, 11324.0, 11334.319940803578, 11355.545778168864, 4354.782260886943, 4473.776311184428, 4497.123061003073, 4514.548545145459, 4515.5484451554585, 8680.392372533863, 8683.956580217058, 8684.131586841266, 8689.6524139035, 8703.43427677199, 5083.771230294646, 5092.312537807348, 5092.872678183001, 5093.439721630618, 5104.591632669378, 3821.3027655061424, 4681.7425041622355, 4749.4775574686755, 4822.517748225219, 4840.128776820206, 3667.9266414671556, 3690.7635941959743, 3779.6787273081545, 3808.790516521618, 3835.6931445484292, 1148.2536351371675, 1151.4818331750746, 1160.0951258018758, 1169.976600467986, 1170.6019953215948, 764.9196834332315, 864.7319331007408, 878.7715194049465, 911.6991392840347, 913.5888850017386, 5628.85927851807, 5657.971710141505, 5735.225744524436, 5836.970815145982, 6052.878942421127, 864.2999170671776, 894.5124906925611, 915.2403505090758, 929.707142250189, 959.5058544849409, 3706.7590606610484, 3866.2847373235477, 3894.4937158169255, 3915.569287378386, 3918.6081391861157, 9952.203823694088, 10288.897111028944, 10446.268761186644, 10458.0, 10474.214433917487, 9258.629654813732, 10277.794444111076, 10405.0, 10406.739831504121, 10423.843642345371, 7142.57144571317, 7696.11494678108, 8033.718819841278, 8042.0, 8155.673773049011, 5654.886902261849, 5784.421557844182, 5798.0, 5958.404159584007, 5979.760809567569, 9769.218462522838, 9774.902250977684, 9795.118439340493, 9799.167070799062, 9805.362651427635, 9477.336586438883, 9752.658656946973, 9775.21798256152, 9855.605775769176, 9969.102780749767, 6368.0766288886925, 6586.571872828251, 6592.604443733391, 6803.557768745016, 7042.894356584553, 7841.647125879464, 8113.35093192555, 8116.513009219466, 8207.753767386748, 8231.876521852057, 7118.822029449305, 7194.316539928698, 7321.487495875238, 7360.595167265852, 7457.179710231979, 5636.605437619492, 5997.790077347272, 7188.353048225685, 7222.530535515385, 7240.203577606564, 6598.8350291243105, 6729.5625784323865, 6813.965930170318, 6821.693023813841, 6822.897656535255, 4266.360045993126, 4884.291777692144, 4894.0, 4978.576820970258, 4990.6506544541535, 4644.651651126112, 4894.853154405374, 4985.227289770091, 5045.0, 5110.080185566634], "value": 4439.7626245954225, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [301.18529378032423, 304.85367023828564, 308.8949757082592, 313.73332667232853, 314.6114548532563, 4336.414584031155, 4437.2013037653205, 5117.903668280688, 5145.536901678848, 5261.342332208474, 4997.600191984641, 5039.848804535861, 5085.974570127153, 5113.318410744268, 5289.0, 3912.2566712324638, 4006.8998275043214, 4041.2016652589464, 4041.979790101045, 4074.8981275468054, 3496.108492334464, 3957.4261732048835, 4033.5159780826193, 4045.4336392905016, 4087.4686290782156, 3634.4003239465455, 3839.9808000959956, 4034.354503279476, 4037.051292946155, 4046.676265898736, 3540.451230059345, 3974.701897357695, 4013.19406267178, 4031.5363733170766, 4062.2484840304637, 3275.082976766509, 3354.798712077283, 3535.628758980296, 3550.5739311282555, 3560.7151427885706, 4664.300354946751, 5085.79656813727, 5164.992826398854, 5201.205584073488, 5205.869853253661, 3251.674832516753, 4454.955450445519, 4530.501644819067, 4540.409746732904, 4547.431571053618, 4843.273508973647, 5514.558835293167, 5522.806701765419, 5529.0, 5554.0835762099205, 3341.398548261312, 3757.6805971492454, 3787.8484860605545, 3790.639889210521, 3794.658480736747, 4821.397325334333, 5389.568834493231, 5411.106112860467, 5776.42235776419, 5814.47669709724, 3376.4260075787174, 3780.640839120279, 3812.218495208476, 3818.579956204815, 3832.9616703833162, 3852.3643598806443, 3872.7482713623526, 3888.6305800948867, 3893.3186692328727, 3909.843606255775, 3860.749051311656, 3921.7449516900906, 4542.772861356919, 4568.703034302826, 4577.977110114428, 5553.194786755978, 5573.331200255995, 5580.130656230208, 5582.436917663061, 5585.32440267918, 1049.6588608702202, 1091.1870656361061, 1242.9005679545796, 1256.7432567432568, 1263.570386068741, 702.9859402811915, 799.9680412268109, 804.7384600005022, 849.218718778725, 868.887044684187, 4336.436263285816, 4634.709356356957, 4797.520247975174, 4879.268109783561, 4939.431965324034, 4895.8911091457185, 5310.734463276898, 5311.920321195185, 5331.333583302087, 5403.729813509309, 4171.0823618803815, 4628.236341003697, 4746.905061898743, 4749.0, 4829.5653391194965, 2455.9754402455746, 2541.567933451318, 2724.9318767030586, 2752.862356882148, 2786.6945569095797, 4492.0791237796175, 4810.845397104669, 5328.200769884548, 5349.518543331119, 5373.011985565366, 5232.869178270574, 5312.415634280226, 5396.91904621431, 5512.058877902319, 5897.380775018647, 5927.911081333784, 5943.206692854352, 5947.204813737537, 5947.821565353046, 5990.880182396415, 4646.883827904329, 5561.916571251353, 5645.294338207724, 5730.681943153015, 5751.9137212941005, 4696.976515117402, 5021.698698077981, 5578.525825304894, 5661.037623604079, 5674.460926212003, 5380.973095134499, 5600.927656767022, 5624.7187640616985, 5922.5261979040715, 5944.64332140073, 3399.8470068847455, 3549.7337699673144, 3776.4901738264984, 3831.846726131035, 3862.7102967277006, 5516.420775818642, 5551.417845913895, 5555.472230138131, 5560.749766260609, 5561.804212094419, 4265.552117027667, 4712.575868171881, 5292.576593872404, 5299.49654782795, 5356.544693701079, 4385.758783266951, 4640.862988567823, 5441.639590102474, 5481.917771233355, 5534.833954981357, 5156.716380599103, 5324.725293944119, 5351.331083614548, 5414.187871819258, 5489.615726899079, 5024.593113928106, 5335.86565373838, 5415.48552887475, 5431.266778984945, 5565.7217139143695, 5402.162664786964, 5425.891482170414, 5445.319335083114, 5448.357546589821, 5471.562275018068], "value": 3961.63834378046, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [669.9564528305659, 683.5112894280588, 702.2240424331114, 732.1616748822598, 733.5232099135558, 14291.213983230931, 14884.404623815062, 14915.0, 14923.552293431201, 14939.626509337271, 12965.092443528956, 13028.544000959944, 13086.280254586018, 13132.803007954864, 13148.07963442557, 659.7064306383661, 678.1658559971238, 679.1544527063812, 800.7837883771393, 804.798800299925, 817.9509229446222, 820.1839170025803, 826.6734639817298, 828.2421584250403, 829.5022986208257, 877.8814859993915, 880.1770344727701, 881.1100788203928, 881.6297155194801, 881.8148188880343, 698.8637215742932, 716.813628456605, 737.3732327521617, 877.8156587116713, 878.4202426398579, 623.1307326279859, 643.4723526708101, 644.2397970394932, 646.024503000468, 647.6891092275687, 13847.930760346133, 13888.791668124986, 14088.929555352157, 14213.78679319811, 14235.928820355935, 13049.412776425084, 13229.140105893086, 13282.933585332108, 13337.666558336023, 13381.598552043455, 10331.27681062326, 11255.774884502345, 11729.0, 11894.52421903123, 12266.509339626493, 4282.7169946403965, 4304.80628371724, 4423.557644235583, 4468.0840427712565, 4500.009997800478, 77650.83523747092, 87436.0, 88496.0, 89599.0, 90454.0, 9830.508474576243, 9943.850842237372, 10281.228907831794, 10517.631882884063, 10531.210159238088, 17238.0, 19206.231750730094, 19316.517087072934, 19467.415977520697, 19557.119929603203, 7216.278372162742, 7220.9638951805955, 7267.963660181666, 7292.562446253241, 7470.925290747132, 4050.210209009215, 4141.399497072907, 4155.397467367215, 4211.599898009684, 4250.213710463574, 3199.2961548459302, 3304.8182349970984, 3308.93382132356, 3312.320974200283, 3317.3696997570382, 585.9443352881469, 631.3749388105742, 633.7718421368304, 641.8908785506475, 688.5868478912713, 523.8637954131954, 570.697530308937, 637.1939496536885, 647.0714524657133, 668.5520701130255, 8676.652933882575, 9219.446833189895, 9237.0, 9262.166405023445, 9412.717618471457, 555.611072249427, 563.4224919457507, 598.6617561077977, 605.4460168945366, 662.4402379988902, 4083.265012297815, 4448.088141930896, 4520.457545094544, 4680.368150299666, 4811.422629284439, 71308.28691713017, 93227.53386233025, 97057.0, 114947.0, 123961.38019310027, 66008.33991660118, 66960.0, 66972.33027669758, 92401.07598923925, 101628.49185754173, 66393.0, 88754.66867996985, 89248.0, 90342.09657903468, 92694.0, 8488.660453581788, 8623.223909848144, 9116.90883091187, 9401.81196376055, 9792.265580081379, 54271.45728542664, 54614.0, 55610.88778224332, 55714.442855570924, 58311.125333120886, 78043.82934255878, 79094.20905790868, 79723.00692482735, 81458.0, 84487.0, 10148.340357876714, 10182.592696292066, 11782.587609433629, 11877.762444750884, 12746.42641081172, 13588.59234222975, 13655.453781849012, 13787.724245514833, 14551.199684017482, 14658.853411466176, 12693.619191424272, 12878.671191426114, 12891.742165156456, 13162.0, 13271.203727775977, 12262.448189831657, 12983.610491685264, 13027.739445211233, 13034.4134513949, 13509.86490135086, 11579.594714184963, 11940.522379105087, 12190.146689731633, 12662.366881656064, 13191.274479903313, 9960.203183745429, 10597.841032384365, 10835.566577336818, 11176.664700059011, 11354.772904542027, 9134.497602631918, 10236.18110551169, 10437.895621043996, 10889.509972051119, 11059.492442102255], "value": 7145.194446240737, "unit": "score"}], "name": "dromaeo_css", "value": 7060.442413362117, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 115.0, 116.0, 114.0, 115.0, 115.0, 115.0, 115.0, 114.0, 116.0], "value": 115.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 116.0, 113.0, 147.0, 114.0, 113.0, 114.0, 112.0, 143.0, 114.0], "value": 123.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 185.0, 184.0, 186.0, 183.0, 182.0, 184.0, 188.0, 223.0, 183.0], "value": 188.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 58.0, 60.0, 88.0, 57.0, 56.0, 58.0, 59.0, 83.0, 58.0], "value": 65.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 69.0, 94.0, 69.0, 67.0, 69.0, 89.0, 69.0, 69.0, 91.0], "value": 77.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 84.0, 89.0, 85.0, 87.0, 83.0, 88.0, 84.0, 87.0, 85.0], "value": 86.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 97.0, 107.0, 96.0, 96.0, 96.0, 95.0, 95.0, 96.0], "value": 96.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 81.0, 79.0, 99.0, 65.0, 122.0, 130.0, 139.0, 80.0, 82.0], "value": 95.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 55.0, 53.0, 56.0, 56.0, 57.0, 56.0, 56.0, 57.0], "value": 55.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 69.0, 79.0, 80.0, 73.0, 70.0, 73.0, 70.0, 72.0, 72.0], "value": 72.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 84.0, 85.0, 85.0, 85.0, 85.0, 83.0, 85.0, 84.0], "value": 84.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 153.0, 155.0, 161.0, 170.0, 149.0, 151.0, 151.0, 195.0, 148.0], "value": 158.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 168.0, 170.0, 169.0, 169.0, 182.0, 155.0, 214.0, 228.0, 221.0], "value": 184.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 68.0, 67.0, 66.0, 68.0, 80.0, 87.0, 66.0, 66.0], "value": 70.1, "unit": "score"}], "name": "kraken", "value": 1473.6, "alertThreshold": 2.0}]} 08:23:09 INFO - Return code: 0 08:23:09 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:23:09 INFO - # TBPL SUCCESS # 08:23:09 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:23:09 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:23:09 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:23:09 INFO - 2016-11-27 08:23:09.732 screenresolution[1938:26801] starting screenresolution argv=screenresolution get 08:23:09 INFO - 2016-11-27 08:23:09.735 screenresolution[1938:26801] Display 0: 1600x1200x32@60 08:23:09 INFO - 2016-11-27 08:23:09.741 screenresolution[1939:26804] starting screenresolution argv=screenresolution list 08:23:09 INFO - Available Modes on Display 0 08:23:09 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:23:09 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:23:09 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:23:09 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:23:09 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:23:09 INFO - Intel Iris: 08:23:09 INFO - Chipset Model: Intel Iris 08:23:09 INFO - Type: GPU 08:23:09 INFO - Bus: Built-In 08:23:09 INFO - VRAM (Dynamic, Max): 1536 MB 08:23:09 INFO - Vendor: Intel (0x8086) 08:23:09 INFO - Device ID: 0x0a2e 08:23:09 INFO - Revision ID: 0x0009 08:23:09 INFO - Displays: 08:23:09 INFO - Display: 08:23:09 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:23:09 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:23:09 INFO - Display Serial Number: 1600x1200 60 08:23:09 INFO - Main Display: Yes 08:23:09 INFO - Mirror: Off 08:23:09 INFO - Online: Yes 08:23:09 INFO - Rotation: Supported 08:23:09 INFO - Return code: 0 08:23:09 INFO - Running post-action listener: _resource_record_post_action 08:23:09 INFO - [mozharness: 2016-11-27 16:23:09.974764Z] Finished run-tests step (success) 08:23:09 INFO - Running post-run listener: _resource_record_post_run 08:23:10 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 634880; Write bytes: 658689024; Read time: 8; Write time: 2005 08:23:10 INFO - TinderboxPrint: CPU usage
53.4% 08:23:10 INFO - TinderboxPrint: I/O read bytes / time
634,880 / 8 08:23:10 INFO - TinderboxPrint: I/O write bytes / time
658,689,024 / 2,005 08:23:10 INFO - TinderboxPrint: CPU idle
2,260.3 (46.6%) 08:23:10 INFO - TinderboxPrint: CPU system
973.0 (20.1%) 08:23:10 INFO - TinderboxPrint: CPU user
1,616.4 (33.3%) 08:23:10 INFO - TinderboxPrint: Swap in / out
348,614,656 / 0 08:23:10 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 347451904; Write bytes: 344377344; Read time: 26906; Write time: 691 08:23:10 INFO - run-tests - Wall time: 1181s; CPU: 53.0%; Read bytes: 569344; Write bytes: 307053568; Read time: 7; Write time: 1288 08:23:10 INFO - Running post-run listener: _upload_blobber_files 08:23:10 INFO - Blob upload gear active. 08:23:10 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:23:10 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 08:23:10 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'] 08:23:10 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 08:23:10 INFO - (blobuploader) - INFO - Open directory for files ... 08:23:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:23:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:23:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:23:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:23:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:23:11 INFO - (blobuploader) - INFO - Done attempting. 08:23:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:23:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:23:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:23:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:23:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:23:12 INFO - (blobuploader) - INFO - Done attempting. 08:23:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:23:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:23:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:23:15 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:23:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:23:15 INFO - (blobuploader) - INFO - Done attempting. 08:23:15 INFO - (blobuploader) - INFO - Iteration through files over. 08:23:15 INFO - Return code: 0 08:23:15 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:23:15 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:23:15 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/76a9cc9f6b468addb1c9009d5909b90fbe7b160b8fa6a0a5156a4ad80397cd121a1f04e08aaab770abe24cca3ef4ae87c02d0d4e9e48ca9eaad5b0eb97175926", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dab17c687d3f3faa5f319269bf80c6ef7de3029ad77414feb23d071bb38328ee2bf3782bbdf72aa7c642acee3bb36dcabe0fc2a899b581fe607cff52468e8fe3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8a3fa6a4b20d52b3afa38d7167071a09069453a1eb72db7661a45c8ee3492fe9cbd23ac0ae9a7f0f242b9bf43782db0262f610e3575db26f2ba34be95f9c5aee"} 08:23:15 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:23:15 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:23:15 INFO - Contents: 08:23:15 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/76a9cc9f6b468addb1c9009d5909b90fbe7b160b8fa6a0a5156a4ad80397cd121a1f04e08aaab770abe24cca3ef4ae87c02d0d4e9e48ca9eaad5b0eb97175926", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dab17c687d3f3faa5f319269bf80c6ef7de3029ad77414feb23d071bb38328ee2bf3782bbdf72aa7c642acee3bb36dcabe0fc2a899b581fe607cff52468e8fe3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8a3fa6a4b20d52b3afa38d7167071a09069453a1eb72db7661a45c8ee3492fe9cbd23ac0ae9a7f0f242b9bf43782db0262f610e3575db26f2ba34be95f9c5aee"} 08:23:15 INFO - Running post-run listener: copy_logs_to_upload_dir 08:23:15 INFO - Copying logs to upload dir... 08:23:15 INFO - mkdir: /builds/slave/test/build/upload/logs 08:23:15 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.312798 ========= master_lag: 0.20 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-11-27 08:23:15.390517) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-27 08:23:15.394410) ========= 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.olR8qxLACv/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.ofIbI9GY99/Listeners TMPDIR=/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/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/76a9cc9f6b468addb1c9009d5909b90fbe7b160b8fa6a0a5156a4ad80397cd121a1f04e08aaab770abe24cca3ef4ae87c02d0d4e9e48ca9eaad5b0eb97175926", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dab17c687d3f3faa5f319269bf80c6ef7de3029ad77414feb23d071bb38328ee2bf3782bbdf72aa7c642acee3bb36dcabe0fc2a899b581fe607cff52468e8fe3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8a3fa6a4b20d52b3afa38d7167071a09069453a1eb72db7661a45c8ee3492fe9cbd23ac0ae9a7f0f242b9bf43782db0262f610e3575db26f2ba34be95f9c5aee"} build_url:https://queue.taskcluster.net/v1/task/YkcB_iyAQaCz6jyl7d4McA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011502 build_url: 'https://queue.taskcluster.net/v1/task/YkcB_iyAQaCz6jyl7d4McA/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/76a9cc9f6b468addb1c9009d5909b90fbe7b160b8fa6a0a5156a4ad80397cd121a1f04e08aaab770abe24cca3ef4ae87c02d0d4e9e48ca9eaad5b0eb97175926", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dab17c687d3f3faa5f319269bf80c6ef7de3029ad77414feb23d071bb38328ee2bf3782bbdf72aa7c642acee3bb36dcabe0fc2a899b581fe607cff52468e8fe3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8a3fa6a4b20d52b3afa38d7167071a09069453a1eb72db7661a45c8ee3492fe9cbd23ac0ae9a7f0f242b9bf43782db0262f610e3575db26f2ba34be95f9c5aee"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-27 08:23:15.430181) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-27 08:23:15.430531) ========= 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.olR8qxLACv/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.ofIbI9GY99/Listeners TMPDIR=/var/folders/4q/f46rsfvj2c19v5lxnyttyfz000000w/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.005042 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-27 08:23:15.478859) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-27 08:23:15.479208) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-27 08:23:15.479530) ========= ========= Total master_lag: 0.38 =========