builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0036 starttime: 1480091035.73 results: success (0) buildid: 20161125062719 builduid: 3b1b2fe0421645f0ad9b330503bcc8a3 revision: c41dab3b9a7230a377d68d30244bd201258ce10d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-25 08:23:55.728062) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-25 08:23:55.728713) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-25 08:23:55.729197) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-25 08:23:55.763926) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-25 08:23:55.764444) ========= 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.567Us1bYDp/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.KrB7zOnvMK/Listeners TMPDIR=/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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:23:55-- 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% 131M=0s 2016-11-25 08:23:55 (131 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.147732 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-25 08:23:55.942779) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-25 08:23:55.943088) ========= 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.567Us1bYDp/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.KrB7zOnvMK/Listeners TMPDIR=/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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.027337 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-25 08:23:55.991530) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-25 08:23:55.994971) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev c41dab3b9a7230a377d68d30244bd201258ce10d --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev c41dab3b9a7230a377d68d30244bd201258ce10d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.567Us1bYDp/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.KrB7zOnvMK/Listeners TMPDIR=/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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:23:56,084 truncating revision to first 12 chars 2016-11-25 08:23:56,084 Setting DEBUG logging. 2016-11-25 08:23:56,084 attempt 1/10 2016-11-25 08:23:56,084 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/c41dab3b9a72?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-25 08:23:57,539 unpacking tar archive at: mozilla-central-c41dab3b9a72/testing/mozharness/ program finished with exit code 0 elapsedTime=1.723872 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-25 08:23:57.744917) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-25 08:23:57.745257) ========= script_repo_revision: c41dab3b9a7230a377d68d30244bd201258ce10d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-25 08:23:57.745667) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-25 08:23:57.745963) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-25 08:23:57.766004) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-11-25 08:23:57.766362) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.567Us1bYDp/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.KrB7zOnvMK/Listeners TMPDIR=/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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:23:57 INFO - MultiFileLogger online at 20161125 08:23:57 in /builds/slave/test 08:23:57 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 08:23:57 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:23:57 INFO - {'append_to_log': False, 08:23:57 INFO - 'base_work_dir': '/builds/slave/test', 08:23:57 INFO - 'blob_upload_branch': 'Firefox', 08:23:57 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:23:57 INFO - 'branch': 'Firefox', 08:23:57 INFO - 'buildbot_json_path': 'buildprops.json', 08:23:57 INFO - 'config_files': ('talos/mac_config.py',), 08:23:57 INFO - 'default_actions': ('clobber', 08:23:57 INFO - 'read-buildbot-config', 08:23:57 INFO - 'download-and-extract', 08:23:57 INFO - 'populate-webroot', 08:23:57 INFO - 'create-virtualenv', 08:23:57 INFO - 'install', 08:23:57 INFO - 'run-tests'), 08:23:57 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:23:57 INFO - 'download_minidump_stackwalk': True, 08:23:57 INFO - 'download_symbols': 'ondemand', 08:23:57 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:23:57 INFO - 'tooltool.py': '/tools/tooltool.py', 08:23:57 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:23:57 INFO - '/tools/misc-python/virtualenv.py')}, 08:23:57 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:23:57 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:23:57 INFO - 'installer_path': 'installer.exe', 08:23:57 INFO - 'log_level': 'info', 08:23:57 INFO - 'log_name': 'talos', 08:23:57 INFO - 'log_to_console': True, 08:23:57 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:23:57 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:23:57 INFO - 'opt_config_files': (), 08:23:57 INFO - 'pip_index': False, 08:23:57 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:23:57 INFO - 'cmd': ('bash', 08:23:57 INFO - '-c', 08:23:57 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:23:57 INFO - 'enabled': True, 08:23:57 INFO - 'halt_on_failure': False, 08:23:57 INFO - 'name': 'check_screen_resolution'},), 08:23:57 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:23:57 INFO - 'cmd': ('bash', 08:23:57 INFO - '-c', 08:23:57 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:23:57 INFO - 'enabled': True, 08:23:57 INFO - 'halt_on_failure': False, 08:23:57 INFO - 'name': 'check_screen_resolution'},), 08:23:57 INFO - 'run_cmd_checks_enabled': True, 08:23:57 INFO - 'sps_profile': False, 08:23:57 INFO - 'sps_profile_interval': 0, 08:23:57 INFO - 'suite': 'dromaeojs-e10s', 08:23:57 INFO - 'system_bits': '32', 08:23:57 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:23:57 INFO - 'title': 't-yosemite-r7-0036', 08:23:57 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:23:57 INFO - 'use_talos_json': True, 08:23:57 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:23:57 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:23:57 INFO - 'work_dir': 'build'} 08:23:57 INFO - [mozharness: 2016-11-25 16:23:57.965948Z] Running clobber step. 08:23:57 INFO - Running pre-action listener: _resource_record_pre_action 08:23:57 INFO - Running main action method: clobber 08:23:57 INFO - rmtree: /builds/slave/test/build 08:23:57 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:24:00 INFO - Running post-action listener: _resource_record_post_action 08:24:00 INFO - [mozharness: 2016-11-25 16:24:00.557401Z] Finished clobber step (success) 08:24:00 INFO - [mozharness: 2016-11-25 16:24:00.557527Z] Running read-buildbot-config step. 08:24:00 INFO - Running pre-action listener: _resource_record_pre_action 08:24:00 INFO - Running main action method: read_buildbot_config 08:24:00 INFO - Using buildbot properties: 08:24:00 INFO - { 08:24:00 INFO - "project": "", 08:24:00 INFO - "product": "firefox", 08:24:00 INFO - "script_repo_revision": "production", 08:24:00 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 08:24:00 INFO - "repository": "", 08:24:00 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 08:24:00 INFO - "buildid": "20161125062719", 08:24:00 INFO - "pgo_build": "False", 08:24:00 INFO - "basedir": "/builds/slave/test", 08:24:00 INFO - "buildnumber": 177, 08:24:00 INFO - "slavename": "t-yosemite-r7-0036", 08:24:00 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 08:24:00 INFO - "platform": "yosemite_r7", 08:24:00 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 08:24:00 INFO - "branch": "mozilla-central", 08:24:00 INFO - "repo_path": "mozilla-central", 08:24:00 INFO - "revision": "c41dab3b9a7230a377d68d30244bd201258ce10d", 08:24:00 INFO - "stage_platform": "macosx64", 08:24:00 INFO - "builduid": "3b1b2fe0421645f0ad9b330503bcc8a3", 08:24:00 INFO - "slavebuilddir": "test" 08:24:00 INFO - } 08:24:00 INFO - Found installer url https://queue.taskcluster.net/v1/task/eJum9pLRREqPofrssXCEwA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 08:24:00 INFO - Running post-action listener: _resource_record_post_action 08:24:00 INFO - [mozharness: 2016-11-25 16:24:00.560836Z] Finished read-buildbot-config step (success) 08:24:00 INFO - [mozharness: 2016-11-25 16:24:00.560952Z] Running download-and-extract step. 08:24:00 INFO - Running pre-action listener: _resource_record_pre_action 08:24:00 INFO - Running main action method: download_and_extract 08:24:00 INFO - mkdir: /builds/slave/test/build/tests 08:24:00 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:24:00 INFO - trying https://queue.taskcluster.net/v1/task/eJum9pLRREqPofrssXCEwA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 08:24:00 INFO - Downloading https://queue.taskcluster.net/v1/task/eJum9pLRREqPofrssXCEwA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 08:24:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eJum9pLRREqPofrssXCEwA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 08:24:01 INFO - Downloaded 1199 bytes. 08:24:01 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 08:24:01 INFO - Using the following test package requirements: 08:24:01 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 08:24:01 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:24:01 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 08:24:01 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:24:01 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 08:24:01 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:24:01 INFO - u'jsshell-mac.zip'], 08:24:01 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:24:01 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 08:24:01 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 08:24:01 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:24:01 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 08:24:01 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:24:01 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 08:24:01 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:24:01 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 08:24:01 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:24:01 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 08:24:01 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:24:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eJum9pLRREqPofrssXCEwA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 08:24:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eJum9pLRREqPofrssXCEwA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:24:01 INFO - Fetch https://queue.taskcluster.net/v1/task/eJum9pLRREqPofrssXCEwA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 08:24:02 INFO - Http code: 200 08:24:02 INFO - via: 1.1 ff555a0cc6346f82fe74596f8ed6cc33.cloudfront.net (CloudFront) 08:24:02 INFO - x-amz-cf-id: RevkMv4T3SfXPa51xkq-pRegYYDWd96TKWeIZ5TMJ8hvamBQ2qcheQ== 08:24:02 INFO - x-amz-version-id: KAkVP5na4HEid82c22_IilROaQxuhd6T 08:24:02 INFO - Expected file size: 16269396 08:24:02 INFO - Obtained file size: 16269396 08:24:09 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 08:24:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eJum9pLRREqPofrssXCEwA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 08:24:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eJum9pLRREqPofrssXCEwA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:24:09 INFO - Fetch https://queue.taskcluster.net/v1/task/eJum9pLRREqPofrssXCEwA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 08:24:10 INFO - Http code: 200 08:24:10 INFO - via: 1.1 93ab344e1a3fa44edbf3f1bc5729427a.cloudfront.net (CloudFront) 08:24:10 INFO - x-amz-cf-id: aMs408HC6BT4m1R8Svr_whXALOah0uIkloCv1yPBwuiIjyTfu2CVSw== 08:24:10 INFO - x-amz-version-id: KAkVP5na4HEid82c22_IilROaQxuhd6T 08:24:10 INFO - Expected file size: 16269396 08:24:10 INFO - Obtained file size: 16269396 08:24:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eJum9pLRREqPofrssXCEwA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 08:24:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eJum9pLRREqPofrssXCEwA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 08:24:16 INFO - Fetch https://queue.taskcluster.net/v1/task/eJum9pLRREqPofrssXCEwA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 08:24:17 INFO - Http code: 200 08:24:17 INFO - via: 1.1 e30ae5b3d9f6779a9b8bc992faad0b09.cloudfront.net (CloudFront) 08:24:17 INFO - x-amz-cf-id: 1fSyNkBPxZRJs3KLoXsYJEByzTt0ZQSxxX-wOFtAjbXzL8-BzAinSA== 08:24:17 INFO - x-amz-version-id: bkUfqgSvn74vbNDU4sdtehnCCaj4U8Ts 08:24:17 INFO - Expected file size: 14409749 08:24:17 INFO - Obtained file size: 14409749 08:24:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:24:18 INFO - trying https://queue.taskcluster.net/v1/task/eJum9pLRREqPofrssXCEwA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 08:24:18 INFO - Downloading https://queue.taskcluster.net/v1/task/eJum9pLRREqPofrssXCEwA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 08:24:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eJum9pLRREqPofrssXCEwA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 08:24:21 INFO - Downloaded 107575728 bytes. 08:24:21 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/eJum9pLRREqPofrssXCEwA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 08:24:21 INFO - mkdir: /builds/slave/test/properties 08:24:21 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:24:21 INFO - Writing to file /builds/slave/test/properties/build_url 08:24:21 INFO - Contents: 08:24:21 INFO - build_url:https://queue.taskcluster.net/v1/task/eJum9pLRREqPofrssXCEwA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 08:24:21 INFO - Running post-action listener: _resource_record_post_action 08:24:21 INFO - Running post-action listener: set_extra_try_arguments 08:24:21 INFO - [mozharness: 2016-11-25 16:24:21.691886Z] Finished download-and-extract step (success) 08:24:21 INFO - [mozharness: 2016-11-25 16:24:21.692036Z] Running populate-webroot step. 08:24:21 INFO - Running pre-action listener: _resource_record_pre_action 08:24:21 INFO - Running main action method: populate_webroot 08:24:21 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:24:21 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:24:21 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:24:21 INFO - '--tppagecycles', 08:24:21 INFO - '7'], 08:24:21 INFO - 'tests': ['tsvgm']}}, 08:24:21 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:24:21 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:24:21 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:24:21 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:24:21 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:24:21 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:24:21 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:24:21 INFO - 'talos_options': ['--disable-e10s'], 08:24:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:24:21 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:24:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:24:21 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:24:21 INFO - 'talos_options': ['--disable-e10s'], 08:24:21 INFO - 'tests': ['damp', 'tps']}, 08:24:21 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:24:21 INFO - 'tests': ['damp', 'tps']}, 08:24:21 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:24:21 INFO - 'tests': ['dromaeo_dom']}, 08:24:21 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:24:21 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:24:21 INFO - 'tests': ['basic_compositor_video']}, 08:24:21 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:24:21 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:24:21 INFO - 'tests': ['a11yr', 08:24:21 INFO - 'ts_paint', 08:24:21 INFO - 'tpaint', 08:24:21 INFO - 'sessionrestore', 08:24:21 INFO - 'sessionrestore_no_auto_restore', 08:24:21 INFO - 'tabpaint']}, 08:24:21 INFO - 'other-e10s': {'tests': ['a11yr', 08:24:21 INFO - 'ts_paint', 08:24:21 INFO - 'tpaint', 08:24:21 INFO - 'sessionrestore', 08:24:21 INFO - 'sessionrestore_no_auto_restore', 08:24:21 INFO - 'tabpaint']}, 08:24:21 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:24:21 INFO - 'ts_paint', 08:24:21 INFO - 'tpaint', 08:24:21 INFO - 'sessionrestore', 08:24:21 INFO - 'sessionrestore_no_auto_restore', 08:24:21 INFO - 'tabpaint']}, 08:24:21 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:24:21 INFO - 'ts_paint', 08:24:21 INFO - 'tpaint', 08:24:21 INFO - 'sessionrestore', 08:24:21 INFO - 'sessionrestore_no_auto_restore', 08:24:21 INFO - 'tabpaint']}, 08:24:21 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:24:21 INFO - 'tests': ['a11yr', 08:24:21 INFO - 'ts_paint', 08:24:21 INFO - 'tpaint', 08:24:21 INFO - 'sessionrestore', 08:24:21 INFO - 'sessionrestore_no_auto_restore', 08:24:21 INFO - 'tabpaint']}, 08:24:21 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:24:21 INFO - 'tests': ['a11yr', 08:24:21 INFO - 'ts_paint', 08:24:21 INFO - 'tpaint', 08:24:21 INFO - 'sessionrestore', 08:24:21 INFO - 'sessionrestore_no_auto_restore', 08:24:21 INFO - 'tabpaint']}, 08:24:21 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:24:21 INFO - 'tests': ['tsvgx', 08:24:21 INFO - 'tsvgr_opacity', 08:24:21 INFO - 'tart', 08:24:21 INFO - 'tscrollx', 08:24:21 INFO - 'cart', 08:24:21 INFO - 'tsvg_static']}, 08:24:21 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:24:21 INFO - 'tsvgr_opacity', 08:24:21 INFO - 'tart', 08:24:21 INFO - 'tscrollx', 08:24:21 INFO - 'cart', 08:24:21 INFO - 'tsvg_static']}, 08:24:21 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:24:21 INFO - 'talos_options': ['--disable-e10s'], 08:24:21 INFO - 'tests': ['tp5o']}, 08:24:21 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:24:21 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:24:21 INFO - 'talos_options': ['--disable-e10s', 08:24:21 INFO - '--xperf_path', 08:24:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:24:21 INFO - 'tests': ['tp5n']}, 08:24:21 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:24:21 INFO - 'talos_options': ['--xperf_path', 08:24:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:24:21 INFO - 'tests': ['tp5n']}}, 08:24:21 INFO - 'talos.zip': {'path': '', 08:24:21 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:24:21 INFO - Running post-action listener: _resource_record_post_action 08:24:21 INFO - [mozharness: 2016-11-25 16:24:21.710739Z] Finished populate-webroot step (success) 08:24:21 INFO - [mozharness: 2016-11-25 16:24:21.710847Z] Running create-virtualenv step. 08:24:21 INFO - Running pre-action listener: _resource_record_pre_action 08:24:21 INFO - Running main action method: create_virtualenv 08:24:21 INFO - Creating virtualenv /builds/slave/test/build/venv 08:24:21 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:24:21 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:24:21 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:24:21 INFO - Using real prefix '/tools/python27' 08:24:21 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:24:22 INFO - Installing distribute.............................................................................................................................................................................................done. 08:24:25 INFO - Installing pip.................done. 08:24:25 INFO - Return code: 0 08:24:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:24:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:24:25 INFO - Reading from file tmpfile_stdout 08:24:25 INFO - Output received: 08:24:25 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:24:25 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:24:25 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:24:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:24:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:24:25 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:24:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:24:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:24:25 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 0x1022593f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021cf570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feff0473af0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10206fd30>, '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 0x101ed6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102067b20>, '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.567Us1bYDp/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.KrB7zOnvMK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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:24:25 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:24:25 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:24:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.567Us1bYDp/Render', 08:24:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:24:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:24:25 INFO - 'HOME': '/Users/cltbld', 08:24:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:24:25 INFO - 'LOGNAME': 'cltbld', 08:24:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:24:25 INFO - 'MOZ_NO_REMOTE': '1', 08:24:25 INFO - 'NO_EM_RESTART': '1', 08:24:25 INFO - 'PAGER': '/bin/cat', 08:24:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:24:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:24:25 INFO - 'PWD': '/builds/slave/test', 08:24:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:24:25 INFO - 'SHELL': '/bin/bash', 08:24:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KrB7zOnvMK/Listeners', 08:24:25 INFO - 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', 08:24:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:24:25 INFO - 'USER': 'cltbld', 08:24:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:24:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:24:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:24:25 INFO - 'XPC_FLAGS': '0x0', 08:24:25 INFO - 'XPC_SERVICE_NAME': '0', 08:24:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:24:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:24:25 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:24:25 INFO - Cleaning up... 08:24:25 INFO - Return code: 0 08:24:25 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:24:25 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:24:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:24:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:24:25 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:24:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:24:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:24:25 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 0x1022593f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021cf570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feff0473af0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10206fd30>, '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 0x101ed6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102067b20>, '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.567Us1bYDp/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.KrB7zOnvMK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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:24:25 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:24:25 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:24:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.567Us1bYDp/Render', 08:24:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:24:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:24:25 INFO - 'HOME': '/Users/cltbld', 08:24:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:24:25 INFO - 'LOGNAME': 'cltbld', 08:24:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:24:25 INFO - 'MOZ_NO_REMOTE': '1', 08:24:25 INFO - 'NO_EM_RESTART': '1', 08:24:25 INFO - 'PAGER': '/bin/cat', 08:24:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:24:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:24:25 INFO - 'PWD': '/builds/slave/test', 08:24:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:24:25 INFO - 'SHELL': '/bin/bash', 08:24:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KrB7zOnvMK/Listeners', 08:24:25 INFO - 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', 08:24:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:24:25 INFO - 'USER': 'cltbld', 08:24:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:24:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:24:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:24:25 INFO - 'XPC_FLAGS': '0x0', 08:24:25 INFO - 'XPC_SERVICE_NAME': '0', 08:24:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:24:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:24:26 INFO - Downloading/unpacking psutil>=3.1.1 08:24:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:24:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:24:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:24:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:24:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:24:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:24:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:24:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:24:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:24:29 INFO - Installing collected packages: psutil 08:24:29 INFO - Running setup.py install for psutil 08:24:29 INFO - building 'psutil._psutil_osx' extension 08:24:29 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:24:30 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:24:30 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:24:30 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:24:30 INFO - building 'psutil._psutil_posix' extension 08:24:30 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:24:30 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:24:30 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:24:30 INFO - ^ 08:24:30 INFO - 1 warning generated. 08:24:30 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:24:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:24:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:24:30 INFO - Successfully installed psutil 08:24:30 INFO - Cleaning up... 08:24:30 INFO - Return code: 0 08:24:30 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:24: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:24:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:24:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:24: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:24:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:24:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:24: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1022593f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021cf570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feff0473af0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10206fd30>, '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 0x101ed6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102067b20>, '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.567Us1bYDp/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.KrB7zOnvMK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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:24: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:24: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 mozsystemmonitor==0.3 08:24:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.567Us1bYDp/Render', 08:24:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:24:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:24:30 INFO - 'HOME': '/Users/cltbld', 08:24:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:24:30 INFO - 'LOGNAME': 'cltbld', 08:24:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:24:30 INFO - 'MOZ_NO_REMOTE': '1', 08:24:30 INFO - 'NO_EM_RESTART': '1', 08:24:30 INFO - 'PAGER': '/bin/cat', 08:24:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:24:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:24:30 INFO - 'PWD': '/builds/slave/test', 08:24:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:24:30 INFO - 'SHELL': '/bin/bash', 08:24:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KrB7zOnvMK/Listeners', 08:24:30 INFO - 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', 08:24:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:24:30 INFO - 'USER': 'cltbld', 08:24:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:24:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:24:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:24:30 INFO - 'XPC_FLAGS': '0x0', 08:24:30 INFO - 'XPC_SERVICE_NAME': '0', 08:24:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:24:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:24:31 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:24:31 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:24:31 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:24:31 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:24:31 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:24:31 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:24:31 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:24:33 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:24:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:24:33 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:24:33 INFO - Installing collected packages: mozsystemmonitor 08:24:33 INFO - Running setup.py install for mozsystemmonitor 08:24:33 INFO - Successfully installed mozsystemmonitor 08:24:33 INFO - Cleaning up... 08:24:33 INFO - Return code: 0 08:24:33 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:24:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:24:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:24:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:24:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:24:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:24:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:24:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1022593f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021cf570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feff0473af0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10206fd30>, '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 0x101ed6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102067b20>, '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.567Us1bYDp/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.KrB7zOnvMK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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:24:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:24:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:24:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.567Us1bYDp/Render', 08:24:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:24:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:24:33 INFO - 'HOME': '/Users/cltbld', 08:24:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:24:33 INFO - 'LOGNAME': 'cltbld', 08:24:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:24:33 INFO - 'MOZ_NO_REMOTE': '1', 08:24:33 INFO - 'NO_EM_RESTART': '1', 08:24:33 INFO - 'PAGER': '/bin/cat', 08:24:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:24:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:24:33 INFO - 'PWD': '/builds/slave/test', 08:24:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:24:33 INFO - 'SHELL': '/bin/bash', 08:24:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KrB7zOnvMK/Listeners', 08:24:33 INFO - 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', 08:24:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:24:33 INFO - 'USER': 'cltbld', 08:24:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:24:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:24:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:24:33 INFO - 'XPC_FLAGS': '0x0', 08:24:33 INFO - 'XPC_SERVICE_NAME': '0', 08:24:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:24:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:24:34 INFO - Downloading/unpacking jsonschema==2.5.1 08:24:34 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:24:34 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:24:34 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:24:34 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:24:34 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:24:34 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:24:36 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:24:36 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:24:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:24:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:24:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:24:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:24:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:24:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:24:37 INFO - Downloading functools32-3.2.3-2.tar.gz 08:24:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:24:37 INFO - warning: no files found matching '*.txt' 08:24:37 INFO - no previously-included directories found matching 'build' 08:24:37 INFO - no previously-included directories found matching 'dist' 08:24:37 INFO - no previously-included directories found matching '.git*' 08:24:37 INFO - Installing collected packages: jsonschema, functools32 08:24:37 INFO - Running setup.py install for functools32 08:24:37 INFO - warning: no files found matching '*.txt' 08:24:37 INFO - no previously-included directories found matching 'build' 08:24:37 INFO - no previously-included directories found matching 'dist' 08:24:37 INFO - no previously-included directories found matching '.git*' 08:24:37 INFO - Successfully installed jsonschema functools32 08:24:37 INFO - Cleaning up... 08:24:37 INFO - Return code: 0 08:24:37 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:24:37 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:24:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:24:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:24:37 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:24:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:24:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:24:37 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 0x1022593f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021cf570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feff0473af0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10206fd30>, '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 0x101ed6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102067b20>, '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.567Us1bYDp/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.KrB7zOnvMK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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:24:37 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:24:37 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:24:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.567Us1bYDp/Render', 08:24:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:24:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:24:37 INFO - 'HOME': '/Users/cltbld', 08:24:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:24:37 INFO - 'LOGNAME': 'cltbld', 08:24:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:24:37 INFO - 'MOZ_NO_REMOTE': '1', 08:24:37 INFO - 'NO_EM_RESTART': '1', 08:24:37 INFO - 'PAGER': '/bin/cat', 08:24:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:24:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:24:37 INFO - 'PWD': '/builds/slave/test', 08:24:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:24:37 INFO - 'SHELL': '/bin/bash', 08:24:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KrB7zOnvMK/Listeners', 08:24:37 INFO - 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', 08:24:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:24:37 INFO - 'USER': 'cltbld', 08:24:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:24:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:24:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:24:37 INFO - 'XPC_FLAGS': '0x0', 08:24:37 INFO - 'XPC_SERVICE_NAME': '0', 08:24:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:24:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:24:37 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:24:37 INFO - Cleaning up... 08:24:37 INFO - Return code: 0 08:24:37 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:24:37 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:24:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:24:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:24:37 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:24:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:24:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:24:37 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 0x1022593f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021cf570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feff0473af0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10206fd30>, '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 0x101ed6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102067b20>, '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.567Us1bYDp/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.KrB7zOnvMK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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:24:37 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:24:37 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:24:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.567Us1bYDp/Render', 08:24:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:24:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:24:37 INFO - 'HOME': '/Users/cltbld', 08:24:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:24:37 INFO - 'LOGNAME': 'cltbld', 08:24:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:24:37 INFO - 'MOZ_NO_REMOTE': '1', 08:24:37 INFO - 'NO_EM_RESTART': '1', 08:24:37 INFO - 'PAGER': '/bin/cat', 08:24:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:24:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:24:37 INFO - 'PWD': '/builds/slave/test', 08:24:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:24:37 INFO - 'SHELL': '/bin/bash', 08:24:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KrB7zOnvMK/Listeners', 08:24:37 INFO - 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', 08:24:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:24:37 INFO - 'USER': 'cltbld', 08:24:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:24:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:24:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:24:37 INFO - 'XPC_FLAGS': '0x0', 08:24:37 INFO - 'XPC_SERVICE_NAME': '0', 08:24:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:24:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:24:38 INFO - Downloading/unpacking blobuploader==1.2.4 08:24: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:24: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:24: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:24: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:24: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:24: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:24:40 INFO - Downloading blobuploader-1.2.4.tar.gz 08:24:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:24:40 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:24:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:24:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:24:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:24:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:24:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:24:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:24:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:24:41 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:24: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:24: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:24: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:24: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:24: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:24: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:24:41 INFO - Downloading docopt-0.6.1.tar.gz 08:24:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:24:41 INFO - Installing collected packages: blobuploader, requests, docopt 08:24:41 INFO - Running setup.py install for blobuploader 08:24:41 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:24:42 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:24:42 INFO - Running setup.py install for requests 08:24:42 INFO - Running setup.py install for docopt 08:24:42 INFO - Successfully installed blobuploader requests docopt 08:24:42 INFO - Cleaning up... 08:24:42 INFO - Return code: 0 08:24:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:24: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:24:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:24:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:24: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:24:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:24:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:24:42 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 0x1022593f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021cf570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feff0473af0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10206fd30>, '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 0x101ed6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102067b20>, '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.567Us1bYDp/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.KrB7zOnvMK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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:24:42 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:24:42 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:24:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.567Us1bYDp/Render', 08:24:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:24:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:24:42 INFO - 'HOME': '/Users/cltbld', 08:24:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:24:42 INFO - 'LOGNAME': 'cltbld', 08:24:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:24:42 INFO - 'MOZ_NO_REMOTE': '1', 08:24:42 INFO - 'NO_EM_RESTART': '1', 08:24:42 INFO - 'PAGER': '/bin/cat', 08:24:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:24:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:24:42 INFO - 'PWD': '/builds/slave/test', 08:24:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:24:42 INFO - 'SHELL': '/bin/bash', 08:24:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KrB7zOnvMK/Listeners', 08:24:42 INFO - 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', 08:24:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:24:42 INFO - 'USER': 'cltbld', 08:24:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:24:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:24:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:24:42 INFO - 'XPC_FLAGS': '0x0', 08:24:42 INFO - 'XPC_SERVICE_NAME': '0', 08:24:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:24:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:24:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:24:43 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-ErEBXw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:24:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:24:43 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-aFybGb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:24:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:24:43 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-Qd3auP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:24:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:24:43 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-y2dkW7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:24:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:24:43 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-dzWRJN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:24:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:24:43 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-Q7xwRL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:24:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:24:43 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-XYw_89-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:24:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:24:43 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-7wikh3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:24:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:24:43 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-LgTb_u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:24:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:24:43 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-03cAlI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:24:44 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-cnlZby-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:24:44 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-qP0LPj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:24:44 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-zXPyz6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:24:44 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-IKiR6Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:24:44 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-IChrR2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:24:44 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-12TEyc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:24:44 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-SePGnt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:24:44 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:24:44 INFO - Running setup.py install for manifestparser 08:24:44 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:24:44 INFO - Running setup.py install for mozcrash 08:24:45 INFO - Running setup.py install for mozdebug 08:24:45 INFO - Running setup.py install for mozdevice 08:24:45 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:24:45 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:24:45 INFO - Running setup.py install for mozfile 08:24:45 INFO - Running setup.py install for mozhttpd 08:24:45 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:24:45 INFO - Running setup.py install for mozinfo 08:24:45 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:24:45 INFO - Running setup.py install for mozInstall 08:24:45 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:24:45 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:24:45 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:24:45 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:24:45 INFO - Running setup.py install for mozleak 08:24:46 INFO - Running setup.py install for mozlog 08:24:46 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:24:46 INFO - Running setup.py install for moznetwork 08:24:46 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:24:46 INFO - Running setup.py install for mozprocess 08:24:46 INFO - Running setup.py install for mozprofile 08:24:46 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:24:46 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:24:46 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:24:46 INFO - Running setup.py install for mozrunner 08:24:46 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:24:46 INFO - Running setup.py install for mozscreenshot 08:24:47 INFO - Running setup.py install for moztest 08:24:47 INFO - Running setup.py install for mozversion 08:24:47 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:24:47 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:24:47 INFO - Cleaning up... 08:24:47 INFO - Return code: 0 08:24:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:24: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:24:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:24:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:24: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:24:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:24:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:24:47 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 0x1022593f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021cf570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feff0473af0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10206fd30>, '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 0x101ed6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102067b20>, '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.567Us1bYDp/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.KrB7zOnvMK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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:24:47 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:24:47 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:24:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.567Us1bYDp/Render', 08:24:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:24:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:24:47 INFO - 'HOME': '/Users/cltbld', 08:24:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:24:47 INFO - 'LOGNAME': 'cltbld', 08:24:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:24:47 INFO - 'MOZ_NO_REMOTE': '1', 08:24:47 INFO - 'NO_EM_RESTART': '1', 08:24:47 INFO - 'PAGER': '/bin/cat', 08:24:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:24:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:24:47 INFO - 'PWD': '/builds/slave/test', 08:24:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:24:47 INFO - 'SHELL': '/bin/bash', 08:24:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KrB7zOnvMK/Listeners', 08:24:47 INFO - 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', 08:24:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:24:47 INFO - 'USER': 'cltbld', 08:24:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:24:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:24:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:24:47 INFO - 'XPC_FLAGS': '0x0', 08:24:47 INFO - 'XPC_SERVICE_NAME': '0', 08:24:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:24:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:24:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:24:47 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-HGaH5_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:24:47 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:24:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:24:47 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-QNduNB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:24:47 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:24:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:24:47 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-OOQ3zw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:24:47 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:24:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:24:47 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-dX9m5v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:24:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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:24:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:24:48 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-Jwe_yT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:24:48 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:24:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:24:48 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-SlpGH1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:24:48 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:24:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:24:48 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-4V41mY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:24:48 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:24:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:24:48 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-amfSh4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:24:48 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:24:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:24:48 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-SAeFrS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:24:48 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:24:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:24:48 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-GBNLOn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:24:48 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:24:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:24:48 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-qtadAD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:24:48 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:24:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:24:48 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-Rw64Qh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:24:48 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:24:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:24:48 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-BmjTMf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:24:49 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:24:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:24:49 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-QikrKL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:24:49 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:24:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:24:49 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-l27oV7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:24:49 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:24:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:24:49 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-CiYoXn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:24:49 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:24:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:24:49 INFO - Running setup.py (path:/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/pip-yQVBxY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:24:49 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:24:49 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:24:49 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:24:49 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:24:49 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:24:49 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:24:49 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:24:49 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:24:49 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:24:49 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:24:49 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:24:49 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:24:49 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:24:51 INFO - Downloading blessings-1.6.tar.gz 08:24:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:24:52 INFO - Installing collected packages: blessings 08:24:52 INFO - Running setup.py install for blessings 08:24:52 INFO - Successfully installed blessings 08:24:52 INFO - Cleaning up... 08:24:52 INFO - Return code: 0 08:24:52 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:24:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:24:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:24:52 INFO - Reading from file tmpfile_stdout 08:24:52 INFO - Current package versions: 08:24:52 INFO - blessings == 1.6 08:24:52 INFO - blobuploader == 1.2.4 08:24:52 INFO - docopt == 0.6.1 08:24:52 INFO - functools32 == 3.2.3-2 08:24:52 INFO - jsonschema == 2.5.1 08:24:52 INFO - manifestparser == 1.1 08:24:52 INFO - mozInstall == 1.12 08:24:52 INFO - mozcrash == 1.0 08:24:52 INFO - mozdebug == 0.1 08:24:52 INFO - mozdevice == 0.49 08:24:52 INFO - mozfile == 1.2 08:24:52 INFO - mozhttpd == 0.7 08:24:52 INFO - mozinfo == 0.9 08:24:52 INFO - mozleak == 0.1 08:24:52 INFO - mozlog == 3.4 08:24:52 INFO - moznetwork == 0.27 08:24:52 INFO - mozprocess == 0.23 08:24:52 INFO - mozprofile == 0.28 08:24:52 INFO - mozrunner == 6.13 08:24:52 INFO - mozscreenshot == 0.1 08:24:52 INFO - mozsystemmonitor == 0.3 08:24:52 INFO - moztest == 0.7 08:24:52 INFO - mozversion == 1.4 08:24:52 INFO - psutil == 3.1.1 08:24:52 INFO - requests == 1.2.3 08:24:52 INFO - wsgiref == 0.1.2 08:24:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:24:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:24:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:24:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:24:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:24:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:24:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:24:53 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 0x1022593f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021cf570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feff0473af0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10206fd30>, '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 0x101ed6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102067b20>, '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.567Us1bYDp/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.KrB7zOnvMK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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:24:53 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:24:53 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:24:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.567Us1bYDp/Render', 08:24:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:24:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:24:53 INFO - 'HOME': '/Users/cltbld', 08:24:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:24:53 INFO - 'LOGNAME': 'cltbld', 08:24:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:24:53 INFO - 'MOZ_NO_REMOTE': '1', 08:24:53 INFO - 'NO_EM_RESTART': '1', 08:24:53 INFO - 'PAGER': '/bin/cat', 08:24:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:24:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:24:53 INFO - 'PWD': '/builds/slave/test', 08:24:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:24:53 INFO - 'SHELL': '/bin/bash', 08:24:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KrB7zOnvMK/Listeners', 08:24:53 INFO - 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', 08:24:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:24:53 INFO - 'USER': 'cltbld', 08:24:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:24:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:24:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:24:53 INFO - 'XPC_FLAGS': '0x0', 08:24:53 INFO - 'XPC_SERVICE_NAME': '0', 08:24:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:24:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:24:53 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:24:53 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:24:53 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:24:53 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:24:53 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:24:53 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:24:53 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:24:53 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:24:53 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:24:53 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:24:53 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:24:53 INFO - Cleaning up... 08:24:53 INFO - Return code: 0 08:24:53 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:24:53 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:24:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:24:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:24:53 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:24:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:24:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:24:53 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 0x1022593f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021cf570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feff0473af0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10206fd30>, '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 0x101ed6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102067b20>, '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.567Us1bYDp/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.KrB7zOnvMK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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:24:53 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:24:53 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:24:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.567Us1bYDp/Render', 08:24:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:24:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:24:53 INFO - 'HOME': '/Users/cltbld', 08:24:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:24:53 INFO - 'LOGNAME': 'cltbld', 08:24:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:24:53 INFO - 'MOZ_NO_REMOTE': '1', 08:24:53 INFO - 'NO_EM_RESTART': '1', 08:24:53 INFO - 'PAGER': '/bin/cat', 08:24:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:24:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:24:53 INFO - 'PWD': '/builds/slave/test', 08:24:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:24:53 INFO - 'SHELL': '/bin/bash', 08:24:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KrB7zOnvMK/Listeners', 08:24:53 INFO - 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', 08:24:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:24:53 INFO - 'USER': 'cltbld', 08:24:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:24:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:24:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:24:53 INFO - 'XPC_FLAGS': '0x0', 08:24:53 INFO - 'XPC_SERVICE_NAME': '0', 08:24:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:24:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:24:54 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:24:54 INFO - Cleaning up... 08:24:54 INFO - Return code: 0 08:24:54 INFO - Running post-action listener: _resource_record_post_action 08:24:54 INFO - Running post-action listener: _start_resource_monitoring 08:24:54 INFO - Starting resource monitoring. 08:24:54 INFO - [mozharness: 2016-11-25 16:24:54.188245Z] Finished create-virtualenv step (success) 08:24:54 INFO - [mozharness: 2016-11-25 16:24:54.189266Z] Running install step. 08:24:54 INFO - Running pre-action listener: _resource_record_pre_action 08:24:54 INFO - Running main action method: install 08:24:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:24:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:24:54 INFO - Reading from file tmpfile_stdout 08:24:54 INFO - Detecting whether we're running mozinstall >=1.0... 08:24:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:24:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:24:54 INFO - Reading from file tmpfile_stdout 08:24:54 INFO - Output received: 08:24:54 INFO - Usage: mozinstall [options] installer 08:24:54 INFO - Options: 08:24:54 INFO - -h, --help show this help message and exit 08:24:54 INFO - -d DEST, --destination=DEST 08:24:54 INFO - Directory to install application into. [default: 08:24:54 INFO - "/builds/slave/test"] 08:24:54 INFO - --app=APP Application being installed. [default: firefox] 08:24:54 INFO - mkdir: /builds/slave/test/build/application 08:24:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:24:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 08:25:27 INFO - Reading from file tmpfile_stdout 08:25:27 INFO - Output received: 08:25:27 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:25:27 INFO - Running post-action listener: _resource_record_post_action 08:25:27 INFO - [mozharness: 2016-11-25 16:25:27.011442Z] Finished install step (success) 08:25:27 INFO - [mozharness: 2016-11-25 16:25:27.011578Z] Running run-tests step. 08:25:27 INFO - Running pre-action listener: _resource_record_pre_action 08:25:27 INFO - Running main action method: run_tests 08:25:27 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:25:27 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:25:27 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:25:27 INFO - 2016-11-25 08:25:27.026 screenresolution[1628:7426] starting screenresolution argv=screenresolution get 08:25:27 INFO - 2016-11-25 08:25:27.028 screenresolution[1628:7426] Display 0: 1600x1200x32@60 08:25:27 INFO - 2016-11-25 08:25:27.034 screenresolution[1629:7428] starting screenresolution argv=screenresolution list 08:25:27 INFO - Available Modes on Display 0 08:25:27 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:25:27 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:25:27 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:25:27 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:25:27 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:25:27 INFO - Intel Iris: 08:25:27 INFO - Chipset Model: Intel Iris 08:25:27 INFO - Type: GPU 08:25:27 INFO - Bus: Built-In 08:25:27 INFO - VRAM (Dynamic, Max): 1536 MB 08:25:27 INFO - Vendor: Intel (0x8086) 08:25:27 INFO - Device ID: 0x0a2e 08:25:27 INFO - Revision ID: 0x0009 08:25:27 INFO - Displays: 08:25:27 INFO - Display: 08:25:27 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:25:27 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:25:27 INFO - Display Serial Number: 1600x1200 60 08:25:27 INFO - Main Display: Yes 08:25:27 INFO - Mirror: Off 08:25:27 INFO - Online: Yes 08:25:27 INFO - Rotation: Supported 08:25:27 INFO - Return code: 0 08:25:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:25:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:25:27 INFO - Python 2.7.3 08:25:27 INFO - Return code: 0 08:25:27 INFO - grabbing minidump binary from tooltool 08:25:27 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:25:27 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 0x10206fd30>, '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 0x101ed6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102067b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:25:27 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:25:27 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:25:27 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:25:27 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:25:27 INFO - Return code: 0 08:25:27 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:25:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:25:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:25:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:25:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:25:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/eJum9pLRREqPofrssXCEwA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0036', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 08:25:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/eJum9pLRREqPofrssXCEwA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0036 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 08:25:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.567Us1bYDp/Render', 08:25:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:25:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:25:27 INFO - 'HOME': '/Users/cltbld', 08:25:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:25:27 INFO - 'LOGNAME': 'cltbld', 08:25:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:25:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:25:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:25:27 INFO - 'MOZ_NO_REMOTE': '1', 08:25:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:25:27 INFO - 'NO_EM_RESTART': '1', 08:25:27 INFO - 'PAGER': '/bin/cat', 08:25:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:25:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:25:27 INFO - 'PWD': '/builds/slave/test', 08:25:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:25:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:25:27 INFO - 'SHELL': '/bin/bash', 08:25:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KrB7zOnvMK/Listeners', 08:25:27 INFO - 'TMPDIR': '/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/', 08:25:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:25:27 INFO - 'USER': 'cltbld', 08:25:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:25:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:25:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:25:27 INFO - 'XPC_FLAGS': '0x0', 08:25:27 INFO - 'XPC_SERVICE_NAME': '0', 08:25:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:25:27 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/eJum9pLRREqPofrssXCEwA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0036', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 08:25:27 INFO - mozversion application_buildid: 20161125062719 08:25:27 INFO - mozversion application_changeset: c41dab3b9a7230a377d68d30244bd201258ce10d 08:25:27 INFO - mozversion application_display_name: Nightly 08:25:27 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:25:27 INFO - mozversion application_name: Firefox 08:25:27 INFO - mozversion application_remotingname: firefox 08:25:27 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:25:27 INFO - mozversion application_vendor: Mozilla 08:25:27 INFO - mozversion application_version: 53.0a1 08:25:27 INFO - mozversion platform_buildid: 20161125062719 08:25:27 INFO - mozversion platform_changeset: c41dab3b9a7230a377d68d30244bd201258ce10d 08:25:27 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:25:27 INFO - mozversion platform_version: 53.0a1 08:25:27 INFO - using testdate: 1480091127 08:25:27 INFO - actual date: 1480091127 08:25:27 INFO - starting webserver on 'localhost:49249' 08:25:27 INFO - SUITE-START | Running 2 tests 08:25:27 INFO - TEST-START | dromaeo_css 08:25:27 INFO - operating with platform_type : mac_ 08:25:27 INFO - Initialising browser for dromaeo_css test... 08:25:27 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/tmp3B9Bj5/profile http://localhost:49249/getInfo.html) 08:25:30 INFO - PROCESS | 1639 | 2016-11-25 08:25:30.926 plugin-container[1641:7598] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f37, name = 'com.apple.tsm.portname' 08:25:30 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:25:30 INFO - PROCESS | 1639 | 2016-11-25 08:25:30.929 plugin-container[1641:7598] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9113, name = 'com.apple.CFPasteboardClient' 08:25:30 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:25:30 INFO - PROCESS | 1639 | 2016-11-25 08:25:30.929 plugin-container[1641:7598] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:25:30 INFO - PROCESS | 1639 | __metrics Screen width/height:1600/1200 08:25:30 INFO - PROCESS | 1639 | colorDepth:24 08:25:30 INFO - PROCESS | 1639 | Browser inner width/height: 1024/689 08:25:30 INFO - PROCESS | 1639 | __metrics 08:25:31 ERROR - PROCESS | 1639 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3198: TypeError: this.tabs is undefined 08:25:31 INFO - TEST-INFO | 1639: exit 0 08:25:31 INFO - Browser initialized. 08:25:31 INFO - Running cycle 1/1 for dromaeo_css test... 08:25:31 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/tmp3B9Bj5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:25:34 INFO - PROCESS | 1642 | 2016-11-25 08:25:34.609 plugin-container[1643:7721] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.tsm.portname' 08:25:34 INFO - PROCESS | 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:25:34 INFO - PROCESS | 1642 | 2016-11-25 08:25:34.610 plugin-container[1643:7721] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x910b, name = 'com.apple.CFPasteboardClient' 08:25:34 INFO - PROCESS | 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:25:34 INFO - PROCESS | 1642 | 2016-11-25 08:25:34.610 plugin-container[1643:7721] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:25:44 INFO - PROCESS | 1642 | RSS: Main: 242327552 08:25:44 INFO - PROCESS | 1642 | 08:26:33 INFO - PROCESS | 1642 | 1480091193833 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:28:46 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49249/tests/dromaeo/cssquery-ext.html) 08:28:48 INFO - PROCESS | 1642 | RSS: Main: 251281408 08:28:48 INFO - PROCESS | 1642 | 08:31:50 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-ext.html (next: http://localhost:49249/tests/dromaeo/cssquery-jquery.html) 08:31:51 INFO - PROCESS | 1642 | RSS: Main: 261451776 08:31:51 INFO - PROCESS | 1642 | 08:34:53 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49249/tests/dromaeo/cssquery-mootools.html) 08:34:53 INFO - PROCESS | 1642 | RSS: Main: 267403264 08:34:53 INFO - PROCESS | 1642 | 08:37:55 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49249/tests/dromaeo/cssquery-prototype.html) 08:37:56 INFO - PROCESS | 1642 | RSS: Main: 266035200 08:37:56 INFO - PROCESS | 1642 | 08:40:59 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49249/tests/dromaeo/cssquery-yui.html) 08:40:59 INFO - PROCESS | 1642 | RSS: Main: 270565376 08:40:59 INFO - PROCESS | 1642 | 08:44:02 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-yui.html (next: http://localhost:49249/tests/dromaeo/cssquery-dojo.html) 08:44:03 INFO - PROCESS | 1642 | RSS: Main: 273174528 08:44:03 INFO - PROCESS | 1642 | 08:44:03 INFO - PROCESS | 1642 | __start_tp_report 08:44:03 INFO - PROCESS | 1642 | _x_x_mozilla_page_load 08:44:03 INFO - PROCESS | 1642 | _x_x_mozilla_page_load_details 08:44:03 INFO - PROCESS | 1642 | |i|pagename|runs| 08:44:03 INFO - PROCESS | 1642 | |0;dojo.html;3106.829124398157;3298.2249171444714;3339.9666003339958;3350.648181940896;3358.4626459766428;4802.59177969873;5131.307273518069;5256.132738098209;5525.11598144297;5563.36021357544;4175.290200665887;4277.700560960734;4449.822007119711;5600.635958662684;5620.971895140518;4371;5017.974910125463;5123.103456895028;5186.688798672092;5221.425643179247;4520.2089634314025;4578.809509527514;4609.5620916013095;4720.221163508034;5210.843674689746;4057.6145266199665;5036.823711170092;5177.48225177483;5197.896042079156;5253.763580638881;5063.594912406998;5110.182370820651;5137.614678899097;5183.092958732207;5225.529702326771;5099.36257967754;5105.387353517602;5108.131617625013;5112.437631860493;5116.820911268088;5539.113741801333;5608.747606357723;5636.2954630671165;5641.915371269434;5660.858478538028;5955.940440595626;6012.368701286389;6026.9095963560585;6032.396760323976;6079.179310793031;6129.38706129388;6170.382961703838;6182.103594978703;6193.845153871144;6200.348963358828;5695.145728140729;5705.515031222351;5709.34342550612;5718.199452076671;5732.31212254528;6116.449519543263;6122.2761798032625;6134.171886795315;6172.166757487771;6201.906971395432;5943.732532036069;5946.197263369476;5967;5978.372270911578;6040.6677632730625;6092;6100.115483254903;6105.908411373833;6108.572399932051;6113.113598528189;4560.657950653715;5885.5585831062845;5986;6031.2159419276095;6055.878882422327;4784.545468180518;6064.3935606439;6068.75724971005;6070.271567411939;6086.02623580225;4772;4802.159622066124;4806.423229212447;4819.542143496362;4840.467548569654;4206.747595144301;4235.470566179228;4296.2696341621995;4322.762248076323;4372.40972468719;5542.224088627595;5670.262865827416;5689.459501347365;5696.174054762037;5722.656640601494;6114.663693496811;6136.17161683176;6162.22972128484;6181.907271390933;6206.503479721701;5517.089680202802;5551.167324901297;5648.717564121777;5678.77284908599;5700.315962084494;4843.2735089736825;5238.790448382022;5245.921311180336;5259.211118332204;5262.10544207478;5644.604877658525;6193.783217587363;6204.782832601018;6224.06639004153;6296.118543404019;6121.265448146251;6163.137160797582;6164.383561643979;6171.290301615192;6176.289726681489;5469.835904922858;5856.682246494504;6086.3305036446845;6116.335771508327;6123.326434092335;5659.660420374791;5681.573881958787;5686.943130568641;5730.74211660468;5731.656100633975;4378.824847006084;4679.251319788817;4787.61699064067;5452.5823285946735;5750.453706897838;5039.79045029183;5089.491050894881;5263.842084737464;5618.915716264178;5644.943550564606;4781.569658730731;5371.865703357291;5946.1972633693895;5947.197128387612;5968.492678122235;4680.4149481314835;5546.168074788652;5727.942720572908;5767.942320576908;5829.737661805314;6013.5189184865985;6032.577719559588;6049.243844519435;6051.697415129314;6067.817965461043;5827.213326200908;5840.2115714378015;5891.469767720754;5914.733836977261;5947.256592925884 08:44:03 INFO - PROCESS | 1642 | |1;ext.html;455.8791920141161;492.1706923833341;492.9721530608681;530.501328750974;538.9784408623656;31250.68749312506;40075.599244007586;40109.99725006873;40350.19299614006;41845.58154418455;40649;41045;41073.97315067125;41125.38311925324;41171.38242926351;8256.793580160484;9181.862272065924;9376.20302274304;9394.718158455258;9413.952930235338;8403.243708066273;11045.668629941074;11280.548778048867;11343.659690209306;11459.59891403801;10997.450127493634;11198.888011119867;11222.831657525143;11249.268797528175;11311.660650180467;11224.607138750105;11240.100791936626;11279.266847654877;11281.661550153467;11291.774164516664;11084.667459976214;11118.888811111867;11131.109511239163;11199.272047316897;11220.990110889978;38758.22483550313;38775.612243877484;38817.61182388196;39005.80497097496;39112;3374.578177727784;3537.6108628050893;3892.8442862285474;3925.2934471795065;3970.7419017763755;29812.8509357454;29866.40267194644;30230;30794.922177723896;30838.53742193869;3139.073973177918;3141.8429078545983;3165.6201255849446;3208.6630903755004;3217.758668099902;461925;465899;466007;467572;467952;57593.71203144041;57862.7106864463;57892.13161802576;57913;58126.4187358121;59700.80598388008;59814.80370392655;60198;60239.698801505714;60358.698206508685;14852.405903763729;15326.463573774865;15343.61640958985;15369.615759605877;15381.846181538265;10727.892721072845;10805.081568066802;11586.652400428;11955.52177912891;11996.700082498008;15917.761233581505;16147.434839780552;16171.757423638655;16191.595210119605;16217.108059056629;4942.851714448572;5385.192221166856;5530.834074977757;6353.904691429632;6379.71291291893;847.4745657692271;925.717656114878;970.257752819094;978.2760757039739;1020.9846852297073;26026.609600856;28409.857950710113;28534;29689.40621187564;29926.700732992827;993.1012433747326;993.6919554937992;1122.1752012270974;1165.1960147498198;1200.3338147328143;12515.123941324202;13241.139325943783;14874.553763387115;15225.31486083129;15359.923200383926;486994;717223;723774;731205;853417;48672.75663621659;55903.44096558982;56400.43599564034;56581.15128273079;56595;1335.1121504199598;1341.9731605367838;1352.3238380809596;1375.1474086066503;1375.587323802855;29448.852755736083;29568;29630;30165.396692066475;30284.84857575698;48712.75643621901;51100.488995109576;52385.214221787464;52560.94878102493;55024;481662;484722;485058;485481;486765;22700;25084.62373064442;25364.492710145325;25603.743962560882;25686.871565642054;36352.63647363493;44122.338164928195;45019.32471012872;45835.77082114568;46599.64752898011;30491.542626861057;38098;38104.618953811216;38972.61027389803;39189.41215881707;33592.83203584064;37236.255274894895;37828.43257351087;38089.49330993599;38420.61579384282;30443.695563044086;32878.34243315168;32987.87651543557;36780.26439471249;37878.053048674;32290.515642265855;33023.339533208724;33293.667063329056;33552.49671254884;33558.66441335653;20028.399148025583;24308.149214777397;24544.877275613508;25037;25048.62427063559 08:44:03 INFO - PROCESS | 1642 | |2;jquery.html;341.6822355209655;366.8789299531155;374.12828110502534;383.41146340367544;396.6033966033966;16443.506694799144;18082.186301616443;19464.70802937957;19678.60642787145;19836.9008154959;18495.260189592434;18657.813421865812;18890.527736806587;19080.522986925334;19454.319098831544;5678.6024978251535;5891.911621325662;5979.1031345298115;5980.31226408962;5987.461128498434;5564.749586268627;5796.44933731297;5854;5857.941420585782;5871.647701137946;4997.900041999158;5816.243888294516;5820.156077368779;5822.7088645567765;5824.708764561776;5451.536619387357;5710.343310519302;5765.4811067004175;5809.883802323972;5819.650820950714;5458.6724796511935;5608.971955140199;5618.719064046782;5623.606347555673;5754.424557544212;12691.873081269161;12757.617271481871;12849.807252891214;12857.807132893015;12910.54813081547;1895.3934740882924;2184.049938276839;2293.621552443845;2316.2703748319227;2316.8841557922206;9743.658971935949;9767.16979056774;10099.747506312327;10257.948710256476;10263.178945684402;1807.8192180781948;1823.8905665660102;1859.5444116191484;1872.6254749050245;1874.6157037807218;115248.42375788151;119707;124147.75852241543;124581.37709311486;127472.0879186813;10910.890891091147;11350.262232954832;11393.772124557463;11395.886041139649;11644.708882278012;14112.2943852809;14254.928725356307;14288.428462861577;14293.356798944073;14297.64255893611;7849.4897831640765;8121.187881211831;8162;8206.630701618442;8261.958690206631;7884.802879928047;7913.366930645535;7915.248051435105;8010.239027292398;8076.717314894068;3993.740406873544;4232.984083819861;4370.628496577763;4421.159979603865;4440.822367105344;1709.6495218480181;1829.44202018383;1869.2242719271621;1924.220690620301;1926.306529649325;761.5430741555023;785.7092875636051;838.3837879158814;843.9282660973886;992.7319623701704;16313.34746610146;16748.497545073667;20237.08933098014;20709.896450517652;20867.686984695243;889.0531583863294;914.7530166854912;915.8168366326761;920.3097676742443;931.9627214911328;5687.184843502493;6122.387761223931;10214.693559193236;10626.787464250672;10684.30552014132;565679;574515;574599;576757;577338;105523;120764.79235207535;121530;125262;132394.67605324017;438.9363542286351;439.3080897586314;449.11300182140013;449.65152007194683;449.95725406085717;11042.668719938414;11443.599474018702;11468.738438771561;11566.07471402269;11701.2979221247;53165.7341713289;54536;54828.72585637046;55110;55462.168067479826;63485.73028539496;74031;78118;79916.80124798248;80934.38131237225;7833.804154896173;8076.394270429624;8078.6364613593705;8098.109207986999;8280.089190189196;11895.405229738306;12290;12341.136120471481;12724.872751272369;12864.678383040124;9384.155426011692;10548.419836908737;10768.246222764645;10934.945325273322;11075.003249707564;9634.277429192978;10436.686899393031;10515.474226288808;10882.455877206266;11063.778724425627;10845.511951962339;10861.891381086089;11046.613368532062;11258.549658013588;11320.490577924178;7884.684612615431;8014.479058861155;8082.070561885458;8117.512949223065;8150.225728555778;8836.513991730517;10585.52365143555;10664.360138391721;10667.466626668791;10960.28758130719 08:44:03 INFO - PROCESS | 1642 | |3;mootools.html;1392.0673148990177;1451.1003178029623;1617.5632579203616;1775.9556011099728;1782.7860656721195;7395.963020184891;7618.96190519048;7859.449838511299;8339.791505212374;8348.998120225566;7860.960695196531;8112.391570632197;8124.146964568709;8232.300254478376;8234.135415781346;2252.155441709359;2290.5075408787106;2291.679164916913;2298;2300.930972070832;1920.932767353143;2269.818414526842;2300.447892505795;2305.2738387408;2316.745158032615;2281.4524514116574;2285.565742508926;2303.527776805759;2304.665823455598;2308.4690521180137;2265.7394399644086;2266.478709896725;2285.2344464604375;2289.1186893046297;2291.713535808024;2251.212075773466;2284.942876428086;2314.0859360552486;2317.8725170115636;2322.6864373309727;8189.836203275901;8225.300849427806;8240.876386854203;8255.298299644537;8262.462939908886;5009.7495125243595;5127.974360128175;5167.018266529385;5169.25045868347;5172.353455818023;3150.4329220740256;3226.2418331692033;3449.344624521358;3464.8440820163155;3467.081223475756;1011.7723512209694;1027.547878933267;1046.9424181670006;1075.876274228458;1094.9014588686978;10554.894451055468;10566.52450639723;10567.15462762977;10668.359898406043;10678.1991350649;4438.822447102144;4472.418585583853;4542.295944128666;4598.793054312564;4654.743989080633;9179.586918588546;9255.259579233645;9259.120383563552;9275.489848058287;9312.8603070954;5123.718195499283;5129.333186685782;5137.743112844343;5138.049460849755;5138.07514647367;4223.852165174266;4359.9536111333055;4547.454305483363;4683.648726345537;4717.764111794397;3372.9494057589154;3577.606463289036;3746.3069332173636;3790.3177428063213;3796.6393192646656;1078.6063086973197;1082.3289560472558;1090.3512410115968;1091.7434402915308;1098.4507746126937;779.719301051621;880.308957468397;883.7127933421664;913.7213149989184;936.3024546712743;5482.725863706799;5564.888702225933;5595.692236926927;5735.4551317624755;5814.883702325929;918.1644703319947;940.3966237564936;948.9003654616304;971.4996776660025;980.632262901412;3601.603823579404;3771.2268984858038;3854.7494412863075;3880.902977425587;3923.058465968147;9596.23230141587;9826.950865245628;9856;9937;9951.104400603981;9440.57517411718;9624.566894489764;9676.661316853877;9825.656102036395;9861.852072218922;7598.468107232441;7745.922540774633;7749.225077492206;7762.2237776223055;7769.961150194326;4118.5263694674295;5394.892102157856;5404.297441332602;5509.669419834822;5622.638491833358;9099.135582119688;9120.087991201091;9192.908070919473;9240.445573265626;9327.580258888502;9289.396189247676;9392.624295028123;9412.764680883032;9427.811443770948;9473.857892131486;6155.815325540241;6368.4586810121655;6481.449076828522;6559.180102487189;6734.8653026940165;7754.379649627904;7903;7905.683772649029;7912.723054693058;7941.642626081955;6947.861042779217;7040.260772618802;7068.71725130989;7086.858262834611;7176.425885929218;6757.898631520629;6966.721331146698;6983.580985140908;6988.8951665724035;7075.681594328371;6263.279722831932;6407.295197528361;6479.935200647933;6492.156019717407;6509.967450162719;4806.49531799156;4827;4854.854354369375;4870.878228044327;4924.212126059813;4718.551737584924;4819.3252944588485;4835.685680430761;4938.728369939688;4954.653174277766 08:44:03 INFO - PROCESS | 1642 | |4;prototype.html;259.76361511024965;293.91770304314775;308.24941268012384;310.4102205808963;311.0667996011964;4721.527847215272;5205.843824685256;5296.152615581508;5337.4662533746605;5424.945750542493;4469.128519938613;5091;5278.445763194857;5331.413544510101;5337.11937530307;3458.9654103458893;4059.8632382932824;4143.544210136897;4153.854615088472;4201.264778663729;3791.8007555617814;3897.941530877039;4135.986683262605;4201.579842015789;4208.559035759267;3085.9662013225598;3174.68253174683;3485.3552092862774;4008.6793056555553;4064.695147863922;3368.040108569046;3576.5886923003927;3965.504311961005;4015.3135683013206;4023.7988100594853;3059.434004709136;3484.094135524782;3518.9296214075575;3531;3545.627709090534;4795.896943702968;5105.2342148678;5145.922811157836;5249.658772179796;5257.76340064698;3597.1366871950804;4035.414864844581;4195.685323600742;4222.113356195203;4618.4829268049;4823.831165909176;4961.189165954437;5635.49280564747;5649.322081350223;5649.915251271234;3231.9980805949995;3693.8337774800198;3808.638179372955;3819.732618716691;3825.3496905526126;4371.3553300253525;5448.891022179534;5830.387809280007;5922.14128951304;5930.614510056833;3505.631908649606;3577.5170352002665;3871.2064026874987;3878.166194268246;3928.6464218219926;3785.905352366158;3901.43429202764;3978.5026871641044;3986.4219688145636;4012.9799351003057;4049.7165198436414;4395.1429471252795;4575.771211439414;4605.539446055434;4611.608013318905;4551.8634440966825;5714.542836573064;5728.903833758883;5739.368669446358;5824.534037277008;1074.254336702858;1210.915235933494;1228.9124125149128;1233.4572787973264;1289.9161554498928;771.9729809456754;775.9757120600866;857.2413414128416;862.4221771413169;878.446578655443;4373.365861949999;4563.722157795823;5127.333446651986;5159.613029022838;5248.790048398021;5135;5319.893602127936;5480.424555421703;5499.862503437366;5564.19319198722;4289.656827453796;4759.452662943806;4861.6353773467135;4882.389701287339;4899.926501102486;2291.8968646410617;2475.8390704604503;2518.55925213087;2538.2765911715073;2712.742289482496;4549.954500455019;5479.424700344029;5513.751881165357;5700.287464066992;5747.936631723144;4984.950150498448;5065.632279284572;5423.701696406735;5451.291332126798;5643.294588176478;4869.70781753096;5261;5267.736613169326;5713.342965559014;5728.828135155785;4707.058588282289;5008.023435430202;5467.45325467466;5525.502704756591;5594.776208951596;4858.198397264553;5403.972980135074;5436.782528698966;5560.499555040065;5839.0949402842625;5824.737886795019;5876.794312199053;6153.784637530396;6166.229221347332;6194.969025154845;3423.7432192585156;3624.4925710401094;3941.2511622791576;3961.4453976443897;3961.643452089326;4776.996830665599;5070.721110338966;5444.972775136099;5905.313082547516;5989.880202395841;4927.876803079952;5433.891322173614;5584.944150558605;5669.546436285004;5793.710314484175;4792.376990991149;5067.822626208065;5516.696581688046;5768.307803063659;5823.4467725566;5396.839679468933;5553.777848885999;5627.127795191752;5639.3232812062815;5672.893785711912;4863;5285.912064734485;5351.983123206578;5431.681812958786;5795.8551036224435;4867.659263851497;5584.525315348241;5656.519195868233;5669.234653321913;5697.572682048946 08:44:03 INFO - PROCESS | 1642 | |5;yui.html;621.5928566788754;709.7232079488997;752.3529764402609;774.3843644302779;779.2869524385188;13902.721945561096;14708.705825883477;14718.26408679567;14753.262336883166;14785.408583656654;13161.539346122887;13301.201927884335;13323.933380333085;13380;13394.19634821909;658.0326919428433;683.7470136049668;810.6473683947472;823.2549542663884;841.4068082002174;846.7036537211989;848.8344772769312;848.9660413583449;851.7019043334814;852.40757673417;874.4010352908247;901.134910485931;902.2150728865896;902.6389444222298;905.5653286422521;700.6461736822923;797.9880301795478;859.8796168536442;895.4985208283382;901.0673952459206;654.8264709851845;674.6997586074199;676.3439463720183;677.6747161362524;677.9118714567074;13406.396712147976;13577.660558486019;13653.863461365458;13655.863441365558;13685;12853.293068881208;12998.870011299861;13060.412281447358;13160.473581056745;13377.732445351136;9719.46542940138;11048.668539943814;11070.059044981186;11219.551217951353;11276.774464510745;3326.7172290355347;3563.2695297463956;3650.689691376236;3702.648248416423;3851.459416233501;82545.58727206386;82874;85177;85740.71111466664;87409.56295218547;10306.896931030593;10348.275620706629;10351.223658225663;10406.47967601632;10441.99971036065;19798.70301945472;19845.900770496344;19921.60156796885;19921.701174482394;20135.39593812188;7492.62536873165;7551.282628150317;7581.052368453943;7632.313091821763;7720.343770779435;4272.482820436248;4283.764392958355;4292.3561465780385;4333.826646934151;4374.147041326974;3296.2748195396975;3389.28824946759;3405.4551271796395;3422.349753546818;3434.862605495802;619.1115748900343;627.7300760672955;637.1939496536885;640.545212898847;648.8150877000032;573.87087905221;629.8079085878851;643.864788394433;652.8944155099765;666.4002397841884;8745.160329593939;8964.910350896538;9106.726798196065;9127.543622818832;9317.39436936597;551.3686828581252;582.0774073094193;627.7489004398186;640.3084668557947;659.1299484680176;3891.9416208756893;4213.052063285736;4386.978065109654;4515.322701594787;4632.351470794091;110567;113801.86198138079;114675;118387;118737.40631296962;56990.86018279611;62908;65123.67438162779;92869;103178;74830.62584687042;84134;85691;86075.5696221515;87279;9532.666356677482;9610.95194524023;9684.661036863676;9691.854622180532;9735.853962190431;49300;49381.50618493913;50592.241116382545;56203.71898140483;56445.43554564402;67406;74214;77769.2223077762;78856;78999;11809.586664466702;12260.632181034583;12378;12378.76252104438;12428.689282767642;13635.659108522366;13965.790513142514;14123.43506259738;14180.361883715052;14484.275786210857;12920.483180672667;13050.477980880658;13090.214587124803;13680.452781888613;13684.178949263076;12539.87460125387;13437.193768373929;13451.529196478075;13573.864261357656;13610.93194534021;12499.312537810507;12747.936260318638;13051.934740326238;13058.477660893837;13315.400806963517;10745.409002504937;10788.788298398653;11011.449427528752;11148.108151347711;11422.94288528552;8742.781430464289;8837.721154660028;9321.673741419017;10373.948130259301;10425.322354046963 08:44:03 INFO - PROCESS | 1642 | __end_tp_report 08:44:03 INFO - PROCESS | 1642 | __start_cc_report 08:44:03 INFO - PROCESS | 1642 | _x_x_mozilla_cycle_collect,364 08:44:03 INFO - PROCESS | 1642 | __end_cc_report 08:44:03 INFO - PROCESS | 1642 | __startTimestamp1480092243169__endTimestamp 08:44:03 INFO - PROCESS | 1642 | 08:44:03 INFO - PROCESS | 1642 | ------- Summary: start ------- 08:44:03 INFO - PROCESS | 1642 | Number of tests: 6 08:44:03 INFO - PROCESS | 1642 | 08:44:03 INFO - PROCESS | 1642 | [#0] dojo.html Cycles:165 Average:5471.05 Median:5670.26 stddev:685.57 (12.1%) stddev-sans-first:662.11 08:44:03 INFO - PROCESS | 1642 | Values: 3106.8 3298.2 3340.0 3350.6 3358.5 4802.6 5131.3 5256.1 5525.1 5563.4 4175.3 4277.7 4449.8 5600.6 5621.0 4371.0 5018.0 5123.1 5186.7 5221.4 4520.2 4578.8 4609.6 4720.2 5210.8 4057.6 5036.8 5177.5 5197.9 5253.8 5063.6 5110.2 5137.6 5183.1 5225.5 5099.4 5105.4 5108.1 5112.4 5116.8 5539.1 5608.7 5636.3 5641.9 5660.9 5955.9 6012.4 6026.9 6032.4 6079.2 6129.4 6170.4 6182.1 6193.8 6200.3 5695.1 5705.5 5709.3 5718.2 5732.3 6116.4 6122.3 6134.2 6172.2 6201.9 5943.7 5946.2 5967.0 5978.4 6040.7 6092.0 6100.1 6105.9 6108.6 6113.1 4560.7 5885.6 5986.0 6031.2 6055.9 4784.5 6064.4 6068.8 6070.3 6086.0 4772.0 4802.2 4806.4 4819.5 4840.5 4206.7 4235.5 4296.3 4322.8 4372.4 5542.2 5670.3 5689.5 5696.2 5722.7 6114.7 6136.2 6162.2 6181.9 6206.5 5517.1 5551.2 5648.7 5678.8 5700.3 4843.3 5238.8 5245.9 5259.2 5262.1 5644.6 6193.8 6204.8 6224.1 6296.1 6121.3 6163.1 6164.4 6171.3 6176.3 5469.8 5856.7 6086.3 6116.3 6123.3 5659.7 5681.6 5686.9 5730.7 5731.7 4378.8 4679.3 4787.6 5452.6 5750.5 5039.8 5089.5 5263.8 5618.9 5644.9 4781.6 5371.9 5946.2 5947.2 5968.5 4680.4 5546.2 5727.9 5767.9 5829.7 6013.5 6032.6 6049.2 6051.7 6067.8 5827.2 5840.2 5891.5 5914.7 5947.3 08:44:03 INFO - PROCESS | 1642 | 08:44:03 INFO - PROCESS | 1642 | [#1] ext.html Cycles:180 Average:67937.90 Median:19945.12 stddev:151633.55 (760.3%) stddev-sans-first:151974.28 08:44:03 INFO - PROCESS | 1642 | Values: 455.9 492.2 493.0 530.5 539.0 31250.7 40075.6 40110.0 40350.2 41845.6 40649.0 41045.0 41074.0 41125.4 41171.4 8256.8 9181.9 9376.2 9394.7 9414.0 8403.2 11045.7 11280.5 11343.7 11459.6 10997.5 11198.9 11222.8 11249.3 11311.7 11224.6 11240.1 11279.3 11281.7 11291.8 11084.7 11118.9 11131.1 11199.3 11221.0 38758.2 38775.6 38817.6 39005.8 39112.0 3374.6 3537.6 3892.8 3925.3 3970.7 29812.9 29866.4 30230.0 30794.9 30838.5 3139.1 3141.8 3165.6 3208.7 3217.8 461925.0 465899.0 466007.0 467572.0 467952.0 57593.7 57862.7 57892.1 57913.0 58126.4 59700.8 59814.8 60198.0 60239.7 60358.7 14852.4 15326.5 15343.6 15369.6 15381.8 10727.9 10805.1 11586.7 11955.5 11996.7 15917.8 16147.4 16171.8 16191.6 16217.1 4942.9 5385.2 5530.8 6353.9 6379.7 847.5 925.7 970.3 978.3 1021.0 26026.6 28409.9 28534.0 29689.4 29926.7 993.1 993.7 1122.2 1165.2 1200.3 12515.1 13241.1 14874.6 15225.3 15359.9 486994.0 717223.0 723774.0 731205.0 853417.0 48672.8 55903.4 56400.4 56581.2 56595.0 1335.1 1342.0 1352.3 1375.1 1375.6 29448.9 29568.0 29630.0 30165.4 30284.8 48712.8 51100.5 52385.2 52560.9 55024.0 481662.0 484722.0 485058.0 485481.0 486765.0 22700.0 25084.6 25364.5 25603.7 25686.9 36352.6 44122.3 45019.3 45835.8 46599.6 30491.5 38098.0 38104.6 38972.6 39189.4 33592.8 37236.3 37828.4 38089.5 38420.6 30443.7 32878.3 32987.9 36780.3 37878.1 32290.5 33023.3 33293.7 33552.5 33558.7 20028.4 24308.1 24544.9 25037.0 25048.6 08:44:03 INFO - PROCESS | 1642 | 08:44:03 INFO - PROCESS | 1642 | [#2] jquery.html Cycles:180 Average:33352.25 Median:378.77 stddev:96087.04 (25368.2%) stddev-sans-first:96324.62 08:44:03 INFO - PROCESS | 1642 | Values: 341.7 366.9 374.1 383.4 396.6 16443.5 18082.2 19464.7 19678.6 19836.9 18495.3 18657.8 18890.5 19080.5 19454.3 5678.6 5891.9 5979.1 5980.3 5987.5 5564.7 5796.4 5854.0 5857.9 5871.6 4997.9 5816.2 5820.2 5822.7 5824.7 5451.5 5710.3 5765.5 5809.9 5819.7 5458.7 5609.0 5618.7 5623.6 5754.4 12691.9 12757.6 12849.8 12857.8 12910.5 1895.4 2184.0 2293.6 2316.3 2316.9 9743.7 9767.2 10099.7 10257.9 10263.2 1807.8 1823.9 1859.5 1872.6 1874.6 115248.4 119707.0 124147.8 124581.4 127472.1 10910.9 11350.3 11393.8 11395.9 11644.7 14112.3 14254.9 14288.4 14293.4 14297.6 7849.5 8121.2 8162.0 8206.6 8262.0 7884.8 7913.4 7915.2 8010.2 8076.7 3993.7 4233.0 4370.6 4421.2 4440.8 1709.6 1829.4 1869.2 1924.2 1926.3 761.5 785.7 838.4 843.9 992.7 16313.3 16748.5 20237.1 20709.9 20867.7 889.1 914.8 915.8 920.3 932.0 5687.2 6122.4 10214.7 10626.8 10684.3 565679.0 574515.0 574599.0 576757.0 577338.0 105523.0 120764.8 121530.0 125262.0 132394.7 438.9 439.3 449.1 449.7 450.0 11042.7 11443.6 11468.7 11566.1 11701.3 53165.7 54536.0 54828.7 55110.0 55462.2 63485.7 74031.0 78118.0 79916.8 80934.4 7833.8 8076.4 8078.6 8098.1 8280.1 11895.4 12290.0 12341.1 12724.9 12864.7 9384.2 10548.4 10768.2 10934.9 11075.0 9634.3 10436.7 10515.5 10882.5 11063.8 10845.5 10861.9 11046.6 11258.5 11320.5 7884.7 8014.5 8082.1 8117.5 8150.2 8836.5 10585.5 10664.4 10667.5 10960.3 08:44:03 INFO - PROCESS | 1642 | 08:44:03 INFO - PROCESS | 1642 | [#3] mootools.html Cycles:180 Average:5344.39 Median:5283.62 stddev:2917.82 (55.2%) stddev-sans-first:2910.89 08:44:03 INFO - PROCESS | 1642 | Values: 1392.1 1451.1 1617.6 1776.0 1782.8 7396.0 7619.0 7859.4 8339.8 8349.0 7861.0 8112.4 8124.1 8232.3 8234.1 2252.2 2290.5 2291.7 2298.0 2300.9 1920.9 2269.8 2300.4 2305.3 2316.7 2281.5 2285.6 2303.5 2304.7 2308.5 2265.7 2266.5 2285.2 2289.1 2291.7 2251.2 2284.9 2314.1 2317.9 2322.7 8189.8 8225.3 8240.9 8255.3 8262.5 5009.7 5128.0 5167.0 5169.3 5172.4 3150.4 3226.2 3449.3 3464.8 3467.1 1011.8 1027.5 1046.9 1075.9 1094.9 10554.9 10566.5 10567.2 10668.4 10678.2 4438.8 4472.4 4542.3 4598.8 4654.7 9179.6 9255.3 9259.1 9275.5 9312.9 5123.7 5129.3 5137.7 5138.0 5138.1 4223.9 4360.0 4547.5 4683.6 4717.8 3372.9 3577.6 3746.3 3790.3 3796.6 1078.6 1082.3 1090.4 1091.7 1098.5 779.7 880.3 883.7 913.7 936.3 5482.7 5564.9 5595.7 5735.5 5814.9 918.2 940.4 948.9 971.5 980.6 3601.6 3771.2 3854.7 3880.9 3923.1 9596.2 9827.0 9856.0 9937.0 9951.1 9440.6 9624.6 9676.7 9825.7 9861.9 7598.5 7745.9 7749.2 7762.2 7770.0 4118.5 5394.9 5404.3 5509.7 5622.6 9099.1 9120.1 9192.9 9240.4 9327.6 9289.4 9392.6 9412.8 9427.8 9473.9 6155.8 6368.5 6481.4 6559.2 6734.9 7754.4 7903.0 7905.7 7912.7 7941.6 6947.9 7040.3 7068.7 7086.9 7176.4 6757.9 6966.7 6983.6 6988.9 7075.7 6263.3 6407.3 6479.9 6492.2 6510.0 4806.5 4827.0 4854.9 4870.9 4924.2 4718.6 4819.3 4835.7 4938.7 4954.7 08:44:03 INFO - PROCESS | 1642 | 08:44:03 INFO - PROCESS | 1642 | [#4] prototype.html Cycles:180 Average:4437.03 Median:4913.90 stddev:1404.43 (28.6%) stddev-sans-first:1372.92 08:44:03 INFO - PROCESS | 1642 | Values: 259.8 293.9 308.2 310.4 311.1 4721.5 5205.8 5296.2 5337.5 5424.9 4469.1 5091.0 5278.4 5331.4 5337.1 3459.0 4059.9 4143.5 4153.9 4201.3 3791.8 3897.9 4136.0 4201.6 4208.6 3086.0 3174.7 3485.4 4008.7 4064.7 3368.0 3576.6 3965.5 4015.3 4023.8 3059.4 3484.1 3518.9 3531.0 3545.6 4795.9 5105.2 5145.9 5249.7 5257.8 3597.1 4035.4 4195.7 4222.1 4618.5 4823.8 4961.2 5635.5 5649.3 5649.9 3232.0 3693.8 3808.6 3819.7 3825.3 4371.4 5448.9 5830.4 5922.1 5930.6 3505.6 3577.5 3871.2 3878.2 3928.6 3785.9 3901.4 3978.5 3986.4 4013.0 4049.7 4395.1 4575.8 4605.5 4611.6 4551.9 5714.5 5728.9 5739.4 5824.5 1074.3 1210.9 1228.9 1233.5 1289.9 772.0 776.0 857.2 862.4 878.4 4373.4 4563.7 5127.3 5159.6 5248.8 5135.0 5319.9 5480.4 5499.9 5564.2 4289.7 4759.5 4861.6 4882.4 4899.9 2291.9 2475.8 2518.6 2538.3 2712.7 4550.0 5479.4 5513.8 5700.3 5747.9 4985.0 5065.6 5423.7 5451.3 5643.3 4869.7 5261.0 5267.7 5713.3 5728.8 4707.1 5008.0 5467.5 5525.5 5594.8 4858.2 5404.0 5436.8 5560.5 5839.1 5824.7 5876.8 6153.8 6166.2 6195.0 3423.7 3624.5 3941.3 3961.4 3961.6 4777.0 5070.7 5445.0 5905.3 5989.9 4927.9 5433.9 5584.9 5669.5 5793.7 4792.4 5067.8 5516.7 5768.3 5823.4 5396.8 5553.8 5627.1 5639.3 5672.9 4863.0 5285.9 5352.0 5431.7 5795.9 4867.7 5584.5 5656.5 5669.2 5697.6 08:44:03 INFO - PROCESS | 1642 | 08:44:03 INFO - PROCESS | 1642 | [#5] yui.html Cycles:180 Average:19954.72 Median:4573.84 stddev:29193.16 (638.3%) stddev-sans-first:29238.96 08:44:03 INFO - PROCESS | 1642 | Values: 621.6 709.7 752.4 774.4 779.3 13902.7 14708.7 14718.3 14753.3 14785.4 13161.5 13301.2 13323.9 13380.0 13394.2 658.0 683.7 810.6 823.3 841.4 846.7 848.8 849.0 851.7 852.4 874.4 901.1 902.2 902.6 905.6 700.6 798.0 859.9 895.5 901.1 654.8 674.7 676.3 677.7 677.9 13406.4 13577.7 13653.9 13655.9 13685.0 12853.3 12998.9 13060.4 13160.5 13377.7 9719.5 11048.7 11070.1 11219.6 11276.8 3326.7 3563.3 3650.7 3702.6 3851.5 82545.6 82874.0 85177.0 85740.7 87409.6 10306.9 10348.3 10351.2 10406.5 10442.0 19798.7 19845.9 19921.6 19921.7 20135.4 7492.6 7551.3 7581.1 7632.3 7720.3 4272.5 4283.8 4292.4 4333.8 4374.1 3296.3 3389.3 3405.5 3422.3 3434.9 619.1 627.7 637.2 640.5 648.8 573.9 629.8 643.9 652.9 666.4 8745.2 8964.9 9106.7 9127.5 9317.4 551.4 582.1 627.7 640.3 659.1 3891.9 4213.1 4387.0 4515.3 4632.4 110567.0 113801.9 114675.0 118387.0 118737.4 56990.9 62908.0 65123.7 92869.0 103178.0 74830.6 84134.0 85691.0 86075.6 87279.0 9532.7 9611.0 9684.7 9691.9 9735.9 49300.0 49381.5 50592.2 56203.7 56445.4 67406.0 74214.0 77769.2 78856.0 78999.0 11809.6 12260.6 12378.0 12378.8 12428.7 13635.7 13965.8 14123.4 14180.4 14484.3 12920.5 13050.5 13090.2 13680.5 13684.2 12539.9 13437.2 13451.5 13573.9 13610.9 12499.3 12747.9 13051.9 13058.5 13315.4 10745.4 10788.8 11011.4 11148.1 11422.9 8742.8 8837.7 9321.7 10373.9 10425.3 08:44:03 INFO - PROCESS | 1642 | -------- Summary: end -------- 08:44:03 INFO - PROCESS | 1642 | 08:44:03 INFO - TEST-INFO | 1642: exit 0 08:44:03 INFO - TEST-OK | dromaeo_css | took 1116221ms 08:44:03 INFO - TEST-START | kraken 08:44:03 INFO - operating with platform_type : mac_ 08:44:03 INFO - Initialising browser for kraken test... 08:44:03 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/tmp_uBQm_/profile http://localhost:49249/getInfo.html) 08:44:07 INFO - PROCESS | 1655 | 2016-11-25 08:44:07.120 plugin-container[1656:10276] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 08:44:07 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:44:07 INFO - PROCESS | 1655 | 2016-11-25 08:44:07.123 plugin-container[1656:10276] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x433b, name = 'com.apple.CFPasteboardClient' 08:44:07 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:44:07 INFO - PROCESS | 1655 | 2016-11-25 08:44:07.123 plugin-container[1656:10276] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:44:07 INFO - PROCESS | 1655 | __metrics Screen width/height:1600/1200 08:44:07 INFO - PROCESS | 1655 | colorDepth:24 08:44:07 INFO - PROCESS | 1655 | Browser inner width/height: 1024/689 08:44:07 INFO - PROCESS | 1655 | __metrics 08:44:07 ERROR - PROCESS | 1655 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3198: TypeError: this.tabs is undefined 08:44:08 INFO - TEST-INFO | 1655: exit 0 08:44:08 INFO - Browser initialized. 08:44:08 INFO - Running cycle 1/1 for kraken test... 08:44:08 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/T/tmp_uBQm_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:44:10 INFO - PROCESS | 1657 | 2016-11-25 08:44:10.964 plugin-container[1658:10390] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 08:44:10 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:44:10 INFO - PROCESS | 1657 | 2016-11-25 08:44:10.965 plugin-container[1658:10390] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0b, name = 'com.apple.CFPasteboardClient' 08:44:10 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:44:10 INFO - PROCESS | 1657 | 2016-11-25 08:44:10.965 plugin-container[1658:10390] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:44:20 INFO - PROCESS | 1657 | RSS: Main: 242638848 08:44:20 INFO - PROCESS | 1657 | 08:44:23 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-beat-detection) 08:44:24 INFO - PROCESS | 1657 | RSS: Main: 254910464 08:44:24 INFO - PROCESS | 1657 | 08:44:27 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-dft) 08:44:27 INFO - PROCESS | 1657 | RSS: Main: 262676480 08:44:27 INFO - PROCESS | 1657 | 08:44:31 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-fft) 08:44:31 INFO - PROCESS | 1657 | RSS: Main: 265302016 08:44:31 INFO - PROCESS | 1657 | 08:44:33 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-oscillator) 08:44:34 INFO - PROCESS | 1657 | RSS: Main: 266465280 08:44:34 INFO - PROCESS | 1657 | 08:44:36 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:44:36 INFO - PROCESS | 1657 | RSS: Main: 268181504 08:44:36 INFO - PROCESS | 1657 | 08:44:39 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-darkroom) 08:44:40 INFO - PROCESS | 1657 | RSS: Main: 273334272 08:44:40 INFO - PROCESS | 1657 | 08:44:43 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-desaturate) 08:44:44 INFO - PROCESS | 1657 | RSS: Main: 273047552 08:44:44 INFO - PROCESS | 1657 | 08:44:47 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49249/tests/kraken/driver.html?testName=json-parse-financial) 08:44:47 INFO - PROCESS | 1657 | RSS: Main: 273252352 08:44:47 INFO - PROCESS | 1657 | 08:44:49 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49249/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:44:49 INFO - PROCESS | 1657 | RSS: Main: 272187392 08:44:49 INFO - PROCESS | 1657 | 08:44:51 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:44:52 INFO - PROCESS | 1657 | RSS: Main: 272277504 08:44:52 INFO - PROCESS | 1657 | 08:44:54 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:44:55 INFO - PROCESS | 1657 | RSS: Main: 272273408 08:44:55 INFO - PROCESS | 1657 | 08:44:58 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:44:59 INFO - PROCESS | 1657 | RSS: Main: 272572416 08:44:59 INFO - PROCESS | 1657 | 08:45:02 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:45:02 INFO - PROCESS | 1657 | RSS: Main: 272580608 08:45:02 INFO - PROCESS | 1657 | 08:45:04 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49249/tests/kraken/driver.html?testName=ai-astar) 08:45:04 INFO - PROCESS | 1657 | RSS: Main: 274132992 08:45:04 INFO - PROCESS | 1657 | 08:45:04 INFO - PROCESS | 1657 | __start_tp_report 08:45:04 INFO - PROCESS | 1657 | _x_x_mozilla_page_load 08:45:04 INFO - PROCESS | 1657 | _x_x_mozilla_page_load_details 08:45:04 INFO - PROCESS | 1657 | |i|pagename|runs| 08:45:04 INFO - PROCESS | 1657 | |0;ai-astar;105;118;119;130;174;163;118;117;122;118 08:45:04 INFO - PROCESS | 1657 | |1;audio-beat-detection;148;116;116;155;115;120;118;116;163;123 08:45:04 INFO - PROCESS | 1657 | |2;audio-dft;198;215;212;203;195;199;196;199;201;193 08:45:04 INFO - PROCESS | 1657 | |3;audio-fft;87;60;62;82;63;62;62;83;94;86 08:45:04 INFO - PROCESS | 1657 | |4;audio-oscillator;87;70;84;72;69;72;81;69;72;81 08:45:04 INFO - PROCESS | 1657 | |5;imaging-gaussian-blur;88;87;88;86;88;84;103;129;104;86 08:45:04 INFO - PROCESS | 1657 | |6;imaging-darkroom;96;111;152;118;95;128;153;95;97;95 08:45:04 INFO - PROCESS | 1657 | |7;imaging-desaturate;92;94;140;143;93;92;93;94;92;95 08:45:04 INFO - PROCESS | 1657 | |8;json-parse-financial;56;58;57;57;57;60;57;59;59;57 08:45:04 INFO - PROCESS | 1657 | |9;json-stringify-tinderbox;69;73;68;75;75;69;75;63;97;107 08:45:04 INFO - PROCESS | 1657 | |10;stanford-crypto-aes;97;90;89;89;87;88;89;90;90;111 08:45:04 INFO - PROCESS | 1657 | |11;stanford-crypto-ccm;148;178;153;165;162;162;165;150;200;160 08:45:04 INFO - PROCESS | 1657 | |12;stanford-crypto-pbkdf2;176;177;174;174;188;188;176;177;175;178 08:45:04 INFO - PROCESS | 1657 | |13;stanford-crypto-sha256-iterative;72;70;78;87;98;89;76;70;68;72 08:45:04 INFO - PROCESS | 1657 | __end_tp_report 08:45:04 INFO - PROCESS | 1657 | __start_cc_report 08:45:04 INFO - PROCESS | 1657 | _x_x_mozilla_cycle_collect,950 08:45:04 INFO - PROCESS | 1657 | __end_cc_report 08:45:04 INFO - PROCESS | 1657 | __startTimestamp1480092304910__endTimestamp 08:45:04 INFO - PROCESS | 1657 | 08:45:04 INFO - PROCESS | 1657 | ------- Summary: start ------- 08:45:04 INFO - PROCESS | 1657 | Number of tests: 14 08:45:04 INFO - PROCESS | 1657 | 08:45:04 INFO - PROCESS | 1657 | [#0] ai-astar Cycles:10 Average:128.40 Median:120.50 stddev:22.14 (18.4%) stddev-sans-first:21.80 08:45:04 INFO - PROCESS | 1657 | Values: 105.0 118.0 119.0 130.0 174.0 163.0 118.0 117.0 122.0 118.0 08:45:04 INFO - PROCESS | 1657 | 08:45:04 INFO - PROCESS | 1657 | [#1] audio-beat-detection Cycles:10 Average:129.00 Median:121.50 stddev:18.66 (15.4%) stddev-sans-first:18.48 08:45:04 INFO - PROCESS | 1657 | Values: 148.0 116.0 116.0 155.0 115.0 120.0 118.0 116.0 163.0 123.0 08:45:04 INFO - PROCESS | 1657 | 08:45:04 INFO - PROCESS | 1657 | [#2] audio-dft Cycles:10 Average:201.10 Median:200.00 stddev:7.17 (3.6%) stddev-sans-first:7.52 08:45:04 INFO - PROCESS | 1657 | Values: 198.0 215.0 212.0 203.0 195.0 199.0 196.0 199.0 201.0 193.0 08:45:04 INFO - PROCESS | 1657 | 08:45:04 INFO - PROCESS | 1657 | [#3] audio-fft Cycles:10 Average:74.10 Median:82.50 stddev:13.36 (16.2%) stddev-sans-first:13.33 08:45:04 INFO - PROCESS | 1657 | Values: 87.0 60.0 62.0 82.0 63.0 62.0 62.0 83.0 94.0 86.0 08:45:04 INFO - PROCESS | 1657 | 08:45:04 INFO - PROCESS | 1657 | [#4] audio-oscillator Cycles:10 Average:75.70 Median:76.50 stddev:6.80 (8.9%) stddev-sans-first:5.85 08:45:04 INFO - PROCESS | 1657 | Values: 87.0 70.0 84.0 72.0 69.0 72.0 81.0 69.0 72.0 81.0 08:45:04 INFO - PROCESS | 1657 | 08:45:04 INFO - PROCESS | 1657 | [#5] imaging-gaussian-blur Cycles:10 Average:94.30 Median:86.50 stddev:14.10 (16.3%) stddev-sans-first:14.77 08:45:04 INFO - PROCESS | 1657 | Values: 88.0 87.0 88.0 86.0 88.0 84.0 103.0 129.0 104.0 86.0 08:45:04 INFO - PROCESS | 1657 | 08:45:04 INFO - PROCESS | 1657 | [#6] imaging-darkroom Cycles:10 Average:114.00 Median:95.00 stddev:23.29 (24.5%) stddev-sans-first:23.77 08:45:04 INFO - PROCESS | 1657 | Values: 96.0 111.0 152.0 118.0 95.0 128.0 153.0 95.0 97.0 95.0 08:45:04 INFO - PROCESS | 1657 | 08:45:04 INFO - PROCESS | 1657 | [#7] imaging-desaturate Cycles:10 Average:102.80 Median:93.00 stddev:20.43 (22.0%) stddev-sans-first:21.30 08:45:04 INFO - PROCESS | 1657 | Values: 92.0 94.0 140.0 143.0 93.0 92.0 93.0 94.0 92.0 95.0 08:45:04 INFO - PROCESS | 1657 | 08:45:04 INFO - PROCESS | 1657 | [#8] json-parse-financial Cycles:10 Average:57.70 Median:57.50 stddev:1.25 (2.2%) stddev-sans-first:1.17 08:45:04 INFO - PROCESS | 1657 | Values: 56.0 58.0 57.0 57.0 57.0 60.0 57.0 59.0 59.0 57.0 08:45:04 INFO - PROCESS | 1657 | 08:45:04 INFO - PROCESS | 1657 | [#9] json-stringify-tinderbox Cycles:10 Average:77.10 Median:74.00 stddev:13.88 (18.8%) stddev-sans-first:14.40 08:45:04 INFO - PROCESS | 1657 | Values: 69.0 73.0 68.0 75.0 75.0 69.0 75.0 63.0 97.0 107.0 08:45:04 INFO - PROCESS | 1657 | 08:45:04 INFO - PROCESS | 1657 | [#10] stanford-crypto-aes Cycles:10 Average:92.00 Median:89.50 stddev:7.20 (8.0%) stddev-sans-first:7.40 08:45:04 INFO - PROCESS | 1657 | Values: 97.0 90.0 89.0 89.0 87.0 88.0 89.0 90.0 90.0 111.0 08:45:04 INFO - PROCESS | 1657 | 08:45:04 INFO - PROCESS | 1657 | [#11] stanford-crypto-ccm Cycles:10 Average:164.30 Median:163.50 stddev:15.24 (9.3%) stddev-sans-first:14.98 08:45:04 INFO - PROCESS | 1657 | Values: 148.0 178.0 153.0 165.0 162.0 162.0 165.0 150.0 200.0 160.0 08:45:04 INFO - PROCESS | 1657 | 08:45:04 INFO - PROCESS | 1657 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.30 Median:177.00 stddev:5.27 (3.0%) stddev-sans-first:5.53 08:45:04 INFO - PROCESS | 1657 | Values: 176.0 177.0 174.0 174.0 188.0 188.0 176.0 177.0 175.0 178.0 08:45:04 INFO - PROCESS | 1657 | 08:45:04 INFO - PROCESS | 1657 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:78.00 Median:77.00 stddev:10.03 (13.0%) stddev-sans-first:10.40 08:45:04 INFO - PROCESS | 1657 | Values: 72.0 70.0 78.0 87.0 98.0 89.0 76.0 70.0 68.0 72.0 08:45:04 INFO - PROCESS | 1657 | -------- Summary: end -------- 08:45:04 INFO - PROCESS | 1657 | 08:45:05 INFO - TEST-INFO | 1657: exit 0 08:45:06 INFO - TEST-OK | kraken | took 62165ms 08:45:06 INFO - SUITE-END | took 1178s 08:45:06 INFO - Completed test suite (00:19:38) 08:45:06 INFO - javascript benchmark 08:45:06 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6974.054614907802, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3106.829124398157, 3298.2249171444714, 3339.9666003339958, 3350.648181940896, 3358.4626459766428, 4802.59177969873, 5131.307273518069, 5256.132738098209, 5525.11598144297, 5563.36021357544, 4175.290200665887, 4277.700560960734, 4449.822007119711, 5600.635958662684, 5620.971895140518, 4371.0, 5017.974910125463, 5123.103456895028, 5186.688798672092, 5221.425643179247, 4520.2089634314025, 4578.809509527514, 4609.5620916013095, 4720.221163508034, 5210.843674689746, 4057.6145266199665, 5036.823711170092, 5177.48225177483, 5197.896042079156, 5253.763580638881, 5063.594912406998, 5110.182370820651, 5137.614678899097, 5183.092958732207, 5225.529702326771, 5099.36257967754, 5105.387353517602, 5108.131617625013, 5112.437631860493, 5116.820911268088, 5539.113741801333, 5608.747606357723, 5636.2954630671165, 5641.915371269434, 5660.858478538028, 5955.940440595626, 6012.368701286389, 6026.9095963560585, 6032.396760323976, 6079.179310793031, 6129.38706129388, 6170.382961703838, 6182.103594978703, 6193.845153871144, 6200.348963358828, 5695.145728140729, 5705.515031222351, 5709.34342550612, 5718.199452076671, 5732.31212254528, 6116.449519543263, 6122.2761798032625, 6134.171886795315, 6172.166757487771, 6201.906971395432, 5943.732532036069, 5946.197263369476, 5967.0, 5978.372270911578, 6040.6677632730625, 6092.0, 6100.115483254903, 6105.908411373833, 6108.572399932051, 6113.113598528189, 4560.657950653715, 5885.5585831062845, 5986.0, 6031.2159419276095, 6055.878882422327, 4784.545468180518, 6064.3935606439, 6068.75724971005, 6070.271567411939, 6086.02623580225, 4772.0, 4802.159622066124, 4806.423229212447, 4819.542143496362, 4840.467548569654, 4206.747595144301, 4235.470566179228, 4296.2696341621995, 4322.762248076323, 4372.40972468719, 5542.224088627595, 5670.262865827416, 5689.459501347365, 5696.174054762037, 5722.656640601494, 6114.663693496811, 6136.17161683176, 6162.22972128484, 6181.907271390933, 6206.503479721701, 5517.089680202802, 5551.167324901297, 5648.717564121777, 5678.77284908599, 5700.315962084494, 4843.2735089736825, 5238.790448382022, 5245.921311180336, 5259.211118332204, 5262.10544207478, 5644.604877658525, 6193.783217587363, 6204.782832601018, 6224.06639004153, 6296.118543404019, 6121.265448146251, 6163.137160797582, 6164.383561643979, 6171.290301615192, 6176.289726681489, 5469.835904922858, 5856.682246494504, 6086.3305036446845, 6116.335771508327, 6123.326434092335, 5659.660420374791, 5681.573881958787, 5686.943130568641, 5730.74211660468, 5731.656100633975, 4378.824847006084, 4679.251319788817, 4787.61699064067, 5452.5823285946735, 5750.453706897838, 5039.79045029183, 5089.491050894881, 5263.842084737464, 5618.915716264178, 5644.943550564606, 4781.569658730731, 5371.865703357291, 5946.1972633693895, 5947.197128387612, 5968.492678122235, 4680.4149481314835, 5546.168074788652, 5727.942720572908, 5767.942320576908, 5829.737661805314, 6013.5189184865985, 6032.577719559588, 6049.243844519435, 6051.697415129314, 6067.817965461043, 5827.213326200908, 5840.2115714378015, 5891.469767720754, 5914.733836977261, 5947.256592925884], "value": 5429.617300410759, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [455.8791920141161, 492.1706923833341, 492.9721530608681, 530.501328750974, 538.9784408623656, 31250.68749312506, 40075.599244007586, 40109.99725006873, 40350.19299614006, 41845.58154418455, 40649.0, 41045.0, 41073.97315067125, 41125.38311925324, 41171.38242926351, 8256.793580160484, 9181.862272065924, 9376.20302274304, 9394.718158455258, 9413.952930235338, 8403.243708066273, 11045.668629941074, 11280.548778048867, 11343.659690209306, 11459.59891403801, 10997.450127493634, 11198.888011119867, 11222.831657525143, 11249.268797528175, 11311.660650180467, 11224.607138750105, 11240.100791936626, 11279.266847654877, 11281.661550153467, 11291.774164516664, 11084.667459976214, 11118.888811111867, 11131.109511239163, 11199.272047316897, 11220.990110889978, 38758.22483550313, 38775.612243877484, 38817.61182388196, 39005.80497097496, 39112.0, 3374.578177727784, 3537.6108628050893, 3892.8442862285474, 3925.2934471795065, 3970.7419017763755, 29812.8509357454, 29866.40267194644, 30230.0, 30794.922177723896, 30838.53742193869, 3139.073973177918, 3141.8429078545983, 3165.6201255849446, 3208.6630903755004, 3217.758668099902, 461925.0, 465899.0, 466007.0, 467572.0, 467952.0, 57593.71203144041, 57862.7106864463, 57892.13161802576, 57913.0, 58126.4187358121, 59700.80598388008, 59814.80370392655, 60198.0, 60239.698801505714, 60358.698206508685, 14852.405903763729, 15326.463573774865, 15343.61640958985, 15369.615759605877, 15381.846181538265, 10727.892721072845, 10805.081568066802, 11586.652400428, 11955.52177912891, 11996.700082498008, 15917.761233581505, 16147.434839780552, 16171.757423638655, 16191.595210119605, 16217.108059056629, 4942.851714448572, 5385.192221166856, 5530.834074977757, 6353.904691429632, 6379.71291291893, 847.4745657692271, 925.717656114878, 970.257752819094, 978.2760757039739, 1020.9846852297073, 26026.609600856, 28409.857950710113, 28534.0, 29689.40621187564, 29926.700732992827, 993.1012433747326, 993.6919554937992, 1122.1752012270974, 1165.1960147498198, 1200.3338147328143, 12515.123941324202, 13241.139325943783, 14874.553763387115, 15225.31486083129, 15359.923200383926, 486994.0, 717223.0, 723774.0, 731205.0, 853417.0, 48672.75663621659, 55903.44096558982, 56400.43599564034, 56581.15128273079, 56595.0, 1335.1121504199598, 1341.9731605367838, 1352.3238380809596, 1375.1474086066503, 1375.587323802855, 29448.852755736083, 29568.0, 29630.0, 30165.396692066475, 30284.84857575698, 48712.75643621901, 51100.488995109576, 52385.214221787464, 52560.94878102493, 55024.0, 481662.0, 484722.0, 485058.0, 485481.0, 486765.0, 22700.0, 25084.62373064442, 25364.492710145325, 25603.743962560882, 25686.871565642054, 36352.63647363493, 44122.338164928195, 45019.32471012872, 45835.77082114568, 46599.64752898011, 30491.542626861057, 38098.0, 38104.618953811216, 38972.61027389803, 39189.41215881707, 33592.83203584064, 37236.255274894895, 37828.43257351087, 38089.49330993599, 38420.61579384282, 30443.695563044086, 32878.34243315168, 32987.87651543557, 36780.26439471249, 37878.053048674, 32290.515642265855, 33023.339533208724, 33293.667063329056, 33552.49671254884, 33558.66441335653, 20028.399148025583, 24308.149214777397, 24544.877275613508, 25037.0, 25048.62427063559], "value": 19478.801141786942, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [341.6822355209655, 366.8789299531155, 374.12828110502534, 383.41146340367544, 396.6033966033966, 16443.506694799144, 18082.186301616443, 19464.70802937957, 19678.60642787145, 19836.9008154959, 18495.260189592434, 18657.813421865812, 18890.527736806587, 19080.522986925334, 19454.319098831544, 5678.6024978251535, 5891.911621325662, 5979.1031345298115, 5980.31226408962, 5987.461128498434, 5564.749586268627, 5796.44933731297, 5854.0, 5857.941420585782, 5871.647701137946, 4997.900041999158, 5816.243888294516, 5820.156077368779, 5822.7088645567765, 5824.708764561776, 5451.536619387357, 5710.343310519302, 5765.4811067004175, 5809.883802323972, 5819.650820950714, 5458.6724796511935, 5608.971955140199, 5618.719064046782, 5623.606347555673, 5754.424557544212, 12691.873081269161, 12757.617271481871, 12849.807252891214, 12857.807132893015, 12910.54813081547, 1895.3934740882924, 2184.049938276839, 2293.621552443845, 2316.2703748319227, 2316.8841557922206, 9743.658971935949, 9767.16979056774, 10099.747506312327, 10257.948710256476, 10263.178945684402, 1807.8192180781948, 1823.8905665660102, 1859.5444116191484, 1872.6254749050245, 1874.6157037807218, 115248.42375788151, 119707.0, 124147.75852241543, 124581.37709311486, 127472.0879186813, 10910.890891091147, 11350.262232954832, 11393.772124557463, 11395.886041139649, 11644.708882278012, 14112.2943852809, 14254.928725356307, 14288.428462861577, 14293.356798944073, 14297.64255893611, 7849.4897831640765, 8121.187881211831, 8162.0, 8206.630701618442, 8261.958690206631, 7884.802879928047, 7913.366930645535, 7915.248051435105, 8010.239027292398, 8076.717314894068, 3993.740406873544, 4232.984083819861, 4370.628496577763, 4421.159979603865, 4440.822367105344, 1709.6495218480181, 1829.44202018383, 1869.2242719271621, 1924.220690620301, 1926.306529649325, 761.5430741555023, 785.7092875636051, 838.3837879158814, 843.9282660973886, 992.7319623701704, 16313.34746610146, 16748.497545073667, 20237.08933098014, 20709.896450517652, 20867.686984695243, 889.0531583863294, 914.7530166854912, 915.8168366326761, 920.3097676742443, 931.9627214911328, 5687.184843502493, 6122.387761223931, 10214.693559193236, 10626.787464250672, 10684.30552014132, 565679.0, 574515.0, 574599.0, 576757.0, 577338.0, 105523.0, 120764.79235207535, 121530.0, 125262.0, 132394.67605324017, 438.9363542286351, 439.3080897586314, 449.11300182140013, 449.65152007194683, 449.95725406085717, 11042.668719938414, 11443.599474018702, 11468.738438771561, 11566.07471402269, 11701.2979221247, 53165.7341713289, 54536.0, 54828.72585637046, 55110.0, 55462.168067479826, 63485.73028539496, 74031.0, 78118.0, 79916.80124798248, 80934.38131237225, 7833.804154896173, 8076.394270429624, 8078.6364613593705, 8098.109207986999, 8280.089190189196, 11895.405229738306, 12290.0, 12341.136120471481, 12724.872751272369, 12864.678383040124, 9384.155426011692, 10548.419836908737, 10768.246222764645, 10934.945325273322, 11075.003249707564, 9634.277429192978, 10436.686899393031, 10515.474226288808, 10882.455877206266, 11063.778724425627, 10845.511951962339, 10861.891381086089, 11046.613368532062, 11258.549658013588, 11320.490577924178, 7884.684612615431, 8014.479058861155, 8082.070561885458, 8117.512949223065, 8150.225728555778, 8836.513991730517, 10585.52365143555, 10664.360138391721, 10667.466626668791, 10960.28758130719], "value": 8752.567497764981, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1392.0673148990177, 1451.1003178029623, 1617.5632579203616, 1775.9556011099728, 1782.7860656721195, 7395.963020184891, 7618.96190519048, 7859.449838511299, 8339.791505212374, 8348.998120225566, 7860.960695196531, 8112.391570632197, 8124.146964568709, 8232.300254478376, 8234.135415781346, 2252.155441709359, 2290.5075408787106, 2291.679164916913, 2298.0, 2300.930972070832, 1920.932767353143, 2269.818414526842, 2300.447892505795, 2305.2738387408, 2316.745158032615, 2281.4524514116574, 2285.565742508926, 2303.527776805759, 2304.665823455598, 2308.4690521180137, 2265.7394399644086, 2266.478709896725, 2285.2344464604375, 2289.1186893046297, 2291.713535808024, 2251.212075773466, 2284.942876428086, 2314.0859360552486, 2317.8725170115636, 2322.6864373309727, 8189.836203275901, 8225.300849427806, 8240.876386854203, 8255.298299644537, 8262.462939908886, 5009.7495125243595, 5127.974360128175, 5167.018266529385, 5169.25045868347, 5172.353455818023, 3150.4329220740256, 3226.2418331692033, 3449.344624521358, 3464.8440820163155, 3467.081223475756, 1011.7723512209694, 1027.547878933267, 1046.9424181670006, 1075.876274228458, 1094.9014588686978, 10554.894451055468, 10566.52450639723, 10567.15462762977, 10668.359898406043, 10678.1991350649, 4438.822447102144, 4472.418585583853, 4542.295944128666, 4598.793054312564, 4654.743989080633, 9179.586918588546, 9255.259579233645, 9259.120383563552, 9275.489848058287, 9312.8603070954, 5123.718195499283, 5129.333186685782, 5137.743112844343, 5138.049460849755, 5138.07514647367, 4223.852165174266, 4359.9536111333055, 4547.454305483363, 4683.648726345537, 4717.764111794397, 3372.9494057589154, 3577.606463289036, 3746.3069332173636, 3790.3177428063213, 3796.6393192646656, 1078.6063086973197, 1082.3289560472558, 1090.3512410115968, 1091.7434402915308, 1098.4507746126937, 779.719301051621, 880.308957468397, 883.7127933421664, 913.7213149989184, 936.3024546712743, 5482.725863706799, 5564.888702225933, 5595.692236926927, 5735.4551317624755, 5814.883702325929, 918.1644703319947, 940.3966237564936, 948.9003654616304, 971.4996776660025, 980.632262901412, 3601.603823579404, 3771.2268984858038, 3854.7494412863075, 3880.902977425587, 3923.058465968147, 9596.23230141587, 9826.950865245628, 9856.0, 9937.0, 9951.104400603981, 9440.57517411718, 9624.566894489764, 9676.661316853877, 9825.656102036395, 9861.852072218922, 7598.468107232441, 7745.922540774633, 7749.225077492206, 7762.2237776223055, 7769.961150194326, 4118.5263694674295, 5394.892102157856, 5404.297441332602, 5509.669419834822, 5622.638491833358, 9099.135582119688, 9120.087991201091, 9192.908070919473, 9240.445573265626, 9327.580258888502, 9289.396189247676, 9392.624295028123, 9412.764680883032, 9427.811443770948, 9473.857892131486, 6155.815325540241, 6368.4586810121655, 6481.449076828522, 6559.180102487189, 6734.8653026940165, 7754.379649627904, 7903.0, 7905.683772649029, 7912.723054693058, 7941.642626081955, 6947.861042779217, 7040.260772618802, 7068.71725130989, 7086.858262834611, 7176.425885929218, 6757.898631520629, 6966.721331146698, 6983.580985140908, 6988.8951665724035, 7075.681594328371, 6263.279722831932, 6407.295197528361, 6479.935200647933, 6492.156019717407, 6509.967450162719, 4806.49531799156, 4827.0, 4854.854354369375, 4870.878228044327, 4924.212126059813, 4718.551737584924, 4819.3252944588485, 4835.685680430761, 4938.728369939688, 4954.653174277766], "value": 4337.253062655262, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [259.76361511024965, 293.91770304314775, 308.24941268012384, 310.4102205808963, 311.0667996011964, 4721.527847215272, 5205.843824685256, 5296.152615581508, 5337.4662533746605, 5424.945750542493, 4469.128519938613, 5091.0, 5278.445763194857, 5331.413544510101, 5337.11937530307, 3458.9654103458893, 4059.8632382932824, 4143.544210136897, 4153.854615088472, 4201.264778663729, 3791.8007555617814, 3897.941530877039, 4135.986683262605, 4201.579842015789, 4208.559035759267, 3085.9662013225598, 3174.68253174683, 3485.3552092862774, 4008.6793056555553, 4064.695147863922, 3368.040108569046, 3576.5886923003927, 3965.504311961005, 4015.3135683013206, 4023.7988100594853, 3059.434004709136, 3484.094135524782, 3518.9296214075575, 3531.0, 3545.627709090534, 4795.896943702968, 5105.2342148678, 5145.922811157836, 5249.658772179796, 5257.76340064698, 3597.1366871950804, 4035.414864844581, 4195.685323600742, 4222.113356195203, 4618.4829268049, 4823.831165909176, 4961.189165954437, 5635.49280564747, 5649.322081350223, 5649.915251271234, 3231.9980805949995, 3693.8337774800198, 3808.638179372955, 3819.732618716691, 3825.3496905526126, 4371.3553300253525, 5448.891022179534, 5830.387809280007, 5922.14128951304, 5930.614510056833, 3505.631908649606, 3577.5170352002665, 3871.2064026874987, 3878.166194268246, 3928.6464218219926, 3785.905352366158, 3901.43429202764, 3978.5026871641044, 3986.4219688145636, 4012.9799351003057, 4049.7165198436414, 4395.1429471252795, 4575.771211439414, 4605.539446055434, 4611.608013318905, 4551.8634440966825, 5714.542836573064, 5728.903833758883, 5739.368669446358, 5824.534037277008, 1074.254336702858, 1210.915235933494, 1228.9124125149128, 1233.4572787973264, 1289.9161554498928, 771.9729809456754, 775.9757120600866, 857.2413414128416, 862.4221771413169, 878.446578655443, 4373.365861949999, 4563.722157795823, 5127.333446651986, 5159.613029022838, 5248.790048398021, 5135.0, 5319.893602127936, 5480.424555421703, 5499.862503437366, 5564.19319198722, 4289.656827453796, 4759.452662943806, 4861.6353773467135, 4882.389701287339, 4899.926501102486, 2291.8968646410617, 2475.8390704604503, 2518.55925213087, 2538.2765911715073, 2712.742289482496, 4549.954500455019, 5479.424700344029, 5513.751881165357, 5700.287464066992, 5747.936631723144, 4984.950150498448, 5065.632279284572, 5423.701696406735, 5451.291332126798, 5643.294588176478, 4869.70781753096, 5261.0, 5267.736613169326, 5713.342965559014, 5728.828135155785, 4707.058588282289, 5008.023435430202, 5467.45325467466, 5525.502704756591, 5594.776208951596, 4858.198397264553, 5403.972980135074, 5436.782528698966, 5560.499555040065, 5839.0949402842625, 5824.737886795019, 5876.794312199053, 6153.784637530396, 6166.229221347332, 6194.969025154845, 3423.7432192585156, 3624.4925710401094, 3941.2511622791576, 3961.4453976443897, 3961.643452089326, 4776.996830665599, 5070.721110338966, 5444.972775136099, 5905.313082547516, 5989.880202395841, 4927.876803079952, 5433.891322173614, 5584.944150558605, 5669.546436285004, 5793.710314484175, 4792.376990991149, 5067.822626208065, 5516.696581688046, 5768.307803063659, 5823.4467725566, 5396.839679468933, 5553.777848885999, 5627.127795191752, 5639.3232812062815, 5672.893785711912, 4863.0, 5285.912064734485, 5351.983123206578, 5431.681812958786, 5795.8551036224435, 4867.659263851497, 5584.525315348241, 5656.519195868233, 5669.234653321913, 5697.572682048946], "value": 3983.3988669162322, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [621.5928566788754, 709.7232079488997, 752.3529764402609, 774.3843644302779, 779.2869524385188, 13902.721945561096, 14708.705825883477, 14718.26408679567, 14753.262336883166, 14785.408583656654, 13161.539346122887, 13301.201927884335, 13323.933380333085, 13380.0, 13394.19634821909, 658.0326919428433, 683.7470136049668, 810.6473683947472, 823.2549542663884, 841.4068082002174, 846.7036537211989, 848.8344772769312, 848.9660413583449, 851.7019043334814, 852.40757673417, 874.4010352908247, 901.134910485931, 902.2150728865896, 902.6389444222298, 905.5653286422521, 700.6461736822923, 797.9880301795478, 859.8796168536442, 895.4985208283382, 901.0673952459206, 654.8264709851845, 674.6997586074199, 676.3439463720183, 677.6747161362524, 677.9118714567074, 13406.396712147976, 13577.660558486019, 13653.863461365458, 13655.863441365558, 13685.0, 12853.293068881208, 12998.870011299861, 13060.412281447358, 13160.473581056745, 13377.732445351136, 9719.46542940138, 11048.668539943814, 11070.059044981186, 11219.551217951353, 11276.774464510745, 3326.7172290355347, 3563.2695297463956, 3650.689691376236, 3702.648248416423, 3851.459416233501, 82545.58727206386, 82874.0, 85177.0, 85740.71111466664, 87409.56295218547, 10306.896931030593, 10348.275620706629, 10351.223658225663, 10406.47967601632, 10441.99971036065, 19798.70301945472, 19845.900770496344, 19921.60156796885, 19921.701174482394, 20135.39593812188, 7492.62536873165, 7551.282628150317, 7581.052368453943, 7632.313091821763, 7720.343770779435, 4272.482820436248, 4283.764392958355, 4292.3561465780385, 4333.826646934151, 4374.147041326974, 3296.2748195396975, 3389.28824946759, 3405.4551271796395, 3422.349753546818, 3434.862605495802, 619.1115748900343, 627.7300760672955, 637.1939496536885, 640.545212898847, 648.8150877000032, 573.87087905221, 629.8079085878851, 643.864788394433, 652.8944155099765, 666.4002397841884, 8745.160329593939, 8964.910350896538, 9106.726798196065, 9127.543622818832, 9317.39436936597, 551.3686828581252, 582.0774073094193, 627.7489004398186, 640.3084668557947, 659.1299484680176, 3891.9416208756893, 4213.052063285736, 4386.978065109654, 4515.322701594787, 4632.351470794091, 110567.0, 113801.86198138079, 114675.0, 118387.0, 118737.40631296962, 56990.86018279611, 62908.0, 65123.67438162779, 92869.0, 103178.0, 74830.62584687042, 84134.0, 85691.0, 86075.5696221515, 87279.0, 9532.666356677482, 9610.95194524023, 9684.661036863676, 9691.854622180532, 9735.853962190431, 49300.0, 49381.50618493913, 50592.241116382545, 56203.71898140483, 56445.43554564402, 67406.0, 74214.0, 77769.2223077762, 78856.0, 78999.0, 11809.586664466702, 12260.632181034583, 12378.0, 12378.76252104438, 12428.689282767642, 13635.659108522366, 13965.790513142514, 14123.43506259738, 14180.361883715052, 14484.275786210857, 12920.483180672667, 13050.477980880658, 13090.214587124803, 13680.452781888613, 13684.178949263076, 12539.87460125387, 13437.193768373929, 13451.529196478075, 13573.864261357656, 13610.93194534021, 12499.312537810507, 12747.936260318638, 13051.934740326238, 13058.477660893837, 13315.400806963517, 10745.409002504937, 10788.788298398653, 11011.449427528752, 11148.108151347711, 11422.94288528552, 8742.781430464289, 8837.721154660028, 9321.673741419017, 10373.948130259301, 10425.322354046963], "value": 7193.310575618876, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1566.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 118.0, 119.0, 130.0, 174.0, 163.0, 118.0, 117.0, 122.0, 118.0], "value": 128.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 116.0, 116.0, 155.0, 115.0, 120.0, 118.0, 116.0, 163.0, 123.0], "value": 129.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 215.0, 212.0, 203.0, 195.0, 199.0, 196.0, 199.0, 201.0, 193.0], "value": 201.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 60.0, 62.0, 82.0, 63.0, 62.0, 62.0, 83.0, 94.0, 86.0], "value": 74.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 70.0, 84.0, 72.0, 69.0, 72.0, 81.0, 69.0, 72.0, 81.0], "value": 75.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 87.0, 88.0, 86.0, 88.0, 84.0, 103.0, 129.0, 104.0, 86.0], "value": 94.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 111.0, 152.0, 118.0, 95.0, 128.0, 153.0, 95.0, 97.0, 95.0], "value": 114.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 94.0, 140.0, 143.0, 93.0, 92.0, 93.0, 94.0, 92.0, 95.0], "value": 102.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 58.0, 57.0, 57.0, 57.0, 60.0, 57.0, 59.0, 59.0, 57.0], "value": 57.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 73.0, 68.0, 75.0, 75.0, 69.0, 75.0, 63.0, 97.0, 107.0], "value": 77.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 90.0, 89.0, 89.0, 87.0, 88.0, 89.0, 90.0, 90.0, 111.0], "value": 92.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 178.0, 153.0, 165.0, 162.0, 162.0, 165.0, 150.0, 200.0, 160.0], "value": 164.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 177.0, 174.0, 174.0, 188.0, 188.0, 176.0, 177.0, 175.0, 178.0], "value": 178.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 70.0, 78.0, 87.0, 98.0, 89.0, 76.0, 70.0, 68.0, 72.0], "value": 78.0, "unit": "score"}]}]} 08:45:06 INFO - Return code: 0 08:45:06 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:45:06 ERROR - # TBPL SUCCESS # 08:45:06 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:45:06 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:45:06 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:45:06 INFO - 2016-11-25 08:45:06.222 screenresolution[1662:10766] starting screenresolution argv=screenresolution get 08:45:06 INFO - 2016-11-25 08:45:06.224 screenresolution[1662:10766] Display 0: 1600x1200x32@60 08:45:06 INFO - 2016-11-25 08:45:06.230 screenresolution[1663:10770] starting screenresolution argv=screenresolution list 08:45:06 INFO - Available Modes on Display 0 08:45:06 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:45:06 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:45:06 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:45:06 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:45:06 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:45:06 INFO - Intel Iris: 08:45:06 INFO - Chipset Model: Intel Iris 08:45:06 INFO - Type: GPU 08:45:06 INFO - Bus: Built-In 08:45:06 INFO - VRAM (Dynamic, Max): 1536 MB 08:45:06 INFO - Vendor: Intel (0x8086) 08:45:06 INFO - Device ID: 0x0a2e 08:45:06 INFO - Revision ID: 0x0009 08:45:06 INFO - Displays: 08:45:06 INFO - Display: 08:45:06 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:45:06 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:45:06 INFO - Display Serial Number: 1600x1200 60 08:45:06 INFO - Main Display: Yes 08:45:06 INFO - Mirror: Off 08:45:06 INFO - Online: Yes 08:45:06 INFO - Rotation: Supported 08:45:06 INFO - Return code: 0 08:45:06 INFO - Running post-action listener: _resource_record_post_action 08:45:06 INFO - [mozharness: 2016-11-25 16:45:06.473958Z] Finished run-tests step (success) 08:45:06 INFO - Running post-run listener: _resource_record_post_run 08:45:06 INFO - Total resource usage - Wall time: 1211s; CPU: 55.0%; Read bytes: 39918080; Write bytes: 692365824; Read time: 389; Write time: 2335 08:45:06 INFO - TinderboxPrint: CPU usage
54.6% 08:45:06 INFO - TinderboxPrint: I/O read bytes / time
39,918,080 / 389 08:45:06 INFO - TinderboxPrint: I/O write bytes / time
692,365,824 / 2,335 08:45:06 INFO - TinderboxPrint: CPU idle
2,199.0 (45.4%) 08:45:06 INFO - TinderboxPrint: CPU system
982.5 (20.3%) 08:45:06 INFO - TinderboxPrint: CPU user
1,664.2 (34.3%) 08:45:06 INFO - TinderboxPrint: Swap in / out
423,358,464 / 0 08:45:06 INFO - install - Wall time: 33s; CPU: 54.0%; Read bytes: 366836224; Write bytes: 366831104; Read time: 27723; Write time: 642 08:45:06 INFO - run-tests - Wall time: 1179s; CPU: 55.0%; Read bytes: 38947328; Write bytes: 306996224; Read time: 374; Write time: 1647 08:45:06 INFO - Running post-run listener: _upload_blobber_files 08:45:06 INFO - Blob upload gear active. 08:45:06 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:45:06 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:45:06 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:45:06 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:45:07 INFO - (blobuploader) - INFO - Open directory for files ... 08:45:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 08:45:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:45:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:45:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 08:45:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:45:08 INFO - (blobuploader) - INFO - Done attempting. 08:45:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 08:45:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:45:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:45:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 08:45:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:45:09 INFO - (blobuploader) - INFO - Done attempting. 08:45:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:45:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:45:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:45:11 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:45:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:45:11 INFO - (blobuploader) - INFO - Done attempting. 08:45:11 INFO - (blobuploader) - INFO - Iteration through files over. 08:45:11 INFO - Return code: 0 08:45:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:45:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:45:11 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/161a262a40046512bd23fffee8c55c1874d3cda4380aa1eadcba5415bc425c7ff6e60f7505741205ab619634b6a2bb676a5dcdeaaa7485437802e1366ebbf83f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/87eecc9b3e1fdabf4e863abdd9da9018d743096df453480b88f2d437611e6536473c89d1a3f4ec29dae5ca30467e3b5210226677098afb4bb4cded10c3d860cb", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/866f8ace5b64625414f6044e80c5eb4969d4936c928efa23f183dc58388fc05c429264fa33776b0e5e8c0bd88500610c9a7426b245801f1838bc053b774a1b89"} 08:45:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:45:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:45:11 INFO - Contents: 08:45:11 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/161a262a40046512bd23fffee8c55c1874d3cda4380aa1eadcba5415bc425c7ff6e60f7505741205ab619634b6a2bb676a5dcdeaaa7485437802e1366ebbf83f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/87eecc9b3e1fdabf4e863abdd9da9018d743096df453480b88f2d437611e6536473c89d1a3f4ec29dae5ca30467e3b5210226677098afb4bb4cded10c3d860cb", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/866f8ace5b64625414f6044e80c5eb4969d4936c928efa23f183dc58388fc05c429264fa33776b0e5e8c0bd88500610c9a7426b245801f1838bc053b774a1b89"} 08:45:11 INFO - Running post-run listener: copy_logs_to_upload_dir 08:45:11 INFO - Copying logs to upload dir... 08:45:11 INFO - mkdir: /builds/slave/test/build/upload/logs 08:45:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.863470 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-11-25 08:45:11.683247) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-25 08:45:11.691148) ========= 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.567Us1bYDp/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.KrB7zOnvMK/Listeners TMPDIR=/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/161a262a40046512bd23fffee8c55c1874d3cda4380aa1eadcba5415bc425c7ff6e60f7505741205ab619634b6a2bb676a5dcdeaaa7485437802e1366ebbf83f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/87eecc9b3e1fdabf4e863abdd9da9018d743096df453480b88f2d437611e6536473c89d1a3f4ec29dae5ca30467e3b5210226677098afb4bb4cded10c3d860cb", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/866f8ace5b64625414f6044e80c5eb4969d4936c928efa23f183dc58388fc05c429264fa33776b0e5e8c0bd88500610c9a7426b245801f1838bc053b774a1b89"} build_url:https://queue.taskcluster.net/v1/task/eJum9pLRREqPofrssXCEwA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010831 build_url: 'https://queue.taskcluster.net/v1/task/eJum9pLRREqPofrssXCEwA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/161a262a40046512bd23fffee8c55c1874d3cda4380aa1eadcba5415bc425c7ff6e60f7505741205ab619634b6a2bb676a5dcdeaaa7485437802e1366ebbf83f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/87eecc9b3e1fdabf4e863abdd9da9018d743096df453480b88f2d437611e6536473c89d1a3f4ec29dae5ca30467e3b5210226677098afb4bb4cded10c3d860cb", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/866f8ace5b64625414f6044e80c5eb4969d4936c928efa23f183dc58388fc05c429264fa33776b0e5e8c0bd88500610c9a7426b245801f1838bc053b774a1b89"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-25 08:45:11.726939) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-25 08:45:11.727251) ========= 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.567Us1bYDp/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.KrB7zOnvMK/Listeners TMPDIR=/var/folders/bh/xg6w1cj94n1g623nbw37rr_c00000w/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.005046 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-25 08:45:11.775156) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-25 08:45:11.775435) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-25 08:45:11.775715) ========= ========= Total master_lag: 0.20 =========