builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0079 starttime: 1480090260.08 results: success (0) buildid: 20161125054319 builduid: 3ee5a34bfa2749ab99753cc938a3c966 revision: 8102d0d48f8d7ea0f6fa76710ca8c44c620190e3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-25 08:11:00.081429) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-25 08:11:00.081923) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-25 08:11:00.082217) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-25 08:11:00.117294) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-25 08:11:00.117686) ========= 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.Yypme3U9px/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.tnQNam44hQ/Listeners TMPDIR=/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/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-25 08:11:00-- 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% 164M=0s 2016-11-25 08:11:00 (164 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.138387 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-25 08:11:00.280427) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-25 08:11:00.283771) ========= 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.Yypme3U9px/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.tnQNam44hQ/Listeners TMPDIR=/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/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.027253 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-25 08:11:00.332447) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-11-25 08:11:00.335815) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 8102d0d48f8d7ea0f6fa76710ca8c44c620190e3 --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 8102d0d48f8d7ea0f6fa76710ca8c44c620190e3 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Yypme3U9px/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.tnQNam44hQ/Listeners TMPDIR=/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/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-25 08:11:00,427 truncating revision to first 12 chars 2016-11-25 08:11:00,427 Setting DEBUG logging. 2016-11-25 08:11:00,427 attempt 1/10 2016-11-25 08:11:00,427 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/8102d0d48f8d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-25 08:11:02,457 unpacking tar archive at: mozilla-aurora-8102d0d48f8d/testing/mozharness/ program finished with exit code 0 elapsedTime=2.292475 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-11-25 08:11:02.655387) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-25 08:11:02.655775) ========= script_repo_revision: 8102d0d48f8d7ea0f6fa76710ca8c44c620190e3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-25 08:11:02.656220) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-25 08:11:02.656503) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-25 08:11:02.677349) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-11-25 08:11:02.677763) ========= /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.Yypme3U9px/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.tnQNam44hQ/Listeners TMPDIR=/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/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:11:02 INFO - MultiFileLogger online at 20161125 08:11:02 in /builds/slave/test 08:11:02 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:11:02 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:11:02 INFO - {'append_to_log': False, 08:11:02 INFO - 'base_work_dir': '/builds/slave/test', 08:11:02 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 08:11:02 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:11:02 INFO - 'branch': 'Mozilla-Aurora', 08:11:02 INFO - 'buildbot_json_path': 'buildprops.json', 08:11:02 INFO - 'config_files': ('talos/mac_config.py',), 08:11:02 INFO - 'default_actions': ('clobber', 08:11:02 INFO - 'read-buildbot-config', 08:11:02 INFO - 'download-and-extract', 08:11:02 INFO - 'populate-webroot', 08:11:02 INFO - 'create-virtualenv', 08:11:02 INFO - 'install', 08:11:02 INFO - 'run-tests'), 08:11:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:11:02 INFO - 'download_minidump_stackwalk': True, 08:11:02 INFO - 'download_symbols': 'ondemand', 08:11:02 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:11:02 INFO - 'tooltool.py': '/tools/tooltool.py', 08:11:02 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:11:02 INFO - '/tools/misc-python/virtualenv.py')}, 08:11:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:11:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:11:02 INFO - 'installer_path': 'installer.exe', 08:11:02 INFO - 'log_level': 'info', 08:11:02 INFO - 'log_name': 'talos', 08:11:02 INFO - 'log_to_console': True, 08:11:02 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:11:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:11:02 INFO - 'opt_config_files': (), 08:11:02 INFO - 'pip_index': False, 08:11:02 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:11:02 INFO - 'cmd': ('bash', 08:11:02 INFO - '-c', 08:11:02 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:11:02 INFO - 'enabled': True, 08:11:02 INFO - 'halt_on_failure': False, 08:11:02 INFO - 'name': 'check_screen_resolution'},), 08:11:02 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:11:02 INFO - 'cmd': ('bash', 08:11:02 INFO - '-c', 08:11:02 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:11:02 INFO - 'enabled': True, 08:11:02 INFO - 'halt_on_failure': False, 08:11:02 INFO - 'name': 'check_screen_resolution'},), 08:11:02 INFO - 'run_cmd_checks_enabled': True, 08:11:02 INFO - 'sps_profile': False, 08:11:02 INFO - 'sps_profile_interval': 0, 08:11:02 INFO - 'suite': 'dromaeojs', 08:11:02 INFO - 'system_bits': '32', 08:11:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:11:02 INFO - 'title': 't-yosemite-r7-0079', 08:11:02 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:11:02 INFO - 'use_talos_json': True, 08:11:02 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:11:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:11:02 INFO - 'work_dir': 'build'} 08:11:02 INFO - [mozharness: 2016-11-25 16:11:02.880860Z] Running clobber step. 08:11:02 INFO - Running pre-action listener: _resource_record_pre_action 08:11:02 INFO - Running main action method: clobber 08:11:02 INFO - rmtree: /builds/slave/test/build 08:11:02 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:11:05 INFO - Running post-action listener: _resource_record_post_action 08:11:05 INFO - [mozharness: 2016-11-25 16:11:05.242010Z] Finished clobber step (success) 08:11:05 INFO - [mozharness: 2016-11-25 16:11:05.242239Z] Running read-buildbot-config step. 08:11:05 INFO - Running pre-action listener: _resource_record_pre_action 08:11:05 INFO - Running main action method: read_buildbot_config 08:11:05 INFO - Using buildbot properties: 08:11:05 INFO - { 08:11:05 INFO - "project": "", 08:11:05 INFO - "product": "firefox", 08:11:05 INFO - "script_repo_revision": "production", 08:11:05 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 08:11:05 INFO - "repository": "", 08:11:05 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 08:11:05 INFO - "buildid": "20161125054319", 08:11:05 INFO - "pgo_build": "False", 08:11:05 INFO - "basedir": "/builds/slave/test", 08:11:05 INFO - "buildnumber": 9, 08:11:05 INFO - "slavename": "t-yosemite-r7-0079", 08:11:05 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 08:11:05 INFO - "platform": "yosemite_r7", 08:11:05 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 08:11:05 INFO - "branch": "mozilla-aurora", 08:11:05 INFO - "repo_path": "releases/mozilla-aurora", 08:11:05 INFO - "revision": "8102d0d48f8d7ea0f6fa76710ca8c44c620190e3", 08:11:05 INFO - "stage_platform": "macosx64", 08:11:05 INFO - "builduid": "3ee5a34bfa2749ab99753cc938a3c966", 08:11:05 INFO - "slavebuilddir": "test" 08:11:05 INFO - } 08:11:05 INFO - Found installer url https://queue.taskcluster.net/v1/task/SSuycGrkR2mqV4eRXAsbsw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 08:11:05 INFO - Running post-action listener: _resource_record_post_action 08:11:05 INFO - [mozharness: 2016-11-25 16:11:05.248087Z] Finished read-buildbot-config step (success) 08:11:05 INFO - [mozharness: 2016-11-25 16:11:05.248265Z] Running download-and-extract step. 08:11:05 INFO - Running pre-action listener: _resource_record_pre_action 08:11:05 INFO - Running main action method: download_and_extract 08:11:05 INFO - mkdir: /builds/slave/test/build/tests 08:11:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:11:05 INFO - trying https://queue.taskcluster.net/v1/task/SSuycGrkR2mqV4eRXAsbsw/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 08:11:05 INFO - Downloading https://queue.taskcluster.net/v1/task/SSuycGrkR2mqV4eRXAsbsw/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:11:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SSuycGrkR2mqV4eRXAsbsw/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:11:06 INFO - Downloaded 1199 bytes. 08:11:06 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 08:11:06 INFO - Using the following test package requirements: 08:11:06 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 08:11:06 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:11:06 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 08:11:06 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:11:06 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 08:11:06 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:11:06 INFO - u'jsshell-mac.zip'], 08:11:06 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:11:06 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 08:11:06 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 08:11:06 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:11:06 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 08:11:06 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:11:06 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 08:11:06 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:11:06 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 08:11:06 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:11:06 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 08:11:06 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 08:11:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SSuycGrkR2mqV4eRXAsbsw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 08:11:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SSuycGrkR2mqV4eRXAsbsw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:11:06 INFO - Fetch https://queue.taskcluster.net/v1/task/SSuycGrkR2mqV4eRXAsbsw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 08:11:07 INFO - Http code: 200 08:11:07 INFO - via: 1.1 41b2ee7cbe95749816a7586d9ab4629d.cloudfront.net (CloudFront) 08:11:07 INFO - x-amz-cf-id: PJaNSpa3T6Cm4j7RDLjGk4bNl_piGq-igOPp-8775Hj7wKvhoEcJqQ== 08:11:07 INFO - x-amz-version-id: LqniN7jYANkM4ugMgCsJakVJQLCCG3ow 08:11:07 INFO - Expected file size: 16279025 08:11:07 INFO - Obtained file size: 16279025 08:11:14 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:11:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SSuycGrkR2mqV4eRXAsbsw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 08:11:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SSuycGrkR2mqV4eRXAsbsw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:11:14 INFO - Fetch https://queue.taskcluster.net/v1/task/SSuycGrkR2mqV4eRXAsbsw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 08:11:14 INFO - Http code: 200 08:11:14 INFO - via: 1.1 e30ae5b3d9f6779a9b8bc992faad0b09.cloudfront.net (CloudFront) 08:11:14 INFO - x-amz-cf-id: t0eQF94hLDpgvUJQT4xv7zjbPv4NzpPeEN3gUIpkHtYj_3_A8Rk_MQ== 08:11:14 INFO - x-amz-version-id: LqniN7jYANkM4ugMgCsJakVJQLCCG3ow 08:11:15 INFO - Expected file size: 16279025 08:11:15 INFO - Obtained file size: 16279025 08:11:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SSuycGrkR2mqV4eRXAsbsw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 08:11:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SSuycGrkR2mqV4eRXAsbsw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 08:11:21 INFO - Fetch https://queue.taskcluster.net/v1/task/SSuycGrkR2mqV4eRXAsbsw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 08:11:22 INFO - Http code: 200 08:11:22 INFO - via: 1.1 bb8a7ff26b131d51953dc2d3e19a2751.cloudfront.net (CloudFront) 08:11:22 INFO - x-amz-cf-id: BRtHenSwxbLx0xSwkdXTrh8WX88ApxJGccYASrc3MtS9dhJi7uwgrA== 08:11:22 INFO - x-amz-version-id: YS9sA_CmRpweOx3N8fNTrnTRWxlMPYm8 08:11:22 INFO - Expected file size: 14409038 08:11:22 INFO - Obtained file size: 14409038 08:11:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:11:22 INFO - trying https://queue.taskcluster.net/v1/task/SSuycGrkR2mqV4eRXAsbsw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:11:22 INFO - Downloading https://queue.taskcluster.net/v1/task/SSuycGrkR2mqV4eRXAsbsw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 08:11:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SSuycGrkR2mqV4eRXAsbsw/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:11:25 INFO - Downloaded 109740416 bytes. 08:11:25 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/SSuycGrkR2mqV4eRXAsbsw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:11:25 INFO - mkdir: /builds/slave/test/properties 08:11:25 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:11:25 INFO - Writing to file /builds/slave/test/properties/build_url 08:11:25 INFO - Contents: 08:11:25 INFO - build_url:https://queue.taskcluster.net/v1/task/SSuycGrkR2mqV4eRXAsbsw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:11:26 INFO - Running post-action listener: _resource_record_post_action 08:11:26 INFO - Running post-action listener: set_extra_try_arguments 08:11:26 INFO - [mozharness: 2016-11-25 16:11:26.170963Z] Finished download-and-extract step (success) 08:11:26 INFO - [mozharness: 2016-11-25 16:11:26.171090Z] Running populate-webroot step. 08:11:26 INFO - Running pre-action listener: _resource_record_pre_action 08:11:26 INFO - Running main action method: populate_webroot 08:11:26 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:11:26 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:11:26 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:11:26 INFO - '--tppagecycles', 08:11:26 INFO - '7'], 08:11:26 INFO - 'tests': ['tsvgm']}}, 08:11:26 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:11:26 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:11:26 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:11:26 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:11:26 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:11:26 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:11:26 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:11:26 INFO - 'talos_options': ['--disable-e10s'], 08:11:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:11:26 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:11:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:11:26 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:11:26 INFO - 'talos_options': ['--disable-e10s'], 08:11:26 INFO - 'tests': ['damp', 'tps']}, 08:11:26 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:11:26 INFO - 'tests': ['damp', 'tps']}, 08:11:26 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:11:26 INFO - 'tests': ['dromaeo_dom']}, 08:11:26 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:11:26 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:11:26 INFO - 'tests': ['basic_compositor_video']}, 08:11:26 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:11:26 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:11:26 INFO - 'tests': ['a11yr', 08:11:26 INFO - 'ts_paint', 08:11:26 INFO - 'tpaint', 08:11:26 INFO - 'sessionrestore', 08:11:26 INFO - 'sessionrestore_no_auto_restore', 08:11:26 INFO - 'tabpaint']}, 08:11:26 INFO - 'other-e10s': {'tests': ['a11yr', 08:11:26 INFO - 'ts_paint', 08:11:26 INFO - 'tpaint', 08:11:26 INFO - 'sessionrestore', 08:11:26 INFO - 'sessionrestore_no_auto_restore', 08:11:26 INFO - 'tabpaint']}, 08:11:26 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:11:26 INFO - 'ts_paint', 08:11:26 INFO - 'tpaint', 08:11:26 INFO - 'sessionrestore', 08:11:26 INFO - 'sessionrestore_no_auto_restore', 08:11:26 INFO - 'tabpaint']}, 08:11:26 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:11:26 INFO - 'ts_paint', 08:11:26 INFO - 'tpaint', 08:11:26 INFO - 'sessionrestore', 08:11:26 INFO - 'sessionrestore_no_auto_restore', 08:11:26 INFO - 'tabpaint']}, 08:11:26 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:11:26 INFO - 'tests': ['a11yr', 08:11:26 INFO - 'ts_paint', 08:11:26 INFO - 'tpaint', 08:11:26 INFO - 'sessionrestore', 08:11:26 INFO - 'sessionrestore_no_auto_restore', 08:11:26 INFO - 'tabpaint']}, 08:11:26 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:11:26 INFO - 'tests': ['a11yr', 08:11:26 INFO - 'ts_paint', 08:11:26 INFO - 'tpaint', 08:11:26 INFO - 'sessionrestore', 08:11:26 INFO - 'sessionrestore_no_auto_restore', 08:11:26 INFO - 'tabpaint']}, 08:11:26 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:11:26 INFO - 'tests': ['tsvgx', 08:11:26 INFO - 'tsvgr_opacity', 08:11:26 INFO - 'tart', 08:11:26 INFO - 'tscrollx', 08:11:26 INFO - 'cart']}, 08:11:26 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:11:26 INFO - 'tsvgr_opacity', 08:11:26 INFO - 'tart', 08:11:26 INFO - 'tscrollx', 08:11:26 INFO - 'cart']}, 08:11:26 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:11:26 INFO - 'talos_options': ['--disable-e10s'], 08:11:26 INFO - 'tests': ['tp5o']}, 08:11:26 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:11:26 INFO - 'tests': ['tp5o']}, 08:11:26 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:11:26 INFO - 'talos_options': ['--disable-e10s', 08:11:26 INFO - '--xperf_path', 08:11:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:11:26 INFO - 'tests': ['tp5n']}, 08:11:26 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:11:26 INFO - 'talos_options': ['--xperf_path', 08:11:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:11:26 INFO - 'tests': ['tp5n']}}, 08:11:26 INFO - 'talos.zip': {'path': '', 08:11:26 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:11:26 INFO - Running post-action listener: _resource_record_post_action 08:11:26 INFO - [mozharness: 2016-11-25 16:11:26.189554Z] Finished populate-webroot step (success) 08:11:26 INFO - [mozharness: 2016-11-25 16:11:26.189644Z] Running create-virtualenv step. 08:11:26 INFO - Running pre-action listener: _resource_record_pre_action 08:11:26 INFO - Running main action method: create_virtualenv 08:11:26 INFO - Creating virtualenv /builds/slave/test/build/venv 08:11:26 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:11:26 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:11:26 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:11:26 INFO - Using real prefix '/tools/python27' 08:11:26 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:11:27 INFO - Installing distribute.............................................................................................................................................................................................done. 08:11:29 INFO - Installing pip.................done. 08:11:29 INFO - Return code: 0 08:11:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:11:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:11:30 INFO - Reading from file tmpfile_stdout 08:11:30 INFO - Output received: 08:11:30 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:11:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:11:30 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:11:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:11:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:30 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:11:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:11:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:30 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 0x10c14d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c0c3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f92a34a51a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf61d30>, '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 0x10bdc7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bf59b20>, '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.Yypme3U9px/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.tnQNam44hQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/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:11:30 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:11:30 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:11:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Yypme3U9px/Render', 08:11:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:11:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:11:30 INFO - 'HOME': '/Users/cltbld', 08:11:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:11:30 INFO - 'LOGNAME': 'cltbld', 08:11:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:11:30 INFO - 'MOZ_NO_REMOTE': '1', 08:11:30 INFO - 'NO_EM_RESTART': '1', 08:11:30 INFO - 'PAGER': '/bin/cat', 08:11:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:11:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:11:30 INFO - 'PWD': '/builds/slave/test', 08:11:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:11:30 INFO - 'SHELL': '/bin/bash', 08:11:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tnQNam44hQ/Listeners', 08:11:30 INFO - 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/', 08:11:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:11:30 INFO - 'USER': 'cltbld', 08:11:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:11:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:11:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:11:30 INFO - 'XPC_FLAGS': '0x0', 08:11:30 INFO - 'XPC_SERVICE_NAME': '0', 08:11:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:11:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:11:30 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:11:30 INFO - Cleaning up... 08:11:30 INFO - Return code: 0 08:11:30 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:11:30 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:11:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:11:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:30 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:11:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:11:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:30 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 0x10c14d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c0c3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f92a34a51a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf61d30>, '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 0x10bdc7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bf59b20>, '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.Yypme3U9px/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.tnQNam44hQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/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:11:30 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:11:30 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:11:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Yypme3U9px/Render', 08:11:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:11:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:11:30 INFO - 'HOME': '/Users/cltbld', 08:11:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:11:30 INFO - 'LOGNAME': 'cltbld', 08:11:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:11:30 INFO - 'MOZ_NO_REMOTE': '1', 08:11:30 INFO - 'NO_EM_RESTART': '1', 08:11:30 INFO - 'PAGER': '/bin/cat', 08:11:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:11:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:11:30 INFO - 'PWD': '/builds/slave/test', 08:11:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:11:30 INFO - 'SHELL': '/bin/bash', 08:11:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tnQNam44hQ/Listeners', 08:11:30 INFO - 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/', 08:11:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:11:30 INFO - 'USER': 'cltbld', 08:11:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:11:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:11:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:11:30 INFO - 'XPC_FLAGS': '0x0', 08:11:30 INFO - 'XPC_SERVICE_NAME': '0', 08:11:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:11:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:11:30 INFO - Downloading/unpacking psutil>=3.1.1 08:11: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:11: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:11: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:11: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:11: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:11: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:11:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:11:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:11:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:11:34 INFO - Installing collected packages: psutil 08:11:34 INFO - Running setup.py install for psutil 08:11:34 INFO - building 'psutil._psutil_osx' extension 08:11: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:11: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:11: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:11: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:11:35 INFO - building 'psutil._psutil_posix' extension 08:11:35 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:11:35 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:11:35 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:11:35 INFO - ^ 08:11:35 INFO - 1 warning generated. 08:11:35 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:11:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:11:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:11:35 INFO - Successfully installed psutil 08:11:35 INFO - Cleaning up... 08:11:35 INFO - Return code: 0 08:11:35 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:11: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:11:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:11:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11: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:11:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:11:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11: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 0x10c14d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c0c3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f92a34a51a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf61d30>, '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 0x10bdc7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bf59b20>, '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.Yypme3U9px/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.tnQNam44hQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/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:11: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:11: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:11:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Yypme3U9px/Render', 08:11:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:11:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:11:35 INFO - 'HOME': '/Users/cltbld', 08:11:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:11:35 INFO - 'LOGNAME': 'cltbld', 08:11:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:11:35 INFO - 'MOZ_NO_REMOTE': '1', 08:11:35 INFO - 'NO_EM_RESTART': '1', 08:11:35 INFO - 'PAGER': '/bin/cat', 08:11:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:11:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:11:35 INFO - 'PWD': '/builds/slave/test', 08:11:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:11:35 INFO - 'SHELL': '/bin/bash', 08:11:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tnQNam44hQ/Listeners', 08:11:35 INFO - 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/', 08:11:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:11:35 INFO - 'USER': 'cltbld', 08:11:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:11:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:11:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:11:35 INFO - 'XPC_FLAGS': '0x0', 08:11:35 INFO - 'XPC_SERVICE_NAME': '0', 08:11:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:11:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:11:35 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:11: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:11: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:11: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:11: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:11: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:11: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:11:38 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:11:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:11:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:11:38 INFO - Installing collected packages: mozsystemmonitor 08:11:38 INFO - Running setup.py install for mozsystemmonitor 08:11:38 INFO - Successfully installed mozsystemmonitor 08:11:38 INFO - Cleaning up... 08:11:38 INFO - Return code: 0 08:11:38 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:11: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:11:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:11:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11: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:11:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:11:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11: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 0x10c14d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c0c3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f92a34a51a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf61d30>, '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 0x10bdc7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bf59b20>, '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.Yypme3U9px/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.tnQNam44hQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/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:11: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:11: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:11:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Yypme3U9px/Render', 08:11:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:11:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:11:38 INFO - 'HOME': '/Users/cltbld', 08:11:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:11:38 INFO - 'LOGNAME': 'cltbld', 08:11:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:11:38 INFO - 'MOZ_NO_REMOTE': '1', 08:11:38 INFO - 'NO_EM_RESTART': '1', 08:11:38 INFO - 'PAGER': '/bin/cat', 08:11:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:11:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:11:38 INFO - 'PWD': '/builds/slave/test', 08:11:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:11:38 INFO - 'SHELL': '/bin/bash', 08:11:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tnQNam44hQ/Listeners', 08:11:38 INFO - 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/', 08:11:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:11:38 INFO - 'USER': 'cltbld', 08:11:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:11:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:11:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:11:38 INFO - 'XPC_FLAGS': '0x0', 08:11:38 INFO - 'XPC_SERVICE_NAME': '0', 08:11:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:11:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:11:38 INFO - Downloading/unpacking jsonschema==2.5.1 08:11: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:11: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:11: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:11: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:11: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:11: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:11:41 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:11:41 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:11:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:11:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:11:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:11:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:11:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:11:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:11:41 INFO - Downloading functools32-3.2.3-2.tar.gz 08:11:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:11:41 INFO - warning: no files found matching '*.txt' 08:11:41 INFO - no previously-included directories found matching 'build' 08:11:41 INFO - no previously-included directories found matching 'dist' 08:11:41 INFO - no previously-included directories found matching '.git*' 08:11:41 INFO - Installing collected packages: jsonschema, functools32 08:11:41 INFO - Running setup.py install for functools32 08:11:42 INFO - warning: no files found matching '*.txt' 08:11:42 INFO - no previously-included directories found matching 'build' 08:11:42 INFO - no previously-included directories found matching 'dist' 08:11:42 INFO - no previously-included directories found matching '.git*' 08:11:42 INFO - Successfully installed jsonschema functools32 08:11:42 INFO - Cleaning up... 08:11:42 INFO - Return code: 0 08:11:42 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:11:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:11:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:11:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:11:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:11:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c14d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c0c3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f92a34a51a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf61d30>, '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 0x10bdc7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bf59b20>, '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.Yypme3U9px/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.tnQNam44hQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/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:11:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:11:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 08:11:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Yypme3U9px/Render', 08:11:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:11:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:11:42 INFO - 'HOME': '/Users/cltbld', 08:11:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:11:42 INFO - 'LOGNAME': 'cltbld', 08:11:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:11:42 INFO - 'MOZ_NO_REMOTE': '1', 08:11:42 INFO - 'NO_EM_RESTART': '1', 08:11:42 INFO - 'PAGER': '/bin/cat', 08:11:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:11:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:11:42 INFO - 'PWD': '/builds/slave/test', 08:11:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:11:42 INFO - 'SHELL': '/bin/bash', 08:11:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tnQNam44hQ/Listeners', 08:11:42 INFO - 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/', 08:11:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:11:42 INFO - 'USER': 'cltbld', 08:11:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:11:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:11:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:11:42 INFO - 'XPC_FLAGS': '0x0', 08:11:42 INFO - 'XPC_SERVICE_NAME': '0', 08:11:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:11:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:11:42 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:11:42 INFO - Cleaning up... 08:11:42 INFO - Return code: 0 08:11:42 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:11:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:11:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:11:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:11:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:11:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c14d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c0c3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f92a34a51a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf61d30>, '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 0x10bdc7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bf59b20>, '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.Yypme3U9px/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.tnQNam44hQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/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:11:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:11:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:11:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Yypme3U9px/Render', 08:11:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:11:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:11:42 INFO - 'HOME': '/Users/cltbld', 08:11:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:11:42 INFO - 'LOGNAME': 'cltbld', 08:11:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:11:42 INFO - 'MOZ_NO_REMOTE': '1', 08:11:42 INFO - 'NO_EM_RESTART': '1', 08:11:42 INFO - 'PAGER': '/bin/cat', 08:11:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:11:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:11:42 INFO - 'PWD': '/builds/slave/test', 08:11:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:11:42 INFO - 'SHELL': '/bin/bash', 08:11:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tnQNam44hQ/Listeners', 08:11:42 INFO - 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/', 08:11:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:11:42 INFO - 'USER': 'cltbld', 08:11:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:11:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:11:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:11:42 INFO - 'XPC_FLAGS': '0x0', 08:11:42 INFO - 'XPC_SERVICE_NAME': '0', 08:11:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:11:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:11:42 INFO - Downloading/unpacking blobuploader==1.2.4 08:11: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:11: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:11: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:11: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:11: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:11: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:11:45 INFO - Downloading blobuploader-1.2.4.tar.gz 08:11:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:11:45 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:11: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:11: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:11: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:11: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:11: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:11: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:11:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:11:45 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:11: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:11: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:11: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:11: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:11: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:11: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:11:46 INFO - Downloading docopt-0.6.1.tar.gz 08:11:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:11:46 INFO - Installing collected packages: blobuploader, requests, docopt 08:11:46 INFO - Running setup.py install for blobuploader 08:11:46 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:11:46 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:11:46 INFO - Running setup.py install for requests 08:11:47 INFO - Running setup.py install for docopt 08:11:47 INFO - Successfully installed blobuploader requests docopt 08:11:47 INFO - Cleaning up... 08:11:47 INFO - Return code: 0 08:11:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:11:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:11:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:11:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:11:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:11:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c14d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c0c3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f92a34a51a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf61d30>, '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 0x10bdc7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bf59b20>, '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.Yypme3U9px/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.tnQNam44hQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/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:11:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:11:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:11:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Yypme3U9px/Render', 08:11:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:11:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:11:47 INFO - 'HOME': '/Users/cltbld', 08:11:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:11:47 INFO - 'LOGNAME': 'cltbld', 08:11:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:11:47 INFO - 'MOZ_NO_REMOTE': '1', 08:11:47 INFO - 'NO_EM_RESTART': '1', 08:11:47 INFO - 'PAGER': '/bin/cat', 08:11:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:11:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:11:47 INFO - 'PWD': '/builds/slave/test', 08:11:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:11:47 INFO - 'SHELL': '/bin/bash', 08:11:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tnQNam44hQ/Listeners', 08:11:47 INFO - 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/', 08:11:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:11:47 INFO - 'USER': 'cltbld', 08:11:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:11:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:11:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:11:47 INFO - 'XPC_FLAGS': '0x0', 08:11:47 INFO - 'XPC_SERVICE_NAME': '0', 08:11:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:11:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:11:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:11:47 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-rSKKrU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:11:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:11:47 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-8rCm7s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:11:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:11:47 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-GF3ma1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:11:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:11:47 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-EQGqOl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:11:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:11:48 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-px9UhL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:11:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:11:48 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-2Sh8TQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:11:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:11:48 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-DkiJbf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:11:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:11:48 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-XusgcB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:11:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:11:48 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-4APjPv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:11:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:11:48 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-2tDyN4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:11:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:11:48 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-ioYfgb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:11:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:11:48 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-LxAefR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:11:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:11:48 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-TfcjqZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:11:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:11:48 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-i3kdeL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:11:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:11:49 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-6XnJkX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:11:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:11:49 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-6pFuty-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:11:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:11:49 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-Zct1GX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:11:49 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:11:49 INFO - Running setup.py install for manifestparser 08:11:49 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:11:49 INFO - Running setup.py install for mozcrash 08:11:49 INFO - Running setup.py install for mozdebug 08:11:49 INFO - Running setup.py install for mozdevice 08:11:49 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:11:49 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:11:49 INFO - Running setup.py install for mozfile 08:11:50 INFO - Running setup.py install for mozhttpd 08:11:50 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:11:50 INFO - Running setup.py install for mozinfo 08:11:50 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:11:50 INFO - Running setup.py install for mozInstall 08:11:50 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:11:50 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:11:50 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:11:50 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:11:50 INFO - Running setup.py install for mozleak 08:11:50 INFO - Running setup.py install for mozlog 08:11:50 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:11:50 INFO - Running setup.py install for moznetwork 08:11:50 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:11:50 INFO - Running setup.py install for mozprocess 08:11:51 INFO - Running setup.py install for mozprofile 08:11:51 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:11:51 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:11:51 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:11:51 INFO - Running setup.py install for mozrunner 08:11:51 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:11:51 INFO - Running setup.py install for mozscreenshot 08:11:51 INFO - Running setup.py install for moztest 08:11:51 INFO - Running setup.py install for mozversion 08:11:51 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:11:51 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:11:51 INFO - Cleaning up... 08:11:51 INFO - Return code: 0 08:11:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:11: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:11:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:11:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11: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:11:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:11:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11: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 0x10c14d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c0c3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f92a34a51a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf61d30>, '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 0x10bdc7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bf59b20>, '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.Yypme3U9px/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.tnQNam44hQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/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:11: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:11: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:11:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Yypme3U9px/Render', 08:11:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:11:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:11:51 INFO - 'HOME': '/Users/cltbld', 08:11:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:11:51 INFO - 'LOGNAME': 'cltbld', 08:11:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:11:51 INFO - 'MOZ_NO_REMOTE': '1', 08:11:51 INFO - 'NO_EM_RESTART': '1', 08:11:51 INFO - 'PAGER': '/bin/cat', 08:11:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:11:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:11:51 INFO - 'PWD': '/builds/slave/test', 08:11:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:11:51 INFO - 'SHELL': '/bin/bash', 08:11:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tnQNam44hQ/Listeners', 08:11:51 INFO - 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/', 08:11:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:11:51 INFO - 'USER': 'cltbld', 08:11:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:11:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:11:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:11:51 INFO - 'XPC_FLAGS': '0x0', 08:11:51 INFO - 'XPC_SERVICE_NAME': '0', 08:11:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:11:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:11:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:11:52 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-MXyTEB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:11:52 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 08:11:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:11:52 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-LEq3Zo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:11: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:11:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:11:52 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-Rlf2XQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:11: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:11:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:11:52 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-1WoY_z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:11: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:11:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:11:52 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-2M4mv_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:11: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:11:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:11:52 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-SulONT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:11: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:11:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:11:52 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-Ru2wn6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:11: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:11:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:11:52 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-cW_Ihq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:11:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 08:11:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:11:53 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-XUJ7O1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:11:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 08:11:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:11:53 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-oquAW9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:11:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:11:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:11:53 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-uRIg39-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:11:53 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 08:11:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:11:53 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-To2jdB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:11: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:11:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:11:53 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-g1w10q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:11: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:11:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:11:53 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-_KVQDf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:11: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:11:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:11:53 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-b8sutv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:11: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:11:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:11:53 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-SXYmgs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:11: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:11:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:11:53 INFO - Running setup.py (path:/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/pip-QY097j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:11:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 08:11:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:11:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:11:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:11:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:11:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:11:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:11:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:11:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:11:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:11:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:11:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:11:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:11:56 INFO - Downloading blessings-1.6.tar.gz 08:11:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:11:56 INFO - Installing collected packages: blessings 08:11:56 INFO - Running setup.py install for blessings 08:11:56 INFO - Successfully installed blessings 08:11:56 INFO - Cleaning up... 08:11:56 INFO - Return code: 0 08:11:56 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:11:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:11:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:11:57 INFO - Reading from file tmpfile_stdout 08:11:57 INFO - Current package versions: 08:11:57 INFO - blessings == 1.6 08:11:57 INFO - blobuploader == 1.2.4 08:11:57 INFO - docopt == 0.6.1 08:11:57 INFO - functools32 == 3.2.3-2 08:11:57 INFO - jsonschema == 2.5.1 08:11:57 INFO - manifestparser == 1.1 08:11:57 INFO - mozInstall == 1.12 08:11:57 INFO - mozcrash == 1.0 08:11:57 INFO - mozdebug == 0.1 08:11:57 INFO - mozdevice == 0.48 08:11:57 INFO - mozfile == 1.2 08:11:57 INFO - mozhttpd == 0.7 08:11:57 INFO - mozinfo == 0.9 08:11:57 INFO - mozleak == 0.1 08:11:57 INFO - mozlog == 3.4 08:11:57 INFO - moznetwork == 0.27 08:11:57 INFO - mozprocess == 0.23 08:11:57 INFO - mozprofile == 0.28 08:11:57 INFO - mozrunner == 6.13 08:11:57 INFO - mozscreenshot == 0.1 08:11:57 INFO - mozsystemmonitor == 0.3 08:11:57 INFO - moztest == 0.7 08:11:57 INFO - mozversion == 1.4 08:11:57 INFO - psutil == 3.1.1 08:11:57 INFO - requests == 1.2.3 08:11:57 INFO - wsgiref == 0.1.2 08:11:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:11: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:11:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:11:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11: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:11:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:11:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c14d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c0c3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f92a34a51a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf61d30>, '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 0x10bdc7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bf59b20>, '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.Yypme3U9px/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.tnQNam44hQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/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:11:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 08:11:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:11:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Yypme3U9px/Render', 08:11:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:11:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:11:57 INFO - 'HOME': '/Users/cltbld', 08:11:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:11:57 INFO - 'LOGNAME': 'cltbld', 08:11:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:11:57 INFO - 'MOZ_NO_REMOTE': '1', 08:11:57 INFO - 'NO_EM_RESTART': '1', 08:11:57 INFO - 'PAGER': '/bin/cat', 08:11:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:11:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:11:57 INFO - 'PWD': '/builds/slave/test', 08:11:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:11:57 INFO - 'SHELL': '/bin/bash', 08:11:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tnQNam44hQ/Listeners', 08:11:57 INFO - 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/', 08:11:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:11:57 INFO - 'USER': 'cltbld', 08:11:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:11:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:11:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:11:57 INFO - 'XPC_FLAGS': '0x0', 08:11:57 INFO - 'XPC_SERVICE_NAME': '0', 08:11:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:11:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:11:58 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:11:58 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:11:58 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:11:58 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:11:58 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:11:58 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:11:58 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:11:58 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:11:58 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:11:58 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:11:58 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:11:58 INFO - Cleaning up... 08:11:58 INFO - Return code: 0 08:11:58 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:11:58 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:11:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:11:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:58 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:11:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:11:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:58 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 0x10c14d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c0c3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f92a34a51a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf61d30>, '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 0x10bdc7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bf59b20>, '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.Yypme3U9px/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.tnQNam44hQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/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:11:58 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:11:58 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:11:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Yypme3U9px/Render', 08:11:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:11:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:11:58 INFO - 'HOME': '/Users/cltbld', 08:11:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:11:58 INFO - 'LOGNAME': 'cltbld', 08:11:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:11:58 INFO - 'MOZ_NO_REMOTE': '1', 08:11:58 INFO - 'NO_EM_RESTART': '1', 08:11:58 INFO - 'PAGER': '/bin/cat', 08:11:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:11:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:11:58 INFO - 'PWD': '/builds/slave/test', 08:11:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:11:58 INFO - 'SHELL': '/bin/bash', 08:11:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tnQNam44hQ/Listeners', 08:11:58 INFO - 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/', 08:11:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:11:58 INFO - 'USER': 'cltbld', 08:11:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:11:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:11:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:11:58 INFO - 'XPC_FLAGS': '0x0', 08:11:58 INFO - 'XPC_SERVICE_NAME': '0', 08:11:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:11:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:11:58 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:11:58 INFO - Cleaning up... 08:11:58 INFO - Return code: 0 08:11:58 INFO - Running post-action listener: _resource_record_post_action 08:11:58 INFO - Running post-action listener: _start_resource_monitoring 08:11:58 INFO - Starting resource monitoring. 08:11:58 INFO - [mozharness: 2016-11-25 16:11:58.819613Z] Finished create-virtualenv step (success) 08:11:58 INFO - [mozharness: 2016-11-25 16:11:58.820569Z] Running install step. 08:11:58 INFO - Running pre-action listener: _resource_record_pre_action 08:11:58 INFO - Running main action method: install 08:11:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:11:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:11:59 INFO - Reading from file tmpfile_stdout 08:11:59 INFO - Detecting whether we're running mozinstall >=1.0... 08:11:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:11:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:11:59 INFO - Reading from file tmpfile_stdout 08:11:59 INFO - Output received: 08:11:59 INFO - Usage: mozinstall [options] installer 08:11:59 INFO - Options: 08:11:59 INFO - -h, --help show this help message and exit 08:11:59 INFO - -d DEST, --destination=DEST 08:11:59 INFO - Directory to install application into. [default: 08:11:59 INFO - "/builds/slave/test"] 08:11:59 INFO - --app=APP Application being installed. [default: firefox] 08:11:59 INFO - mkdir: /builds/slave/test/build/application 08:11:59 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:11:59 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:12:29 INFO - Reading from file tmpfile_stdout 08:12:29 INFO - Output received: 08:12:29 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 08:12:29 INFO - Running post-action listener: _resource_record_post_action 08:12:29 INFO - [mozharness: 2016-11-25 16:12:29.935021Z] Finished install step (success) 08:12:29 INFO - [mozharness: 2016-11-25 16:12:29.935148Z] Running run-tests step. 08:12:29 INFO - Running pre-action listener: _resource_record_pre_action 08:12:29 INFO - Running main action method: run_tests 08:12:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:12:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:12:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:12:29 INFO - 2016-11-25 08:12:29.949 screenresolution[1621:7469] starting screenresolution argv=screenresolution get 08:12:29 INFO - 2016-11-25 08:12:29.952 screenresolution[1621:7469] Display 0: 1600x1200x32@60 08:12:29 INFO - 2016-11-25 08:12:29.958 screenresolution[1622:7471] starting screenresolution argv=screenresolution list 08:12:29 INFO - Available Modes on Display 0 08:12:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:12:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:12:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:12:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:12:30 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:12:30 INFO - Intel Iris: 08:12:30 INFO - Chipset Model: Intel Iris 08:12:30 INFO - Type: GPU 08:12:30 INFO - Bus: Built-In 08:12:30 INFO - VRAM (Dynamic, Max): 1536 MB 08:12:30 INFO - Vendor: Intel (0x8086) 08:12:30 INFO - Device ID: 0x0a2e 08:12:30 INFO - Revision ID: 0x0009 08:12:30 INFO - Displays: 08:12:30 INFO - Display: 08:12:30 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:12:30 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:12:30 INFO - Display Serial Number: 1600x1200 60 08:12:30 INFO - Main Display: Yes 08:12:30 INFO - Mirror: Off 08:12:30 INFO - Online: Yes 08:12:30 INFO - Rotation: Supported 08:12:30 INFO - Return code: 0 08:12:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:12:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:12:30 INFO - Python 2.7.3 08:12:30 INFO - Return code: 0 08:12:30 INFO - grabbing minidump binary from tooltool 08:12:30 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:12:30 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 0x10bf61d30>, '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 0x10bdc7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bf59b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:12:30 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:12:30 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:12:30 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:12:30 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:12:30 INFO - Return code: 0 08:12:30 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:12:30 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:12:30 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:12:30 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:12:30 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:12:30 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/SSuycGrkR2mqV4eRXAsbsw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0079', '--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:12:30 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/SSuycGrkR2mqV4eRXAsbsw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0079 --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:12:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Yypme3U9px/Render', 08:12:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:12:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:12:30 INFO - 'HOME': '/Users/cltbld', 08:12:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:12:30 INFO - 'LOGNAME': 'cltbld', 08:12:30 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:12:30 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:12:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:12:30 INFO - 'MOZ_NO_REMOTE': '1', 08:12:30 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:12:30 INFO - 'NO_EM_RESTART': '1', 08:12:30 INFO - 'PAGER': '/bin/cat', 08:12:30 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:12:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:12:30 INFO - 'PWD': '/builds/slave/test', 08:12:30 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:12:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:12:30 INFO - 'SHELL': '/bin/bash', 08:12:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tnQNam44hQ/Listeners', 08:12:30 INFO - 'TMPDIR': '/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/', 08:12:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:12:30 INFO - 'USER': 'cltbld', 08:12:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:12:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:12:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:12:30 INFO - 'XPC_FLAGS': '0x0', 08:12:30 INFO - 'XPC_SERVICE_NAME': '0', 08:12:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:12:30 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/SSuycGrkR2mqV4eRXAsbsw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0079', '--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:12:30 INFO - mozversion application_buildid: 20161125054319 08:12:30 INFO - mozversion application_changeset: 8102d0d48f8d7ea0f6fa76710ca8c44c620190e3 08:12:30 INFO - mozversion application_display_name: FirefoxDeveloperEdition 08:12:30 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:12:30 INFO - mozversion application_name: Firefox 08:12:30 INFO - mozversion application_remotingname: firefox-dev 08:12:30 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:12:30 INFO - mozversion application_vendor: Mozilla 08:12:30 INFO - mozversion application_version: 52.0a2 08:12:30 INFO - mozversion platform_buildid: 20161125054319 08:12:30 INFO - mozversion platform_changeset: 8102d0d48f8d7ea0f6fa76710ca8c44c620190e3 08:12:30 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:12:30 INFO - mozversion platform_version: 52.0a2 08:12:30 INFO - using testdate: 1480090350 08:12:30 INFO - actual date: 1480090350 08:12:30 INFO - starting webserver on 'localhost:49241' 08:12:30 INFO - SUITE-START | Running 2 tests 08:12:30 INFO - TEST-START | dromaeo_css 08:12:30 INFO - operating with platform_type : mac_ 08:12:30 INFO - Initialising browser for dromaeo_css test... 08:12:30 INFO - TEST-INFO | started process 1632 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/tmpSNDez4/profile http://localhost:49241/getInfo.html) 08:12:33 INFO - PROCESS | 1632 | __metrics Screen width/height:1600/1200 08:12:33 INFO - PROCESS | 1632 | colorDepth:24 08:12:33 INFO - PROCESS | 1632 | Browser inner width/height: 1024/689 08:12:33 INFO - PROCESS | 1632 | __metrics 08:12:33 INFO - PROCESS | 1632 | 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:12:33 INFO - PROCESS | 1632 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:12:34 INFO - TEST-INFO | 1632: exit 0 08:12:34 INFO - Browser initialized. 08:12:34 INFO - Running cycle 1/1 for dromaeo_css test... 08:12:34 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/tmpSNDez4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:12:46 INFO - PROCESS | 1634 | RSS: Main: 223027200 08:12:46 INFO - PROCESS | 1634 | 08:13:38 INFO - PROCESS | 1634 | 1480090418430 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:15:50 INFO - PROCESS | 1634 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 08:15:52 INFO - PROCESS | 1634 | RSS: Main: 371507200 08:15:52 INFO - PROCESS | 1634 | 08:18:55 INFO - PROCESS | 1634 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 08:18:56 INFO - PROCESS | 1634 | RSS: Main: 349437952 08:18:56 INFO - PROCESS | 1634 | 08:21:58 INFO - PROCESS | 1634 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 08:21:59 INFO - PROCESS | 1634 | RSS: Main: 317247488 08:21:59 INFO - PROCESS | 1634 | 08:25:01 INFO - PROCESS | 1634 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 08:25:01 INFO - PROCESS | 1634 | RSS: Main: 328822784 08:25:01 INFO - PROCESS | 1634 | 08:28:05 INFO - PROCESS | 1634 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 08:28:05 INFO - PROCESS | 1634 | RSS: Main: 312246272 08:28:05 INFO - PROCESS | 1634 | 08:31:09 INFO - PROCESS | 1634 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 08:31:09 INFO - PROCESS | 1634 | RSS: Main: 454787072 08:31:09 INFO - PROCESS | 1634 | 08:31:09 INFO - PROCESS | 1634 | __start_tp_report 08:31:09 INFO - PROCESS | 1634 | _x_x_mozilla_page_load 08:31:09 INFO - PROCESS | 1634 | _x_x_mozilla_page_load_details 08:31:09 INFO - PROCESS | 1634 | |i|pagename|runs| 08:31:09 INFO - PROCESS | 1634 | |0;dojo.html;3337.4159522083637;3351.949720754189;3358.9328213435733;3365.259642878566;3422.794632322061;5606.55147588193;5775.5090817280525;5775.56683248757;5793.5365170786345;5795.420457954203;5658.745356458959;5693.117566777156;5795.623284486505;5804.564657650682;5812.622179558336;5186.611004174683;5226.24219488174;5238.711870847102;5249.212618107295;5285.762140703659;5201;5204.0372531081875;5225.2162175673575;5226.817061402851;5308.628396012281;5275.155975043994;5341.519263266306;5350.571954243671;5370.087085195526;5370.758315875795;5320.414754377015;5327;5328.653637513549;5332.520073193431;5363.275957745733;5058;5150.484951504857;5260.552853007499;5287.656302340374;5297.046531624306;5065.366829146356;5560.388357280696;5672.716364181774;5674.914876276859;5725.914111288334;6173.382661733835;6202.131701561785;6209.441150296449;6213.409726076015;6270.592411493239;6239.500839932794;6239.5199450683285;6255.499560035186;6256.562040657156;6262.561620686553;5720.1991721159075;5741.33974592923;5784.652920824764;5852.063669812809;5879.617824841372;6289.8742025159245;6300.873982520416;6308.779192728232;6316.431521163117;6317.399847014526;6210;6212.502999760009;6229.844253893689;6234.719437625317;6235.563510554309;6134.325224225332;6154.599951003171;6156.230471191101;6226;6256.343083976117;5072.771725272371;5133.281340612317;6109.829807539378;6261.123442718023;6269.498440124779;6238;6250.093736408196;6289.213848268966;6296.779612713533;6311.211098612674;4836.854894353175;4970.72661003641;4972.975135124301;4978.925316120261;4984.77568509418;4409.492908315585;4411.8014689338415;4424.424824772759;4445.955540444555;4455.309427038814;5763.6541807491685;5764.798232061858;5782.595218334676;5785.537157027427;5818.79634212809;6284.622922624657;6297.118403423525;6312.337204593543;6315.873682526324;6317.4630156437215;5493.835184944458;5588.469095435927;5602.579806514527;5712.143178523172;5790.449907258803;5404.1353383459245;5411.350637923474;5432.266644003088;5470.671759694431;5483.561315094783;6259.405356491126;6261.906071408933;6267.373262673878;6272.686365681789;6286.874262514724;6193.721282542387;6205.782797602063;6308.621482710869;6315.368463153648;6323.272823625158;4851.223804191312;5385.3268341457315;6216.347283535345;6244.281907580505;6303.527235457267;6227.283862355705;6267.871783079089;6275.592086514362;6276.592021518587;6278.529110316653;6144.508439324933;6168.383161683796;6190.442860142609;6192.318844926965;6264.874702505833;6046.2139921809885;6057.515398768176;6058.151858739692;6079.635221886701;6113.633182008915;5444;5957.748872736774;6125.84596676928;6135;6137.140800288053;5893.6463812169695;6059.454649081604;6065.363136870565;6070.605410648471;6079.300880398634;5993.910091348546;6076.240469941257;6109.236345456818;6119.051547010221;6135;6089.360617135137;6096.75612975476;6103.6032657877095;6106.847328816815;6126.877462450815 08:31:09 INFO - PROCESS | 1634 | |1;ext.html;492.06262070755224;498.1854667618445;515.2014377714543;544.6242092955857;551.6910530103142;29990.85004574977;31878.521822172657;38092.809535952314;39331.21337573247;39367.81896543101;41280.174396512055;41746;41753.79123104388;41775.58224417755;41852.79073604635;9311.58097885595;9500.28747843911;9503.477308748015;9510.762230944247;9515.096065873731;7296.525725827804;8812.16284452976;9335.673251436201;11067.280626759275;11409.771804563903;9957.502124893763;11310.660680179608;11326.20716549842;11384.487698053566;11473.541058357656;11217.214794964355;11218.607348742837;11239.887601123966;11263.929926656996;11265.15511336653;8425.325973922072;8689.391742578022;8690.304775617935;8765;8773.473591584461;38117.80941095304;38739.80630096832;38745.837624871296;40688;40877.79561102176;3264.7877887937448;3757.849686012556;3804.8097595120134;3843.5964223756664;3923.4899463069905;23745.525089498286;24603;28299.434011319863;29683.851580742175;30836.53745193824;3142.874285028596;3155.4162479941283;3195.952060719091;3208.3904058228864;3215.3569286142865;451979;452439;453103;453618;454919;55205.723971379884;55399.72300138474;55412;55439.44560554343;60832.39167608356;63563.68218158972;63566.68216658887;63577;63616;63637;15840.524784256491;16371.017738935701;16372.017678939303;16389.180540972902;16389.754153687703;11411.372374519315;12683.98528117767;12778.23330600167;12778.61664150077;12800.423980920881;15155.545333640008;16876.915615421844;16953.491395258163;17167.742483862752;17604.647907041788;5887.676177810262;6882.655867206719;6883.174019117738;6934.306569343126;6974.686139123752;877.912208779117;992.8709267795141;1029.7425643589104;1040.661784919904;1042.0725554256717;29266.268343291587;29444.2836757709;29603.407931841244;29709.257268568457;29977.850110749743;1005.4369553050316;1019.2763138171981;1033.8604288420968;1034.8758149022015;1040.7866387390566;13964.301784910916;14482.34829432678;14737.778933316009;15167.772483412758;15468.191848326278;617126;619967;620396;620586;621683;56867.14699279514;57135.71432142896;57162;57284.42715572874;57327.42672573304;1355.098859258582;1380.0339762166523;1384.0726713102247;1384.7715127004335;1388.9722205555831;27597.448051039268;28588.42823143567;29023.854880725463;29265.56437235655;29985;50806.74596626993;51022.74488627533;51179.48820511747;51232.74383628058;51304;554760;557642;557984;558938;562192;34385.82807085948;35027.82486087553;36010.819945900104;40364.78905632836;40746;40548.391774124;42025;44845.52246606639;44851.102977940915;45902;37401.6259837409;37633.81183094176;37778.866634001024;37801.62198378091;39174.80412598033;37545.62454375421;38348.80825595854;38410.438320145535;38552.84341469761;38936.80531597324;36384.818075909454;36865.26269474542;37101.628983709816;37354.626453735116;37626.81186594049;32117.357652846345;33180.00459986204;33844.15389615279;33873.49189762205;34422.82788586041;24922.50154996926;24937;25164.74835251624;25419.491610167323;25465.745342547078 08:31:09 INFO - PROCESS | 1634 | |2;jquery.html;349.7062467527277;367.1042655919556;370.82941846750475;378.08692008798755;402.41247778243763;14812.481563145278;15162.090274583532;15640.374385024614;19808.801911980874;20320;19759.604807903834;20494.590108197826;20499.69250461244;20956.89521552394;21017.68473472899;5204.115300398922;5752.137179423089;6043.335233124353;6055.364186760393;6059.0608455689435;5247.895042099175;5936.109583562478;5976.581639285252;5985.431384018511;6000.489958353523;5663.688497132656;5930.88138237235;5936;5938.703064846741;5957.791477298296;5074.13739664258;5888.263967004124;5945.435183657567;5949.732262048196;5949.851253718648;4948.208286674115;5125.436202017812;5180.6373553851245;5194.039102766001;5207.973960130175;25402;25434.745652543606;25438.36404089894;25519.362015949566;26006.73993260081;2131.9467013324634;2141.3468891987936;2309.307207837642;2418.298693378918;2559.8992433253693;15080.868934829821;15213.543593692208;15293.92942494026;15305.6173595661;15909.443169489128;1762.409592786418;1835.779706435223;1868.3740946782843;1871.9157637906192;1925.5859990102194;112111;118067;120353;129693;133454.3327283367;9296.72109836706;11260;11288.774224515464;11668.649940501798;11778.52885884555;14676.779848302283;14680.339384727713;14708.411663533552;14733.336999834819;14753.557393278219;8270.793230169294;8514.361422893184;8520.74437766868;8646.221840034426;8675.956620216859;8392.202740739742;8402.117777633383;8421.536815475207;8586.184312490428;8589.355798315151;4452.198604251266;4572.862814115582;4578.175928332866;4581.358609794632;4650.534946505322;1857.7956424793263;1911.0731295321757;1912.1013123831776;1912.4549503391745;1915.1764741161435;808.4542933519969;834.2533432577931;905.5245995852099;925.4817302310728;978.5645387802502;20148.597028059357;20868.373948781562;21066.789332106484;21082;21142.577148456945;916.2440986186423;918.3112665500875;932.2681694869494;937.4000639590668;984.4044353166231;10609.257351985287;10872.293300935415;11048.723781905517;11109.333439993627;11158.995690387741;549928.7510687343;589274;599547;605367.8658033496;622271;116947.8305216937;119389;125502;129278.99999999812;134376;938.3150300280834;951.2390087929629;978.6476868327396;1016.8525563793356;1020.3928662445685;19199.90400047991;19453.610927781647;19782.406527803614;20060.69908951396;20131.698024529353;54117;54328.45671543234;55238;56210.43789562052;56492.30523084314;72944;75379.24620753941;79944.60027700057;82746;84102;5001.174806157089;6011.308699499438;6107.633541987495;6449.516286278642;6935.82660433473;10718.606581144713;10934.289271197666;11083.833742493707;11331.433428328386;12686.809697854724;9230.815383692154;9579.808403832023;9631.470269135265;10474.21443391764;10846.45767711627;8950.283977281932;10810.135189185004;10868.836967445337;11211.55153793839;11530.481128349076;11036.392998385167;11105.611303604335;11271.436428178722;11353.148513861328;11469.770604587695;6773.288804675438;7255.709771609288;7400.814979625552;7617.5048621839405;7672.386209103147;8967.820643587222;10624;10765.623203188165;10782.73043173927;11311.490982905625 08:31:09 INFO - PROCESS | 1634 | |3;mootools.html;1317.4795955597533;1474.675571374298;1495.6186172526004;1639.5819066138133;1805.684005299073;7455.291747284006;7888.211178882116;8126.349892008648;8332.541710205936;8353.039400468948;8306.792330191733;8400.243978041975;8411.789705257372;8413.663453461853;8439.957800211007;2258.062903894886;2277.1802151225547;2288.3706980580305;2290.0839664134396;2291.140822191678;2315.907363705458;2348.9530209395803;2366.656834758959;2367.2898130560848;2371.1463873005705;2294.093832935989;2295.82781291403;2319.9652005219937;2330.1145564685394;2346.718393792739;2279.863208207513;2298.2186056740707;2317.7450480447137;2323.6630688550067;2325.7092863392077;1949.8635095543175;2310.5378924215056;2335.89045203527;2337.6026075567192;2344.4256157241584;8369.58152092393;8452.28155606774;8453.112423195538;8457.915420845775;8467.237948584658;5033.471485494007;5238.978399212155;5240.266362709223;5262.789488420459;5262.789488420459;3400.846961886721;3525.665061819123;3560.3235385276716;3585.910352241189;3587.2646107548153;1056.4031322302951;1082.7942690888706;1084.1814430105298;1092.3009274064605;1101.4657890922897;10996.560137594486;11089.72275693106;11109;11116.943890330405;11205;4253.723507971972;4416.712913660602;4502.077074199781;4508.594226519629;4540.818367265339;8798.736037918872;8799.076097009849;8799.384043116921;8803.427777194462;8806.86789698155;5117.283580298761;5119.155339368963;5123.462036486189;5126.256692779526;5128.9230661540105;4043.838246470167;4262.552431994658;4414.337849322563;4438.866833994985;4710.152172608895;3646.4165733482514;3700.259948010355;3766.9246615067545;3768.4158955361963;3778.905527361783;1072.1476426241156;1127.9436028198556;1137.732632831284;1140.9828852567218;1152.5850693750244;695.2977492732376;822.1613953767124;841.7390608911257;859.6991053131354;888.6312180445173;5509.338879334506;5634.577406694514;5666.858328541737;5800.303963524404;5896.439838215449;813.1868131868132;871.5293741379711;875.2297977779641;890.225503811688;904.1455824246156;3622.855085796539;3625.836837342326;3714.3871261241593;3728.422094575345;3758.473813666089;8596.78508037304;9779.266555008402;10303.948480257552;10305.587776488857;10315;8492.490450572985;10263.794724105475;10266.024727650862;10267.435291058913;10278.383297002203;7818.569978651229;7999;8007.639656215601;8014.83970320602;8027.317677997436;5240.973795131;5385.219143224289;5414.729263536886;5592.636478628876;5601.159826026129;9391.436513809193;9406.200472959876;9506.857397139187;9637.18083962843;9670.516474176404;9525.523723813643;9595.088466595662;9718.416894986323;9732.854007189757;9773;6689.130413046273;6755.088063111417;6813.965930170318;6818.795436136924;6846.863062738817;7817.218278172365;7832.647530861239;7833.216678332121;8015.038195416588;8040.075391330072;7096.787096387116;7231.710731570889;7272.96363518179;7283.672234749555;7350.154732205865;6734.3265673434225;7057.2237053925055;7074.504784665024;7108.857822843617;7135.42916566663;6631.1048008520165;6661.666916654245;6736.93263067363;6758.189017317756;6764.763233287008;4767.213409787346;4791.353167322367;4816.15717249487;4878.902421951612;4905.945221777335;3787.8295476703065;4769.689970152068;4792.904141917073;4939.851804445872;5053.772580233825 08:31:09 INFO - PROCESS | 1634 | |4;prototype.html;294.71265516121787;298.26775266720205;304.17568389664007;309.5403326060799;312.33628539353873;4214.936775948359;4911.214205727084;5115.7697903594335;5188.143956247224;5215.739213039352;4238.597333253344;4875.512448755132;4915.655904086715;5050.267711181877;5099.770510327035;3941.881743547684;3949;3949.901252468697;3981.7810020448865;3988.2821092203462;3325.900222993302;3713.925721485569;3732.701383889296;3746.5316835395574;3981.2681483554748;2945.4698154332214;3078.769092318074;3793.525809273841;3803.239352129571;3938.6258305460933;3592.335417947662;3884.582127523462;3925.623963391481;3948.1708841143395;3952.7430717003303;3444.5877190351835;3448.2930999145115;3450.741194410429;3453.568303962005;3461.2904354607244;4091.468109145792;4708.693934894221;4743.770427898917;5022.6233032522705;5041.445441001487;4439.779060758285;4445.355423463579;4467.955320446787;4471.262241730111;4533.232039504596;4732.929006064912;5359.356877174764;5389.380221274564;5415.214793854869;5421.783128674848;3092.5206592978125;3518.4546395308766;3726.16161363691;3733.290674771785;3743.850245990157;4623.491415944243;5201.375834899836;5364;5646.6894320812335;5656.575756818214;3745.6441638044344;3771.340015497277;3781.054736315921;3785.1861849702445;3795;3389.847456864447;3636.4363523653874;3683.9815800920824;3849.4418309344987;3876.244132394156;4376.846810361686;4393.340998850198;4412.470503539532;4461;4465.888352791206;4717.43390793107;4795.520447955177;4825.734584597881;5261.921071183935;5415.72539533397;970.0348153587225;1123.769627226433;1166.7666466706692;1186.9881301186876;1222.7615614955175;776.8076003334854;789.5657388436337;815.1155995744565;850.8766228896775;864.2524216552637;4569.474510431343;4706.435227772689;4774.901772592254;4785.521447855187;4813.663043586916;4559.651779333646;4671.602913752369;4983.975080124649;5107.284980102789;5229.790808367623;4571.697066336078;4578.198815207325;4725.574698277172;4767.475577686451;4808.422989241313;2253.4816992091583;2333.614953532682;2732.808703390783;2738.52075886719;2754.801661277351;4216.894577635584;4456.9331460028125;5146.639735218574;5280.630355875129;5413.837584872382;4651.44176700809;4857.7085374876915;4915.4347250065985;5310.928737792264;5411.594130440233;4643.582077613031;4889.79959018436;5179.507946745053;5448.700321482356;5454.47274763067;4302.548232435674;4467.865964021084;4867.902641947212;4882.707037577757;4979.626528010342;4214.831406743696;4852.975735121302;5174.6119041071315;5598.272224610774;5605.327360716577;5389.83830485086;5553.777848885999;5819.883602328015;5849.883002339844;5870.9412905872105;3241.54618353426;3808.0479880029993;3849.538055433366;3853.4605155278846;3857.5370955485523;5051.595872330278;5233.136532472252;5615.241942337895;5617.455199820082;5620.690862002629;4782.617390608673;5003.924941125813;5445.754941027584;5487.890242195054;5519.3652729936575;5226.346706661668;5412.997191039341;5500;5502.642328248651;5545.722713864371;5077.111505486599;5214.530692237717;5235.240890070842;5339.839804805862;5374.381946076252;5015.498450154955;5297.205419187153;5472.945270547243;5477.260569823023;5489.505944465017;5031.295618613471;5232.26748255237;5327.561558379293;5347.4652534747775;5377.27406800077 08:31:09 INFO - PROCESS | 1634 | |5;yui.html;689.8689249042685;727.792579114952;749.3068911257087;767.5279702982666;772.4631381190076;14264.572062838119;14782.408703651869;14783.965122441434;14793.926030369847;14808.6297842554;12986.28575428351;13000.959923206145;13009.349532523383;13013;13066.93466532666;656.2027137028509;678.3589507915023;682.2529330383222;805.5931754463987;840.1640367834013;824.826786374859;825.4964471672275;828.6354004238116;832.6253186066264;833.9916600833975;878.7232021913109;880.8590625499921;882.995585022074;883.0815951410558;884.3720958119743;681.2540268406087;705.1714235772997;875.8773771671971;876.5617191404298;876.6318146378536;627.8995360742305;650.3691419323249;651.993480065198;653.4772182254178;659.9868002639969;13697.452101915911;13743.931280343535;13753.587392378246;13756.037077404586;13767.586972390844;12725.427355769012;12734.426950787236;12935.48258069676;12960.870391296156;13104.80342794859;11550.942245288803;11823.358268915017;11859.762804743857;11892;11936.16446848721;4329.328954012133;4347.891302717426;4387.319965405367;4462.553744625544;4535.63714902807;83104;87292;89164;90187.54906225493;91188.54405727996;10050.246231532665;10198.694039178836;10290.99040324307;10320.896791032144;10676.199285053652;19153.233870645297;19290.4212873614;19335.709964350546;19465.221391144478;19516.414507564798;7271.89092163618;7287.526310789782;7314.268573142643;7407.962960185165;7412.703491860373;4049.4128351389472;4056.5943405659787;4082.755034697928;4086.652634526039;4092.324766413568;3218.1632775478074;3513.8594456221977;3676.283124790694;3705.9814700926863;3711.4432835074954;700.7547358424613;706.0785674694573;711.4237467651219;711.9359257666835;726.1794172584945;631.9210098737658;634.2167423232397;635.9268684101295;644.5262731892055;675.2336098528206;6790.5246632736225;8082.9221117365305;8240.752777416566;8354.791130221793;8862.822743545094;583.2417856786161;583.4807021750645;595.9659989917576;654.3783405764543;673.952823302374;4120.9638122893175;4323.718958267703;4349.847755328612;4384.714993525411;4387.648988080946;75655;104272;105457;108055;109175;96642.0335796633;96806.5159674197;98023.01976980282;101170;102926.48536757268;61462.692686536284;86658.70011949952;87395;88568.5571572151;91759.87013567232;8203.917960820554;8328.417010809186;8344.37417193725;8442.93740563834;8519.616617252115;57184.42815571957;57577;57817.4218257812;58996;59977;74533;79620;81313.18686813056;81968.77046844421;84448.73326899979;9475;11237;11266.211365204357;11487.310761354345;11631.127665425296;11598.478068487108;11864.584739534075;11926.701832454259;12354.44405001795;12382.56661016896;10668;11399.20205585601;11813.042139046016;11816;11844.526218951145;10849.728756781144;10884.782304354027;11891.702707432038;12012.579559715368;12043;10836.837447438136;10950.890491094988;11615.245009074382;11693.12301577368;11714.1800073994;7754.418418618513;9284.071592840663;9630.951845240728;9746.512674366395;9855.211583073193;9759.9024009759;9771.218302535923;9787.46168960714;9787.755306117404;9822 08:31:09 INFO - PROCESS | 1634 | __end_tp_report 08:31:09 INFO - PROCESS | 1634 | __start_cc_report 08:31:09 INFO - PROCESS | 1634 | _x_x_mozilla_cycle_collect,1849 08:31:09 INFO - PROCESS | 1634 | __end_cc_report 08:31:09 INFO - PROCESS | 1634 | __startTimestamp1480091469347__endTimestamp 08:31:09 INFO - PROCESS | 1634 | 08:31:09 INFO - PROCESS | 1634 | ------- Summary: start ------- 08:31:09 INFO - PROCESS | 1634 | Number of tests: 6 08:31:09 INFO - PROCESS | 1634 | 08:31:09 INFO - PROCESS | 1634 | [#0] dojo.html Cycles:165 Average:5739.00 Median:5993.91 stddev:640.40 (10.7%) stddev-sans-first:614.03 08:31:09 INFO - PROCESS | 1634 | Values: 3337.4 3351.9 3358.9 3365.3 3422.8 5606.6 5775.5 5775.6 5793.5 5795.4 5658.7 5693.1 5795.6 5804.6 5812.6 5186.6 5226.2 5238.7 5249.2 5285.8 5201.0 5204.0 5225.2 5226.8 5308.6 5275.2 5341.5 5350.6 5370.1 5370.8 5320.4 5327.0 5328.7 5332.5 5363.3 5058.0 5150.5 5260.6 5287.7 5297.0 5065.4 5560.4 5672.7 5674.9 5725.9 6173.4 6202.1 6209.4 6213.4 6270.6 6239.5 6239.5 6255.5 6256.6 6262.6 5720.2 5741.3 5784.7 5852.1 5879.6 6289.9 6300.9 6308.8 6316.4 6317.4 6210.0 6212.5 6229.8 6234.7 6235.6 6134.3 6154.6 6156.2 6226.0 6256.3 5072.8 5133.3 6109.8 6261.1 6269.5 6238.0 6250.1 6289.2 6296.8 6311.2 4836.9 4970.7 4973.0 4978.9 4984.8 4409.5 4411.8 4424.4 4446.0 4455.3 5763.7 5764.8 5782.6 5785.5 5818.8 6284.6 6297.1 6312.3 6315.9 6317.5 5493.8 5588.5 5602.6 5712.1 5790.4 5404.1 5411.4 5432.3 5470.7 5483.6 6259.4 6261.9 6267.4 6272.7 6286.9 6193.7 6205.8 6308.6 6315.4 6323.3 4851.2 5385.3 6216.3 6244.3 6303.5 6227.3 6267.9 6275.6 6276.6 6278.5 6144.5 6168.4 6190.4 6192.3 6264.9 6046.2 6057.5 6058.2 6079.6 6113.6 5444.0 5957.7 6125.8 6135.0 6137.1 5893.6 6059.5 6065.4 6070.6 6079.3 5993.9 6076.2 6109.2 6119.1 6135.0 6089.4 6096.8 6103.6 6106.8 6126.9 08:31:09 INFO - PROCESS | 1634 | 08:31:09 INFO - PROCESS | 1634 | [#1] ext.html Cycles:180 Average:67872.72 Median:20803.22 stddev:146336.79 (703.4%) stddev-sans-first:146659.86 08:31:09 INFO - PROCESS | 1634 | Values: 492.1 498.2 515.2 544.6 551.7 29990.9 31878.5 38092.8 39331.2 39367.8 41280.2 41746.0 41753.8 41775.6 41852.8 9311.6 9500.3 9503.5 9510.8 9515.1 7296.5 8812.2 9335.7 11067.3 11409.8 9957.5 11310.7 11326.2 11384.5 11473.5 11217.2 11218.6 11239.9 11263.9 11265.2 8425.3 8689.4 8690.3 8765.0 8773.5 38117.8 38739.8 38745.8 40688.0 40877.8 3264.8 3757.8 3804.8 3843.6 3923.5 23745.5 24603.0 28299.4 29683.9 30836.5 3142.9 3155.4 3196.0 3208.4 3215.4 451979.0 452439.0 453103.0 453618.0 454919.0 55205.7 55399.7 55412.0 55439.4 60832.4 63563.7 63566.7 63577.0 63616.0 63637.0 15840.5 16371.0 16372.0 16389.2 16389.8 11411.4 12684.0 12778.2 12778.6 12800.4 15155.5 16876.9 16953.5 17167.7 17604.6 5887.7 6882.7 6883.2 6934.3 6974.7 877.9 992.9 1029.7 1040.7 1042.1 29266.3 29444.3 29603.4 29709.3 29977.9 1005.4 1019.3 1033.9 1034.9 1040.8 13964.3 14482.3 14737.8 15167.8 15468.2 617126.0 619967.0 620396.0 620586.0 621683.0 56867.1 57135.7 57162.0 57284.4 57327.4 1355.1 1380.0 1384.1 1384.8 1389.0 27597.4 28588.4 29023.9 29265.6 29985.0 50806.7 51022.7 51179.5 51232.7 51304.0 554760.0 557642.0 557984.0 558938.0 562192.0 34385.8 35027.8 36010.8 40364.8 40746.0 40548.4 42025.0 44845.5 44851.1 45902.0 37401.6 37633.8 37778.9 37801.6 39174.8 37545.6 38348.8 38410.4 38552.8 38936.8 36384.8 36865.3 37101.6 37354.6 37626.8 32117.4 33180.0 33844.2 33873.5 34422.8 24922.5 24937.0 25164.7 25419.5 25465.7 08:31:09 INFO - PROCESS | 1634 | 08:31:09 INFO - PROCESS | 1634 | [#2] jquery.html Cycles:180 Average:34937.64 Median:13178.22 stddev:99252.13 (753.2%) stddev-sans-first:99496.58 08:31:09 INFO - PROCESS | 1634 | Values: 349.7 367.1 370.8 378.1 402.4 14812.5 15162.1 15640.4 19808.8 20320.0 19759.6 20494.6 20499.7 20956.9 21017.7 5204.1 5752.1 6043.3 6055.4 6059.1 5247.9 5936.1 5976.6 5985.4 6000.5 5663.7 5930.9 5936.0 5938.7 5957.8 5074.1 5888.3 5945.4 5949.7 5949.9 4948.2 5125.4 5180.6 5194.0 5208.0 25402.0 25434.7 25438.4 25519.4 26006.7 2131.9 2141.3 2309.3 2418.3 2559.9 15080.9 15213.5 15293.9 15305.6 15909.4 1762.4 1835.8 1868.4 1871.9 1925.6 112111.0 118067.0 120353.0 129693.0 133454.3 9296.7 11260.0 11288.8 11668.6 11778.5 14676.8 14680.3 14708.4 14733.3 14753.6 8270.8 8514.4 8520.7 8646.2 8676.0 8392.2 8402.1 8421.5 8586.2 8589.4 4452.2 4572.9 4578.2 4581.4 4650.5 1857.8 1911.1 1912.1 1912.5 1915.2 808.5 834.3 905.5 925.5 978.6 20148.6 20868.4 21066.8 21082.0 21142.6 916.2 918.3 932.3 937.4 984.4 10609.3 10872.3 11048.7 11109.3 11159.0 549928.8 589274.0 599547.0 605367.9 622271.0 116947.8 119389.0 125502.0 129279.0 134376.0 938.3 951.2 978.6 1016.9 1020.4 19199.9 19453.6 19782.4 20060.7 20131.7 54117.0 54328.5 55238.0 56210.4 56492.3 72944.0 75379.2 79944.6 82746.0 84102.0 5001.2 6011.3 6107.6 6449.5 6935.8 10718.6 10934.3 11083.8 11331.4 12686.8 9230.8 9579.8 9631.5 10474.2 10846.5 8950.3 10810.1 10868.8 11211.6 11530.5 11036.4 11105.6 11271.4 11353.1 11469.8 6773.3 7255.7 7400.8 7617.5 7672.4 8967.8 10624.0 10765.6 10782.7 11311.5 08:31:09 INFO - PROCESS | 1634 | 08:31:09 INFO - PROCESS | 1634 | [#3] mootools.html Cycles:180 Average:5419.42 Median:5124.86 stddev:3000.02 (58.5%) stddev-sans-first:2992.59 08:31:09 INFO - PROCESS | 1634 | Values: 1317.5 1474.7 1495.6 1639.6 1805.7 7455.3 7888.2 8126.3 8332.5 8353.0 8306.8 8400.2 8411.8 8413.7 8440.0 2258.1 2277.2 2288.4 2290.1 2291.1 2315.9 2349.0 2366.7 2367.3 2371.1 2294.1 2295.8 2320.0 2330.1 2346.7 2279.9 2298.2 2317.7 2323.7 2325.7 1949.9 2310.5 2335.9 2337.6 2344.4 8369.6 8452.3 8453.1 8457.9 8467.2 5033.5 5239.0 5240.3 5262.8 5262.8 3400.8 3525.7 3560.3 3585.9 3587.3 1056.4 1082.8 1084.2 1092.3 1101.5 10996.6 11089.7 11109.0 11116.9 11205.0 4253.7 4416.7 4502.1 4508.6 4540.8 8798.7 8799.1 8799.4 8803.4 8806.9 5117.3 5119.2 5123.5 5126.3 5128.9 4043.8 4262.6 4414.3 4438.9 4710.2 3646.4 3700.3 3766.9 3768.4 3778.9 1072.1 1127.9 1137.7 1141.0 1152.6 695.3 822.2 841.7 859.7 888.6 5509.3 5634.6 5666.9 5800.3 5896.4 813.2 871.5 875.2 890.2 904.1 3622.9 3625.8 3714.4 3728.4 3758.5 8596.8 9779.3 10303.9 10305.6 10315.0 8492.5 10263.8 10266.0 10267.4 10278.4 7818.6 7999.0 8007.6 8014.8 8027.3 5241.0 5385.2 5414.7 5592.6 5601.2 9391.4 9406.2 9506.9 9637.2 9670.5 9525.5 9595.1 9718.4 9732.9 9773.0 6689.1 6755.1 6814.0 6818.8 6846.9 7817.2 7832.6 7833.2 8015.0 8040.1 7096.8 7231.7 7273.0 7283.7 7350.2 6734.3 7057.2 7074.5 7108.9 7135.4 6631.1 6661.7 6736.9 6758.2 6764.8 4767.2 4791.4 4816.2 4878.9 4905.9 3787.8 4769.7 4792.9 4939.9 5053.8 08:31:09 INFO - PROCESS | 1634 | 08:31:09 INFO - PROCESS | 1634 | [#4] prototype.html Cycles:180 Average:4273.53 Median:4755.62 stddev:1311.27 (27.6%) stddev-sans-first:1280.49 08:31:09 INFO - PROCESS | 1634 | Values: 294.7 298.3 304.2 309.5 312.3 4214.9 4911.2 5115.8 5188.1 5215.7 4238.6 4875.5 4915.7 5050.3 5099.8 3941.9 3949.0 3949.9 3981.8 3988.3 3325.9 3713.9 3732.7 3746.5 3981.3 2945.5 3078.8 3793.5 3803.2 3938.6 3592.3 3884.6 3925.6 3948.2 3952.7 3444.6 3448.3 3450.7 3453.6 3461.3 4091.5 4708.7 4743.8 5022.6 5041.4 4439.8 4445.4 4468.0 4471.3 4533.2 4732.9 5359.4 5389.4 5415.2 5421.8 3092.5 3518.5 3726.2 3733.3 3743.9 4623.5 5201.4 5364.0 5646.7 5656.6 3745.6 3771.3 3781.1 3785.2 3795.0 3389.8 3636.4 3684.0 3849.4 3876.2 4376.8 4393.3 4412.5 4461.0 4465.9 4717.4 4795.5 4825.7 5261.9 5415.7 970.0 1123.8 1166.8 1187.0 1222.8 776.8 789.6 815.1 850.9 864.3 4569.5 4706.4 4774.9 4785.5 4813.7 4559.7 4671.6 4984.0 5107.3 5229.8 4571.7 4578.2 4725.6 4767.5 4808.4 2253.5 2333.6 2732.8 2738.5 2754.8 4216.9 4456.9 5146.6 5280.6 5413.8 4651.4 4857.7 4915.4 5310.9 5411.6 4643.6 4889.8 5179.5 5448.7 5454.5 4302.5 4467.9 4867.9 4882.7 4979.6 4214.8 4853.0 5174.6 5598.3 5605.3 5389.8 5553.8 5819.9 5849.9 5870.9 3241.5 3808.0 3849.5 3853.5 3857.5 5051.6 5233.1 5615.2 5617.5 5620.7 4782.6 5003.9 5445.8 5487.9 5519.4 5226.3 5413.0 5500.0 5502.6 5545.7 5077.1 5214.5 5235.2 5339.8 5374.4 5015.5 5297.2 5472.9 5477.3 5489.5 5031.3 5232.3 5327.6 5347.5 5377.3 08:31:09 INFO - PROCESS | 1634 | 08:31:09 INFO - PROCESS | 1634 | [#5] yui.html Cycles:180 Average:20242.48 Median:29795.98 stddev:30131.46 (101.1%) stddev-sans-first:30180.22 08:31:09 INFO - PROCESS | 1634 | Values: 689.9 727.8 749.3 767.5 772.5 14264.6 14782.4 14784.0 14793.9 14808.6 12986.3 13001.0 13009.3 13013.0 13066.9 656.2 678.4 682.3 805.6 840.2 824.8 825.5 828.6 832.6 834.0 878.7 880.9 883.0 883.1 884.4 681.3 705.2 875.9 876.6 876.6 627.9 650.4 652.0 653.5 660.0 13697.5 13743.9 13753.6 13756.0 13767.6 12725.4 12734.4 12935.5 12960.9 13104.8 11550.9 11823.4 11859.8 11892.0 11936.2 4329.3 4347.9 4387.3 4462.6 4535.6 83104.0 87292.0 89164.0 90187.5 91188.5 10050.2 10198.7 10291.0 10320.9 10676.2 19153.2 19290.4 19335.7 19465.2 19516.4 7271.9 7287.5 7314.3 7408.0 7412.7 4049.4 4056.6 4082.8 4086.7 4092.3 3218.2 3513.9 3676.3 3706.0 3711.4 700.8 706.1 711.4 711.9 726.2 631.9 634.2 635.9 644.5 675.2 6790.5 8082.9 8240.8 8354.8 8862.8 583.2 583.5 596.0 654.4 674.0 4121.0 4323.7 4349.8 4384.7 4387.6 75655.0 104272.0 105457.0 108055.0 109175.0 96642.0 96806.5 98023.0 101170.0 102926.5 61462.7 86658.7 87395.0 88568.6 91759.9 8203.9 8328.4 8344.4 8442.9 8519.6 57184.4 57577.0 57817.4 58996.0 59977.0 74533.0 79620.0 81313.2 81968.8 84448.7 9475.0 11237.0 11266.2 11487.3 11631.1 11598.5 11864.6 11926.7 12354.4 12382.6 10668.0 11399.2 11813.0 11816.0 11844.5 10849.7 10884.8 11891.7 12012.6 12043.0 10836.8 10950.9 11615.2 11693.1 11714.2 7754.4 9284.1 9631.0 9746.5 9855.2 9759.9 9771.2 9787.5 9787.8 9822.0 08:31:09 INFO - PROCESS | 1634 | -------- Summary: end -------- 08:31:09 INFO - PROCESS | 1634 | 08:31:09 INFO - TEST-INFO | 1634: exit 0 08:31:09 INFO - TEST-OK | dromaeo_css | took 1119459ms 08:31:09 INFO - TEST-START | kraken 08:31:09 INFO - operating with platform_type : mac_ 08:31:09 INFO - Initialising browser for kraken test... 08:31:10 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/tmpSq0U3R/profile http://localhost:49241/getInfo.html) 08:31:12 INFO - PROCESS | 1644 | __metrics Screen width/height:1600/1200 08:31:12 INFO - PROCESS | 1644 | colorDepth:24 08:31:12 INFO - PROCESS | 1644 | Browser inner width/height: 1024/689 08:31:12 INFO - PROCESS | 1644 | __metrics 08:31:12 INFO - PROCESS | 1644 | 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:31:12 INFO - PROCESS | 1644 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:31:13 INFO - TEST-INFO | 1644: exit 0 08:31:13 INFO - Browser initialized. 08:31:13 INFO - Running cycle 1/1 for kraken test... 08:31:13 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/T/tmpSq0U3R/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:31:25 INFO - PROCESS | 1645 | RSS: Main: 222543872 08:31:25 INFO - PROCESS | 1645 | 08:31:28 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 08:31:29 INFO - PROCESS | 1645 | RSS: Main: 269807616 08:31:29 INFO - PROCESS | 1645 | 08:31:32 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 08:31:32 INFO - PROCESS | 1645 | RSS: Main: 282464256 08:31:32 INFO - PROCESS | 1645 | 08:31:35 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 08:31:36 INFO - PROCESS | 1645 | RSS: Main: 379879424 08:31:36 INFO - PROCESS | 1645 | 08:31:38 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 08:31:39 INFO - PROCESS | 1645 | RSS: Main: 282857472 08:31:39 INFO - PROCESS | 1645 | 08:31:41 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:31:41 INFO - PROCESS | 1645 | RSS: Main: 283717632 08:31:41 INFO - PROCESS | 1645 | 08:31:44 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 08:31:45 INFO - PROCESS | 1645 | RSS: Main: 324919296 08:31:45 INFO - PROCESS | 1645 | 08:31:47 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 08:31:48 INFO - PROCESS | 1645 | RSS: Main: 325750784 08:31:48 INFO - PROCESS | 1645 | 08:31:51 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 08:31:52 INFO - PROCESS | 1645 | RSS: Main: 323547136 08:31:52 INFO - PROCESS | 1645 | 08:31:53 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:31:54 INFO - PROCESS | 1645 | RSS: Main: 300920832 08:31:54 INFO - PROCESS | 1645 | 08:31:56 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:31:56 INFO - PROCESS | 1645 | RSS: Main: 300130304 08:31:56 INFO - PROCESS | 1645 | 08:31:58 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:31:59 INFO - PROCESS | 1645 | RSS: Main: 302632960 08:31:59 INFO - PROCESS | 1645 | 08:32:02 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:32:03 INFO - PROCESS | 1645 | RSS: Main: 315555840 08:32:03 INFO - PROCESS | 1645 | 08:32:06 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:32:06 INFO - PROCESS | 1645 | RSS: Main: 315379712 08:32:06 INFO - PROCESS | 1645 | 08:32:08 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 08:32:08 INFO - PROCESS | 1645 | RSS: Main: 359276544 08:32:08 INFO - PROCESS | 1645 | 08:32:08 INFO - PROCESS | 1645 | __start_tp_report 08:32:08 INFO - PROCESS | 1645 | _x_x_mozilla_page_load 08:32:08 INFO - PROCESS | 1645 | _x_x_mozilla_page_load_details 08:32:08 INFO - PROCESS | 1645 | |i|pagename|runs| 08:32:08 INFO - PROCESS | 1645 | |0;ai-astar;104;112;115;115;114;115;114;114;115;114 08:32:08 INFO - PROCESS | 1645 | |1;audio-beat-detection;142;114;102;164;150;109;113;110;140;110 08:32:08 INFO - PROCESS | 1645 | |2;audio-dft;182;187;182;185;184;184;182;187;183;183 08:32:08 INFO - PROCESS | 1645 | |3;audio-fft;81;59;58;88;62;59;59;59;82;57 08:32:08 INFO - PROCESS | 1645 | |4;audio-oscillator;87;71;94;68;68;68;91;68;69;93 08:32:08 INFO - PROCESS | 1645 | |5;imaging-gaussian-blur;88;85;88;85;88;85;89;86;89;85 08:32:08 INFO - PROCESS | 1645 | |6;imaging-darkroom;93;94;95;93;93;93;94;93;97;95 08:32:08 INFO - PROCESS | 1645 | |7;imaging-desaturate;87;85;65;114;65;126;127;126;84;82 08:32:08 INFO - PROCESS | 1645 | |8;json-parse-financial;55;53;53;53;57;56;56;55;56;56 08:32:08 INFO - PROCESS | 1645 | |9;json-stringify-tinderbox;64;74;67;72;74;69;70;68;70;71 08:32:08 INFO - PROCESS | 1645 | |10;stanford-crypto-aes;85;86;83;85;86;89;82;79;84;84 08:32:08 INFO - PROCESS | 1645 | |11;stanford-crypto-ccm;148;150;149;155;163;157;143;154;233;171 08:32:08 INFO - PROCESS | 1645 | |12;stanford-crypto-pbkdf2;167;176;169;168;168;167;165;168;223;199 08:32:08 INFO - PROCESS | 1645 | |13;stanford-crypto-sha256-iterative;68;67;67;67;66;66;65;66;66;66 08:32:08 INFO - PROCESS | 1645 | __end_tp_report 08:32:08 INFO - PROCESS | 1645 | __start_cc_report 08:32:08 INFO - PROCESS | 1645 | _x_x_mozilla_cycle_collect,2033 08:32:08 INFO - PROCESS | 1645 | __end_cc_report 08:32:08 INFO - PROCESS | 1645 | __startTimestamp1480091528922__endTimestamp 08:32:08 INFO - PROCESS | 1645 | 08:32:08 INFO - PROCESS | 1645 | ------- Summary: start ------- 08:32:08 INFO - PROCESS | 1645 | Number of tests: 14 08:32:08 INFO - PROCESS | 1645 | 08:32:08 INFO - PROCESS | 1645 | [#0] ai-astar Cycles:10 Average:113.20 Median:114.50 stddev:3.36 (2.9%) stddev-sans-first:0.97 08:32:08 INFO - PROCESS | 1645 | Values: 104.0 112.0 115.0 115.0 114.0 115.0 114.0 114.0 115.0 114.0 08:32:08 INFO - PROCESS | 1645 | 08:32:08 INFO - PROCESS | 1645 | [#1] audio-beat-detection Cycles:10 Average:125.40 Median:127.00 stddev:21.50 (16.9%) stddev-sans-first:21.94 08:32:08 INFO - PROCESS | 1645 | Values: 142.0 114.0 102.0 164.0 150.0 109.0 113.0 110.0 140.0 110.0 08:32:08 INFO - PROCESS | 1645 | 08:32:08 INFO - PROCESS | 1645 | [#2] audio-dft Cycles:10 Average:183.90 Median:184.00 stddev:1.91 (1.0%) stddev-sans-first:1.90 08:32:08 INFO - PROCESS | 1645 | Values: 182.0 187.0 182.0 185.0 184.0 184.0 182.0 187.0 183.0 183.0 08:32:08 INFO - PROCESS | 1645 | 08:32:08 INFO - PROCESS | 1645 | [#3] audio-fft Cycles:10 Average:66.40 Median:60.50 stddev:12.11 (20.0%) stddev-sans-first:11.64 08:32:08 INFO - PROCESS | 1645 | Values: 81.0 59.0 58.0 88.0 62.0 59.0 59.0 59.0 82.0 57.0 08:32:08 INFO - PROCESS | 1645 | 08:32:08 INFO - PROCESS | 1645 | [#4] audio-oscillator Cycles:10 Average:77.70 Median:79.00 stddev:11.83 (15.0%) stddev-sans-first:12.06 08:32:08 INFO - PROCESS | 1645 | Values: 87.0 71.0 94.0 68.0 68.0 68.0 91.0 68.0 69.0 93.0 08:32:08 INFO - PROCESS | 1645 | 08:32:08 INFO - PROCESS | 1645 | [#5] imaging-gaussian-blur Cycles:10 Average:86.80 Median:88.00 stddev:1.75 (2.0%) stddev-sans-first:1.80 08:32:08 INFO - PROCESS | 1645 | Values: 88.0 85.0 88.0 85.0 88.0 85.0 89.0 86.0 89.0 85.0 08:32:08 INFO - PROCESS | 1645 | 08:32:08 INFO - PROCESS | 1645 | [#6] imaging-darkroom Cycles:10 Average:94.00 Median:94.00 stddev:1.33 (1.4%) stddev-sans-first:1.36 08:32:08 INFO - PROCESS | 1645 | Values: 93.0 94.0 95.0 93.0 93.0 93.0 94.0 93.0 97.0 95.0 08:32:08 INFO - PROCESS | 1645 | 08:32:08 INFO - PROCESS | 1645 | [#7] imaging-desaturate Cycles:10 Average:96.10 Median:73.50 stddev:24.83 (33.8%) stddev-sans-first:26.12 08:32:08 INFO - PROCESS | 1645 | Values: 87.0 85.0 65.0 114.0 65.0 126.0 127.0 126.0 84.0 82.0 08:32:08 INFO - PROCESS | 1645 | 08:32:08 INFO - PROCESS | 1645 | [#8] json-parse-financial Cycles:10 Average:55.00 Median:56.00 stddev:1.49 (2.7%) stddev-sans-first:1.58 08:32:08 INFO - PROCESS | 1645 | Values: 55.0 53.0 53.0 53.0 57.0 56.0 56.0 55.0 56.0 56.0 08:32:08 INFO - PROCESS | 1645 | 08:32:08 INFO - PROCESS | 1645 | [#9] json-stringify-tinderbox Cycles:10 Average:69.90 Median:70.50 stddev:3.11 (4.4%) stddev-sans-first:2.46 08:32:08 INFO - PROCESS | 1645 | Values: 64.0 74.0 67.0 72.0 74.0 69.0 70.0 68.0 70.0 71.0 08:32:08 INFO - PROCESS | 1645 | 08:32:08 INFO - PROCESS | 1645 | [#10] stanford-crypto-aes Cycles:10 Average:84.30 Median:85.00 stddev:2.67 (3.1%) stddev-sans-first:2.82 08:32:08 INFO - PROCESS | 1645 | Values: 85.0 86.0 83.0 85.0 86.0 89.0 82.0 79.0 84.0 84.0 08:32:08 INFO - PROCESS | 1645 | 08:32:08 INFO - PROCESS | 1645 | [#11] stanford-crypto-ccm Cycles:10 Average:162.30 Median:156.00 stddev:26.10 (16.7%) stddev-sans-first:27.16 08:32:08 INFO - PROCESS | 1645 | Values: 148.0 150.0 149.0 155.0 163.0 157.0 143.0 154.0 233.0 171.0 08:32:08 INFO - PROCESS | 1645 | 08:32:08 INFO - PROCESS | 1645 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.00 Median:168.50 stddev:19.01 (11.3%) stddev-sans-first:19.81 08:32:08 INFO - PROCESS | 1645 | Values: 167.0 176.0 169.0 168.0 168.0 167.0 165.0 168.0 223.0 199.0 08:32:08 INFO - PROCESS | 1645 | 08:32:08 INFO - PROCESS | 1645 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.40 Median:66.50 stddev:0.84 (1.3%) stddev-sans-first:0.67 08:32:08 INFO - PROCESS | 1645 | Values: 68.0 67.0 67.0 67.0 66.0 66.0 65.0 66.0 66.0 66.0 08:32:08 INFO - PROCESS | 1645 | -------- Summary: end -------- 08:32:08 INFO - PROCESS | 1645 | 08:32:09 INFO - TEST-INFO | 1645: exit 0 08:32:09 INFO - TEST-OK | kraken | took 59978ms 08:32:09 INFO - SUITE-END | took 1179s 08:32:10 INFO - Completed test suite (00:19:39) 08:32:10 INFO - javascript benchmark 08:32:10 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3337.4159522083637, 3351.949720754189, 3358.9328213435733, 3365.259642878566, 3422.794632322061, 5606.55147588193, 5775.5090817280525, 5775.56683248757, 5793.5365170786345, 5795.420457954203, 5658.745356458959, 5693.117566777156, 5795.623284486505, 5804.564657650682, 5812.622179558336, 5186.611004174683, 5226.24219488174, 5238.711870847102, 5249.212618107295, 5285.762140703659, 5201.0, 5204.0372531081875, 5225.2162175673575, 5226.817061402851, 5308.628396012281, 5275.155975043994, 5341.519263266306, 5350.571954243671, 5370.087085195526, 5370.758315875795, 5320.414754377015, 5327.0, 5328.653637513549, 5332.520073193431, 5363.275957745733, 5058.0, 5150.484951504857, 5260.552853007499, 5287.656302340374, 5297.046531624306, 5065.366829146356, 5560.388357280696, 5672.716364181774, 5674.914876276859, 5725.914111288334, 6173.382661733835, 6202.131701561785, 6209.441150296449, 6213.409726076015, 6270.592411493239, 6239.500839932794, 6239.5199450683285, 6255.499560035186, 6256.562040657156, 6262.561620686553, 5720.1991721159075, 5741.33974592923, 5784.652920824764, 5852.063669812809, 5879.617824841372, 6289.8742025159245, 6300.873982520416, 6308.779192728232, 6316.431521163117, 6317.399847014526, 6210.0, 6212.502999760009, 6229.844253893689, 6234.719437625317, 6235.563510554309, 6134.325224225332, 6154.599951003171, 6156.230471191101, 6226.0, 6256.343083976117, 5072.771725272371, 5133.281340612317, 6109.829807539378, 6261.123442718023, 6269.498440124779, 6238.0, 6250.093736408196, 6289.213848268966, 6296.779612713533, 6311.211098612674, 4836.854894353175, 4970.72661003641, 4972.975135124301, 4978.925316120261, 4984.77568509418, 4409.492908315585, 4411.8014689338415, 4424.424824772759, 4445.955540444555, 4455.309427038814, 5763.6541807491685, 5764.798232061858, 5782.595218334676, 5785.537157027427, 5818.79634212809, 6284.622922624657, 6297.118403423525, 6312.337204593543, 6315.873682526324, 6317.4630156437215, 5493.835184944458, 5588.469095435927, 5602.579806514527, 5712.143178523172, 5790.449907258803, 5404.1353383459245, 5411.350637923474, 5432.266644003088, 5470.671759694431, 5483.561315094783, 6259.405356491126, 6261.906071408933, 6267.373262673878, 6272.686365681789, 6286.874262514724, 6193.721282542387, 6205.782797602063, 6308.621482710869, 6315.368463153648, 6323.272823625158, 4851.223804191312, 5385.3268341457315, 6216.347283535345, 6244.281907580505, 6303.527235457267, 6227.283862355705, 6267.871783079089, 6275.592086514362, 6276.592021518587, 6278.529110316653, 6144.508439324933, 6168.383161683796, 6190.442860142609, 6192.318844926965, 6264.874702505833, 6046.2139921809885, 6057.515398768176, 6058.151858739692, 6079.635221886701, 6113.633182008915, 5444.0, 5957.748872736774, 6125.84596676928, 6135.0, 6137.140800288053, 5893.6463812169695, 6059.454649081604, 6065.363136870565, 6070.605410648471, 6079.300880398634, 5993.910091348546, 6076.240469941257, 6109.236345456818, 6119.051547010221, 6135.0, 6089.360617135137, 6096.75612975476, 6103.6032657877095, 6106.847328816815, 6126.877462450815], "value": 5699.10742905443, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [492.06262070755224, 498.1854667618445, 515.2014377714543, 544.6242092955857, 551.6910530103142, 29990.85004574977, 31878.521822172657, 38092.809535952314, 39331.21337573247, 39367.81896543101, 41280.174396512055, 41746.0, 41753.79123104388, 41775.58224417755, 41852.79073604635, 9311.58097885595, 9500.28747843911, 9503.477308748015, 9510.762230944247, 9515.096065873731, 7296.525725827804, 8812.16284452976, 9335.673251436201, 11067.280626759275, 11409.771804563903, 9957.502124893763, 11310.660680179608, 11326.20716549842, 11384.487698053566, 11473.541058357656, 11217.214794964355, 11218.607348742837, 11239.887601123966, 11263.929926656996, 11265.15511336653, 8425.325973922072, 8689.391742578022, 8690.304775617935, 8765.0, 8773.473591584461, 38117.80941095304, 38739.80630096832, 38745.837624871296, 40688.0, 40877.79561102176, 3264.7877887937448, 3757.849686012556, 3804.8097595120134, 3843.5964223756664, 3923.4899463069905, 23745.525089498286, 24603.0, 28299.434011319863, 29683.851580742175, 30836.53745193824, 3142.874285028596, 3155.4162479941283, 3195.952060719091, 3208.3904058228864, 3215.3569286142865, 451979.0, 452439.0, 453103.0, 453618.0, 454919.0, 55205.723971379884, 55399.72300138474, 55412.0, 55439.44560554343, 60832.39167608356, 63563.68218158972, 63566.68216658887, 63577.0, 63616.0, 63637.0, 15840.524784256491, 16371.017738935701, 16372.017678939303, 16389.180540972902, 16389.754153687703, 11411.372374519315, 12683.98528117767, 12778.23330600167, 12778.61664150077, 12800.423980920881, 15155.545333640008, 16876.915615421844, 16953.491395258163, 17167.742483862752, 17604.647907041788, 5887.676177810262, 6882.655867206719, 6883.174019117738, 6934.306569343126, 6974.686139123752, 877.912208779117, 992.8709267795141, 1029.7425643589104, 1040.661784919904, 1042.0725554256717, 29266.268343291587, 29444.2836757709, 29603.407931841244, 29709.257268568457, 29977.850110749743, 1005.4369553050316, 1019.2763138171981, 1033.8604288420968, 1034.8758149022015, 1040.7866387390566, 13964.301784910916, 14482.34829432678, 14737.778933316009, 15167.772483412758, 15468.191848326278, 617126.0, 619967.0, 620396.0, 620586.0, 621683.0, 56867.14699279514, 57135.71432142896, 57162.0, 57284.42715572874, 57327.42672573304, 1355.098859258582, 1380.0339762166523, 1384.0726713102247, 1384.7715127004335, 1388.9722205555831, 27597.448051039268, 28588.42823143567, 29023.854880725463, 29265.56437235655, 29985.0, 50806.74596626993, 51022.74488627533, 51179.48820511747, 51232.74383628058, 51304.0, 554760.0, 557642.0, 557984.0, 558938.0, 562192.0, 34385.82807085948, 35027.82486087553, 36010.819945900104, 40364.78905632836, 40746.0, 40548.391774124, 42025.0, 44845.52246606639, 44851.102977940915, 45902.0, 37401.6259837409, 37633.81183094176, 37778.866634001024, 37801.62198378091, 39174.80412598033, 37545.62454375421, 38348.80825595854, 38410.438320145535, 38552.84341469761, 38936.80531597324, 36384.818075909454, 36865.26269474542, 37101.628983709816, 37354.626453735116, 37626.81186594049, 32117.357652846345, 33180.00459986204, 33844.15389615279, 33873.49189762205, 34422.82788586041, 24922.50154996926, 24937.0, 25164.74835251624, 25419.491610167323, 25465.745342547078], "value": 19807.78887365035, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [349.7062467527277, 367.1042655919556, 370.82941846750475, 378.08692008798755, 402.41247778243763, 14812.481563145278, 15162.090274583532, 15640.374385024614, 19808.801911980874, 20320.0, 19759.604807903834, 20494.590108197826, 20499.69250461244, 20956.89521552394, 21017.68473472899, 5204.115300398922, 5752.137179423089, 6043.335233124353, 6055.364186760393, 6059.0608455689435, 5247.895042099175, 5936.109583562478, 5976.581639285252, 5985.431384018511, 6000.489958353523, 5663.688497132656, 5930.88138237235, 5936.0, 5938.703064846741, 5957.791477298296, 5074.13739664258, 5888.263967004124, 5945.435183657567, 5949.732262048196, 5949.851253718648, 4948.208286674115, 5125.436202017812, 5180.6373553851245, 5194.039102766001, 5207.973960130175, 25402.0, 25434.745652543606, 25438.36404089894, 25519.362015949566, 26006.73993260081, 2131.9467013324634, 2141.3468891987936, 2309.307207837642, 2418.298693378918, 2559.8992433253693, 15080.868934829821, 15213.543593692208, 15293.92942494026, 15305.6173595661, 15909.443169489128, 1762.409592786418, 1835.779706435223, 1868.3740946782843, 1871.9157637906192, 1925.5859990102194, 112111.0, 118067.0, 120353.0, 129693.0, 133454.3327283367, 9296.72109836706, 11260.0, 11288.774224515464, 11668.649940501798, 11778.52885884555, 14676.779848302283, 14680.339384727713, 14708.411663533552, 14733.336999834819, 14753.557393278219, 8270.793230169294, 8514.361422893184, 8520.74437766868, 8646.221840034426, 8675.956620216859, 8392.202740739742, 8402.117777633383, 8421.536815475207, 8586.184312490428, 8589.355798315151, 4452.198604251266, 4572.862814115582, 4578.175928332866, 4581.358609794632, 4650.534946505322, 1857.7956424793263, 1911.0731295321757, 1912.1013123831776, 1912.4549503391745, 1915.1764741161435, 808.4542933519969, 834.2533432577931, 905.5245995852099, 925.4817302310728, 978.5645387802502, 20148.597028059357, 20868.373948781562, 21066.789332106484, 21082.0, 21142.577148456945, 916.2440986186423, 918.3112665500875, 932.2681694869494, 937.4000639590668, 984.4044353166231, 10609.257351985287, 10872.293300935415, 11048.723781905517, 11109.333439993627, 11158.995690387741, 549928.7510687343, 589274.0, 599547.0, 605367.8658033496, 622271.0, 116947.8305216937, 119389.0, 125502.0, 129278.99999999812, 134376.0, 938.3150300280834, 951.2390087929629, 978.6476868327396, 1016.8525563793356, 1020.3928662445685, 19199.90400047991, 19453.610927781647, 19782.406527803614, 20060.69908951396, 20131.698024529353, 54117.0, 54328.45671543234, 55238.0, 56210.43789562052, 56492.30523084314, 72944.0, 75379.24620753941, 79944.60027700057, 82746.0, 84102.0, 5001.174806157089, 6011.308699499438, 6107.633541987495, 6449.516286278642, 6935.82660433473, 10718.606581144713, 10934.289271197666, 11083.833742493707, 11331.433428328386, 12686.809697854724, 9230.815383692154, 9579.808403832023, 9631.470269135265, 10474.21443391764, 10846.45767711627, 8950.283977281932, 10810.135189185004, 10868.836967445337, 11211.55153793839, 11530.481128349076, 11036.392998385167, 11105.611303604335, 11271.436428178722, 11353.148513861328, 11469.770604587695, 6773.288804675438, 7255.709771609288, 7400.814979625552, 7617.5048621839405, 7672.386209103147, 8967.820643587222, 10624.0, 10765.623203188165, 10782.73043173927, 11311.490982905625], "value": 9440.731520839738, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1317.4795955597533, 1474.675571374298, 1495.6186172526004, 1639.5819066138133, 1805.684005299073, 7455.291747284006, 7888.211178882116, 8126.349892008648, 8332.541710205936, 8353.039400468948, 8306.792330191733, 8400.243978041975, 8411.789705257372, 8413.663453461853, 8439.957800211007, 2258.062903894886, 2277.1802151225547, 2288.3706980580305, 2290.0839664134396, 2291.140822191678, 2315.907363705458, 2348.9530209395803, 2366.656834758959, 2367.2898130560848, 2371.1463873005705, 2294.093832935989, 2295.82781291403, 2319.9652005219937, 2330.1145564685394, 2346.718393792739, 2279.863208207513, 2298.2186056740707, 2317.7450480447137, 2323.6630688550067, 2325.7092863392077, 1949.8635095543175, 2310.5378924215056, 2335.89045203527, 2337.6026075567192, 2344.4256157241584, 8369.58152092393, 8452.28155606774, 8453.112423195538, 8457.915420845775, 8467.237948584658, 5033.471485494007, 5238.978399212155, 5240.266362709223, 5262.789488420459, 5262.789488420459, 3400.846961886721, 3525.665061819123, 3560.3235385276716, 3585.910352241189, 3587.2646107548153, 1056.4031322302951, 1082.7942690888706, 1084.1814430105298, 1092.3009274064605, 1101.4657890922897, 10996.560137594486, 11089.72275693106, 11109.0, 11116.943890330405, 11205.0, 4253.723507971972, 4416.712913660602, 4502.077074199781, 4508.594226519629, 4540.818367265339, 8798.736037918872, 8799.076097009849, 8799.384043116921, 8803.427777194462, 8806.86789698155, 5117.283580298761, 5119.155339368963, 5123.462036486189, 5126.256692779526, 5128.9230661540105, 4043.838246470167, 4262.552431994658, 4414.337849322563, 4438.866833994985, 4710.152172608895, 3646.4165733482514, 3700.259948010355, 3766.9246615067545, 3768.4158955361963, 3778.905527361783, 1072.1476426241156, 1127.9436028198556, 1137.732632831284, 1140.9828852567218, 1152.5850693750244, 695.2977492732376, 822.1613953767124, 841.7390608911257, 859.6991053131354, 888.6312180445173, 5509.338879334506, 5634.577406694514, 5666.858328541737, 5800.303963524404, 5896.439838215449, 813.1868131868132, 871.5293741379711, 875.2297977779641, 890.225503811688, 904.1455824246156, 3622.855085796539, 3625.836837342326, 3714.3871261241593, 3728.422094575345, 3758.473813666089, 8596.78508037304, 9779.266555008402, 10303.948480257552, 10305.587776488857, 10315.0, 8492.490450572985, 10263.794724105475, 10266.024727650862, 10267.435291058913, 10278.383297002203, 7818.569978651229, 7999.0, 8007.639656215601, 8014.83970320602, 8027.317677997436, 5240.973795131, 5385.219143224289, 5414.729263536886, 5592.636478628876, 5601.159826026129, 9391.436513809193, 9406.200472959876, 9506.857397139187, 9637.18083962843, 9670.516474176404, 9525.523723813643, 9595.088466595662, 9718.416894986323, 9732.854007189757, 9773.0, 6689.130413046273, 6755.088063111417, 6813.965930170318, 6818.795436136924, 6846.863062738817, 7817.218278172365, 7832.647530861239, 7833.216678332121, 8015.038195416588, 8040.075391330072, 7096.787096387116, 7231.710731570889, 7272.96363518179, 7283.672234749555, 7350.154732205865, 6734.3265673434225, 7057.2237053925055, 7074.504784665024, 7108.857822843617, 7135.42916566663, 6631.1048008520165, 6661.666916654245, 6736.93263067363, 6758.189017317756, 6764.763233287008, 4767.213409787346, 4791.353167322367, 4816.15717249487, 4878.902421951612, 4905.945221777335, 3787.8295476703065, 4769.689970152068, 4792.904141917073, 4939.851804445872, 5053.772580233825], "value": 4369.24348590279, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [294.71265516121787, 298.26775266720205, 304.17568389664007, 309.5403326060799, 312.33628539353873, 4214.936775948359, 4911.214205727084, 5115.7697903594335, 5188.143956247224, 5215.739213039352, 4238.597333253344, 4875.512448755132, 4915.655904086715, 5050.267711181877, 5099.770510327035, 3941.881743547684, 3949.0, 3949.901252468697, 3981.7810020448865, 3988.2821092203462, 3325.900222993302, 3713.925721485569, 3732.701383889296, 3746.5316835395574, 3981.2681483554748, 2945.4698154332214, 3078.769092318074, 3793.525809273841, 3803.239352129571, 3938.6258305460933, 3592.335417947662, 3884.582127523462, 3925.623963391481, 3948.1708841143395, 3952.7430717003303, 3444.5877190351835, 3448.2930999145115, 3450.741194410429, 3453.568303962005, 3461.2904354607244, 4091.468109145792, 4708.693934894221, 4743.770427898917, 5022.6233032522705, 5041.445441001487, 4439.779060758285, 4445.355423463579, 4467.955320446787, 4471.262241730111, 4533.232039504596, 4732.929006064912, 5359.356877174764, 5389.380221274564, 5415.214793854869, 5421.783128674848, 3092.5206592978125, 3518.4546395308766, 3726.16161363691, 3733.290674771785, 3743.850245990157, 4623.491415944243, 5201.375834899836, 5364.0, 5646.6894320812335, 5656.575756818214, 3745.6441638044344, 3771.340015497277, 3781.054736315921, 3785.1861849702445, 3795.0, 3389.847456864447, 3636.4363523653874, 3683.9815800920824, 3849.4418309344987, 3876.244132394156, 4376.846810361686, 4393.340998850198, 4412.470503539532, 4461.0, 4465.888352791206, 4717.43390793107, 4795.520447955177, 4825.734584597881, 5261.921071183935, 5415.72539533397, 970.0348153587225, 1123.769627226433, 1166.7666466706692, 1186.9881301186876, 1222.7615614955175, 776.8076003334854, 789.5657388436337, 815.1155995744565, 850.8766228896775, 864.2524216552637, 4569.474510431343, 4706.435227772689, 4774.901772592254, 4785.521447855187, 4813.663043586916, 4559.651779333646, 4671.602913752369, 4983.975080124649, 5107.284980102789, 5229.790808367623, 4571.697066336078, 4578.198815207325, 4725.574698277172, 4767.475577686451, 4808.422989241313, 2253.4816992091583, 2333.614953532682, 2732.808703390783, 2738.52075886719, 2754.801661277351, 4216.894577635584, 4456.9331460028125, 5146.639735218574, 5280.630355875129, 5413.837584872382, 4651.44176700809, 4857.7085374876915, 4915.4347250065985, 5310.928737792264, 5411.594130440233, 4643.582077613031, 4889.79959018436, 5179.507946745053, 5448.700321482356, 5454.47274763067, 4302.548232435674, 4467.865964021084, 4867.902641947212, 4882.707037577757, 4979.626528010342, 4214.831406743696, 4852.975735121302, 5174.6119041071315, 5598.272224610774, 5605.327360716577, 5389.83830485086, 5553.777848885999, 5819.883602328015, 5849.883002339844, 5870.9412905872105, 3241.54618353426, 3808.0479880029993, 3849.538055433366, 3853.4605155278846, 3857.5370955485523, 5051.595872330278, 5233.136532472252, 5615.241942337895, 5617.455199820082, 5620.690862002629, 4782.617390608673, 5003.924941125813, 5445.754941027584, 5487.890242195054, 5519.3652729936575, 5226.346706661668, 5412.997191039341, 5500.0, 5502.642328248651, 5545.722713864371, 5077.111505486599, 5214.530692237717, 5235.240890070842, 5339.839804805862, 5374.381946076252, 5015.498450154955, 5297.205419187153, 5472.945270547243, 5477.260569823023, 5489.505944465017, 5031.295618613471, 5232.26748255237, 5327.561558379293, 5347.4652534747775, 5377.27406800077], "value": 3852.906847206873, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [689.8689249042685, 727.792579114952, 749.3068911257087, 767.5279702982666, 772.4631381190076, 14264.572062838119, 14782.408703651869, 14783.965122441434, 14793.926030369847, 14808.6297842554, 12986.28575428351, 13000.959923206145, 13009.349532523383, 13013.0, 13066.93466532666, 656.2027137028509, 678.3589507915023, 682.2529330383222, 805.5931754463987, 840.1640367834013, 824.826786374859, 825.4964471672275, 828.6354004238116, 832.6253186066264, 833.9916600833975, 878.7232021913109, 880.8590625499921, 882.995585022074, 883.0815951410558, 884.3720958119743, 681.2540268406087, 705.1714235772997, 875.8773771671971, 876.5617191404298, 876.6318146378536, 627.8995360742305, 650.3691419323249, 651.993480065198, 653.4772182254178, 659.9868002639969, 13697.452101915911, 13743.931280343535, 13753.587392378246, 13756.037077404586, 13767.586972390844, 12725.427355769012, 12734.426950787236, 12935.48258069676, 12960.870391296156, 13104.80342794859, 11550.942245288803, 11823.358268915017, 11859.762804743857, 11892.0, 11936.16446848721, 4329.328954012133, 4347.891302717426, 4387.319965405367, 4462.553744625544, 4535.63714902807, 83104.0, 87292.0, 89164.0, 90187.54906225493, 91188.54405727996, 10050.246231532665, 10198.694039178836, 10290.99040324307, 10320.896791032144, 10676.199285053652, 19153.233870645297, 19290.4212873614, 19335.709964350546, 19465.221391144478, 19516.414507564798, 7271.89092163618, 7287.526310789782, 7314.268573142643, 7407.962960185165, 7412.703491860373, 4049.4128351389472, 4056.5943405659787, 4082.755034697928, 4086.652634526039, 4092.324766413568, 3218.1632775478074, 3513.8594456221977, 3676.283124790694, 3705.9814700926863, 3711.4432835074954, 700.7547358424613, 706.0785674694573, 711.4237467651219, 711.9359257666835, 726.1794172584945, 631.9210098737658, 634.2167423232397, 635.9268684101295, 644.5262731892055, 675.2336098528206, 6790.5246632736225, 8082.9221117365305, 8240.752777416566, 8354.791130221793, 8862.822743545094, 583.2417856786161, 583.4807021750645, 595.9659989917576, 654.3783405764543, 673.952823302374, 4120.9638122893175, 4323.718958267703, 4349.847755328612, 4384.714993525411, 4387.648988080946, 75655.0, 104272.0, 105457.0, 108055.0, 109175.0, 96642.0335796633, 96806.5159674197, 98023.01976980282, 101170.0, 102926.48536757268, 61462.692686536284, 86658.70011949952, 87395.0, 88568.5571572151, 91759.87013567232, 8203.917960820554, 8328.417010809186, 8344.37417193725, 8442.93740563834, 8519.616617252115, 57184.42815571957, 57577.0, 57817.4218257812, 58996.0, 59977.0, 74533.0, 79620.0, 81313.18686813056, 81968.77046844421, 84448.73326899979, 9475.0, 11237.0, 11266.211365204357, 11487.310761354345, 11631.127665425296, 11598.478068487108, 11864.584739534075, 11926.701832454259, 12354.44405001795, 12382.56661016896, 10668.0, 11399.20205585601, 11813.042139046016, 11816.0, 11844.526218951145, 10849.728756781144, 10884.782304354027, 11891.702707432038, 12012.579559715368, 12043.0, 10836.837447438136, 10950.890491094988, 11615.245009074382, 11693.12301577368, 11714.1800073994, 7754.418418618513, 9284.071592840663, 9630.951845240728, 9746.512674366395, 9855.211583073193, 9759.9024009759, 9771.218302535923, 9787.46168960714, 9787.755306117404, 9822.0], "value": 7080.940158823916, "unit": "score"}], "name": "dromaeo_css", "value": 7090.2906343052555, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 112.0, 115.0, 115.0, 114.0, 115.0, 114.0, 114.0, 115.0, 114.0], "value": 113.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 114.0, 102.0, 164.0, 150.0, 109.0, 113.0, 110.0, 140.0, 110.0], "value": 125.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 187.0, 182.0, 185.0, 184.0, 184.0, 182.0, 187.0, 183.0, 183.0], "value": 183.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 59.0, 58.0, 88.0, 62.0, 59.0, 59.0, 59.0, 82.0, 57.0], "value": 66.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 71.0, 94.0, 68.0, 68.0, 68.0, 91.0, 68.0, 69.0, 93.0], "value": 77.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 88.0, 85.0, 88.0, 85.0, 89.0, 86.0, 89.0, 85.0], "value": 86.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 95.0, 93.0, 93.0, 93.0, 94.0, 93.0, 97.0, 95.0], "value": 94.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 65.0, 114.0, 65.0, 126.0, 127.0, 126.0, 84.0, 82.0], "value": 96.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 53.0, 53.0, 53.0, 57.0, 56.0, 56.0, 55.0, 56.0, 56.0], "value": 55.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 74.0, 67.0, 72.0, 74.0, 69.0, 70.0, 68.0, 70.0, 71.0], "value": 69.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 83.0, 85.0, 86.0, 89.0, 82.0, 79.0, 84.0, 84.0], "value": 84.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 150.0, 149.0, 155.0, 163.0, 157.0, 143.0, 154.0, 233.0, 171.0], "value": 162.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 176.0, 169.0, 168.0, 168.0, 167.0, 165.0, 168.0, 223.0, 199.0], "value": 177.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 67.0, 67.0, 66.0, 66.0, 65.0, 66.0, 66.0, 66.0], "value": 66.4, "unit": "score"}], "name": "kraken", "value": 1458.4, "alertThreshold": 2.0}]} 08:32:10 INFO - Return code: 0 08:32:10 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:32:10 INFO - # TBPL SUCCESS # 08:32:10 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:32:10 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:32:10 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:32:10 INFO - 2016-11-25 08:32:10.209 screenresolution[1649:9888] starting screenresolution argv=screenresolution get 08:32:10 INFO - 2016-11-25 08:32:10.212 screenresolution[1649:9888] Display 0: 1600x1200x32@60 08:32:10 INFO - 2016-11-25 08:32:10.218 screenresolution[1650:9891] starting screenresolution argv=screenresolution list 08:32:10 INFO - Available Modes on Display 0 08:32:10 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:32:10 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:32:10 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:32:10 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:32:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:32:10 INFO - Intel Iris: 08:32:10 INFO - Chipset Model: Intel Iris 08:32:10 INFO - Type: GPU 08:32:10 INFO - Bus: Built-In 08:32:10 INFO - VRAM (Dynamic, Max): 1536 MB 08:32:10 INFO - Vendor: Intel (0x8086) 08:32:10 INFO - Device ID: 0x0a2e 08:32:10 INFO - Revision ID: 0x0009 08:32:10 INFO - Displays: 08:32:10 INFO - Display: 08:32:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:32:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:32:10 INFO - Display Serial Number: 1600x1200 60 08:32:10 INFO - Main Display: Yes 08:32:10 INFO - Mirror: Off 08:32:10 INFO - Online: Yes 08:32:10 INFO - Rotation: Supported 08:32:10 INFO - Return code: 0 08:32:10 INFO - Running post-action listener: _resource_record_post_action 08:32:10 INFO - [mozharness: 2016-11-25 16:32:10.468628Z] Finished run-tests step (success) 08:32:10 INFO - Running post-run listener: _resource_record_post_run 08:32:10 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 38933504; Write bytes: 657314304; Read time: 379; Write time: 1847 08:32:10 INFO - TinderboxPrint: CPU usage
53.5% 08:32:10 INFO - TinderboxPrint: I/O read bytes / time
38,933,504 / 379 08:32:10 INFO - TinderboxPrint: I/O write bytes / time
657,314,304 / 1,847 08:32:10 INFO - TinderboxPrint: CPU idle
2,254.8 (46.5%) 08:32:10 INFO - TinderboxPrint: CPU system
970.0 (20.0%) 08:32:10 INFO - TinderboxPrint: CPU user
1,620.9 (33.4%) 08:32:10 INFO - TinderboxPrint: Swap in / out
387,026,944 / 0 08:32:10 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 349364736; Write bytes: 350381568; Read time: 26923; Write time: 603 08:32:10 INFO - run-tests - Wall time: 1181s; CPU: 53.0%; Read bytes: 34616320; Write bytes: 305503232; Read time: 347; Write time: 1237 08:32:10 INFO - Running post-run listener: _upload_blobber_files 08:32:10 INFO - Blob upload gear active. 08:32:10 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:32: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:32: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:32: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:32:11 INFO - (blobuploader) - INFO - Open directory for files ... 08:32:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:32:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:32:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:32:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:32:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:32:12 INFO - (blobuploader) - INFO - Done attempting. 08:32:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:32:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:32:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:32:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:32:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:32:13 INFO - (blobuploader) - INFO - Done attempting. 08:32:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:32:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:32:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:32:15 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:32:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:32:15 INFO - (blobuploader) - INFO - Done attempting. 08:32:15 INFO - (blobuploader) - INFO - Iteration through files over. 08:32:15 INFO - Return code: 0 08:32:15 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:32:15 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:32:15 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c93bd76fcc0fdcd6708648b2a05cacf77793b62e357d3ad29270c353d119cd8dfec6936e75139bedecb7e778288c561ad81913ac17afa7dcc9d79179a2fde710", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/63bc592892a84746f718a2ff218ee26e27112b9ecbada50af88ef46ad083ad0b03683e9bbfa67fb0752656a24d782f7e7abf504060b9b3dfe30944be22cebf90", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c92722504781929c257f76c77a9b8222253229699bcec75736de83273e7499041ae9342a9f4fdb90175b5d0c85230b329ba8a10e67c324c3705c9ef5197f9196"} 08:32:15 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:32:15 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:32:15 INFO - Contents: 08:32:15 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c93bd76fcc0fdcd6708648b2a05cacf77793b62e357d3ad29270c353d119cd8dfec6936e75139bedecb7e778288c561ad81913ac17afa7dcc9d79179a2fde710", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/63bc592892a84746f718a2ff218ee26e27112b9ecbada50af88ef46ad083ad0b03683e9bbfa67fb0752656a24d782f7e7abf504060b9b3dfe30944be22cebf90", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c92722504781929c257f76c77a9b8222253229699bcec75736de83273e7499041ae9342a9f4fdb90175b5d0c85230b329ba8a10e67c324c3705c9ef5197f9196"} 08:32:15 INFO - Running post-run listener: copy_logs_to_upload_dir 08:32:15 INFO - Copying logs to upload dir... 08:32:15 INFO - mkdir: /builds/slave/test/build/upload/logs 08:32:15 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.178357 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-11-25 08:32:15.913158) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-25 08:32:15.918272) ========= 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.Yypme3U9px/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.tnQNam44hQ/Listeners TMPDIR=/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/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/c93bd76fcc0fdcd6708648b2a05cacf77793b62e357d3ad29270c353d119cd8dfec6936e75139bedecb7e778288c561ad81913ac17afa7dcc9d79179a2fde710", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/63bc592892a84746f718a2ff218ee26e27112b9ecbada50af88ef46ad083ad0b03683e9bbfa67fb0752656a24d782f7e7abf504060b9b3dfe30944be22cebf90", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c92722504781929c257f76c77a9b8222253229699bcec75736de83273e7499041ae9342a9f4fdb90175b5d0c85230b329ba8a10e67c324c3705c9ef5197f9196"} build_url:https://queue.taskcluster.net/v1/task/SSuycGrkR2mqV4eRXAsbsw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010897 build_url: 'https://queue.taskcluster.net/v1/task/SSuycGrkR2mqV4eRXAsbsw/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/c93bd76fcc0fdcd6708648b2a05cacf77793b62e357d3ad29270c353d119cd8dfec6936e75139bedecb7e778288c561ad81913ac17afa7dcc9d79179a2fde710", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/63bc592892a84746f718a2ff218ee26e27112b9ecbada50af88ef46ad083ad0b03683e9bbfa67fb0752656a24d782f7e7abf504060b9b3dfe30944be22cebf90", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c92722504781929c257f76c77a9b8222253229699bcec75736de83273e7499041ae9342a9f4fdb90175b5d0c85230b329ba8a10e67c324c3705c9ef5197f9196"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-25 08:32:15.955674) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-25 08:32:15.959014) ========= 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.Yypme3U9px/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.tnQNam44hQ/Listeners TMPDIR=/var/folders/0d/jvrzwxys08z_8pq41nzpqw_800000w/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.005064 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-25 08:32:16.007533) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-25 08:32:16.010898) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-25 08:32:16.011217) ========= ========= Total master_lag: 0.20 =========