builder: cedar_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0265 starttime: 1479910737.89 results: success (0) buildid: 20161123024236 builduid: 85a1cea35edb4fd995bfdfa93b1c438a revision: e5ed1abefc08d819a0f1d5d78ba961ff07ec1ae7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-23 06:18:57.886529) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-23 06:18:57.887079) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-23 06:18:57.887411) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-23 06:18:57.914005) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-23 06:18:57.914366) ========= 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.s1ybmjvsyt/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.IvF69ditL1/Listeners TMPDIR=/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-11-23 06:18:57-- 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% 18.2M=0.001s 2016-11-23 06:18:58 (18.2 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.145312 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-23 06:18:58.089295) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-23 06:18:58.089689) ========= 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.s1ybmjvsyt/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.IvF69ditL1/Listeners TMPDIR=/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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.024536 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-23 06:18:58.140945) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-23 06:18:58.141355) ========= bash -c 'python archiver_client.py mozharness --repo projects/cedar --rev e5ed1abefc08d819a0f1d5d78ba961ff07ec1ae7 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/cedar --rev e5ed1abefc08d819a0f1d5d78ba961ff07ec1ae7 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.s1ybmjvsyt/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.IvF69ditL1/Listeners TMPDIR=/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-11-23 06:18:58,242 truncating revision to first 12 chars 2016-11-23 06:18:58,242 Setting DEBUG logging. 2016-11-23 06:18:58,242 attempt 1/10 2016-11-23 06:18:58,242 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/cedar/e5ed1abefc08?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-23 06:18:59,308 unpacking tar archive at: cedar-e5ed1abefc08/testing/mozharness/ program finished with exit code 0 elapsedTime=1.348618 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-23 06:18:59.514598) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-23 06:18:59.515064) ========= script_repo_revision: e5ed1abefc08d819a0f1d5d78ba961ff07ec1ae7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-23 06:18:59.515553) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-23 06:18:59.515871) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-23 06:18:59.537057) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-11-23 06:18:59.537638) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Cedar --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Cedar 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', 'Cedar', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Cedar'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.s1ybmjvsyt/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.IvF69ditL1/Listeners TMPDIR=/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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 06:18:59 INFO - MultiFileLogger online at 20161123 06:18:59 in /builds/slave/test 06:18:59 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Cedar --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Cedar 06:18:59 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:18:59 INFO - {'append_to_log': False, 06:18:59 INFO - 'base_work_dir': '/builds/slave/test', 06:18:59 INFO - 'blob_upload_branch': 'Cedar', 06:18:59 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:18:59 INFO - 'branch': 'Cedar', 06:18:59 INFO - 'buildbot_json_path': 'buildprops.json', 06:18:59 INFO - 'config_files': ('talos/mac_config.py',), 06:18:59 INFO - 'default_actions': ('clobber', 06:18:59 INFO - 'read-buildbot-config', 06:18:59 INFO - 'download-and-extract', 06:18:59 INFO - 'populate-webroot', 06:18:59 INFO - 'create-virtualenv', 06:18:59 INFO - 'install', 06:18:59 INFO - 'run-tests'), 06:18:59 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:18:59 INFO - 'download_minidump_stackwalk': True, 06:18:59 INFO - 'download_symbols': 'ondemand', 06:18:59 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:18:59 INFO - 'tooltool.py': '/tools/tooltool.py', 06:18:59 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:18:59 INFO - '/tools/misc-python/virtualenv.py')}, 06:18:59 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:18:59 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:18:59 INFO - 'installer_path': 'installer.exe', 06:18:59 INFO - 'log_level': 'info', 06:18:59 INFO - 'log_name': 'talos', 06:18:59 INFO - 'log_to_console': True, 06:18:59 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 06:18:59 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 06:18:59 INFO - 'opt_config_files': (), 06:18:59 INFO - 'pip_index': False, 06:18:59 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:18:59 INFO - 'cmd': ('bash', 06:18:59 INFO - '-c', 06:18:59 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:18:59 INFO - 'enabled': True, 06:18:59 INFO - 'halt_on_failure': False, 06:18:59 INFO - 'name': 'check_screen_resolution'},), 06:18:59 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:18:59 INFO - 'cmd': ('bash', 06:18:59 INFO - '-c', 06:18:59 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:18:59 INFO - 'enabled': True, 06:18:59 INFO - 'halt_on_failure': False, 06:18:59 INFO - 'name': 'check_screen_resolution'},), 06:18:59 INFO - 'run_cmd_checks_enabled': True, 06:18:59 INFO - 'sps_profile': False, 06:18:59 INFO - 'sps_profile_interval': 0, 06:18:59 INFO - 'suite': 'dromaeojs-e10s', 06:18:59 INFO - 'system_bits': '32', 06:18:59 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:18:59 INFO - 'title': 't-yosemite-r7-0265', 06:18:59 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:18:59 INFO - 'use_talos_json': True, 06:18:59 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:18:59 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:18:59 INFO - 'work_dir': 'build'} 06:18:59 INFO - [mozharness: 2016-11-23 14:18:59.747501Z] Running clobber step. 06:18:59 INFO - Running pre-action listener: _resource_record_pre_action 06:18:59 INFO - Running main action method: clobber 06:18:59 INFO - rmtree: /builds/slave/test/build 06:18:59 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:19:02 INFO - Running post-action listener: _resource_record_post_action 06:19:02 INFO - [mozharness: 2016-11-23 14:19:02.078095Z] Finished clobber step (success) 06:19:02 INFO - [mozharness: 2016-11-23 14:19:02.078371Z] Running read-buildbot-config step. 06:19:02 INFO - Running pre-action listener: _resource_record_pre_action 06:19:02 INFO - Running main action method: read_buildbot_config 06:19:02 INFO - Using buildbot properties: 06:19:02 INFO - { 06:19:02 INFO - "project": "", 06:19:02 INFO - "product": "firefox", 06:19:02 INFO - "script_repo_revision": "default", 06:19:02 INFO - "builddir": "cedar_yosemite_r7_test-dromaeojs-e10s", 06:19:02 INFO - "repository": "", 06:19:02 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 cedar talos dromaeojs-e10s", 06:19:02 INFO - "buildid": "20161123024236", 06:19:02 INFO - "pgo_build": "False", 06:19:02 INFO - "basedir": "/builds/slave/test", 06:19:02 INFO - "buildnumber": 0, 06:19:02 INFO - "slavename": "t-yosemite-r7-0265", 06:19:02 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 06:19:02 INFO - "platform": "yosemite_r7", 06:19:02 INFO - "scheduler": "tests-cedar-macosx64-talos", 06:19:02 INFO - "branch": "cedar", 06:19:02 INFO - "repo_path": "projects/cedar", 06:19:02 INFO - "revision": "e5ed1abefc08d819a0f1d5d78ba961ff07ec1ae7", 06:19:02 INFO - "stage_platform": "macosx64", 06:19:02 INFO - "builduid": "85a1cea35edb4fd995bfdfa93b1c438a", 06:19:02 INFO - "slavebuilddir": "test" 06:19:02 INFO - } 06:19:02 INFO - Found installer url https://queue.taskcluster.net/v1/task/WlGxBgDkSs-_HxOeqvbfxQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 06:19:02 INFO - Running post-action listener: _resource_record_post_action 06:19:02 INFO - [mozharness: 2016-11-23 14:19:02.083789Z] Finished read-buildbot-config step (success) 06:19:02 INFO - [mozharness: 2016-11-23 14:19:02.083995Z] Running download-and-extract step. 06:19:02 INFO - Running pre-action listener: _resource_record_pre_action 06:19:02 INFO - Running main action method: download_and_extract 06:19:02 INFO - mkdir: /builds/slave/test/build/tests 06:19:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:19:02 INFO - trying https://queue.taskcluster.net/v1/task/WlGxBgDkSs-_HxOeqvbfxQ/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 06:19:02 INFO - Downloading https://queue.taskcluster.net/v1/task/WlGxBgDkSs-_HxOeqvbfxQ/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 06:19:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WlGxBgDkSs-_HxOeqvbfxQ/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 06:19:03 INFO - Downloaded 1199 bytes. 06:19:03 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 06:19:03 INFO - Using the following test package requirements: 06:19:03 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 06:19:03 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 06:19:03 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 06:19:03 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 06:19:03 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 06:19:03 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 06:19:03 INFO - u'jsshell-mac.zip'], 06:19:03 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 06:19:03 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 06:19:03 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 06:19:03 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 06:19:03 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 06:19:03 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 06:19:03 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 06:19:03 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 06:19:03 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 06:19:03 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 06:19:03 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 06:19:03 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 06:19:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WlGxBgDkSs-_HxOeqvbfxQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 06:19:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WlGxBgDkSs-_HxOeqvbfxQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 06:19:03 INFO - Fetch https://queue.taskcluster.net/v1/task/WlGxBgDkSs-_HxOeqvbfxQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 06:19:04 INFO - Http code: 200 06:19:04 INFO - via: 1.1 23172d761378a3865feafcda78328053.cloudfront.net (CloudFront) 06:19:04 INFO - x-amz-cf-id: FjQfwRgY9qtLE8mRZlZUtqKw7tHy3zhBoyQrwz6ZT2vuPWAJ-oDmZA== 06:19:04 INFO - x-amz-version-id: jGbqWHfb.3mnwUz6A4ZS_ENEuUkFX662 06:19:04 INFO - Expected file size: 16267074 06:19:04 INFO - Obtained file size: 16267074 06:19:11 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 06:19:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WlGxBgDkSs-_HxOeqvbfxQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 06:19:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WlGxBgDkSs-_HxOeqvbfxQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 06:19:11 INFO - Fetch https://queue.taskcluster.net/v1/task/WlGxBgDkSs-_HxOeqvbfxQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 06:19:11 INFO - Http code: 200 06:19:11 INFO - via: 1.1 6cd88b9fd84ad5daa3b3867fb8c5c91a.cloudfront.net (CloudFront) 06:19:11 INFO - x-amz-cf-id: NyFvCS3-BH9zRsB3lBNlZlRF5lI-_EVFmS3u5iCJODv5jWmQbGlU-A== 06:19:11 INFO - x-amz-version-id: jGbqWHfb.3mnwUz6A4ZS_ENEuUkFX662 06:19:12 INFO - Expected file size: 16267074 06:19:12 INFO - Obtained file size: 16267074 06:19:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WlGxBgDkSs-_HxOeqvbfxQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 06:19:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WlGxBgDkSs-_HxOeqvbfxQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 06:19:18 INFO - Fetch https://queue.taskcluster.net/v1/task/WlGxBgDkSs-_HxOeqvbfxQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 06:19:19 INFO - Http code: 200 06:19:19 INFO - via: 1.1 c0740de9f16cf32ffedfd2f9806b8483.cloudfront.net (CloudFront) 06:19:19 INFO - x-amz-cf-id: Eq-zo6W6lcZSssiWgos-tUHuq1ISV8QWqAexUxYZUC88JaEFG-F8JQ== 06:19:19 INFO - x-amz-version-id: s6OZtePjbbwrV.MVvz5JoF4FleTR6cQD 06:19:19 INFO - Expected file size: 14409506 06:19:19 INFO - Obtained file size: 14409506 06:19:19 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')]} 06:19:19 INFO - trying https://queue.taskcluster.net/v1/task/WlGxBgDkSs-_HxOeqvbfxQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 06:19:19 INFO - Downloading https://queue.taskcluster.net/v1/task/WlGxBgDkSs-_HxOeqvbfxQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 06:19:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WlGxBgDkSs-_HxOeqvbfxQ/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 06:19:22 INFO - Downloaded 107541276 bytes. 06:19:22 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/WlGxBgDkSs-_HxOeqvbfxQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 06:19:22 INFO - mkdir: /builds/slave/test/properties 06:19:22 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:19:22 INFO - Writing to file /builds/slave/test/properties/build_url 06:19:22 INFO - Contents: 06:19:22 INFO - build_url:https://queue.taskcluster.net/v1/task/WlGxBgDkSs-_HxOeqvbfxQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 06:19:23 INFO - Running post-action listener: _resource_record_post_action 06:19:23 INFO - Running post-action listener: set_extra_try_arguments 06:19:23 INFO - [mozharness: 2016-11-23 14:19:23.029231Z] Finished download-and-extract step (success) 06:19:23 INFO - [mozharness: 2016-11-23 14:19:23.029401Z] Running populate-webroot step. 06:19:23 INFO - Running pre-action listener: _resource_record_pre_action 06:19:23 INFO - Running main action method: populate_webroot 06:19:23 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:19:23 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:19:23 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:19:23 INFO - '--tppagecycles', 06:19:23 INFO - '7'], 06:19:23 INFO - 'tests': ['tsvgm']}}, 06:19:23 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:19:23 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:19:23 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:19:23 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:19:23 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:19:23 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:19:23 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 06:19:23 INFO - 'talos_options': ['--disable-e10s'], 06:19:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:19:23 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 06:19:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:19:23 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 06:19:23 INFO - 'talos_options': ['--disable-e10s'], 06:19:23 INFO - 'tests': ['damp', 'tps']}, 06:19:23 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 06:19:23 INFO - 'tests': ['damp', 'tps']}, 06:19:23 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:19:23 INFO - 'tests': ['dromaeo_dom']}, 06:19:23 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:19:23 INFO - 'g4': {'talos_options': ['--disable-e10s'], 06:19:23 INFO - 'tests': ['basic_compositor_video']}, 06:19:23 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 06:19:23 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:19:23 INFO - 'tests': ['a11yr', 06:19:23 INFO - 'ts_paint', 06:19:23 INFO - 'tpaint', 06:19:23 INFO - 'sessionrestore', 06:19:23 INFO - 'sessionrestore_no_auto_restore', 06:19:23 INFO - 'tabpaint']}, 06:19:23 INFO - 'other-e10s': {'tests': ['a11yr', 06:19:23 INFO - 'ts_paint', 06:19:23 INFO - 'tpaint', 06:19:23 INFO - 'sessionrestore', 06:19:23 INFO - 'sessionrestore_no_auto_restore', 06:19:23 INFO - 'tabpaint']}, 06:19:23 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:19:23 INFO - 'ts_paint', 06:19:23 INFO - 'tpaint', 06:19:23 INFO - 'sessionrestore', 06:19:23 INFO - 'sessionrestore_no_auto_restore', 06:19:23 INFO - 'tabpaint']}, 06:19:23 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:19:23 INFO - 'ts_paint', 06:19:23 INFO - 'tpaint', 06:19:23 INFO - 'sessionrestore', 06:19:23 INFO - 'sessionrestore_no_auto_restore', 06:19:23 INFO - 'tabpaint']}, 06:19:23 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:19:23 INFO - 'tests': ['a11yr', 06:19:23 INFO - 'ts_paint', 06:19:23 INFO - 'tpaint', 06:19:23 INFO - 'sessionrestore', 06:19:23 INFO - 'sessionrestore_no_auto_restore', 06:19:23 INFO - 'tabpaint']}, 06:19:23 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:19:23 INFO - 'tests': ['a11yr', 06:19:23 INFO - 'ts_paint', 06:19:23 INFO - 'tpaint', 06:19:23 INFO - 'sessionrestore', 06:19:23 INFO - 'sessionrestore_no_auto_restore', 06:19:23 INFO - 'tabpaint']}, 06:19:23 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:19:23 INFO - 'tests': ['tsvgx', 06:19:23 INFO - 'tsvgr_opacity', 06:19:23 INFO - 'tart', 06:19:23 INFO - 'tscrollx', 06:19:23 INFO - 'cart']}, 06:19:23 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:19:23 INFO - 'tsvgr_opacity', 06:19:23 INFO - 'tart', 06:19:23 INFO - 'tscrollx', 06:19:23 INFO - 'cart']}, 06:19:23 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 06:19:23 INFO - 'talos_options': ['--disable-e10s'], 06:19:23 INFO - 'tests': ['tp5o']}, 06:19:23 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 06:19:23 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 06:19:23 INFO - 'talos_options': ['--disable-e10s', 06:19:23 INFO - '--xperf_path', 06:19:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:19:23 INFO - 'tests': ['tp5n']}, 06:19:23 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 06:19:23 INFO - 'talos_options': ['--xperf_path', 06:19:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:19:23 INFO - 'tests': ['tp5n']}}, 06:19:23 INFO - 'talos.zip': {'path': '', 06:19:23 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:19:23 INFO - Running post-action listener: _resource_record_post_action 06:19:23 INFO - [mozharness: 2016-11-23 14:19:23.047299Z] Finished populate-webroot step (success) 06:19:23 INFO - [mozharness: 2016-11-23 14:19:23.047434Z] Running create-virtualenv step. 06:19:23 INFO - Running pre-action listener: _resource_record_pre_action 06:19:23 INFO - Running main action method: create_virtualenv 06:19:23 INFO - Creating virtualenv /builds/slave/test/build/venv 06:19:23 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 06:19:23 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:19:23 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:19:23 INFO - Using real prefix '/tools/python27' 06:19:23 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:19:23 INFO - Installing distribute.............................................................................................................................................................................................done. 06:19:26 INFO - Installing pip.................done. 06:19:26 INFO - Return code: 0 06:19:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 06:19:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 06:19:27 INFO - Reading from file tmpfile_stdout 06:19:27 INFO - Output received: 06:19:27 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) 06:19:27 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:19: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')]} 06:19:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:19:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:19: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')]} 06:19:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:19:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:19:27 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 0x10b1743f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0ea570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe16bcdd960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af8ad30>, '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 0x10adf1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10af82b20>, '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.s1ybmjvsyt/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.IvF69ditL1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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 06:19:27 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 06:19:27 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 06:19:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.s1ybmjvsyt/Render', 06:19:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:19:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:19:27 INFO - 'HOME': '/Users/cltbld', 06:19:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:19:27 INFO - 'LOGNAME': 'cltbld', 06:19:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:19:27 INFO - 'MOZ_NO_REMOTE': '1', 06:19:27 INFO - 'NO_EM_RESTART': '1', 06:19:27 INFO - 'PAGER': '/bin/cat', 06:19:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:19:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:19:27 INFO - 'PWD': '/builds/slave/test', 06:19:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:19:27 INFO - 'SHELL': '/bin/bash', 06:19:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IvF69ditL1/Listeners', 06:19:27 INFO - 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/', 06:19:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:19:27 INFO - 'USER': 'cltbld', 06:19:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:19:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:19:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:19:27 INFO - 'XPC_FLAGS': '0x0', 06:19:27 INFO - 'XPC_SERVICE_NAME': '0', 06:19:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:19:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:19:27 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 06:19:27 INFO - Cleaning up... 06:19:27 INFO - Return code: 0 06:19:27 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:19: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')]} 06:19:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:19:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:19: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')]} 06:19:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:19:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:19:27 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 0x10b1743f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0ea570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe16bcdd960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af8ad30>, '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 0x10adf1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10af82b20>, '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.s1ybmjvsyt/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.IvF69ditL1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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 06:19:27 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 06:19:27 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 06:19:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.s1ybmjvsyt/Render', 06:19:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:19:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:19:27 INFO - 'HOME': '/Users/cltbld', 06:19:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:19:27 INFO - 'LOGNAME': 'cltbld', 06:19:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:19:27 INFO - 'MOZ_NO_REMOTE': '1', 06:19:27 INFO - 'NO_EM_RESTART': '1', 06:19:27 INFO - 'PAGER': '/bin/cat', 06:19:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:19:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:19:27 INFO - 'PWD': '/builds/slave/test', 06:19:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:19:27 INFO - 'SHELL': '/bin/bash', 06:19:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IvF69ditL1/Listeners', 06:19:27 INFO - 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/', 06:19:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:19:27 INFO - 'USER': 'cltbld', 06:19:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:19:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:19:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:19:27 INFO - 'XPC_FLAGS': '0x0', 06:19:27 INFO - 'XPC_SERVICE_NAME': '0', 06:19:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:19:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:19:27 INFO - Downloading/unpacking psutil>=3.1.1 06:19:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:19:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:19:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:19:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:19:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:19:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:19:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:19:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:19:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:19:31 INFO - Installing collected packages: psutil 06:19:31 INFO - Running setup.py install for psutil 06:19:31 INFO - building 'psutil._psutil_osx' extension 06:19:31 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 06:19:31 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 06:19:31 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 06:19:31 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 06:19:31 INFO - building 'psutil._psutil_posix' extension 06:19:31 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 06:19:31 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 06:19:31 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 06:19:31 INFO - ^ 06:19:31 INFO - 1 warning generated. 06:19:32 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 06:19:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:19:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:19:32 INFO - Successfully installed psutil 06:19:32 INFO - Cleaning up... 06:19:32 INFO - Return code: 0 06:19:32 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 06:19:32 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')]} 06:19:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:19:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:19:32 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')]} 06:19:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:19:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:19:32 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 0x10b1743f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0ea570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe16bcdd960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af8ad30>, '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 0x10adf1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10af82b20>, '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.s1ybmjvsyt/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.IvF69ditL1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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 06:19:32 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 06:19:32 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 06:19:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.s1ybmjvsyt/Render', 06:19:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:19:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:19:32 INFO - 'HOME': '/Users/cltbld', 06:19:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:19:32 INFO - 'LOGNAME': 'cltbld', 06:19:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:19:32 INFO - 'MOZ_NO_REMOTE': '1', 06:19:32 INFO - 'NO_EM_RESTART': '1', 06:19:32 INFO - 'PAGER': '/bin/cat', 06:19:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:19:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:19:32 INFO - 'PWD': '/builds/slave/test', 06:19:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:19:32 INFO - 'SHELL': '/bin/bash', 06:19:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IvF69ditL1/Listeners', 06:19:32 INFO - 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/', 06:19:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:19:32 INFO - 'USER': 'cltbld', 06:19:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:19:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:19:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:19:32 INFO - 'XPC_FLAGS': '0x0', 06:19:32 INFO - 'XPC_SERVICE_NAME': '0', 06:19:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:19:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:19:32 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:19:32 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 06:19:32 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 06:19:32 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 06:19:32 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 06:19:32 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 06:19:32 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 06:19:35 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:19:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:19:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 06:19:35 INFO - Installing collected packages: mozsystemmonitor 06:19:35 INFO - Running setup.py install for mozsystemmonitor 06:19:35 INFO - Successfully installed mozsystemmonitor 06:19:35 INFO - Cleaning up... 06:19:35 INFO - Return code: 0 06:19:35 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 06:19:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:19:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:19:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:19:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:19:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:19:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:19:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10b1743f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0ea570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe16bcdd960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af8ad30>, '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 0x10adf1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10af82b20>, '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.s1ybmjvsyt/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.IvF69ditL1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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 06:19:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 06:19:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 06:19:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.s1ybmjvsyt/Render', 06:19:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:19:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:19:35 INFO - 'HOME': '/Users/cltbld', 06:19:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:19:35 INFO - 'LOGNAME': 'cltbld', 06:19:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:19:35 INFO - 'MOZ_NO_REMOTE': '1', 06:19:35 INFO - 'NO_EM_RESTART': '1', 06:19:35 INFO - 'PAGER': '/bin/cat', 06:19:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:19:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:19:35 INFO - 'PWD': '/builds/slave/test', 06:19:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:19:35 INFO - 'SHELL': '/bin/bash', 06:19:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IvF69ditL1/Listeners', 06:19:35 INFO - 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/', 06:19:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:19:35 INFO - 'USER': 'cltbld', 06:19:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:19:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:19:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:19:35 INFO - 'XPC_FLAGS': '0x0', 06:19:35 INFO - 'XPC_SERVICE_NAME': '0', 06:19:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:19:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:19:35 INFO - Downloading/unpacking jsonschema==2.5.1 06:19:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:19:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:19:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:19:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:19:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:19:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:19:38 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:19:38 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 06:19: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 06:19: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 06:19: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 06:19: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 06:19: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 06:19: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 06:19:38 INFO - Downloading functools32-3.2.3-2.tar.gz 06:19:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:19:38 INFO - warning: no files found matching '*.txt' 06:19:38 INFO - no previously-included directories found matching 'build' 06:19:38 INFO - no previously-included directories found matching 'dist' 06:19:38 INFO - no previously-included directories found matching '.git*' 06:19:38 INFO - Installing collected packages: jsonschema, functools32 06:19:38 INFO - Running setup.py install for functools32 06:19:39 INFO - warning: no files found matching '*.txt' 06:19:39 INFO - no previously-included directories found matching 'build' 06:19:39 INFO - no previously-included directories found matching 'dist' 06:19:39 INFO - no previously-included directories found matching '.git*' 06:19:39 INFO - Successfully installed jsonschema functools32 06:19:39 INFO - Cleaning up... 06:19:39 INFO - Return code: 0 06:19:39 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 06:19:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:19:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:19:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:19:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:19:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:19:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:19:39 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 0x10b1743f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0ea570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe16bcdd960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af8ad30>, '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 0x10adf1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10af82b20>, '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.s1ybmjvsyt/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.IvF69ditL1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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 06:19:39 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 06:19:39 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 06:19:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.s1ybmjvsyt/Render', 06:19:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:19:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:19:39 INFO - 'HOME': '/Users/cltbld', 06:19:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:19:39 INFO - 'LOGNAME': 'cltbld', 06:19:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:19:39 INFO - 'MOZ_NO_REMOTE': '1', 06:19:39 INFO - 'NO_EM_RESTART': '1', 06:19:39 INFO - 'PAGER': '/bin/cat', 06:19:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:19:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:19:39 INFO - 'PWD': '/builds/slave/test', 06:19:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:19:39 INFO - 'SHELL': '/bin/bash', 06:19:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IvF69ditL1/Listeners', 06:19:39 INFO - 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/', 06:19:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:19:39 INFO - 'USER': 'cltbld', 06:19:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:19:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:19:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:19:39 INFO - 'XPC_FLAGS': '0x0', 06:19:39 INFO - 'XPC_SERVICE_NAME': '0', 06:19:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:19:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:19:39 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 06:19:39 INFO - Cleaning up... 06:19:39 INFO - Return code: 0 06:19:39 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:19:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:19:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:19:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:19:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:19:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:19:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:19:39 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 0x10b1743f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0ea570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe16bcdd960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af8ad30>, '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 0x10adf1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10af82b20>, '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.s1ybmjvsyt/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.IvF69ditL1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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 06:19:39 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 06:19:39 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 06:19:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.s1ybmjvsyt/Render', 06:19:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:19:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:19:39 INFO - 'HOME': '/Users/cltbld', 06:19:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:19:39 INFO - 'LOGNAME': 'cltbld', 06:19:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:19:39 INFO - 'MOZ_NO_REMOTE': '1', 06:19:39 INFO - 'NO_EM_RESTART': '1', 06:19:39 INFO - 'PAGER': '/bin/cat', 06:19:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:19:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:19:39 INFO - 'PWD': '/builds/slave/test', 06:19:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:19:39 INFO - 'SHELL': '/bin/bash', 06:19:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IvF69ditL1/Listeners', 06:19:39 INFO - 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/', 06:19:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:19:39 INFO - 'USER': 'cltbld', 06:19:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:19:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:19:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:19:39 INFO - 'XPC_FLAGS': '0x0', 06:19:39 INFO - 'XPC_SERVICE_NAME': '0', 06:19:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:19:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:19:39 INFO - Downloading/unpacking blobuploader==1.2.4 06:19:39 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 06:19:39 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 06:19:39 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 06:19:39 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 06:19:39 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 06:19:39 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 06:19:42 INFO - Downloading blobuploader-1.2.4.tar.gz 06:19:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:19:42 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:19:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:19:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:19:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:19:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:19:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:19:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:19:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:19:42 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:19:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:19:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:19:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:19:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:19:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:19:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:19:43 INFO - Downloading docopt-0.6.1.tar.gz 06:19:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:19:43 INFO - Installing collected packages: blobuploader, requests, docopt 06:19:43 INFO - Running setup.py install for blobuploader 06:19:43 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:19:43 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:19:43 INFO - Running setup.py install for requests 06:19:44 INFO - Running setup.py install for docopt 06:19:44 INFO - Successfully installed blobuploader requests docopt 06:19:44 INFO - Cleaning up... 06:19:44 INFO - Return code: 0 06:19:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:19:44 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')]} 06:19:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:19:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:19:44 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')]} 06:19:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:19:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:19:44 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 0x10b1743f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0ea570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe16bcdd960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af8ad30>, '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 0x10adf1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10af82b20>, '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.s1ybmjvsyt/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.IvF69ditL1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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 06:19:44 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 06:19:44 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 06:19:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.s1ybmjvsyt/Render', 06:19:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:19:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:19:44 INFO - 'HOME': '/Users/cltbld', 06:19:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:19:44 INFO - 'LOGNAME': 'cltbld', 06:19:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:19:44 INFO - 'MOZ_NO_REMOTE': '1', 06:19:44 INFO - 'NO_EM_RESTART': '1', 06:19:44 INFO - 'PAGER': '/bin/cat', 06:19:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:19:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:19:44 INFO - 'PWD': '/builds/slave/test', 06:19:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:19:44 INFO - 'SHELL': '/bin/bash', 06:19:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IvF69ditL1/Listeners', 06:19:44 INFO - 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/', 06:19:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:19:44 INFO - 'USER': 'cltbld', 06:19:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:19:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:19:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:19:44 INFO - 'XPC_FLAGS': '0x0', 06:19:44 INFO - 'XPC_SERVICE_NAME': '0', 06:19:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:19:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:19:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:19:44 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-hM7XEB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:19:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:19:44 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-7oL_z6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:19:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:19:44 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-xBesOY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:19:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:19:45 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-2FZkBh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:19:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:19:45 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-Ln93yb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:19:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:19:45 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-zWJBPO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:19:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:19:45 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-qGZKgw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:19:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:19:45 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-7Ix2lX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:19:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:19:45 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-HQdrVb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:19:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:19:45 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-Vr93Mq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:19:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:19:45 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-lTxU7U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:19:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:19:45 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-Vlpn6S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:19:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:19:45 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-A3IJt3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:19:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:19:46 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-28NRMM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:19:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:19:46 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-VmJi5a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:19:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:19:46 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-vqz8rc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:19:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:19:46 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-x6C1o2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:19:46 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:19:46 INFO - Running setup.py install for manifestparser 06:19:46 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:19:46 INFO - Running setup.py install for mozcrash 06:19:46 INFO - Running setup.py install for mozdebug 06:19:46 INFO - Running setup.py install for mozdevice 06:19:47 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 06:19:47 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:19:47 INFO - Running setup.py install for mozfile 06:19:47 INFO - Running setup.py install for mozhttpd 06:19:47 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:19:47 INFO - Running setup.py install for mozinfo 06:19:47 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:19:47 INFO - Running setup.py install for mozInstall 06:19:47 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:19:47 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:19:47 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:19:47 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:19:47 INFO - Running setup.py install for mozleak 06:19:47 INFO - Running setup.py install for mozlog 06:19:47 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:19:47 INFO - Running setup.py install for moznetwork 06:19:48 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:19:48 INFO - Running setup.py install for mozprocess 06:19:48 INFO - Running setup.py install for mozprofile 06:19:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:19:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:19:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:19:48 INFO - Running setup.py install for mozrunner 06:19:48 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:19:48 INFO - Running setup.py install for mozscreenshot 06:19:48 INFO - Running setup.py install for moztest 06:19:48 INFO - Running setup.py install for mozversion 06:19:49 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:19:49 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:19:49 INFO - Cleaning up... 06:19:49 INFO - Return code: 0 06:19:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:19:49 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')]} 06:19:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:19:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:19:49 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')]} 06:19:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:19:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:19:49 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 0x10b1743f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0ea570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe16bcdd960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af8ad30>, '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 0x10adf1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10af82b20>, '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.s1ybmjvsyt/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.IvF69ditL1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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 06:19:49 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 06:19:49 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 06:19:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.s1ybmjvsyt/Render', 06:19:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:19:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:19:49 INFO - 'HOME': '/Users/cltbld', 06:19:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:19:49 INFO - 'LOGNAME': 'cltbld', 06:19:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:19:49 INFO - 'MOZ_NO_REMOTE': '1', 06:19:49 INFO - 'NO_EM_RESTART': '1', 06:19:49 INFO - 'PAGER': '/bin/cat', 06:19:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:19:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:19:49 INFO - 'PWD': '/builds/slave/test', 06:19:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:19:49 INFO - 'SHELL': '/bin/bash', 06:19:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IvF69ditL1/Listeners', 06:19:49 INFO - 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/', 06:19:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:19:49 INFO - 'USER': 'cltbld', 06:19:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:19:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:19:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:19:49 INFO - 'XPC_FLAGS': '0x0', 06:19:49 INFO - 'XPC_SERVICE_NAME': '0', 06:19:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:19:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:19:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:19:49 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-xir4Mo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:19:49 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)) 06:19:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:19:49 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-TGVJYd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:19:49 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)) 06:19:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:19:49 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-sOZ8X0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:19:49 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)) 06:19:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:19:49 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-fFQch8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:19:49 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)) 06:19:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:19:49 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-8ozqtS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:19:49 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)) 06:19:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:19:49 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-qBqZb6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:19:50 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)) 06:19:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:19:50 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-1GnYWV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:19:50 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)) 06:19:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:19:50 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-UrClCJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:19:50 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)) 06:19:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:19:50 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-jmg3Za-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:19:50 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)) 06:19:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:19:50 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-RQqy33-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:19:50 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)) 06:19:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:19:50 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-Vt1tUV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:19:50 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)) 06:19:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:19:50 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-ZKGWlm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:19:50 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)) 06:19:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:19:50 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-_CwOhD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:19:50 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)) 06:19:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:19:50 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-k9Icaf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:19:50 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)) 06:19:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:19:50 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-JZzIzm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:19:50 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)) 06:19:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:19:50 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-xJkl8J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:19:51 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)) 06:19:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:19:51 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-WEcVSE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:19:51 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)) 06:19:51 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)) 06:19:51 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)) 06:19:51 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)) 06:19:51 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)) 06:19:51 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)) 06:19:51 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:19:51 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 06:19:51 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 06:19:51 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 06:19:51 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 06:19:51 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 06:19:51 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 06:19:53 INFO - Downloading blessings-1.6.tar.gz 06:19:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:19:53 INFO - Installing collected packages: blessings 06:19:53 INFO - Running setup.py install for blessings 06:19:54 INFO - Successfully installed blessings 06:19:54 INFO - Cleaning up... 06:19:54 INFO - Return code: 0 06:19:54 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:19:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:19:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:19:54 INFO - Reading from file tmpfile_stdout 06:19:54 INFO - Current package versions: 06:19:54 INFO - blessings == 1.6 06:19:54 INFO - blobuploader == 1.2.4 06:19:54 INFO - docopt == 0.6.1 06:19:54 INFO - functools32 == 3.2.3-2 06:19:54 INFO - jsonschema == 2.5.1 06:19:54 INFO - manifestparser == 1.1 06:19:54 INFO - mozInstall == 1.12 06:19:54 INFO - mozcrash == 1.0 06:19:54 INFO - mozdebug == 0.1 06:19:54 INFO - mozdevice == 0.49 06:19:54 INFO - mozfile == 1.2 06:19:54 INFO - mozhttpd == 0.7 06:19:54 INFO - mozinfo == 0.9 06:19:54 INFO - mozleak == 0.1 06:19:54 INFO - mozlog == 3.4 06:19:54 INFO - moznetwork == 0.27 06:19:54 INFO - mozprocess == 0.23 06:19:54 INFO - mozprofile == 0.28 06:19:54 INFO - mozrunner == 6.13 06:19:54 INFO - mozscreenshot == 0.1 06:19:54 INFO - mozsystemmonitor == 0.3 06:19:54 INFO - moztest == 0.7 06:19:54 INFO - mozversion == 1.4 06:19:54 INFO - psutil == 3.1.1 06:19:54 INFO - requests == 1.2.3 06:19:54 INFO - wsgiref == 0.1.2 06:19:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:19:54 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')]} 06:19:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:19:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:19:54 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')]} 06:19:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:19:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:19:55 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 0x10b1743f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0ea570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe16bcdd960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af8ad30>, '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 0x10adf1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10af82b20>, '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.s1ybmjvsyt/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.IvF69ditL1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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 06:19:55 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 06:19:55 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 06:19:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.s1ybmjvsyt/Render', 06:19:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:19:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:19:55 INFO - 'HOME': '/Users/cltbld', 06:19:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:19:55 INFO - 'LOGNAME': 'cltbld', 06:19:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:19:55 INFO - 'MOZ_NO_REMOTE': '1', 06:19:55 INFO - 'NO_EM_RESTART': '1', 06:19:55 INFO - 'PAGER': '/bin/cat', 06:19:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:19:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:19:55 INFO - 'PWD': '/builds/slave/test', 06:19:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:19:55 INFO - 'SHELL': '/bin/bash', 06:19:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IvF69ditL1/Listeners', 06:19:55 INFO - 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/', 06:19:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:19:55 INFO - 'USER': 'cltbld', 06:19:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:19:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:19:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:19:55 INFO - 'XPC_FLAGS': '0x0', 06:19:55 INFO - 'XPC_SERVICE_NAME': '0', 06:19:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:19:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:19:55 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)) 06:19:55 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)) 06:19:55 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)) 06:19:55 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)) 06:19:55 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)) 06:19:55 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)) 06:19:55 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)) 06:19:55 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)) 06:19:55 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)) 06:19:55 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)) 06:19:55 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)) 06:19:55 INFO - Cleaning up... 06:19:55 INFO - Return code: 0 06:19:55 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:19:55 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')]} 06:19:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:19:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:19:55 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')]} 06:19:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:19:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:19:55 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 0x10b1743f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0ea570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe16bcdd960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af8ad30>, '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 0x10adf1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10af82b20>, '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.s1ybmjvsyt/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.IvF69ditL1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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 06:19:55 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 06:19:55 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 06:19:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.s1ybmjvsyt/Render', 06:19:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:19:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:19:55 INFO - 'HOME': '/Users/cltbld', 06:19:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:19:55 INFO - 'LOGNAME': 'cltbld', 06:19:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:19:55 INFO - 'MOZ_NO_REMOTE': '1', 06:19:55 INFO - 'NO_EM_RESTART': '1', 06:19:55 INFO - 'PAGER': '/bin/cat', 06:19:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:19:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:19:55 INFO - 'PWD': '/builds/slave/test', 06:19:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:19:55 INFO - 'SHELL': '/bin/bash', 06:19:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IvF69ditL1/Listeners', 06:19:55 INFO - 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/', 06:19:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:19:55 INFO - 'USER': 'cltbld', 06:19:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:19:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:19:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:19:55 INFO - 'XPC_FLAGS': '0x0', 06:19:55 INFO - 'XPC_SERVICE_NAME': '0', 06:19:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:19:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:19:56 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 06:19:56 INFO - Cleaning up... 06:19:56 INFO - Return code: 0 06:19:56 INFO - Running post-action listener: _resource_record_post_action 06:19:56 INFO - Running post-action listener: _start_resource_monitoring 06:19:56 INFO - Starting resource monitoring. 06:19:56 INFO - [mozharness: 2016-11-23 14:19:56.169684Z] Finished create-virtualenv step (success) 06:19:56 INFO - [mozharness: 2016-11-23 14:19:56.170744Z] Running install step. 06:19:56 INFO - Running pre-action listener: _resource_record_pre_action 06:19:56 INFO - Running main action method: install 06:19:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:19:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:19:56 INFO - Reading from file tmpfile_stdout 06:19:56 INFO - Detecting whether we're running mozinstall >=1.0... 06:19:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:19:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:19:56 INFO - Reading from file tmpfile_stdout 06:19:56 INFO - Output received: 06:19:56 INFO - Usage: mozinstall [options] installer 06:19:56 INFO - Options: 06:19:56 INFO - -h, --help show this help message and exit 06:19:56 INFO - -d DEST, --destination=DEST 06:19:56 INFO - Directory to install application into. [default: 06:19:56 INFO - "/builds/slave/test"] 06:19:56 INFO - --app=APP Application being installed. [default: firefox] 06:19:56 INFO - mkdir: /builds/slave/test/build/application 06:19:56 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'] 06:19:56 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 06:20:29 INFO - Reading from file tmpfile_stdout 06:20:29 INFO - Output received: 06:20:29 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 06:20:29 INFO - Running post-action listener: _resource_record_post_action 06:20:29 INFO - [mozharness: 2016-11-23 14:20:29.232208Z] Finished install step (success) 06:20:29 INFO - [mozharness: 2016-11-23 14:20:29.232358Z] Running run-tests step. 06:20:29 INFO - Running pre-action listener: _resource_record_pre_action 06:20:29 INFO - Running main action method: run_tests 06:20:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:20:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:20:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:20:29 INFO - 2016-11-23 06:20:29.247 screenresolution[1632:7439] starting screenresolution argv=screenresolution get 06:20:29 INFO - 2016-11-23 06:20:29.250 screenresolution[1632:7439] Display 0: 1600x1200x32@60 06:20:29 INFO - 2016-11-23 06:20:29.255 screenresolution[1633:7442] starting screenresolution argv=screenresolution list 06:20:29 INFO - Available Modes on Display 0 06:20:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:20:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:20:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:20:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:20:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:20:29 INFO - Intel Iris: 06:20:29 INFO - Chipset Model: Intel Iris 06:20:29 INFO - Type: GPU 06:20:29 INFO - Bus: Built-In 06:20:29 INFO - VRAM (Dynamic, Max): 1536 MB 06:20:29 INFO - Vendor: Intel (0x8086) 06:20:29 INFO - Device ID: 0x0a2e 06:20:29 INFO - Revision ID: 0x0009 06:20:29 INFO - Displays: 06:20:29 INFO - Display: 06:20:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:20:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:20:29 INFO - Display Serial Number: 1600x1200 60 06:20:29 INFO - Main Display: Yes 06:20:29 INFO - Mirror: Off 06:20:29 INFO - Online: Yes 06:20:29 INFO - Rotation: Supported 06:20:29 INFO - Return code: 0 06:20:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:20:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:20:29 INFO - Python 2.7.3 06:20:29 INFO - Return code: 0 06:20:29 INFO - grabbing minidump binary from tooltool 06:20:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:20:29 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af8ad30>, '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 0x10adf1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10af82b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:20:29 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 06:20:29 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 06:20:29 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 06:20:29 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:20:29 INFO - Return code: 0 06:20:29 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 06:20:29 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:20:29 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:20:29 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 06:20:29 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:20:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Cedar', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WlGxBgDkSs-_HxOeqvbfxQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0265', '--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 06:20:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Cedar --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/WlGxBgDkSs-_HxOeqvbfxQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0265 --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 06:20:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.s1ybmjvsyt/Render', 06:20:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:20:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:20:29 INFO - 'HOME': '/Users/cltbld', 06:20:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:20:29 INFO - 'LOGNAME': 'cltbld', 06:20:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:20:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 06:20:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:20:29 INFO - 'MOZ_NO_REMOTE': '1', 06:20:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:20:29 INFO - 'NO_EM_RESTART': '1', 06:20:29 INFO - 'PAGER': '/bin/cat', 06:20:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:20:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:20:29 INFO - 'PWD': '/builds/slave/test', 06:20:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:20:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:20:29 INFO - 'SHELL': '/bin/bash', 06:20:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IvF69ditL1/Listeners', 06:20:29 INFO - 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/', 06:20:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:20:29 INFO - 'USER': 'cltbld', 06:20:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:20:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:20:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:20:29 INFO - 'XPC_FLAGS': '0x0', 06:20:29 INFO - 'XPC_SERVICE_NAME': '0', 06:20:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:20:29 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Cedar', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WlGxBgDkSs-_HxOeqvbfxQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0265', '--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 06:20:29 INFO - mozversion application_buildid: 20161123024236 06:20:29 INFO - mozversion application_changeset: e5ed1abefc08d819a0f1d5d78ba961ff07ec1ae7 06:20:29 INFO - mozversion application_display_name: Nightly 06:20:29 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:20:29 INFO - mozversion application_name: Firefox 06:20:29 INFO - mozversion application_remotingname: firefox 06:20:29 INFO - mozversion application_repository: https://hg.mozilla.org/projects/cedar 06:20:29 INFO - mozversion application_vendor: Mozilla 06:20:29 INFO - mozversion application_version: 53.0a1 06:20:29 INFO - mozversion platform_buildid: 20161123024236 06:20:29 INFO - mozversion platform_changeset: e5ed1abefc08d819a0f1d5d78ba961ff07ec1ae7 06:20:29 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/cedar 06:20:29 INFO - mozversion platform_version: 53.0a1 06:20:29 INFO - using testdate: 1479910829 06:20:29 INFO - actual date: 1479910829 06:20:29 INFO - starting webserver on 'localhost:49248' 06:20:29 INFO - SUITE-START | Running 2 tests 06:20:29 INFO - TEST-START | dromaeo_css 06:20:29 INFO - operating with platform_type : mac_ 06:20:29 INFO - Initialising browser for dromaeo_css test... 06:20:29 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/tmpytogTF/profile http://localhost:49248/getInfo.html) 06:20:33 INFO - PROCESS | 1643 | 2016-11-23 06:20:33.065 plugin-container[1645:7609] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e3f, name = 'com.apple.tsm.portname' 06:20:33 INFO - PROCESS | 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:20:33 INFO - PROCESS | 1643 | 2016-11-23 06:20:33.068 plugin-container[1645:7609] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4d1b, name = 'com.apple.CFPasteboardClient' 06:20:33 INFO - PROCESS | 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:20:33 INFO - PROCESS | 1643 | 2016-11-23 06:20:33.068 plugin-container[1645:7609] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:20:33 INFO - PROCESS | 1643 | __metrics Screen width/height:1600/1200 06:20:33 INFO - PROCESS | 1643 | colorDepth:24 06:20:33 INFO - PROCESS | 1643 | Browser inner width/height: 1024/689 06:20:33 INFO - PROCESS | 1643 | __metrics 06:20:33 ERROR - PROCESS | 1643 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3198: TypeError: this.tabs is undefined 06:20:34 INFO - TEST-INFO | 1643: exit 0 06:20:34 INFO - Browser initialized. 06:20:34 INFO - Running cycle 1/1 for dromaeo_css test... 06:20:34 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/tmpytogTF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:20:36 INFO - PROCESS | 1646 | 2016-11-23 06:20:36.837 plugin-container[1647:7737] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8843, name = 'com.apple.tsm.portname' 06:20:36 INFO - PROCESS | 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:20:36 INFO - PROCESS | 1646 | 2016-11-23 06:20:36.837 plugin-container[1647:7737] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0f, name = 'com.apple.CFPasteboardClient' 06:20:36 INFO - PROCESS | 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:20:36 INFO - PROCESS | 1646 | 2016-11-23 06:20:36.837 plugin-container[1647:7737] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:20:46 INFO - PROCESS | 1646 | RSS: Main: 241770496 06:20:46 INFO - PROCESS | 1646 | 06:21:36 INFO - PROCESS | 1646 | 1479910896176 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] 06:23:49 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49248/tests/dromaeo/cssquery-ext.html) 06:23:51 INFO - PROCESS | 1646 | RSS: Main: 248676352 06:23:51 INFO - PROCESS | 1646 | 06:26:53 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-ext.html (next: http://localhost:49248/tests/dromaeo/cssquery-jquery.html) 06:26:54 INFO - PROCESS | 1646 | RSS: Main: 260583424 06:26:54 INFO - PROCESS | 1646 | 06:29:56 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49248/tests/dromaeo/cssquery-mootools.html) 06:29:57 INFO - PROCESS | 1646 | RSS: Main: 265732096 06:29:57 INFO - PROCESS | 1646 | 06:32:59 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49248/tests/dromaeo/cssquery-prototype.html) 06:32:59 INFO - PROCESS | 1646 | RSS: Main: 265281536 06:32:59 INFO - PROCESS | 1646 | 06:36:02 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49248/tests/dromaeo/cssquery-yui.html) 06:36:03 INFO - PROCESS | 1646 | RSS: Main: 270712832 06:36:03 INFO - PROCESS | 1646 | 06:39:06 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-yui.html (next: http://localhost:49248/tests/dromaeo/cssquery-dojo.html) 06:39:06 INFO - PROCESS | 1646 | RSS: Main: 272920576 06:39:06 INFO - PROCESS | 1646 | 06:39:06 INFO - PROCESS | 1646 | __start_tp_report 06:39:06 INFO - PROCESS | 1646 | _x_x_mozilla_page_load 06:39:06 INFO - PROCESS | 1646 | _x_x_mozilla_page_load_details 06:39:06 INFO - PROCESS | 1646 | |i|pagename|runs| 06:39:06 INFO - PROCESS | 1646 | |0;dojo.html;3343.114074770186;3346.4980252962055;3362.394768941591;3367.175042114682;3367.764256502046;4686.578207961283;5283.365996080471;5556.583256255777;5598.944010559903;5602.411746766592;5464.180372944061;5594.076977298751;5598.524125449342;5634.661920284786;5649.180868774025;5103.387593488777;5152.304438900738;5153.716545589991;5158.509941555565;5201.40183878855;5152.510511501401;5171.327727395434;5195.220716892459;5213.504717051874;5255.869987952589;67.86898997216612;5189.922151167735;5204.76578553966;5221.373435187783;5259.0796610593;4807;5028.044671512437;5148.279240906239;5161.664491808058;5183.377994640668;5080.441151473335;5095.312132862054;5119.59043276537;5142.871428214287;5144.639875208737;5437.646552974044;5606.467385598362;5632.352279487829;5647.943520564783;5657.3494048184575;5922.97038514809;5957.582969192158;5994.980853254957;6131.264248290234;6136.294326152505;6084.5436592255755;6126.548209435026;6134.4978165938865;6142.95923517857;6158.230221222348;5684.54523638108;5693.117566777156;5705.087186050295;5718.742656580423;5744.368119506851;6196.938030619726;6310.337414571496;6325.968370158212;6327.620342779448;6330.39861213184;5849.8245052647635;5877.52979761618;5881.206037185011;6050.334463209044;6087.36082711309;6105.914670190187;6111.622125543816;6121.357257487989;6139.416755408229;6141.84645383869;5921.993261145615;6002.6698531580305;6031.638101713911;6033.215681961405;6047.637141771508;4701.858944231679;4856.927146092811;6094.360092190344;6094.786682466092;6100.938990610126;4784.40194975628;4794.640401969797;4806.206975848946;4806.255030470283;4917.483664215278;4209.810558524874;4213.157368526307;4277.529471758104;4304.354346847936;4356.3683265926265;4778.784954677048;5604.131359639263;5821.301443826768;5829.038208695602;5836.533077353802;6082.695865206722;6119.654885716942;6126.693665316717;6141.815745527641;6144.78493252734;5825.79609713658;5938.970305148533;5944.138099975566;5970.910436343458;6030.4572588467245;5283.550898173621;5287.603429742708;5290.735463226823;5296.655717378358;5297.152455607162;6173.135760993375;6234.75060997555;6345.49236061114;6350.491960643138;6359.650219237894;6104.368208815833;6134.773364742599;6143.573288550908;6216.906746398898;6230.626162430269;6357.332480089524;6361.90457143134;6373.521985851173;6374.585651932795;6395.904061438989;5893.675847828411;6101.542384321105;6110.908336374869;6123.510119190365;6126.754929802758;6059.151718759465;6091.847703807441;6116.153172518083;6122.7244773984385;6137.478314343152;5681.517071048842;5864.32560255576;5891.646501209942;6008.1588577598295;6009.789657361971;5931.792387266425;5956.225690660186;5981.700914954322;5984.760609575568;6034.969825150846;5249.448807875118;5426.430224826337;5695.231143795535;5749.367569567428;5810.622309549865;5891.469767720926;5918.082697181943;5960.791372301948;5970.31341395745;5975.073863551156;5857.707114644336;5861.238039054895;5875.676837773964;5878.382769809108;5881.676507792113 06:39:06 INFO - PROCESS | 1646 | |1;ext.html;433.7787728258588;501.28060751621945;509.3454910440085;519.6051001239057;559.8858272038644;29813.105606831803;38558.80720596397;38914.80542597283;39070;39275.50142979978;39270.21459570807;39760.005999850015;39798.204035919334;39839.800800996025;39947.40078898819;8281;9075.682351117728;9247.029061948499;9271.629134834599;9291.024442433549;8335.291500222458;10995.175361847854;11228.719282017973;11230.438478076063;11408.71478213043;11155.38645374504;11182.66452006441;11199.992000719934;11208.943955280212;11244.49399777008;11151.996320331127;11178.720531986684;11193.888061119447;11200.831987520194;11204.943975280154;11035.558577656884;11078.169137314733;11110.222364421654;11129.27659702125;11138.331700097939;29551.113466596038;33145.83989560378;38109.856704298916;38274.23451530954;38993.805030974945;2558.5522533556555;2961.2152779513444;3110.644701409784;3366.5960084789735;3417.96582034179;29523.114306570624;29534;29677.258068548243;29722.554161687593;30254.941077062416;2759.599858020576;3132.9530057048933;3156.952269770514;3169.144331030609;3255.690709382605;330935;360409.9795010265;362877;364741.1762941195;380245;56958.86082278331;57149.71425142848;57412.24934633771;57579.424205758245;57696.711516442985;52586.737066314425;56617;60049.69975150184;60251.698741506014;60344;15242.618934526725;15769.132697701507;15779.211039448212;15784.526464206092;15795.763063554055;11094.334339939629;11337.659870203906;12060.155789094679;12180.878191218151;12324.198927069712;14529.893165490945;15084.170370629501;15790.526284211492;16294.837051629569;16309.939853909695;5162;5665.915011274834;6105.664188469676;6160.753569857246;6186.907196392058;829.3821103278096;939.9906000939903;956.8373376526007;956.9760755981017;1020.6886899495577;23083.653745193835;26420.735792641826;28995.42009159805;29106.41787164245;29851.552226716616;916.6287653500343;934.1079269297804;985.1133979418437;986.7911808035243;1091.0344345254348;12836.743265134644;13954.813840823645;14966.625834354229;15092.924535377253;15126.621834454227;600021;607291;616129.9193504003;616451;616804;55555;55574.72212638911;55719.72140139274;55763.44236557582;56030.43969560334;1303.8305020347293;1330.2573628546636;1332.1673953778889;1343.6640839790052;1349.7030653256268;28158.71841281561;29236;29285.560716588843;29505.11484655464;29690;48728.75635621799;49058;49152.77118071934;49193.50806491889;49231.76920577014;545302;547896;548503;549143.254283726;552818;32650.857219997186;38404;38492.23015539617;38743.45026198921;38822.6117738819;42637;44286.33570496381;44340.33489497595;45385.319220211066;45645.08709825851;31770.72917083383;37346.813265933495;37509.24981500409;38435.80782096072;38808.80595597004;27918.720812791613;34168.48747268843;37066;38027.2394552113;38302.808485957394;28915.710842892142;35690.286194275446;35884.461733074546;36259.637403625624;36297.63702363048;27290.317742056606;32672;33221.667783322824;33599.20388107974;33929.49105763366;22803.657945131166;24900.37749056288;24903.128390506245;24978.125765598117;25179.992800287782 06:39:06 INFO - PROCESS | 1646 | |2;jquery.html;323.8148376940398;332.77038843198176;347.9249120218525;363.54012174599137;374.7039838527563;18352.541186470346;18663.440096797083;19878.90060549695;19933.10301036453;20071.39785806426;18542.53643658906;18959.71560426591;19040;19424.708629370536;19571.5107122322;5172.172452407615;5434.972825135888;5440.211169380437;5446.673199608016;5448.182772584105;4309.181255561449;5077.263796749469;5308.9469105308835;5370.3287089113865;5380.192971054334;5354.250404943311;5358.490943360374;5360.303160589118;5370.275012873251;5374.596905232103;5212.843614691566;5261.342332208474;5288.894222115536;5359.222912677659;5370.43610420904;5224.451432599561;5234.031704134709;5235.895282094375;5242.2136679498;5243.003829272426;12728.981681465553;13081.738365232737;13171.012174086982;13203.471861125543;13357.666058348523;1993.750781152356;2170.7829217078324;2187.7484089329773;2418.9879050604636;2656.2828036430055;10413.062824345769;10630.681079567625;11198.272112312674;11284.492197851116;15478.5356439307;1776.289484206315;1823.188681036939;1829.1311626977079;1834.3121329501437;1867.9439616811517;101764;108495.45752271268;108594.45702771515;120668;120684;11258.887411125948;11377.772444551229;11439.485223164978;11537.826932596017;11756.294622322688;14396.640083997983;14444.494442694455;14452.349644266033;14455.060421072596;14476;8001.8399632008195;8185.181481851767;8314.127016663284;8454.365922555833;8479.109693482218;8097.595120243965;8100.149484304063;8210.055843577946;8230.670773169126;8265.876011859827;4370.972821386971;4469.486009108994;4503.279475284005;4547.272436410159;4704.3649107370065;1921.8366438852574;1934.7775005874505;1939.2727727102338;1940.9126589303514;1960.1081507913866;798.2217338095311;840.8402403543307;862.2756884217288;888.4846788862444;968.2157452463528;18848.963307018246;18861.622767544573;20581.794182058286;20750.688739668916;20849.061792219083;877.2368039805283;903.5346796399859;914.7696348411376;921.7326975177258;946.976325591852;9764.144812485605;10422.166226701846;11047.948714123;11074.006678797894;11079.72300692489;508196;520282;528966;563192;573643;113411.43294283476;114151.42924285325;118774;119769.40115299368;121978;884.9601767920459;889.7019498467956;916.9174774270348;920.1994264989369;923.1922068190333;16173.595660108122;16399.180040997664;18550.44348669542;18588.44234672962;18704.251829926652;36552.45171322486;50478.7380315495;50848.74575627098;51250.74374628103;52268;68091;77430.45139097073;78171;78646.21353786389;78722.81915771382;6443.323451037573;7807.492512986638;7826;8004.479708818908;8081.353491720531;8972.237359824489;11797.115216358636;12077.69805754828;12521.311327877054;12929.159604625669;10498.055175034284;10521.684349469528;10801.405922674014;10844.349339039367;11011.61459348919;9446.574904129468;10535.367877927349;10614.310069845435;10902.018818306073;10967.890321096687;9081.409708368936;10371.585136594753;10872.67381978542;11153.609623663133;11550.01824844864;6766.458683305422;7757.612119393895;7856.25365590268;7876.369890408639;8160.576510395476;9688.5640146195;10582.576696932036;10917.727056823644;10922.50848711794;11246.550137994389 06:39:06 INFO - PROCESS | 1646 | |3;mootools.html;1404.5996890886097;1474.6460849396149;1650.4141029934365;1652.5868532866784;1796.6047469556693;7483.588402637853;7627.542347459156;7703.57630330333;8259.628316725753;8438.11399803021;7882.605869706506;8141.43009989301;8155.796105097375;8269.503829770218;8285.171482851727;2274.8066414354757;2302.2977991712605;2307.1348244408346;2309.8960546775434;2314.2363020203375;2308.665243539686;2329.790318871301;2337.1002164166835;2357.233898982829;2359.1742889988536;1993.2624928776372;2295.8737269450176;2304.8156147508244;2308.480591866825;2321.6401457774073;2251.7297924249115;2271.046160612547;2285.04028308111;2293.5412917416584;2295.9655605165935;2290.438842483602;2300.4478925058033;2302.4243939015246;2311.653252012195;2313.1788215183706;8264.504129752235;8277.337812974947;8314.750557483225;8327.458715183493;8341.54121523316;4973.303737476756;5151.922721159186;5152.510511501401;5158.2520534522655;5162.845114646567;3183;3266.297745984601;3518.2787528556582;3524.400851855191;3525.964740352589;1022.728976821143;1045.3988956350129;1056.1286938275875;1074.897884700952;1085.0505807418508;10553.472326383728;10740.140788736882;10788.08301294391;10805.675829725122;10827.025567698865;3876.457295978565;4382.364557139197;4482.99132695141;4559.176329468173;4585.449746030497;7339.669714862844;8100.635471403683;8616.956915215384;9329.11373419524;9357.485338306322;5105.33630628016;5167.354080739908;5169.638125331266;5173.430922598511;5174.870628234249;4235.407043013981;4391.626711729475;4557.749323787158;4700;4736.928946065812;3694.464302676097;3772.660460558563;3778.981105094457;3823.7705737655274;3853.056001279648;1235.610782603495;1235.6972541727334;1242.5092088625124;1248.1387842388904;1264.178284115314;667.6261293675557;883.5449743382163;885.4642941020719;895.1451363947415;896.2516298890499;5499.422560631157;5515.420880807537;5634.084411300178;5722.656640601577;5729.684867332254;419.6013786902455;424.9065205654751;432.6149726743201;435.23616053825333;436.4152036271412;3270.296886169485;3546.858125674996;3857.1321452672923;3876.2247550490015;3915.647591716759;9521.952975047918;10005.099541041342;10053.145482633912;10058.547365368577;10067.748306292402;9890.752731181634;9935.80128397428;9991.500424978867;9994.500274986221;10014.899851001397;6612.900806487906;7462.440316976248;7595.848083038309;7689.154193038873;7819.3744500439825;5383.353997520323;5490.592296529555;5503.532199762904;5612.831615051555;5620.156976453564;8940.955295223483;8999.280057595508;8999.685011024583;9103.31725120632;9177.898652161783;9213.907860921305;9305.441673499612;9418.952905235705;9474.431534107976;9475.905240947503;6535.379138981789;6751.696173672099;6911.7926462206215;6998.825029374307;7186.10173728284;7816.765497035098;7912.0505539335645;7984.121746607984;7996.640151193326;7998.600069996361;7004;7123.430125589836;7179.856402871809;7182.712691492282;7238.565686058853;6775.4240889524945;6871.862562748617;7016.052832867497;7033.296670332926;7054.329838665319;6584.637844918576;6632.668366581748;6711.295313991961;6723.092382528492;6728.495362847904;4744.833930812405;4791.065742180242;4803.303520989367;4853.684510506806;4899.534544218293;4763.95236047649;4834.468208496992;4884.12085824555;4939.753012349296;4989.101961646938 06:39:06 INFO - PROCESS | 1646 | |4;prototype.html;294.1249781898851;300.05781845006675;300.13261673762815;306.722416213327;310.6644823590523;4577.519360467153;4588.73809702332;4950.811805166761;5247.422783493822;5321.388040375358;4022.9195416091734;4633.51727447217;5121.564667003309;5255.474452554743;5333.879680386968;3620.7284453665948;3957.4261732048835;4167.458040525003;4176.958230417687;4190.141021090684;3824.579296277407;4189.6438802701805;4192.832286708528;4201.831926722927;4207.474065741782;3844.308024555593;3968.0675041365257;4034.5763694812063;4060.309747342944;4062.248484030449;4015.7590544567274;4040.272750904836;4061.8220715992325;4081.89795255121;4183.828528012162;3106.937861242785;3507;3533.893983180483;3542.3800834854055;3547;4115.115250221217;4169.478815148106;5019.222020586778;5265.947340526623;5271.947280527184;4637.25803871379;4645.349651048856;4653.767311634439;4660.324252983299;4690.273007683814;4816.56650901417;5040.697558146523;5481.369642491125;5493.917591236094;5686.9715651421475;2889.4943384907556;3204.599425071866;3698.7965661888584;3813.9427908581392;3839.616038396166;5183.50756678115;5910.044977511245;5950.613210141327;5985.461308482214;6000.669963152025;3471.8090505022465;3731.869384571581;3915.471411359487;3919.451276821247;3937;3342.799432034086;3471.8090505022465;3835.5013848199555;3973.1921975501077;4012.6589239914933;3460.4290292102023;3881.4565960765517;4106.281400754856;4396.164728701537;4639.30410438437;5695.430456954355;5704.515116215168;5705.885882282331;5722.856928576819;5736.311642602831;1127.9661610151709;1154.7921374152565;1203.199872085071;1271.9109662323733;1287.4357655448675;787.1773996174012;839.937479088953;865.6580650642961;869.7519060148707;881.8589025755848;4507.8196872124745;4840;4948.73807179167;5155.690658560498;5196.792128314825;4570.611498022706;4870.198026730132;5081.8475445736685;5175.14610089331;5372.247885296062;4199.727017743837;4627.537246275346;5164.727519891236;5194.052520084762;5217.139172036647;2503.6745223121243;2802.425502771968;2813.3682464170734;2920.6349206349205;2935.765138788892;5171.4828517148735;5334.413214546396;5621.49406553404;5844.006518891799;5855.473007429352;4150.85873819084;4986.301917731521;5605.80379686709;5643.1535269709875;5650.672092058363;5804.738786754522;5835.912461313169;5842.130518234124;5858.150568167592;5858.794942177004;5162.561182299547;5466.453354664502;5531.865967476577;5542.584306177134;5553.611247212818;5448.945510544844;5481.232627432242;5487.64330318528;5827.863566604471;5902.2327097477055;6282.303777980012;6284.371562843679;6309.463695585927;6312.526560508072;6321.273053598711;3235.449973504557;3799.7340186186702;3942.4480572719267;3973.980130099331;3976.562578116347;5196.766145523537;5269.552088072403;5846.444587764156;5898.9115163273445;5915.85210369727;4509.481409637933;5193.974030129825;5547.001539722889;5770.596058275881;5787.508061814793;5745;5763.250777398911;5765.192872997867;5782.971085144715;5792.7103644818435;4508.887277818081;4701.553352431513;5332.68003919766;5577.219189313419;5661.094224923992;5737.368889422244;5746.454086861741;5749.741261643319;5768.711564421678;5782.739776709974;5568.331800184003;5624.578156638187;5624.775008999758;5629.662220266797;5654.900197356883 06:39:06 INFO - PROCESS | 1646 | |5;yui.html;679.1374953808663;684.8116767888831;690.3855568544;735.6652723011024;753.5968256982516;14152.929235353822;14752;14791.852081479183;14810.333534990938;14842.851571484282;13253.602391928258;13253.734925301489;13432.26122563261;13439.328033598305;13443.596692099252;661.8014595621319;676.7022510095567;677.6510097299895;831.600831600832;833.9291160251386;811.679386642276;839.2866063845744;849.0617867256685;852.3436953545767;857.6740838481369;856.8372009318209;893.5175005497024;894.7136916186823;895.7044175422095;899.2850683706437;868.5006121480143;885.8759773631697;892.3218354050903;894.3381897395946;894.7852515396323;573.9124359339071;640.5068097564896;644.5262731892102;668.6055227415848;670.3865962644223;10069.597216111346;12773.872261277362;14203.076800007966;14210.502632407919;14233.928830355782;13105.541306054338;13351.532696355582;13460.932695336558;13461.798073028913;13477.932610336984;11422.200445968703;11545.480453379618;11611.593594224161;11657;11915.165938384316;3591.299696559172;3621.112827357288;3741.345264578688;3778.414345776409;3783.4324851272254;95958.0404195956;96002.51998740032;96112.51943740307;97149.51425242829;97154.54268185984;10131.7467063324;10199.643012494675;10374;10412;10641.73395665099;20206.29277975264;20440.591188176153;20449.182032718523;20503.79496205019;20671.966401679856;7558.735444259424;7599.316061554487;7609;7714.727070033494;7768.300852923264;4188.874333769992;4244.448221731155;4297.011687311936;4349.28236840924;4393.428854248928;3029.742471889914;3101.674324195972;3105.0840002199407;3105.534169874492;3114.672959339282;589.908564172554;599.3526990849872;617.8239201827457;626.0890404461497;627.8461776864606;482.14178761804044;559.2561892682722;586.5688718791685;672.4049216443483;682.7780971184286;8623.741287761377;8873.778155546162;9730.95134524337;9808.166305863939;9880.407175569346;483.7943873853598;576.7202906590301;637.1907677249867;640.5580149696694;675.6452862247261;3383.509391138271;4457.064016556559;4466.6873318867365;4526.343680166423;4821.2045012572535;104675.4766226164;116761;116978.41510792561;117396;124139.13791293137;69254.65372673105;69691;97157;97314;97810;57660;59339.406605933385;78644;79224;86592.93849430606;8659.523726194868;8723.694670686495;9522.714778349631;9757.316987810786;9790.957656806608;46287;51494;52459.73770131125;55129.897402052535;57960;75610.6219468899;81254.18745812467;82109;82163;83811.74282385648;10705.411202383944;11740.765184696429;12049;12112;12157.635270941886;13938.93030534841;13995.23026233567;14219.50231741884;14399.568012959628;14457.638559036108;12781.552645657357;12919.677008074874;13012.284324361872;13018.218906865619;13526.79709804334;12800.231986080493;12954.352282385655;13127.48248537967;13221.47114115463;13467.989900757286;12602.369881505705;12658.810117848056;12807.29559874216;12950.7943545777;13119.27843968591;9332.766680833034;10816.513256903301;10907.2364934457;11244.26912250701;11469.827952580885;7760.611969401621;8400.87398689008;11045.447727613748;11243.381614011309;11257.437128143396 06:39:06 INFO - PROCESS | 1646 | __end_tp_report 06:39:06 INFO - PROCESS | 1646 | __start_cc_report 06:39:06 INFO - PROCESS | 1646 | _x_x_mozilla_cycle_collect,362 06:39:06 INFO - PROCESS | 1646 | __end_cc_report 06:39:06 INFO - PROCESS | 1646 | __startTimestamp1479911946396__endTimestamp 06:39:06 INFO - PROCESS | 1646 | 06:39:06 INFO - PROCESS | 1646 | ------- Summary: start ------- 06:39:06 INFO - PROCESS | 1646 | Number of tests: 6 06:39:06 INFO - PROCESS | 1646 | 06:39:06 INFO - PROCESS | 1646 | [#0] dojo.html Cycles:165 Average:5597.80 Median:5875.68 stddev:775.81 (13.2%) stddev-sans-first:757.76 06:39:06 INFO - PROCESS | 1646 | Values: 3343.1 3346.5 3362.4 3367.2 3367.8 4686.6 5283.4 5556.6 5598.9 5602.4 5464.2 5594.1 5598.5 5634.7 5649.2 5103.4 5152.3 5153.7 5158.5 5201.4 5152.5 5171.3 5195.2 5213.5 5255.9 67.9 5189.9 5204.8 5221.4 5259.1 4807.0 5028.0 5148.3 5161.7 5183.4 5080.4 5095.3 5119.6 5142.9 5144.6 5437.6 5606.5 5632.4 5647.9 5657.3 5923.0 5957.6 5995.0 6131.3 6136.3 6084.5 6126.5 6134.5 6143.0 6158.2 5684.5 5693.1 5705.1 5718.7 5744.4 6196.9 6310.3 6326.0 6327.6 6330.4 5849.8 5877.5 5881.2 6050.3 6087.4 6105.9 6111.6 6121.4 6139.4 6141.8 5922.0 6002.7 6031.6 6033.2 6047.6 4701.9 4856.9 6094.4 6094.8 6100.9 4784.4 4794.6 4806.2 4806.3 4917.5 4209.8 4213.2 4277.5 4304.4 4356.4 4778.8 5604.1 5821.3 5829.0 5836.5 6082.7 6119.7 6126.7 6141.8 6144.8 5825.8 5939.0 5944.1 5970.9 6030.5 5283.6 5287.6 5290.7 5296.7 5297.2 6173.1 6234.8 6345.5 6350.5 6359.7 6104.4 6134.8 6143.6 6216.9 6230.6 6357.3 6361.9 6373.5 6374.6 6395.9 5893.7 6101.5 6110.9 6123.5 6126.8 6059.2 6091.8 6116.2 6122.7 6137.5 5681.5 5864.3 5891.6 6008.2 6009.8 5931.8 5956.2 5981.7 5984.8 6035.0 5249.4 5426.4 5695.2 5749.4 5810.6 5891.5 5918.1 5960.8 5970.3 5975.1 5857.7 5861.2 5875.7 5878.4 5881.7 06:39:06 INFO - PROCESS | 1646 | 06:39:06 INFO - PROCESS | 1646 | [#1] ext.html Cycles:180 Average:64177.68 Median:35787.37 stddev:138385.30 (386.7%) stddev-sans-first:138690.75 06:39:06 INFO - PROCESS | 1646 | Values: 433.8 501.3 509.3 519.6 559.9 29813.1 38558.8 38914.8 39070.0 39275.5 39270.2 39760.0 39798.2 39839.8 39947.4 8281.0 9075.7 9247.0 9271.6 9291.0 8335.3 10995.2 11228.7 11230.4 11408.7 11155.4 11182.7 11200.0 11208.9 11244.5 11152.0 11178.7 11193.9 11200.8 11204.9 11035.6 11078.2 11110.2 11129.3 11138.3 29551.1 33145.8 38109.9 38274.2 38993.8 2558.6 2961.2 3110.6 3366.6 3418.0 29523.1 29534.0 29677.3 29722.6 30254.9 2759.6 3133.0 3157.0 3169.1 3255.7 330935.0 360410.0 362877.0 364741.2 380245.0 56958.9 57149.7 57412.2 57579.4 57696.7 52586.7 56617.0 60049.7 60251.7 60344.0 15242.6 15769.1 15779.2 15784.5 15795.8 11094.3 11337.7 12060.2 12180.9 12324.2 14529.9 15084.2 15790.5 16294.8 16309.9 5162.0 5665.9 6105.7 6160.8 6186.9 829.4 940.0 956.8 957.0 1020.7 23083.7 26420.7 28995.4 29106.4 29851.6 916.6 934.1 985.1 986.8 1091.0 12836.7 13954.8 14966.6 15092.9 15126.6 600021.0 607291.0 616129.9 616451.0 616804.0 55555.0 55574.7 55719.7 55763.4 56030.4 1303.8 1330.3 1332.2 1343.7 1349.7 28158.7 29236.0 29285.6 29505.1 29690.0 48728.8 49058.0 49152.8 49193.5 49231.8 545302.0 547896.0 548503.0 549143.3 552818.0 32650.9 38404.0 38492.2 38743.5 38822.6 42637.0 44286.3 44340.3 45385.3 45645.1 31770.7 37346.8 37509.2 38435.8 38808.8 27918.7 34168.5 37066.0 38027.2 38302.8 28915.7 35690.3 35884.5 36259.6 36297.6 27290.3 32672.0 33221.7 33599.2 33929.5 22803.7 24900.4 24903.1 24978.1 25180.0 06:39:06 INFO - PROCESS | 1646 | 06:39:06 INFO - PROCESS | 1646 | [#2] jquery.html Cycles:180 Average:32127.68 Median:328.29 stddev:90271.48 (27497.3%) stddev-sans-first:90493.13 06:39:06 INFO - PROCESS | 1646 | Values: 323.8 332.8 347.9 363.5 374.7 18352.5 18663.4 19878.9 19933.1 20071.4 18542.5 18959.7 19040.0 19424.7 19571.5 5172.2 5435.0 5440.2 5446.7 5448.2 4309.2 5077.3 5308.9 5370.3 5380.2 5354.3 5358.5 5360.3 5370.3 5374.6 5212.8 5261.3 5288.9 5359.2 5370.4 5224.5 5234.0 5235.9 5242.2 5243.0 12729.0 13081.7 13171.0 13203.5 13357.7 1993.8 2170.8 2187.7 2419.0 2656.3 10413.1 10630.7 11198.3 11284.5 15478.5 1776.3 1823.2 1829.1 1834.3 1867.9 101764.0 108495.5 108594.5 120668.0 120684.0 11258.9 11377.8 11439.5 11537.8 11756.3 14396.6 14444.5 14452.3 14455.1 14476.0 8001.8 8185.2 8314.1 8454.4 8479.1 8097.6 8100.1 8210.1 8230.7 8265.9 4371.0 4469.5 4503.3 4547.3 4704.4 1921.8 1934.8 1939.3 1940.9 1960.1 798.2 840.8 862.3 888.5 968.2 18849.0 18861.6 20581.8 20750.7 20849.1 877.2 903.5 914.8 921.7 947.0 9764.1 10422.2 11047.9 11074.0 11079.7 508196.0 520282.0 528966.0 563192.0 573643.0 113411.4 114151.4 118774.0 119769.4 121978.0 885.0 889.7 916.9 920.2 923.2 16173.6 16399.2 18550.4 18588.4 18704.3 36552.5 50478.7 50848.7 51250.7 52268.0 68091.0 77430.5 78171.0 78646.2 78722.8 6443.3 7807.5 7826.0 8004.5 8081.4 8972.2 11797.1 12077.7 12521.3 12929.2 10498.1 10521.7 10801.4 10844.3 11011.6 9446.6 10535.4 10614.3 10902.0 10967.9 9081.4 10371.6 10872.7 11153.6 11550.0 6766.5 7757.6 7856.3 7876.4 8160.6 9688.6 10582.6 10917.7 10922.5 11246.6 06:39:06 INFO - PROCESS | 1646 | 06:39:06 INFO - PROCESS | 1646 | [#3] mootools.html Cycles:180 Average:5348.51 Median:5047.22 stddev:2937.05 (58.2%) stddev-sans-first:2930.34 06:39:06 INFO - PROCESS | 1646 | Values: 1404.6 1474.6 1650.4 1652.6 1796.6 7483.6 7627.5 7703.6 8259.6 8438.1 7882.6 8141.4 8155.8 8269.5 8285.2 2274.8 2302.3 2307.1 2309.9 2314.2 2308.7 2329.8 2337.1 2357.2 2359.2 1993.3 2295.9 2304.8 2308.5 2321.6 2251.7 2271.0 2285.0 2293.5 2296.0 2290.4 2300.4 2302.4 2311.7 2313.2 8264.5 8277.3 8314.8 8327.5 8341.5 4973.3 5151.9 5152.5 5158.3 5162.8 3183.0 3266.3 3518.3 3524.4 3526.0 1022.7 1045.4 1056.1 1074.9 1085.1 10553.5 10740.1 10788.1 10805.7 10827.0 3876.5 4382.4 4483.0 4559.2 4585.4 7339.7 8100.6 8617.0 9329.1 9357.5 5105.3 5167.4 5169.6 5173.4 5174.9 4235.4 4391.6 4557.7 4700.0 4736.9 3694.5 3772.7 3779.0 3823.8 3853.1 1235.6 1235.7 1242.5 1248.1 1264.2 667.6 883.5 885.5 895.1 896.3 5499.4 5515.4 5634.1 5722.7 5729.7 419.6 424.9 432.6 435.2 436.4 3270.3 3546.9 3857.1 3876.2 3915.6 9522.0 10005.1 10053.1 10058.5 10067.7 9890.8 9935.8 9991.5 9994.5 10014.9 6612.9 7462.4 7595.8 7689.2 7819.4 5383.4 5490.6 5503.5 5612.8 5620.2 8941.0 8999.3 8999.7 9103.3 9177.9 9213.9 9305.4 9419.0 9474.4 9475.9 6535.4 6751.7 6911.8 6998.8 7186.1 7816.8 7912.1 7984.1 7996.6 7998.6 7004.0 7123.4 7179.9 7182.7 7238.6 6775.4 6871.9 7016.1 7033.3 7054.3 6584.6 6632.7 6711.3 6723.1 6728.5 4744.8 4791.1 4803.3 4853.7 4899.5 4764.0 4834.5 4884.1 4939.8 4989.1 06:39:06 INFO - PROCESS | 1646 | 06:39:06 INFO - PROCESS | 1646 | [#4] prototype.html Cycles:180 Average:4496.76 Median:5029.96 stddev:1425.56 (28.3%) stddev-sans-first:1394.22 06:39:06 INFO - PROCESS | 1646 | Values: 294.1 300.1 300.1 306.7 310.7 4577.5 4588.7 4950.8 5247.4 5321.4 4022.9 4633.5 5121.6 5255.5 5333.9 3620.7 3957.4 4167.5 4177.0 4190.1 3824.6 4189.6 4192.8 4201.8 4207.5 3844.3 3968.1 4034.6 4060.3 4062.2 4015.8 4040.3 4061.8 4081.9 4183.8 3106.9 3507.0 3533.9 3542.4 3547.0 4115.1 4169.5 5019.2 5265.9 5271.9 4637.3 4645.3 4653.8 4660.3 4690.3 4816.6 5040.7 5481.4 5493.9 5687.0 2889.5 3204.6 3698.8 3813.9 3839.6 5183.5 5910.0 5950.6 5985.5 6000.7 3471.8 3731.9 3915.5 3919.5 3937.0 3342.8 3471.8 3835.5 3973.2 4012.7 3460.4 3881.5 4106.3 4396.2 4639.3 5695.4 5704.5 5705.9 5722.9 5736.3 1128.0 1154.8 1203.2 1271.9 1287.4 787.2 839.9 865.7 869.8 881.9 4507.8 4840.0 4948.7 5155.7 5196.8 4570.6 4870.2 5081.8 5175.1 5372.2 4199.7 4627.5 5164.7 5194.1 5217.1 2503.7 2802.4 2813.4 2920.6 2935.8 5171.5 5334.4 5621.5 5844.0 5855.5 4150.9 4986.3 5605.8 5643.2 5650.7 5804.7 5835.9 5842.1 5858.2 5858.8 5162.6 5466.5 5531.9 5542.6 5553.6 5448.9 5481.2 5487.6 5827.9 5902.2 6282.3 6284.4 6309.5 6312.5 6321.3 3235.4 3799.7 3942.4 3974.0 3976.6 5196.8 5269.6 5846.4 5898.9 5915.9 4509.5 5194.0 5547.0 5770.6 5787.5 5745.0 5763.3 5765.2 5783.0 5792.7 4508.9 4701.6 5332.7 5577.2 5661.1 5737.4 5746.5 5749.7 5768.7 5782.7 5568.3 5624.6 5624.8 5629.7 5654.9 06:39:06 INFO - PROCESS | 1646 | 06:39:06 INFO - PROCESS | 1646 | [#5] yui.html Cycles:180 Average:20430.60 Median:25406.67 stddev:30309.81 (119.3%) stddev-sans-first:30358.55 06:39:06 INFO - PROCESS | 1646 | Values: 679.1 684.8 690.4 735.7 753.6 14152.9 14752.0 14791.9 14810.3 14842.9 13253.6 13253.7 13432.3 13439.3 13443.6 661.8 676.7 677.7 831.6 833.9 811.7 839.3 849.1 852.3 857.7 856.8 893.5 894.7 895.7 899.3 868.5 885.9 892.3 894.3 894.8 573.9 640.5 644.5 668.6 670.4 10069.6 12773.9 14203.1 14210.5 14233.9 13105.5 13351.5 13460.9 13461.8 13477.9 11422.2 11545.5 11611.6 11657.0 11915.2 3591.3 3621.1 3741.3 3778.4 3783.4 95958.0 96002.5 96112.5 97149.5 97154.5 10131.7 10199.6 10374.0 10412.0 10641.7 20206.3 20440.6 20449.2 20503.8 20672.0 7558.7 7599.3 7609.0 7714.7 7768.3 4188.9 4244.4 4297.0 4349.3 4393.4 3029.7 3101.7 3105.1 3105.5 3114.7 589.9 599.4 617.8 626.1 627.8 482.1 559.3 586.6 672.4 682.8 8623.7 8873.8 9731.0 9808.2 9880.4 483.8 576.7 637.2 640.6 675.6 3383.5 4457.1 4466.7 4526.3 4821.2 104675.5 116761.0 116978.4 117396.0 124139.1 69254.7 69691.0 97157.0 97314.0 97810.0 57660.0 59339.4 78644.0 79224.0 86592.9 8659.5 8723.7 9522.7 9757.3 9791.0 46287.0 51494.0 52459.7 55129.9 57960.0 75610.6 81254.2 82109.0 82163.0 83811.7 10705.4 11740.8 12049.0 12112.0 12157.6 13938.9 13995.2 14219.5 14399.6 14457.6 12781.6 12919.7 13012.3 13018.2 13526.8 12800.2 12954.4 13127.5 13221.5 13468.0 12602.4 12658.8 12807.3 12950.8 13119.3 9332.8 10816.5 10907.2 11244.3 11469.8 7760.6 8400.9 11045.4 11243.4 11257.4 06:39:06 INFO - PROCESS | 1646 | -------- Summary: end -------- 06:39:06 INFO - PROCESS | 1646 | 06:39:07 INFO - TEST-INFO | 1646: exit 0 06:39:07 INFO - TEST-OK | dromaeo_css | took 1117220ms 06:39:07 INFO - TEST-START | kraken 06:39:07 INFO - operating with platform_type : mac_ 06:39:07 INFO - Initialising browser for kraken test... 06:39:07 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/tmpof_5Qn/profile http://localhost:49248/getInfo.html) 06:39:10 INFO - PROCESS | 1658 | 2016-11-23 06:39:10.090 plugin-container[1659:10021] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b3f, name = 'com.apple.tsm.portname' 06:39:10 INFO - PROCESS | 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:39:10 INFO - PROCESS | 1658 | 2016-11-23 06:39:10.093 plugin-container[1659:10021] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900f, name = 'com.apple.CFPasteboardClient' 06:39:10 INFO - PROCESS | 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:39:10 INFO - PROCESS | 1658 | 2016-11-23 06:39:10.093 plugin-container[1659:10021] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:39:10 INFO - PROCESS | 1658 | __metrics Screen width/height:1600/1200 06:39:10 INFO - PROCESS | 1658 | colorDepth:24 06:39:10 INFO - PROCESS | 1658 | Browser inner width/height: 1024/689 06:39:10 INFO - PROCESS | 1658 | __metrics 06:39:10 ERROR - PROCESS | 1658 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3198: TypeError: this.tabs is undefined 06:39:10 INFO - TEST-INFO | 1658: exit 0 06:39:10 INFO - Browser initialized. 06:39:10 INFO - Running cycle 1/1 for kraken test... 06:39:10 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/tmpof_5Qn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:39:13 INFO - PROCESS | 1660 | 2016-11-23 06:39:13.680 plugin-container[1661:10133] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 06:39:13 INFO - PROCESS | 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:39:13 INFO - PROCESS | 1660 | 2016-11-23 06:39:13.681 plugin-container[1661:10133] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 06:39:13 INFO - PROCESS | 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:39:13 INFO - PROCESS | 1660 | 2016-11-23 06:39:13.681 plugin-container[1661:10133] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:39:23 INFO - PROCESS | 1660 | RSS: Main: 241766400 06:39:23 INFO - PROCESS | 1660 | 06:39:26 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-beat-detection) 06:39:27 INFO - PROCESS | 1660 | RSS: Main: 253767680 06:39:27 INFO - PROCESS | 1660 | 06:39:30 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-dft) 06:39:30 INFO - PROCESS | 1660 | RSS: Main: 261521408 06:39:30 INFO - PROCESS | 1660 | 06:39:34 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-fft) 06:39:34 INFO - PROCESS | 1660 | RSS: Main: 264282112 06:39:34 INFO - PROCESS | 1660 | 06:39:36 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-oscillator) 06:39:37 INFO - PROCESS | 1660 | RSS: Main: 265453568 06:39:37 INFO - PROCESS | 1660 | 06:39:39 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49248/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:39:40 INFO - PROCESS | 1660 | RSS: Main: 267128832 06:39:40 INFO - PROCESS | 1660 | 06:39:43 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49248/tests/kraken/driver.html?testName=imaging-darkroom) 06:39:43 INFO - PROCESS | 1660 | RSS: Main: 272744448 06:39:43 INFO - PROCESS | 1660 | 06:39:46 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49248/tests/kraken/driver.html?testName=imaging-desaturate) 06:39:47 INFO - PROCESS | 1660 | RSS: Main: 272367616 06:39:47 INFO - PROCESS | 1660 | 06:39:50 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49248/tests/kraken/driver.html?testName=json-parse-financial) 06:39:51 INFO - PROCESS | 1660 | RSS: Main: 272465920 06:39:51 INFO - PROCESS | 1660 | 06:39:52 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49248/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:39:53 INFO - PROCESS | 1660 | RSS: Main: 271532032 06:39:53 INFO - PROCESS | 1660 | 06:39:55 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:39:56 INFO - PROCESS | 1660 | RSS: Main: 271556608 06:39:56 INFO - PROCESS | 1660 | 06:39:58 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:39:59 INFO - PROCESS | 1660 | RSS: Main: 271556608 06:39:59 INFO - PROCESS | 1660 | 06:40:02 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:40:02 INFO - PROCESS | 1660 | RSS: Main: 271896576 06:40:02 INFO - PROCESS | 1660 | 06:40:05 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:40:06 INFO - PROCESS | 1660 | RSS: Main: 271704064 06:40:06 INFO - PROCESS | 1660 | 06:40:08 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49248/tests/kraken/driver.html?testName=ai-astar) 06:40:08 INFO - PROCESS | 1660 | RSS: Main: 274907136 06:40:08 INFO - PROCESS | 1660 | 06:40:08 INFO - PROCESS | 1660 | __start_tp_report 06:40:08 INFO - PROCESS | 1660 | _x_x_mozilla_page_load 06:40:08 INFO - PROCESS | 1660 | _x_x_mozilla_page_load_details 06:40:08 INFO - PROCESS | 1660 | |i|pagename|runs| 06:40:08 INFO - PROCESS | 1660 | |0;ai-astar;107;117;118;117;118;117;119;119;116;121 06:40:08 INFO - PROCESS | 1660 | |1;audio-beat-detection;148;143;147;189;106;142;150;151;133;112 06:40:08 INFO - PROCESS | 1660 | |2;audio-dft;208;259;280;267;194;199;196;199;255;214 06:40:08 INFO - PROCESS | 1660 | |3;audio-fft;85;60;63;93;64;61;76;51;68;52 06:40:08 INFO - PROCESS | 1660 | |4;audio-oscillator;89;71;83;73;73;71;79;70;62;98 06:40:08 INFO - PROCESS | 1660 | |5;imaging-gaussian-blur;79;132;136;88;90;90;92;84;124;134 06:40:08 INFO - PROCESS | 1660 | |6;imaging-darkroom;155;111;153;154;95;108;97;95;97;96 06:40:08 INFO - PROCESS | 1660 | |7;imaging-desaturate;110;143;112;92;91;92;98;140;93;149 06:40:08 INFO - PROCESS | 1660 | |8;json-parse-financial;58;56;56;57;56;58;59;59;59;57 06:40:08 INFO - PROCESS | 1660 | |9;json-stringify-tinderbox;69;79;75;86;78;81;73;109;110;85 06:40:08 INFO - PROCESS | 1660 | |10;stanford-crypto-aes;86;107;91;79;81;97;80;95;108;126 06:40:08 INFO - PROCESS | 1660 | |11;stanford-crypto-ccm;163;147;150;148;151;152;141;166;213;170 06:40:08 INFO - PROCESS | 1660 | |12;stanford-crypto-pbkdf2;181;182;236;189;176;174;179;173;174;177 06:40:08 INFO - PROCESS | 1660 | |13;stanford-crypto-sha256-iterative;66;69;68;67;71;81;87;92;90;90 06:40:08 INFO - PROCESS | 1660 | __end_tp_report 06:40:08 INFO - PROCESS | 1660 | __start_cc_report 06:40:08 INFO - PROCESS | 1660 | _x_x_mozilla_cycle_collect,968 06:40:08 INFO - PROCESS | 1660 | __end_cc_report 06:40:08 INFO - PROCESS | 1660 | __startTimestamp1479912008594__endTimestamp 06:40:08 INFO - PROCESS | 1660 | 06:40:08 INFO - PROCESS | 1660 | ------- Summary: start ------- 06:40:08 INFO - PROCESS | 1660 | Number of tests: 14 06:40:08 INFO - PROCESS | 1660 | 06:40:08 INFO - PROCESS | 1660 | [#0] ai-astar Cycles:10 Average:116.90 Median:118.00 stddev:3.75 (3.2%) stddev-sans-first:1.50 06:40:08 INFO - PROCESS | 1660 | Values: 107.0 117.0 118.0 117.0 118.0 117.0 119.0 119.0 116.0 121.0 06:40:08 INFO - PROCESS | 1660 | 06:40:08 INFO - PROCESS | 1660 | [#1] audio-beat-detection Cycles:10 Average:142.10 Median:147.50 stddev:22.83 (15.5%) stddev-sans-first:24.12 06:40:08 INFO - PROCESS | 1660 | Values: 148.0 143.0 147.0 189.0 106.0 142.0 150.0 151.0 133.0 112.0 06:40:08 INFO - PROCESS | 1660 | 06:40:08 INFO - PROCESS | 1660 | [#2] audio-dft Cycles:10 Average:227.10 Median:234.50 stddev:33.94 (14.5%) stddev-sans-first:35.28 06:40:08 INFO - PROCESS | 1660 | Values: 208.0 259.0 280.0 267.0 194.0 199.0 196.0 199.0 255.0 214.0 06:40:08 INFO - PROCESS | 1660 | 06:40:08 INFO - PROCESS | 1660 | [#3] audio-fft Cycles:10 Average:67.30 Median:66.00 stddev:13.63 (20.7%) stddev-sans-first:12.86 06:40:08 INFO - PROCESS | 1660 | Values: 85.0 60.0 63.0 93.0 64.0 61.0 76.0 51.0 68.0 52.0 06:40:08 INFO - PROCESS | 1660 | 06:40:08 INFO - PROCESS | 1660 | [#4] audio-oscillator Cycles:10 Average:76.90 Median:76.00 stddev:10.56 (13.9%) stddev-sans-first:10.25 06:40:08 INFO - PROCESS | 1660 | Values: 89.0 71.0 83.0 73.0 73.0 71.0 79.0 70.0 62.0 98.0 06:40:08 INFO - PROCESS | 1660 | 06:40:08 INFO - PROCESS | 1660 | [#5] imaging-gaussian-blur Cycles:10 Average:104.90 Median:86.00 stddev:23.37 (27.2%) stddev-sans-first:22.84 06:40:08 INFO - PROCESS | 1660 | Values: 79.0 132.0 136.0 88.0 90.0 90.0 92.0 84.0 124.0 134.0 06:40:08 INFO - PROCESS | 1660 | 06:40:08 INFO - PROCESS | 1660 | [#6] imaging-darkroom Cycles:10 Average:116.10 Median:95.00 stddev:26.72 (28.1%) stddev-sans-first:24.36 06:40:08 INFO - PROCESS | 1660 | Values: 155.0 111.0 153.0 154.0 95.0 108.0 97.0 95.0 97.0 96.0 06:40:08 INFO - PROCESS | 1660 | 06:40:08 INFO - PROCESS | 1660 | [#7] imaging-desaturate Cycles:10 Average:112.00 Median:91.50 stddev:23.37 (25.5%) stddev-sans-first:24.78 06:40:08 INFO - PROCESS | 1660 | Values: 110.0 143.0 112.0 92.0 91.0 92.0 98.0 140.0 93.0 149.0 06:40:08 INFO - PROCESS | 1660 | 06:40:08 INFO - PROCESS | 1660 | [#8] json-parse-financial Cycles:10 Average:57.50 Median:58.00 stddev:1.27 (2.2%) stddev-sans-first:1.33 06:40:08 INFO - PROCESS | 1660 | Values: 58.0 56.0 56.0 57.0 56.0 58.0 59.0 59.0 59.0 57.0 06:40:08 INFO - PROCESS | 1660 | 06:40:08 INFO - PROCESS | 1660 | [#9] json-stringify-tinderbox Cycles:10 Average:84.50 Median:78.50 stddev:14.14 (18.0%) stddev-sans-first:13.85 06:40:08 INFO - PROCESS | 1660 | Values: 69.0 79.0 75.0 86.0 78.0 81.0 73.0 109.0 110.0 85.0 06:40:08 INFO - PROCESS | 1660 | 06:40:08 INFO - PROCESS | 1660 | [#10] stanford-crypto-aes Cycles:10 Average:95.00 Median:83.50 stddev:15.10 (18.1%) stddev-sans-first:15.66 06:40:08 INFO - PROCESS | 1660 | Values: 86.0 107.0 91.0 79.0 81.0 97.0 80.0 95.0 108.0 126.0 06:40:08 INFO - PROCESS | 1660 | 06:40:08 INFO - PROCESS | 1660 | [#11] stanford-crypto-ccm Cycles:10 Average:160.10 Median:157.50 stddev:20.74 (13.2%) stddev-sans-first:21.98 06:40:08 INFO - PROCESS | 1660 | Values: 163.0 147.0 150.0 148.0 151.0 152.0 141.0 166.0 213.0 170.0 06:40:08 INFO - PROCESS | 1660 | 06:40:08 INFO - PROCESS | 1660 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:184.10 Median:180.00 stddev:18.86 (10.5%) stddev-sans-first:19.97 06:40:08 INFO - PROCESS | 1660 | Values: 181.0 182.0 236.0 189.0 176.0 174.0 179.0 173.0 174.0 177.0 06:40:08 INFO - PROCESS | 1660 | 06:40:08 INFO - PROCESS | 1660 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:78.10 Median:84.00 stddev:10.90 (13.0%) stddev-sans-first:10.64 06:40:08 INFO - PROCESS | 1660 | Values: 66.0 69.0 68.0 67.0 71.0 81.0 87.0 92.0 90.0 90.0 06:40:08 INFO - PROCESS | 1660 | -------- Summary: end -------- 06:40:08 INFO - PROCESS | 1660 | 06:40:09 INFO - TEST-INFO | 1660: exit 0 06:40:09 INFO - TEST-OK | kraken | took 62768ms 06:40:09 INFO - SUITE-END | took 1179s 06:40:10 INFO - Completed test suite (00:19:40) 06:40:10 INFO - javascript benchmark 06:40:10 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7009.541672652512, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3343.114074770186, 3346.4980252962055, 3362.394768941591, 3367.175042114682, 3367.764256502046, 4686.578207961283, 5283.365996080471, 5556.583256255777, 5598.944010559903, 5602.411746766592, 5464.180372944061, 5594.076977298751, 5598.524125449342, 5634.661920284786, 5649.180868774025, 5103.387593488777, 5152.304438900738, 5153.716545589991, 5158.509941555565, 5201.40183878855, 5152.510511501401, 5171.327727395434, 5195.220716892459, 5213.504717051874, 5255.869987952589, 67.86898997216612, 5189.922151167735, 5204.76578553966, 5221.373435187783, 5259.0796610593, 4807.0, 5028.044671512437, 5148.279240906239, 5161.664491808058, 5183.377994640668, 5080.441151473335, 5095.312132862054, 5119.59043276537, 5142.871428214287, 5144.639875208737, 5437.646552974044, 5606.467385598362, 5632.352279487829, 5647.943520564783, 5657.3494048184575, 5922.97038514809, 5957.582969192158, 5994.980853254957, 6131.264248290234, 6136.294326152505, 6084.5436592255755, 6126.548209435026, 6134.4978165938865, 6142.95923517857, 6158.230221222348, 5684.54523638108, 5693.117566777156, 5705.087186050295, 5718.742656580423, 5744.368119506851, 6196.938030619726, 6310.337414571496, 6325.968370158212, 6327.620342779448, 6330.39861213184, 5849.8245052647635, 5877.52979761618, 5881.206037185011, 6050.334463209044, 6087.36082711309, 6105.914670190187, 6111.622125543816, 6121.357257487989, 6139.416755408229, 6141.84645383869, 5921.993261145615, 6002.6698531580305, 6031.638101713911, 6033.215681961405, 6047.637141771508, 4701.858944231679, 4856.927146092811, 6094.360092190344, 6094.786682466092, 6100.938990610126, 4784.40194975628, 4794.640401969797, 4806.206975848946, 4806.255030470283, 4917.483664215278, 4209.810558524874, 4213.157368526307, 4277.529471758104, 4304.354346847936, 4356.3683265926265, 4778.784954677048, 5604.131359639263, 5821.301443826768, 5829.038208695602, 5836.533077353802, 6082.695865206722, 6119.654885716942, 6126.693665316717, 6141.815745527641, 6144.78493252734, 5825.79609713658, 5938.970305148533, 5944.138099975566, 5970.910436343458, 6030.4572588467245, 5283.550898173621, 5287.603429742708, 5290.735463226823, 5296.655717378358, 5297.152455607162, 6173.135760993375, 6234.75060997555, 6345.49236061114, 6350.491960643138, 6359.650219237894, 6104.368208815833, 6134.773364742599, 6143.573288550908, 6216.906746398898, 6230.626162430269, 6357.332480089524, 6361.90457143134, 6373.521985851173, 6374.585651932795, 6395.904061438989, 5893.675847828411, 6101.542384321105, 6110.908336374869, 6123.510119190365, 6126.754929802758, 6059.151718759465, 6091.847703807441, 6116.153172518083, 6122.7244773984385, 6137.478314343152, 5681.517071048842, 5864.32560255576, 5891.646501209942, 6008.1588577598295, 6009.789657361971, 5931.792387266425, 5956.225690660186, 5981.700914954322, 5984.760609575568, 6034.969825150846, 5249.448807875118, 5426.430224826337, 5695.231143795535, 5749.367569567428, 5810.622309549865, 5891.469767720926, 5918.082697181943, 5960.791372301948, 5970.31341395745, 5975.073863551156, 5857.707114644336, 5861.238039054895, 5875.676837773964, 5878.382769809108, 5881.676507792113], "value": 5551.4879299867425, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [433.7787728258588, 501.28060751621945, 509.3454910440085, 519.6051001239057, 559.8858272038644, 29813.105606831803, 38558.80720596397, 38914.80542597283, 39070.0, 39275.50142979978, 39270.21459570807, 39760.005999850015, 39798.204035919334, 39839.800800996025, 39947.40078898819, 8281.0, 9075.682351117728, 9247.029061948499, 9271.629134834599, 9291.024442433549, 8335.291500222458, 10995.175361847854, 11228.719282017973, 11230.438478076063, 11408.71478213043, 11155.38645374504, 11182.66452006441, 11199.992000719934, 11208.943955280212, 11244.49399777008, 11151.996320331127, 11178.720531986684, 11193.888061119447, 11200.831987520194, 11204.943975280154, 11035.558577656884, 11078.169137314733, 11110.222364421654, 11129.27659702125, 11138.331700097939, 29551.113466596038, 33145.83989560378, 38109.856704298916, 38274.23451530954, 38993.805030974945, 2558.5522533556555, 2961.2152779513444, 3110.644701409784, 3366.5960084789735, 3417.96582034179, 29523.114306570624, 29534.0, 29677.258068548243, 29722.554161687593, 30254.941077062416, 2759.599858020576, 3132.9530057048933, 3156.952269770514, 3169.144331030609, 3255.690709382605, 330935.0, 360409.9795010265, 362877.0, 364741.1762941195, 380245.0, 56958.86082278331, 57149.71425142848, 57412.24934633771, 57579.424205758245, 57696.711516442985, 52586.737066314425, 56617.0, 60049.69975150184, 60251.698741506014, 60344.0, 15242.618934526725, 15769.132697701507, 15779.211039448212, 15784.526464206092, 15795.763063554055, 11094.334339939629, 11337.659870203906, 12060.155789094679, 12180.878191218151, 12324.198927069712, 14529.893165490945, 15084.170370629501, 15790.526284211492, 16294.837051629569, 16309.939853909695, 5162.0, 5665.915011274834, 6105.664188469676, 6160.753569857246, 6186.907196392058, 829.3821103278096, 939.9906000939903, 956.8373376526007, 956.9760755981017, 1020.6886899495577, 23083.653745193835, 26420.735792641826, 28995.42009159805, 29106.41787164245, 29851.552226716616, 916.6287653500343, 934.1079269297804, 985.1133979418437, 986.7911808035243, 1091.0344345254348, 12836.743265134644, 13954.813840823645, 14966.625834354229, 15092.924535377253, 15126.621834454227, 600021.0, 607291.0, 616129.9193504003, 616451.0, 616804.0, 55555.0, 55574.72212638911, 55719.72140139274, 55763.44236557582, 56030.43969560334, 1303.8305020347293, 1330.2573628546636, 1332.1673953778889, 1343.6640839790052, 1349.7030653256268, 28158.71841281561, 29236.0, 29285.560716588843, 29505.11484655464, 29690.0, 48728.75635621799, 49058.0, 49152.77118071934, 49193.50806491889, 49231.76920577014, 545302.0, 547896.0, 548503.0, 549143.254283726, 552818.0, 32650.857219997186, 38404.0, 38492.23015539617, 38743.45026198921, 38822.6117738819, 42637.0, 44286.33570496381, 44340.33489497595, 45385.319220211066, 45645.08709825851, 31770.72917083383, 37346.813265933495, 37509.24981500409, 38435.80782096072, 38808.80595597004, 27918.720812791613, 34168.48747268843, 37066.0, 38027.2394552113, 38302.808485957394, 28915.710842892142, 35690.286194275446, 35884.461733074546, 36259.637403625624, 36297.63702363048, 27290.317742056606, 32672.0, 33221.667783322824, 33599.20388107974, 33929.49105763366, 22803.657945131166, 24900.37749056288, 24903.128390506245, 24978.125765598117, 25179.992800287782], "value": 19193.958568229584, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [323.8148376940398, 332.77038843198176, 347.9249120218525, 363.54012174599137, 374.7039838527563, 18352.541186470346, 18663.440096797083, 19878.90060549695, 19933.10301036453, 20071.39785806426, 18542.53643658906, 18959.71560426591, 19040.0, 19424.708629370536, 19571.5107122322, 5172.172452407615, 5434.972825135888, 5440.211169380437, 5446.673199608016, 5448.182772584105, 4309.181255561449, 5077.263796749469, 5308.9469105308835, 5370.3287089113865, 5380.192971054334, 5354.250404943311, 5358.490943360374, 5360.303160589118, 5370.275012873251, 5374.596905232103, 5212.843614691566, 5261.342332208474, 5288.894222115536, 5359.222912677659, 5370.43610420904, 5224.451432599561, 5234.031704134709, 5235.895282094375, 5242.2136679498, 5243.003829272426, 12728.981681465553, 13081.738365232737, 13171.012174086982, 13203.471861125543, 13357.666058348523, 1993.750781152356, 2170.7829217078324, 2187.7484089329773, 2418.9879050604636, 2656.2828036430055, 10413.062824345769, 10630.681079567625, 11198.272112312674, 11284.492197851116, 15478.5356439307, 1776.289484206315, 1823.188681036939, 1829.1311626977079, 1834.3121329501437, 1867.9439616811517, 101764.0, 108495.45752271268, 108594.45702771515, 120668.0, 120684.0, 11258.887411125948, 11377.772444551229, 11439.485223164978, 11537.826932596017, 11756.294622322688, 14396.640083997983, 14444.494442694455, 14452.349644266033, 14455.060421072596, 14476.0, 8001.8399632008195, 8185.181481851767, 8314.127016663284, 8454.365922555833, 8479.109693482218, 8097.595120243965, 8100.149484304063, 8210.055843577946, 8230.670773169126, 8265.876011859827, 4370.972821386971, 4469.486009108994, 4503.279475284005, 4547.272436410159, 4704.3649107370065, 1921.8366438852574, 1934.7775005874505, 1939.2727727102338, 1940.9126589303514, 1960.1081507913866, 798.2217338095311, 840.8402403543307, 862.2756884217288, 888.4846788862444, 968.2157452463528, 18848.963307018246, 18861.622767544573, 20581.794182058286, 20750.688739668916, 20849.061792219083, 877.2368039805283, 903.5346796399859, 914.7696348411376, 921.7326975177258, 946.976325591852, 9764.144812485605, 10422.166226701846, 11047.948714123, 11074.006678797894, 11079.72300692489, 508196.0, 520282.0, 528966.0, 563192.0, 573643.0, 113411.43294283476, 114151.42924285325, 118774.0, 119769.40115299368, 121978.0, 884.9601767920459, 889.7019498467956, 916.9174774270348, 920.1994264989369, 923.1922068190333, 16173.595660108122, 16399.180040997664, 18550.44348669542, 18588.44234672962, 18704.251829926652, 36552.45171322486, 50478.7380315495, 50848.74575627098, 51250.74374628103, 52268.0, 68091.0, 77430.45139097073, 78171.0, 78646.21353786389, 78722.81915771382, 6443.323451037573, 7807.492512986638, 7826.0, 8004.479708818908, 8081.353491720531, 8972.237359824489, 11797.115216358636, 12077.69805754828, 12521.311327877054, 12929.159604625669, 10498.055175034284, 10521.684349469528, 10801.405922674014, 10844.349339039367, 11011.61459348919, 9446.574904129468, 10535.367877927349, 10614.310069845435, 10902.018818306073, 10967.890321096687, 9081.409708368936, 10371.585136594753, 10872.67381978542, 11153.609623663133, 11550.01824844864, 6766.458683305422, 7757.612119393895, 7856.25365590268, 7876.369890408639, 8160.576510395476, 9688.5640146195, 10582.576696932036, 10917.727056823644, 10922.50848711794, 11246.550137994389], "value": 8981.261695018784, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1404.5996890886097, 1474.6460849396149, 1650.4141029934365, 1652.5868532866784, 1796.6047469556693, 7483.588402637853, 7627.542347459156, 7703.57630330333, 8259.628316725753, 8438.11399803021, 7882.605869706506, 8141.43009989301, 8155.796105097375, 8269.503829770218, 8285.171482851727, 2274.8066414354757, 2302.2977991712605, 2307.1348244408346, 2309.8960546775434, 2314.2363020203375, 2308.665243539686, 2329.790318871301, 2337.1002164166835, 2357.233898982829, 2359.1742889988536, 1993.2624928776372, 2295.8737269450176, 2304.8156147508244, 2308.480591866825, 2321.6401457774073, 2251.7297924249115, 2271.046160612547, 2285.04028308111, 2293.5412917416584, 2295.9655605165935, 2290.438842483602, 2300.4478925058033, 2302.4243939015246, 2311.653252012195, 2313.1788215183706, 8264.504129752235, 8277.337812974947, 8314.750557483225, 8327.458715183493, 8341.54121523316, 4973.303737476756, 5151.922721159186, 5152.510511501401, 5158.2520534522655, 5162.845114646567, 3183.0, 3266.297745984601, 3518.2787528556582, 3524.400851855191, 3525.964740352589, 1022.728976821143, 1045.3988956350129, 1056.1286938275875, 1074.897884700952, 1085.0505807418508, 10553.472326383728, 10740.140788736882, 10788.08301294391, 10805.675829725122, 10827.025567698865, 3876.457295978565, 4382.364557139197, 4482.99132695141, 4559.176329468173, 4585.449746030497, 7339.669714862844, 8100.635471403683, 8616.956915215384, 9329.11373419524, 9357.485338306322, 5105.33630628016, 5167.354080739908, 5169.638125331266, 5173.430922598511, 5174.870628234249, 4235.407043013981, 4391.626711729475, 4557.749323787158, 4700.0, 4736.928946065812, 3694.464302676097, 3772.660460558563, 3778.981105094457, 3823.7705737655274, 3853.056001279648, 1235.610782603495, 1235.6972541727334, 1242.5092088625124, 1248.1387842388904, 1264.178284115314, 667.6261293675557, 883.5449743382163, 885.4642941020719, 895.1451363947415, 896.2516298890499, 5499.422560631157, 5515.420880807537, 5634.084411300178, 5722.656640601577, 5729.684867332254, 419.6013786902455, 424.9065205654751, 432.6149726743201, 435.23616053825333, 436.4152036271412, 3270.296886169485, 3546.858125674996, 3857.1321452672923, 3876.2247550490015, 3915.647591716759, 9521.952975047918, 10005.099541041342, 10053.145482633912, 10058.547365368577, 10067.748306292402, 9890.752731181634, 9935.80128397428, 9991.500424978867, 9994.500274986221, 10014.899851001397, 6612.900806487906, 7462.440316976248, 7595.848083038309, 7689.154193038873, 7819.3744500439825, 5383.353997520323, 5490.592296529555, 5503.532199762904, 5612.831615051555, 5620.156976453564, 8940.955295223483, 8999.280057595508, 8999.685011024583, 9103.31725120632, 9177.898652161783, 9213.907860921305, 9305.441673499612, 9418.952905235705, 9474.431534107976, 9475.905240947503, 6535.379138981789, 6751.696173672099, 6911.7926462206215, 6998.825029374307, 7186.10173728284, 7816.765497035098, 7912.0505539335645, 7984.121746607984, 7996.640151193326, 7998.600069996361, 7004.0, 7123.430125589836, 7179.856402871809, 7182.712691492282, 7238.565686058853, 6775.4240889524945, 6871.862562748617, 7016.052832867497, 7033.296670332926, 7054.329838665319, 6584.637844918576, 6632.668366581748, 6711.295313991961, 6723.092382528492, 6728.495362847904, 4744.833930812405, 4791.065742180242, 4803.303520989367, 4853.684510506806, 4899.534544218293, 4763.95236047649, 4834.468208496992, 4884.12085824555, 4939.753012349296, 4989.101961646938], "value": 4268.711926151288, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [294.1249781898851, 300.05781845006675, 300.13261673762815, 306.722416213327, 310.6644823590523, 4577.519360467153, 4588.73809702332, 4950.811805166761, 5247.422783493822, 5321.388040375358, 4022.9195416091734, 4633.51727447217, 5121.564667003309, 5255.474452554743, 5333.879680386968, 3620.7284453665948, 3957.4261732048835, 4167.458040525003, 4176.958230417687, 4190.141021090684, 3824.579296277407, 4189.6438802701805, 4192.832286708528, 4201.831926722927, 4207.474065741782, 3844.308024555593, 3968.0675041365257, 4034.5763694812063, 4060.309747342944, 4062.248484030449, 4015.7590544567274, 4040.272750904836, 4061.8220715992325, 4081.89795255121, 4183.828528012162, 3106.937861242785, 3507.0, 3533.893983180483, 3542.3800834854055, 3547.0, 4115.115250221217, 4169.478815148106, 5019.222020586778, 5265.947340526623, 5271.947280527184, 4637.25803871379, 4645.349651048856, 4653.767311634439, 4660.324252983299, 4690.273007683814, 4816.56650901417, 5040.697558146523, 5481.369642491125, 5493.917591236094, 5686.9715651421475, 2889.4943384907556, 3204.599425071866, 3698.7965661888584, 3813.9427908581392, 3839.616038396166, 5183.50756678115, 5910.044977511245, 5950.613210141327, 5985.461308482214, 6000.669963152025, 3471.8090505022465, 3731.869384571581, 3915.471411359487, 3919.451276821247, 3937.0, 3342.799432034086, 3471.8090505022465, 3835.5013848199555, 3973.1921975501077, 4012.6589239914933, 3460.4290292102023, 3881.4565960765517, 4106.281400754856, 4396.164728701537, 4639.30410438437, 5695.430456954355, 5704.515116215168, 5705.885882282331, 5722.856928576819, 5736.311642602831, 1127.9661610151709, 1154.7921374152565, 1203.199872085071, 1271.9109662323733, 1287.4357655448675, 787.1773996174012, 839.937479088953, 865.6580650642961, 869.7519060148707, 881.8589025755848, 4507.8196872124745, 4840.0, 4948.73807179167, 5155.690658560498, 5196.792128314825, 4570.611498022706, 4870.198026730132, 5081.8475445736685, 5175.14610089331, 5372.247885296062, 4199.727017743837, 4627.537246275346, 5164.727519891236, 5194.052520084762, 5217.139172036647, 2503.6745223121243, 2802.425502771968, 2813.3682464170734, 2920.6349206349205, 2935.765138788892, 5171.4828517148735, 5334.413214546396, 5621.49406553404, 5844.006518891799, 5855.473007429352, 4150.85873819084, 4986.301917731521, 5605.80379686709, 5643.1535269709875, 5650.672092058363, 5804.738786754522, 5835.912461313169, 5842.130518234124, 5858.150568167592, 5858.794942177004, 5162.561182299547, 5466.453354664502, 5531.865967476577, 5542.584306177134, 5553.611247212818, 5448.945510544844, 5481.232627432242, 5487.64330318528, 5827.863566604471, 5902.2327097477055, 6282.303777980012, 6284.371562843679, 6309.463695585927, 6312.526560508072, 6321.273053598711, 3235.449973504557, 3799.7340186186702, 3942.4480572719267, 3973.980130099331, 3976.562578116347, 5196.766145523537, 5269.552088072403, 5846.444587764156, 5898.9115163273445, 5915.85210369727, 4509.481409637933, 5193.974030129825, 5547.001539722889, 5770.596058275881, 5787.508061814793, 5745.0, 5763.250777398911, 5765.192872997867, 5782.971085144715, 5792.7103644818435, 4508.887277818081, 4701.553352431513, 5332.68003919766, 5577.219189313419, 5661.094224923992, 5737.368889422244, 5746.454086861741, 5749.741261643319, 5768.711564421678, 5782.739776709974, 5568.331800184003, 5624.578156638187, 5624.775008999758, 5629.662220266797, 5654.900197356883], "value": 4038.817264026319, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [679.1374953808663, 684.8116767888831, 690.3855568544, 735.6652723011024, 753.5968256982516, 14152.929235353822, 14752.0, 14791.852081479183, 14810.333534990938, 14842.851571484282, 13253.602391928258, 13253.734925301489, 13432.26122563261, 13439.328033598305, 13443.596692099252, 661.8014595621319, 676.7022510095567, 677.6510097299895, 831.600831600832, 833.9291160251386, 811.679386642276, 839.2866063845744, 849.0617867256685, 852.3436953545767, 857.6740838481369, 856.8372009318209, 893.5175005497024, 894.7136916186823, 895.7044175422095, 899.2850683706437, 868.5006121480143, 885.8759773631697, 892.3218354050903, 894.3381897395946, 894.7852515396323, 573.9124359339071, 640.5068097564896, 644.5262731892102, 668.6055227415848, 670.3865962644223, 10069.597216111346, 12773.872261277362, 14203.076800007966, 14210.502632407919, 14233.928830355782, 13105.541306054338, 13351.532696355582, 13460.932695336558, 13461.798073028913, 13477.932610336984, 11422.200445968703, 11545.480453379618, 11611.593594224161, 11657.0, 11915.165938384316, 3591.299696559172, 3621.112827357288, 3741.345264578688, 3778.414345776409, 3783.4324851272254, 95958.0404195956, 96002.51998740032, 96112.51943740307, 97149.51425242829, 97154.54268185984, 10131.7467063324, 10199.643012494675, 10374.0, 10412.0, 10641.73395665099, 20206.29277975264, 20440.591188176153, 20449.182032718523, 20503.79496205019, 20671.966401679856, 7558.735444259424, 7599.316061554487, 7609.0, 7714.727070033494, 7768.300852923264, 4188.874333769992, 4244.448221731155, 4297.011687311936, 4349.28236840924, 4393.428854248928, 3029.742471889914, 3101.674324195972, 3105.0840002199407, 3105.534169874492, 3114.672959339282, 589.908564172554, 599.3526990849872, 617.8239201827457, 626.0890404461497, 627.8461776864606, 482.14178761804044, 559.2561892682722, 586.5688718791685, 672.4049216443483, 682.7780971184286, 8623.741287761377, 8873.778155546162, 9730.95134524337, 9808.166305863939, 9880.407175569346, 483.7943873853598, 576.7202906590301, 637.1907677249867, 640.5580149696694, 675.6452862247261, 3383.509391138271, 4457.064016556559, 4466.6873318867365, 4526.343680166423, 4821.2045012572535, 104675.4766226164, 116761.0, 116978.41510792561, 117396.0, 124139.13791293137, 69254.65372673105, 69691.0, 97157.0, 97314.0, 97810.0, 57660.0, 59339.406605933385, 78644.0, 79224.0, 86592.93849430606, 8659.523726194868, 8723.694670686495, 9522.714778349631, 9757.316987810786, 9790.957656806608, 46287.0, 51494.0, 52459.73770131125, 55129.897402052535, 57960.0, 75610.6219468899, 81254.18745812467, 82109.0, 82163.0, 83811.74282385648, 10705.411202383944, 11740.765184696429, 12049.0, 12112.0, 12157.635270941886, 13938.93030534841, 13995.23026233567, 14219.50231741884, 14399.568012959628, 14457.638559036108, 12781.552645657357, 12919.677008074874, 13012.284324361872, 13018.218906865619, 13526.79709804334, 12800.231986080493, 12954.352282385655, 13127.48248537967, 13221.47114115463, 13467.989900757286, 12602.369881505705, 12658.810117848056, 12807.29559874216, 12950.7943545777, 13119.27843968591, 9332.766680833034, 10816.513256903301, 10907.2364934457, 11244.26912250701, 11469.827952580885, 7760.611969401621, 8400.87398689008, 11045.447727613748, 11243.381614011309, 11257.437128143396], "value": 7188.333825668817, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1622.5999999999997, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 117.0, 118.0, 117.0, 118.0, 117.0, 119.0, 119.0, 116.0, 121.0], "value": 116.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 143.0, 147.0, 189.0, 106.0, 142.0, 150.0, 151.0, 133.0, 112.0], "value": 142.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [208.0, 259.0, 280.0, 267.0, 194.0, 199.0, 196.0, 199.0, 255.0, 214.0], "value": 227.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 60.0, 63.0, 93.0, 64.0, 61.0, 76.0, 51.0, 68.0, 52.0], "value": 67.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 71.0, 83.0, 73.0, 73.0, 71.0, 79.0, 70.0, 62.0, 98.0], "value": 76.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 132.0, 136.0, 88.0, 90.0, 90.0, 92.0, 84.0, 124.0, 134.0], "value": 104.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 111.0, 153.0, 154.0, 95.0, 108.0, 97.0, 95.0, 97.0, 96.0], "value": 116.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 143.0, 112.0, 92.0, 91.0, 92.0, 98.0, 140.0, 93.0, 149.0], "value": 112.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 56.0, 56.0, 57.0, 56.0, 58.0, 59.0, 59.0, 59.0, 57.0], "value": 57.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 79.0, 75.0, 86.0, 78.0, 81.0, 73.0, 109.0, 110.0, 85.0], "value": 84.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 107.0, 91.0, 79.0, 81.0, 97.0, 80.0, 95.0, 108.0, 126.0], "value": 95.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 147.0, 150.0, 148.0, 151.0, 152.0, 141.0, 166.0, 213.0, 170.0], "value": 160.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 182.0, 236.0, 189.0, 176.0, 174.0, 179.0, 173.0, 174.0, 177.0], "value": 184.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 69.0, 68.0, 67.0, 71.0, 81.0, 87.0, 92.0, 90.0, 90.0], "value": 78.1, "unit": "score"}]}]} 06:40:10 INFO - Return code: 0 06:40:10 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:40:10 ERROR - # TBPL SUCCESS # 06:40:10 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:40:10 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:40:10 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:40:10 INFO - 2016-11-23 06:40:10.400 screenresolution[1665:10517] starting screenresolution argv=screenresolution get 06:40:10 INFO - 2016-11-23 06:40:10.403 screenresolution[1665:10517] Display 0: 1600x1200x32@60 06:40:10 INFO - 2016-11-23 06:40:10.409 screenresolution[1666:10520] starting screenresolution argv=screenresolution list 06:40:10 INFO - Available Modes on Display 0 06:40:10 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:40:10 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:40:10 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:40:10 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:40:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:40:10 INFO - Intel Iris: 06:40:10 INFO - Chipset Model: Intel Iris 06:40:10 INFO - Type: GPU 06:40:10 INFO - Bus: Built-In 06:40:10 INFO - VRAM (Dynamic, Max): 1536 MB 06:40:10 INFO - Vendor: Intel (0x8086) 06:40:10 INFO - Device ID: 0x0a2e 06:40:10 INFO - Revision ID: 0x0009 06:40:10 INFO - Displays: 06:40:10 INFO - Display: 06:40:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:40:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:40:10 INFO - Display Serial Number: 1600x1200 60 06:40:10 INFO - Main Display: Yes 06:40:10 INFO - Mirror: Off 06:40:10 INFO - Online: Yes 06:40:10 INFO - Rotation: Supported 06:40:10 INFO - Return code: 0 06:40:10 INFO - Running post-action listener: _resource_record_post_action 06:40:10 INFO - [mozharness: 2016-11-23 14:40:10.644307Z] Finished run-tests step (success) 06:40:10 INFO - Running post-run listener: _resource_record_post_run 06:40:10 INFO - Total resource usage - Wall time: 1214s; CPU: 55.0%; Read bytes: 38578688; Write bytes: 696020992; Read time: 246; Write time: 1977 06:40:10 INFO - TinderboxPrint: CPU usage
54.6% 06:40:10 INFO - TinderboxPrint: I/O read bytes / time
38,578,688 / 246 06:40:10 INFO - TinderboxPrint: I/O write bytes / time
696,020,992 / 1,977 06:40:10 INFO - TinderboxPrint: CPU idle
2,203.5 (45.4%) 06:40:10 INFO - TinderboxPrint: CPU system
984.7 (20.3%) 06:40:10 INFO - TinderboxPrint: CPU user
1,669.5 (34.4%) 06:40:10 INFO - TinderboxPrint: Swap in / out
421,531,648 / 0 06:40:10 INFO - install - Wall time: 33s; CPU: 55.0%; Read bytes: 384066048; Write bytes: 386518016; Read time: 28820; Write time: 814 06:40:10 INFO - run-tests - Wall time: 1181s; CPU: 55.0%; Read bytes: 35420672; Write bytes: 308163584; Read time: 223; Write time: 1157 06:40:11 INFO - Running post-run listener: _upload_blobber_files 06:40:11 INFO - Blob upload gear active. 06:40:11 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:40:11 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 06:40:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Cedar', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:40:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Cedar -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:40:11 INFO - (blobuploader) - INFO - Open directory for files ... 06:40:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 06:40:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:40:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:40:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 06:40:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:40:12 INFO - (blobuploader) - INFO - Done attempting. 06:40:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 06:40:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:40:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:40:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 06:40:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:40:13 INFO - (blobuploader) - INFO - Done attempting. 06:40:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:40:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:40:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:40:16 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:40:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:40:16 INFO - (blobuploader) - INFO - Done attempting. 06:40:16 INFO - (blobuploader) - INFO - Iteration through files over. 06:40:16 INFO - Return code: 0 06:40:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:40:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:40:16 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/d440593347b5fefc1f88a84938847e3d7fc341095757d28876e6dee552845ffb30d64b37022712d41c5b433e7a6b62dc7757c0bf784a391e467d5f3e235a42df", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/b8d3665a92a3ab9e365118a9fef58f9fdc26783f4e6b38bad9057b5547629c15da939cf3daae6215a7c7fc2858b9ba39cd1b429b0e155fdd505d761f4f632e82", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/1d4bb08a007fabd71429b702aebfecdb316cca6bc69e9ae84438e4202b8886b6513d5ab47b6bb15c0979c211e6a26064c4ec66a16106a56cfac73ce96a303517"} 06:40:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:40:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:40:16 INFO - Contents: 06:40:16 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/d440593347b5fefc1f88a84938847e3d7fc341095757d28876e6dee552845ffb30d64b37022712d41c5b433e7a6b62dc7757c0bf784a391e467d5f3e235a42df", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/b8d3665a92a3ab9e365118a9fef58f9fdc26783f4e6b38bad9057b5547629c15da939cf3daae6215a7c7fc2858b9ba39cd1b429b0e155fdd505d761f4f632e82", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/1d4bb08a007fabd71429b702aebfecdb316cca6bc69e9ae84438e4202b8886b6513d5ab47b6bb15c0979c211e6a26064c4ec66a16106a56cfac73ce96a303517"} 06:40:16 INFO - Running post-run listener: copy_logs_to_upload_dir 06:40:16 INFO - Copying logs to upload dir... 06:40:16 INFO - mkdir: /builds/slave/test/build/upload/logs 06:40:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.904131 ========= master_lag: 0.40 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-11-23 06:40:16.841237) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-23 06:40:16.844283) ========= 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.s1ybmjvsyt/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.IvF69ditL1/Listeners TMPDIR=/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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/Cedar/sha512/d440593347b5fefc1f88a84938847e3d7fc341095757d28876e6dee552845ffb30d64b37022712d41c5b433e7a6b62dc7757c0bf784a391e467d5f3e235a42df", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/b8d3665a92a3ab9e365118a9fef58f9fdc26783f4e6b38bad9057b5547629c15da939cf3daae6215a7c7fc2858b9ba39cd1b429b0e155fdd505d761f4f632e82", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/1d4bb08a007fabd71429b702aebfecdb316cca6bc69e9ae84438e4202b8886b6513d5ab47b6bb15c0979c211e6a26064c4ec66a16106a56cfac73ce96a303517"} build_url:https://queue.taskcluster.net/v1/task/WlGxBgDkSs-_HxOeqvbfxQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010874 build_url: 'https://queue.taskcluster.net/v1/task/WlGxBgDkSs-_HxOeqvbfxQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/d440593347b5fefc1f88a84938847e3d7fc341095757d28876e6dee552845ffb30d64b37022712d41c5b433e7a6b62dc7757c0bf784a391e467d5f3e235a42df", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/b8d3665a92a3ab9e365118a9fef58f9fdc26783f4e6b38bad9057b5547629c15da939cf3daae6215a7c7fc2858b9ba39cd1b429b0e155fdd505d761f4f632e82", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/1d4bb08a007fabd71429b702aebfecdb316cca6bc69e9ae84438e4202b8886b6513d5ab47b6bb15c0979c211e6a26064c4ec66a16106a56cfac73ce96a303517"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-23 06:40:16.878375) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-23 06:40:16.878676) ========= 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.s1ybmjvsyt/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.IvF69ditL1/Listeners TMPDIR=/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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.004971 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-23 06:40:16.926518) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-23 06:40:16.926819) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-23 06:40:16.927123) ========= ========= Total master_lag: 0.55 =========