builder: larch_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0352 starttime: 1479343181.09 results: success (0) buildid: 20161116124710 builduid: ef9f6765b6624dc5842f8f97ad547878 revision: fbbcd82b29623828d64038b7272da2906f4bd8f5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-16 16:39:41.092672) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-16 16:39:41.093106) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 1 secs) (at 2016-11-16 16:39:41.095024) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 1 secs) (at 2016-11-16 16:39:43.086264) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-16 16:39:43.086582) ========= 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.nduNWQrzEH/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.aIoDmv8vYM/Listeners TMPDIR=/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/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-16 16:39:43-- 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.6M=0.001s 2016-11-16 16:39:43 (18.6 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.149039 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-16 16:39:43.257796) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-16 16:39:43.258114) ========= 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.nduNWQrzEH/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.aIoDmv8vYM/Listeners TMPDIR=/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/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.024426 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-16 16:39:43.309833) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-11-16 16:39:43.313359) ========= bash -c 'python archiver_client.py mozharness --repo projects/larch --rev fbbcd82b29623828d64038b7272da2906f4bd8f5 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/larch --rev fbbcd82b29623828d64038b7272da2906f4bd8f5 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.nduNWQrzEH/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.aIoDmv8vYM/Listeners TMPDIR=/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/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-16 16:39:43,411 truncating revision to first 12 chars 2016-11-16 16:39:43,411 Setting DEBUG logging. 2016-11-16 16:39:43,411 attempt 1/10 2016-11-16 16:39:43,411 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/larch/fbbcd82b2962?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-16 16:39:45,164 unpacking tar archive at: larch-fbbcd82b2962/testing/mozharness/ program finished with exit code 0 elapsedTime=2.004212 ========= master_lag: 0.08 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-11-16 16:39:45.395469) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-16 16:39:45.395786) ========= script_repo_revision: fbbcd82b29623828d64038b7272da2906f4bd8f5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-16 16:39:45.399255) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-16 16:39:45.399571) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-16 16:39:45.445576) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-11-16 16:39:45.445924) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch 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', 'Larch', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Larch'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.nduNWQrzEH/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.aIoDmv8vYM/Listeners TMPDIR=/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/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 16:39:45 INFO - MultiFileLogger online at 20161116 16:39:45 in /builds/slave/test 16:39:45 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch 16:39:45 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:39:45 INFO - {'append_to_log': False, 16:39:45 INFO - 'base_work_dir': '/builds/slave/test', 16:39:45 INFO - 'blob_upload_branch': 'Larch', 16:39:45 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:39:45 INFO - 'branch': 'Larch', 16:39:45 INFO - 'buildbot_json_path': 'buildprops.json', 16:39:45 INFO - 'config_files': ('talos/mac_config.py',), 16:39:45 INFO - 'default_actions': ('clobber', 16:39:45 INFO - 'read-buildbot-config', 16:39:45 INFO - 'download-and-extract', 16:39:45 INFO - 'populate-webroot', 16:39:45 INFO - 'create-virtualenv', 16:39:45 INFO - 'install', 16:39:45 INFO - 'run-tests'), 16:39:45 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:39:45 INFO - 'download_minidump_stackwalk': True, 16:39:45 INFO - 'download_symbols': 'ondemand', 16:39:45 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:39:45 INFO - 'tooltool.py': '/tools/tooltool.py', 16:39:45 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:39:45 INFO - '/tools/misc-python/virtualenv.py')}, 16:39:45 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:39:45 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:39:45 INFO - 'installer_path': 'installer.exe', 16:39:45 INFO - 'log_level': 'info', 16:39:45 INFO - 'log_name': 'talos', 16:39:45 INFO - 'log_to_console': True, 16:39:45 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:39:45 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:39:45 INFO - 'opt_config_files': (), 16:39:45 INFO - 'pip_index': False, 16:39:45 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:39:45 INFO - 'cmd': ('bash', 16:39:45 INFO - '-c', 16:39:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:39:45 INFO - 'enabled': True, 16:39:45 INFO - 'halt_on_failure': False, 16:39:45 INFO - 'name': 'check_screen_resolution'},), 16:39:45 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:39:45 INFO - 'cmd': ('bash', 16:39:45 INFO - '-c', 16:39:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:39:45 INFO - 'enabled': True, 16:39:45 INFO - 'halt_on_failure': False, 16:39:45 INFO - 'name': 'check_screen_resolution'},), 16:39:45 INFO - 'run_cmd_checks_enabled': True, 16:39:45 INFO - 'sps_profile': False, 16:39:45 INFO - 'sps_profile_interval': 0, 16:39:45 INFO - 'suite': 'dromaeojs-e10s', 16:39:45 INFO - 'system_bits': '32', 16:39:45 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:39:45 INFO - 'title': 't-yosemite-r7-0352', 16:39:45 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:39:45 INFO - 'use_talos_json': True, 16:39:45 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:39:45 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:39:45 INFO - 'work_dir': 'build'} 16:39:45 INFO - [mozharness: 2016-11-17 00:39:45.655881Z] Running clobber step. 16:39:45 INFO - Running pre-action listener: _resource_record_pre_action 16:39:45 INFO - Running main action method: clobber 16:39:45 INFO - rmtree: /builds/slave/test/build 16:39:45 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:39:48 INFO - Running post-action listener: _resource_record_post_action 16:39:48 INFO - [mozharness: 2016-11-17 00:39:48.046485Z] Finished clobber step (success) 16:39:48 INFO - [mozharness: 2016-11-17 00:39:48.046677Z] Running read-buildbot-config step. 16:39:48 INFO - Running pre-action listener: _resource_record_pre_action 16:39:48 INFO - Running main action method: read_buildbot_config 16:39:48 INFO - Using buildbot properties: 16:39:48 INFO - { 16:39:48 INFO - "project": "", 16:39:48 INFO - "product": "firefox", 16:39:48 INFO - "script_repo_revision": "production", 16:39:48 INFO - "builddir": "larch_yosemite_r7_test-dromaeojs-e10s", 16:39:48 INFO - "repository": "", 16:39:48 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 larch talos dromaeojs-e10s", 16:39:48 INFO - "buildid": "20161116124710", 16:39:48 INFO - "pgo_build": "False", 16:39:48 INFO - "basedir": "/builds/slave/test", 16:39:48 INFO - "buildnumber": 2, 16:39:48 INFO - "slavename": "t-yosemite-r7-0352", 16:39:48 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 16:39:48 INFO - "platform": "yosemite_r7", 16:39:48 INFO - "scheduler": "tests-larch-macosx64-talos", 16:39:48 INFO - "branch": "larch", 16:39:48 INFO - "repo_path": "projects/larch", 16:39:48 INFO - "revision": "fbbcd82b29623828d64038b7272da2906f4bd8f5", 16:39:48 INFO - "stage_platform": "macosx64", 16:39:48 INFO - "builduid": "ef9f6765b6624dc5842f8f97ad547878", 16:39:48 INFO - "slavebuilddir": "test" 16:39:48 INFO - } 16:39:48 INFO - Found installer url https://queue.taskcluster.net/v1/task/R9QhHUCsQU-P8cyv1cXmnw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 16:39:48 INFO - Running post-action listener: _resource_record_post_action 16:39:48 INFO - [mozharness: 2016-11-17 00:39:48.050898Z] Finished read-buildbot-config step (success) 16:39:48 INFO - [mozharness: 2016-11-17 00:39:48.051030Z] Running download-and-extract step. 16:39:48 INFO - Running pre-action listener: _resource_record_pre_action 16:39:48 INFO - Running main action method: download_and_extract 16:39:48 INFO - mkdir: /builds/slave/test/build/tests 16:39:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:39:48 INFO - trying https://queue.taskcluster.net/v1/task/R9QhHUCsQU-P8cyv1cXmnw/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 16:39:48 INFO - Downloading https://queue.taskcluster.net/v1/task/R9QhHUCsQU-P8cyv1cXmnw/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 16:39:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/R9QhHUCsQU-P8cyv1cXmnw/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 16:39:48 INFO - Downloaded 1199 bytes. 16:39:48 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 16:39:48 INFO - Using the following test package requirements: 16:39:48 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 16:39:48 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 16:39:48 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 16:39:48 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 16:39:48 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 16:39:48 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 16:39:48 INFO - u'jsshell-mac.zip'], 16:39:48 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 16:39:48 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 16:39:48 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 16:39:48 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 16:39:48 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 16:39:48 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 16:39:48 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 16:39:48 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 16:39:48 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 16:39:48 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 16:39:48 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 16:39:48 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 16:39:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/R9QhHUCsQU-P8cyv1cXmnw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 16:39:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/R9QhHUCsQU-P8cyv1cXmnw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:39:48 INFO - Fetch https://queue.taskcluster.net/v1/task/R9QhHUCsQU-P8cyv1cXmnw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 16:39:49 INFO - Http code: 200 16:39:49 INFO - via: 1.1 9baadab4e4d95afe7af7bcba342b29c6.cloudfront.net (CloudFront) 16:39:49 INFO - x-amz-cf-id: NKINTS--c97pR2OfZojIIN1nzqqd-G0UwLeasE34uD3R960AL2NnGg== 16:39:49 INFO - x-amz-version-id: tXqbINfV8G.NHaOZShoO9AS.K89lkFeb 16:39:49 INFO - Expected file size: 16268109 16:39:49 INFO - Obtained file size: 16268109 16:39:56 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 16:39:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/R9QhHUCsQU-P8cyv1cXmnw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 16:39:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/R9QhHUCsQU-P8cyv1cXmnw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:39:56 INFO - Fetch https://queue.taskcluster.net/v1/task/R9QhHUCsQU-P8cyv1cXmnw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 16:39:57 INFO - Http code: 200 16:39:57 INFO - via: 1.1 0cf1d7257c633ae75623c5e75bf3805e.cloudfront.net (CloudFront) 16:39:57 INFO - x-amz-cf-id: ml-yQTzTtcUeiuCMLCBXa1oZoyBGHWW_8JhQjb9fKs3JTjQcdqapcw== 16:39:57 INFO - x-amz-version-id: tXqbINfV8G.NHaOZShoO9AS.K89lkFeb 16:39:57 INFO - Expected file size: 16268109 16:39:57 INFO - Obtained file size: 16268109 16:40:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/R9QhHUCsQU-P8cyv1cXmnw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 16:40:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/R9QhHUCsQU-P8cyv1cXmnw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 16:40:03 INFO - Fetch https://queue.taskcluster.net/v1/task/R9QhHUCsQU-P8cyv1cXmnw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 16:40:04 INFO - Http code: 200 16:40:04 INFO - via: 1.1 bfc29822e21ecc352f71dce83e06ab74.cloudfront.net (CloudFront) 16:40:04 INFO - x-amz-cf-id: UggiVhoWdQExkNVuYzfMjFg46JSAtWIvqS0GO2nUMnvBesAFAuxj6g== 16:40:04 INFO - x-amz-version-id: 8NailMDt.6cTzlxZkdc7KMJzR.6El9ll 16:40:05 INFO - Expected file size: 14409366 16:40:05 INFO - Obtained file size: 14409366 16:40:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:40:05 INFO - trying https://queue.taskcluster.net/v1/task/R9QhHUCsQU-P8cyv1cXmnw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 16:40:05 INFO - Downloading https://queue.taskcluster.net/v1/task/R9QhHUCsQU-P8cyv1cXmnw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 16:40:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/R9QhHUCsQU-P8cyv1cXmnw/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 16:40:08 INFO - Downloaded 111037884 bytes. 16:40:08 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/R9QhHUCsQU-P8cyv1cXmnw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 16:40:08 INFO - mkdir: /builds/slave/test/properties 16:40:08 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:40:08 INFO - Writing to file /builds/slave/test/properties/build_url 16:40:08 INFO - Contents: 16:40:08 INFO - build_url:https://queue.taskcluster.net/v1/task/R9QhHUCsQU-P8cyv1cXmnw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 16:40:08 INFO - Running post-action listener: _resource_record_post_action 16:40:08 INFO - Running post-action listener: set_extra_try_arguments 16:40:08 INFO - [mozharness: 2016-11-17 00:40:08.996679Z] Finished download-and-extract step (success) 16:40:08 INFO - [mozharness: 2016-11-17 00:40:08.996831Z] Running populate-webroot step. 16:40:08 INFO - Running pre-action listener: _resource_record_pre_action 16:40:08 INFO - Running main action method: populate_webroot 16:40:09 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:40:09 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:40:09 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:40:09 INFO - '--tppagecycles', 16:40:09 INFO - '7'], 16:40:09 INFO - 'tests': ['tsvgm']}}, 16:40:09 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:40:09 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:40:09 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:40:09 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:40:09 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:40:09 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:40:09 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 16:40:09 INFO - 'talos_options': ['--disable-e10s'], 16:40:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:40:09 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:40:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:40:09 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 16:40:09 INFO - 'talos_options': ['--disable-e10s'], 16:40:09 INFO - 'tests': ['damp', 'tps']}, 16:40:09 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:40:09 INFO - 'tests': ['damp', 'tps']}, 16:40:09 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:40:09 INFO - 'tests': ['dromaeo_dom']}, 16:40:09 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:40:09 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:40:09 INFO - 'tests': ['basic_compositor_video']}, 16:40:09 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:40:09 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:40:09 INFO - 'tests': ['a11yr', 16:40:09 INFO - 'ts_paint', 16:40:09 INFO - 'tpaint', 16:40:09 INFO - 'sessionrestore', 16:40:09 INFO - 'sessionrestore_no_auto_restore', 16:40:09 INFO - 'tabpaint']}, 16:40:09 INFO - 'other-e10s': {'tests': ['a11yr', 16:40:09 INFO - 'ts_paint', 16:40:09 INFO - 'tpaint', 16:40:09 INFO - 'sessionrestore', 16:40:09 INFO - 'sessionrestore_no_auto_restore', 16:40:09 INFO - 'tabpaint']}, 16:40:09 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:40:09 INFO - 'ts_paint', 16:40:09 INFO - 'tpaint', 16:40:09 INFO - 'sessionrestore', 16:40:09 INFO - 'sessionrestore_no_auto_restore', 16:40:09 INFO - 'tabpaint']}, 16:40:09 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:40:09 INFO - 'ts_paint', 16:40:09 INFO - 'tpaint', 16:40:09 INFO - 'sessionrestore', 16:40:09 INFO - 'sessionrestore_no_auto_restore', 16:40:09 INFO - 'tabpaint']}, 16:40:09 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:40:09 INFO - 'tests': ['a11yr', 16:40:09 INFO - 'ts_paint', 16:40:09 INFO - 'tpaint', 16:40:09 INFO - 'sessionrestore', 16:40:09 INFO - 'sessionrestore_no_auto_restore', 16:40:09 INFO - 'tabpaint']}, 16:40:09 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:40:09 INFO - 'tests': ['a11yr', 16:40:09 INFO - 'ts_paint', 16:40:09 INFO - 'tpaint', 16:40:09 INFO - 'sessionrestore', 16:40:09 INFO - 'sessionrestore_no_auto_restore', 16:40:09 INFO - 'tabpaint']}, 16:40:09 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:40:09 INFO - 'tests': ['tsvgx', 16:40:09 INFO - 'tsvgr_opacity', 16:40:09 INFO - 'tart', 16:40:09 INFO - 'tscrollx', 16:40:09 INFO - 'cart']}, 16:40:09 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:40:09 INFO - 'tsvgr_opacity', 16:40:09 INFO - 'tart', 16:40:09 INFO - 'tscrollx', 16:40:09 INFO - 'cart']}, 16:40:09 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 16:40:09 INFO - 'talos_options': ['--disable-e10s'], 16:40:09 INFO - 'tests': ['tp5o']}, 16:40:09 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:40:09 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 16:40:09 INFO - 'talos_options': ['--disable-e10s', 16:40:09 INFO - '--xperf_path', 16:40:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:40:09 INFO - 'tests': ['tp5n']}, 16:40:09 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:40:09 INFO - 'talos_options': ['--xperf_path', 16:40:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:40:09 INFO - 'tests': ['tp5n']}}, 16:40:09 INFO - 'talos.zip': {'path': '', 16:40:09 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:40:09 INFO - Running post-action listener: _resource_record_post_action 16:40:09 INFO - [mozharness: 2016-11-17 00:40:09.014696Z] Finished populate-webroot step (success) 16:40:09 INFO - [mozharness: 2016-11-17 00:40:09.014825Z] Running create-virtualenv step. 16:40:09 INFO - Running pre-action listener: _resource_record_pre_action 16:40:09 INFO - Running main action method: create_virtualenv 16:40:09 INFO - Creating virtualenv /builds/slave/test/build/venv 16:40:09 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 16:40:09 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:40:09 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:40:09 INFO - Using real prefix '/tools/python27' 16:40:09 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:40:09 INFO - Installing distribute.............................................................................................................................................................................................done. 16:40:12 INFO - Installing pip.................done. 16:40:12 INFO - Return code: 0 16:40:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:40:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:40:12 INFO - Reading from file tmpfile_stdout 16:40:12 INFO - Output received: 16:40:12 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 16:40:12 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:40:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:40:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:40:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:40:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:40:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:40:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:40:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c7893f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6ff570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcff9d7da70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c59fd30>, '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 0x10c406db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c597b20>, '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.nduNWQrzEH/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.aIoDmv8vYM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/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 16:40:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 16:40:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 16:40:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nduNWQrzEH/Render', 16:40:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:40:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:40:13 INFO - 'HOME': '/Users/cltbld', 16:40:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:40:13 INFO - 'LOGNAME': 'cltbld', 16:40:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:40:13 INFO - 'MOZ_NO_REMOTE': '1', 16:40:13 INFO - 'NO_EM_RESTART': '1', 16:40:13 INFO - 'PAGER': '/bin/cat', 16:40:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:40:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:40:13 INFO - 'PWD': '/builds/slave/test', 16:40:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:40:13 INFO - 'SHELL': '/bin/bash', 16:40:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aIoDmv8vYM/Listeners', 16:40:13 INFO - 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/', 16:40:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:40:13 INFO - 'USER': 'cltbld', 16:40:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:40:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:40:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:40:13 INFO - 'XPC_FLAGS': '0x0', 16:40:13 INFO - 'XPC_SERVICE_NAME': '0', 16:40:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:40:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:40:13 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 16:40:13 INFO - Cleaning up... 16:40:13 INFO - Return code: 0 16:40:13 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:40:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:40:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:40:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:40:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:40:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:40:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:40:14 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 0x10c7893f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6ff570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcff9d7da70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c59fd30>, '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 0x10c406db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c597b20>, '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.nduNWQrzEH/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.aIoDmv8vYM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/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 16:40:14 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 16:40:14 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 16:40:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nduNWQrzEH/Render', 16:40:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:40:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:40:14 INFO - 'HOME': '/Users/cltbld', 16:40:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:40:14 INFO - 'LOGNAME': 'cltbld', 16:40:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:40:14 INFO - 'MOZ_NO_REMOTE': '1', 16:40:14 INFO - 'NO_EM_RESTART': '1', 16:40:14 INFO - 'PAGER': '/bin/cat', 16:40:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:40:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:40:14 INFO - 'PWD': '/builds/slave/test', 16:40:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:40:14 INFO - 'SHELL': '/bin/bash', 16:40:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aIoDmv8vYM/Listeners', 16:40:14 INFO - 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/', 16:40:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:40:14 INFO - 'USER': 'cltbld', 16:40:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:40:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:40:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:40:14 INFO - 'XPC_FLAGS': '0x0', 16:40:14 INFO - 'XPC_SERVICE_NAME': '0', 16:40:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:40:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:40:14 INFO - Downloading/unpacking psutil>=3.1.1 16:40:14 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 16:40:14 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 16:40:14 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 16:40:14 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 16:40:14 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 16:40:14 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 16:40:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:40:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:40:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:40:18 INFO - Installing collected packages: psutil 16:40:18 INFO - Running setup.py install for psutil 16:40:18 INFO - building 'psutil._psutil_osx' extension 16:40:18 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 16:40:19 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 16:40:19 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 16:40:19 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 16:40:19 INFO - building 'psutil._psutil_posix' extension 16:40:19 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 16:40:19 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:40:19 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:40:19 INFO - ^ 16:40:19 INFO - 1 warning generated. 16:40:19 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 16:40:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:40:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:40:19 INFO - Successfully installed psutil 16:40:19 INFO - Cleaning up... 16:40:19 INFO - Return code: 0 16:40:19 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:40: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')]} 16:40:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:40:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:40: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')]} 16:40:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:40:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:40:19 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 0x10c7893f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6ff570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcff9d7da70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c59fd30>, '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 0x10c406db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c597b20>, '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.nduNWQrzEH/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.aIoDmv8vYM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/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 16:40:19 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 16:40:19 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 16:40:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nduNWQrzEH/Render', 16:40:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:40:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:40:19 INFO - 'HOME': '/Users/cltbld', 16:40:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:40:19 INFO - 'LOGNAME': 'cltbld', 16:40:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:40:19 INFO - 'MOZ_NO_REMOTE': '1', 16:40:19 INFO - 'NO_EM_RESTART': '1', 16:40:19 INFO - 'PAGER': '/bin/cat', 16:40:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:40:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:40:19 INFO - 'PWD': '/builds/slave/test', 16:40:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:40:19 INFO - 'SHELL': '/bin/bash', 16:40:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aIoDmv8vYM/Listeners', 16:40:19 INFO - 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/', 16:40:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:40:19 INFO - 'USER': 'cltbld', 16:40:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:40:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:40:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:40:19 INFO - 'XPC_FLAGS': '0x0', 16:40:19 INFO - 'XPC_SERVICE_NAME': '0', 16:40:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:40:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:40:19 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:40:19 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 16:40:19 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 16:40:19 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 16:40:19 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 16:40:19 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 16:40:19 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 16:40:22 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:40:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:40:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:40:22 INFO - Installing collected packages: mozsystemmonitor 16:40:22 INFO - Running setup.py install for mozsystemmonitor 16:40:22 INFO - Successfully installed mozsystemmonitor 16:40:22 INFO - Cleaning up... 16:40:22 INFO - Return code: 0 16:40:22 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:40:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:40:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:40:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:40:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:40:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:40:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:40:22 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 0x10c7893f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6ff570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcff9d7da70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c59fd30>, '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 0x10c406db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c597b20>, '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.nduNWQrzEH/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.aIoDmv8vYM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/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 16:40:22 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 16:40:22 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 16:40:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nduNWQrzEH/Render', 16:40:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:40:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:40:22 INFO - 'HOME': '/Users/cltbld', 16:40:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:40:22 INFO - 'LOGNAME': 'cltbld', 16:40:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:40:22 INFO - 'MOZ_NO_REMOTE': '1', 16:40:22 INFO - 'NO_EM_RESTART': '1', 16:40:22 INFO - 'PAGER': '/bin/cat', 16:40:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:40:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:40:22 INFO - 'PWD': '/builds/slave/test', 16:40:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:40:22 INFO - 'SHELL': '/bin/bash', 16:40:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aIoDmv8vYM/Listeners', 16:40:22 INFO - 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/', 16:40:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:40:22 INFO - 'USER': 'cltbld', 16:40:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:40:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:40:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:40:22 INFO - 'XPC_FLAGS': '0x0', 16:40:22 INFO - 'XPC_SERVICE_NAME': '0', 16:40:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:40:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:40:22 INFO - Downloading/unpacking jsonschema==2.5.1 16:40:22 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 16:40:22 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 16:40:22 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 16:40:22 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 16:40:22 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 16:40:22 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 16:40:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:40:25 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:40:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:40:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:40:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:40:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:40:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:40:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:40:25 INFO - Downloading functools32-3.2.3-2.tar.gz 16:40:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:40:25 INFO - warning: no files found matching '*.txt' 16:40:25 INFO - no previously-included directories found matching 'build' 16:40:25 INFO - no previously-included directories found matching 'dist' 16:40:26 INFO - no previously-included directories found matching '.git*' 16:40:26 INFO - Installing collected packages: jsonschema, functools32 16:40:26 INFO - Running setup.py install for functools32 16:40:26 INFO - warning: no files found matching '*.txt' 16:40:26 INFO - no previously-included directories found matching 'build' 16:40:26 INFO - no previously-included directories found matching 'dist' 16:40:26 INFO - no previously-included directories found matching '.git*' 16:40:26 INFO - Successfully installed jsonschema functools32 16:40:26 INFO - Cleaning up... 16:40:26 INFO - Return code: 0 16:40:26 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:40:26 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')]} 16:40:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:40:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:40:26 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')]} 16:40:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:40:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:40:26 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 0x10c7893f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6ff570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcff9d7da70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c59fd30>, '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 0x10c406db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c597b20>, '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.nduNWQrzEH/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.aIoDmv8vYM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/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 16:40:26 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 16:40:26 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 16:40:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nduNWQrzEH/Render', 16:40:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:40:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:40:26 INFO - 'HOME': '/Users/cltbld', 16:40:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:40:26 INFO - 'LOGNAME': 'cltbld', 16:40:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:40:26 INFO - 'MOZ_NO_REMOTE': '1', 16:40:26 INFO - 'NO_EM_RESTART': '1', 16:40:26 INFO - 'PAGER': '/bin/cat', 16:40:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:40:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:40:26 INFO - 'PWD': '/builds/slave/test', 16:40:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:40:26 INFO - 'SHELL': '/bin/bash', 16:40:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aIoDmv8vYM/Listeners', 16:40:26 INFO - 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/', 16:40:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:40:26 INFO - 'USER': 'cltbld', 16:40:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:40:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:40:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:40:26 INFO - 'XPC_FLAGS': '0x0', 16:40:26 INFO - 'XPC_SERVICE_NAME': '0', 16:40:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:40:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:40:26 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:40:26 INFO - Cleaning up... 16:40:26 INFO - Return code: 0 16:40:26 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:40:26 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')]} 16:40:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:40:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:40:26 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')]} 16:40:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:40:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:40:26 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 0x10c7893f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6ff570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcff9d7da70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c59fd30>, '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 0x10c406db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c597b20>, '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.nduNWQrzEH/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.aIoDmv8vYM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/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 16:40:26 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 16:40:26 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 16:40:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nduNWQrzEH/Render', 16:40:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:40:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:40:26 INFO - 'HOME': '/Users/cltbld', 16:40:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:40:26 INFO - 'LOGNAME': 'cltbld', 16:40:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:40:26 INFO - 'MOZ_NO_REMOTE': '1', 16:40:26 INFO - 'NO_EM_RESTART': '1', 16:40:26 INFO - 'PAGER': '/bin/cat', 16:40:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:40:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:40:26 INFO - 'PWD': '/builds/slave/test', 16:40:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:40:26 INFO - 'SHELL': '/bin/bash', 16:40:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aIoDmv8vYM/Listeners', 16:40:26 INFO - 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/', 16:40:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:40:26 INFO - 'USER': 'cltbld', 16:40:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:40:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:40:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:40:26 INFO - 'XPC_FLAGS': '0x0', 16:40:26 INFO - 'XPC_SERVICE_NAME': '0', 16:40:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:40:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:40:26 INFO - Downloading/unpacking blobuploader==1.2.4 16:40:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:40:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:40:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:40:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:40:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:40:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:40:29 INFO - Downloading blobuploader-1.2.4.tar.gz 16:40:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:40:29 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:40:29 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 16:40:29 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 16:40:29 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 16:40:29 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 16:40:29 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 16:40:29 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 16:40:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:40:30 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:40:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:40:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:40:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:40:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:40:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:40:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:40:30 INFO - Downloading docopt-0.6.1.tar.gz 16:40:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:40:30 INFO - Installing collected packages: blobuploader, requests, docopt 16:40:30 INFO - Running setup.py install for blobuploader 16:40:30 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:40:30 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:40:30 INFO - Running setup.py install for requests 16:40:31 INFO - Running setup.py install for docopt 16:40:31 INFO - Successfully installed blobuploader requests docopt 16:40:31 INFO - Cleaning up... 16:40:31 INFO - Return code: 0 16:40:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:40:31 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')]} 16:40:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:40:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:40:31 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')]} 16:40:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:40:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:40:31 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 0x10c7893f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6ff570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcff9d7da70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c59fd30>, '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 0x10c406db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c597b20>, '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.nduNWQrzEH/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.aIoDmv8vYM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/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 16:40:31 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 16:40:31 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 16:40:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nduNWQrzEH/Render', 16:40:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:40:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:40:31 INFO - 'HOME': '/Users/cltbld', 16:40:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:40:31 INFO - 'LOGNAME': 'cltbld', 16:40:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:40:31 INFO - 'MOZ_NO_REMOTE': '1', 16:40:31 INFO - 'NO_EM_RESTART': '1', 16:40:31 INFO - 'PAGER': '/bin/cat', 16:40:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:40:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:40:31 INFO - 'PWD': '/builds/slave/test', 16:40:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:40:31 INFO - 'SHELL': '/bin/bash', 16:40:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aIoDmv8vYM/Listeners', 16:40:31 INFO - 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/', 16:40:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:40:31 INFO - 'USER': 'cltbld', 16:40:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:40:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:40:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:40:31 INFO - 'XPC_FLAGS': '0x0', 16:40:31 INFO - 'XPC_SERVICE_NAME': '0', 16:40:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:40:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:40:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:40:31 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-TiicQI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:40:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:40:31 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-SSRtxO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:40:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:40:32 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-X3LCIr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:40:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:40:32 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-XdD46F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:40:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:40:32 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-a3J1jF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:40:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:40:32 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-Ce4j4S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:40:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:40:32 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-dR7obA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:40:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:40:32 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-mDJNhV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:40:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:40:32 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-ZzxQET-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:40:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:40:32 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-knW0lI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:40:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:40:32 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-U_LshT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:40:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:40:32 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-ZuyeiW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:40:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:40:33 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-VcZz8P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:40:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:40:33 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-DHSAEF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:40:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:40:33 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-n573nw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:40:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:40:33 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-3KbYCk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:40:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:40:33 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-yLnHdo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:40:33 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:40:33 INFO - Running setup.py install for manifestparser 16:40:33 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:40:33 INFO - Running setup.py install for mozcrash 16:40:33 INFO - Running setup.py install for mozdebug 16:40:34 INFO - Running setup.py install for mozdevice 16:40:34 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:40:34 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:40:34 INFO - Running setup.py install for mozfile 16:40:34 INFO - Running setup.py install for mozhttpd 16:40:34 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:40:34 INFO - Running setup.py install for mozinfo 16:40:34 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:40:34 INFO - Running setup.py install for mozInstall 16:40:34 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:40:34 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:40:34 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:40:34 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:40:34 INFO - Running setup.py install for mozleak 16:40:34 INFO - Running setup.py install for mozlog 16:40:35 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:40:35 INFO - Running setup.py install for moznetwork 16:40:35 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:40:35 INFO - Running setup.py install for mozprocess 16:40:35 INFO - Running setup.py install for mozprofile 16:40:35 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:40:35 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:40:35 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:40:35 INFO - Running setup.py install for mozrunner 16:40:35 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:40:35 INFO - Running setup.py install for mozscreenshot 16:40:35 INFO - Running setup.py install for moztest 16:40:36 INFO - Running setup.py install for mozversion 16:40:36 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:40:36 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:40:36 INFO - Cleaning up... 16:40:36 INFO - Return code: 0 16:40:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:40:36 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')]} 16:40:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:40:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:40:36 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')]} 16:40:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:40:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:40:36 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 0x10c7893f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6ff570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcff9d7da70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c59fd30>, '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 0x10c406db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c597b20>, '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.nduNWQrzEH/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.aIoDmv8vYM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/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 16:40:36 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 16:40:36 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 16:40:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nduNWQrzEH/Render', 16:40:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:40:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:40:36 INFO - 'HOME': '/Users/cltbld', 16:40:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:40:36 INFO - 'LOGNAME': 'cltbld', 16:40:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:40:36 INFO - 'MOZ_NO_REMOTE': '1', 16:40:36 INFO - 'NO_EM_RESTART': '1', 16:40:36 INFO - 'PAGER': '/bin/cat', 16:40:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:40:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:40:36 INFO - 'PWD': '/builds/slave/test', 16:40:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:40:36 INFO - 'SHELL': '/bin/bash', 16:40:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aIoDmv8vYM/Listeners', 16:40:36 INFO - 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/', 16:40:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:40:36 INFO - 'USER': 'cltbld', 16:40:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:40:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:40:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:40:36 INFO - 'XPC_FLAGS': '0x0', 16:40:36 INFO - 'XPC_SERVICE_NAME': '0', 16:40:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:40:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:40:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:40:36 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-VPFZKr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:40:36 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)) 16:40:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:40:36 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-GfIJXF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:40:36 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)) 16:40:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:40:36 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-FQqzKA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:40:36 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)) 16:40:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:40:36 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-bVeRoc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:40:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:40:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:40:36 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-2RpmjH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:40:37 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)) 16:40:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:40:37 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-ft05Pa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:40:37 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)) 16:40:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:40:37 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-dw0Zin-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:40:37 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)) 16:40:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:40:37 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-D2KyB7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:40:37 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)) 16:40:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:40:37 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-_tKJBc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:40:37 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)) 16:40:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:40:37 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-_CwpFv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:40:37 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)) 16:40:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:40:37 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-0lS0eX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:40:37 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)) 16:40:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:40:37 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-CyTHCu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:40:37 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)) 16:40:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:40:37 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-uNOyh4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:40:37 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)) 16:40:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:40:37 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-XNcOTm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:40:38 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)) 16:40:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:40:38 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-kf_1l7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:40:38 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)) 16:40:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:40:38 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-gaVpZb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:40:38 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)) 16:40:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:40:38 INFO - Running setup.py (path:/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/pip-kgKWgl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:40:38 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)) 16:40:38 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)) 16:40:38 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)) 16:40:38 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)) 16:40:38 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:40:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:40:38 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:40: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 16:40: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 16:40: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 16:40: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 16:40: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 16:40: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 16:40:40 INFO - Downloading blessings-1.6.tar.gz 16:40:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:40:40 INFO - Installing collected packages: blessings 16:40:40 INFO - Running setup.py install for blessings 16:40:41 INFO - Successfully installed blessings 16:40:41 INFO - Cleaning up... 16:40:41 INFO - Return code: 0 16:40:41 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:40:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:40:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:40:41 INFO - Reading from file tmpfile_stdout 16:40:41 INFO - Current package versions: 16:40:41 INFO - blessings == 1.6 16:40:41 INFO - blobuploader == 1.2.4 16:40:41 INFO - docopt == 0.6.1 16:40:41 INFO - functools32 == 3.2.3-2 16:40:41 INFO - jsonschema == 2.5.1 16:40:41 INFO - manifestparser == 1.1 16:40:41 INFO - mozInstall == 1.12 16:40:41 INFO - mozcrash == 1.0 16:40:41 INFO - mozdebug == 0.1 16:40:41 INFO - mozdevice == 0.48 16:40:41 INFO - mozfile == 1.2 16:40:41 INFO - mozhttpd == 0.7 16:40:41 INFO - mozinfo == 0.9 16:40:41 INFO - mozleak == 0.1 16:40:41 INFO - mozlog == 3.4 16:40:41 INFO - moznetwork == 0.27 16:40:41 INFO - mozprocess == 0.23 16:40:41 INFO - mozprofile == 0.28 16:40:41 INFO - mozrunner == 6.13 16:40:41 INFO - mozscreenshot == 0.1 16:40:41 INFO - mozsystemmonitor == 0.3 16:40:41 INFO - moztest == 0.7 16:40:41 INFO - mozversion == 1.4 16:40:41 INFO - psutil == 3.1.1 16:40:41 INFO - requests == 1.2.3 16:40:41 INFO - wsgiref == 0.1.2 16:40:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:40:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:40:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:40:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:40:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:40:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:40:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:40:41 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 0x10c7893f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6ff570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcff9d7da70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c59fd30>, '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 0x10c406db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c597b20>, '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.nduNWQrzEH/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.aIoDmv8vYM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/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 16:40:41 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 16:40:41 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 16:40:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nduNWQrzEH/Render', 16:40:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:40:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:40:41 INFO - 'HOME': '/Users/cltbld', 16:40:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:40:41 INFO - 'LOGNAME': 'cltbld', 16:40:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:40:41 INFO - 'MOZ_NO_REMOTE': '1', 16:40:41 INFO - 'NO_EM_RESTART': '1', 16:40:41 INFO - 'PAGER': '/bin/cat', 16:40:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:40:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:40:41 INFO - 'PWD': '/builds/slave/test', 16:40:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:40:41 INFO - 'SHELL': '/bin/bash', 16:40:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aIoDmv8vYM/Listeners', 16:40:41 INFO - 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/', 16:40:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:40:41 INFO - 'USER': 'cltbld', 16:40:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:40:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:40:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:40:41 INFO - 'XPC_FLAGS': '0x0', 16:40:41 INFO - 'XPC_SERVICE_NAME': '0', 16:40:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:40:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:40:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:40:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 16:40:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 16:40:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:40:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 16:40:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 16:40:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 16:40:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 16:40:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 16:40:41 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:40:41 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:40:41 INFO - Cleaning up... 16:40:41 INFO - Return code: 0 16:40:41 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:40:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:40:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:40:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:40:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:40:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:40:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:40:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c7893f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6ff570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcff9d7da70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c59fd30>, '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 0x10c406db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c597b20>, '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.nduNWQrzEH/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.aIoDmv8vYM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/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 16:40:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:40:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:40:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nduNWQrzEH/Render', 16:40:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:40:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:40:41 INFO - 'HOME': '/Users/cltbld', 16:40:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:40:41 INFO - 'LOGNAME': 'cltbld', 16:40:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:40:41 INFO - 'MOZ_NO_REMOTE': '1', 16:40:41 INFO - 'NO_EM_RESTART': '1', 16:40:41 INFO - 'PAGER': '/bin/cat', 16:40:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:40:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:40:41 INFO - 'PWD': '/builds/slave/test', 16:40:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:40:41 INFO - 'SHELL': '/bin/bash', 16:40:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aIoDmv8vYM/Listeners', 16:40:41 INFO - 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/', 16:40:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:40:41 INFO - 'USER': 'cltbld', 16:40:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:40:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:40:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:40:41 INFO - 'XPC_FLAGS': '0x0', 16:40:41 INFO - 'XPC_SERVICE_NAME': '0', 16:40:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:40:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:40:42 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:40:42 INFO - Cleaning up... 16:40:42 INFO - Return code: 0 16:40:42 INFO - Running post-action listener: _resource_record_post_action 16:40:42 INFO - Running post-action listener: _start_resource_monitoring 16:40:42 INFO - Starting resource monitoring. 16:40:42 INFO - [mozharness: 2016-11-17 00:40:42.100847Z] Finished create-virtualenv step (success) 16:40:42 INFO - [mozharness: 2016-11-17 00:40:42.101802Z] Running install step. 16:40:42 INFO - Running pre-action listener: _resource_record_pre_action 16:40:42 INFO - Running main action method: install 16:40:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:40:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:40:42 INFO - Reading from file tmpfile_stdout 16:40:42 INFO - Detecting whether we're running mozinstall >=1.0... 16:40:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:40:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:40:42 INFO - Reading from file tmpfile_stdout 16:40:42 INFO - Output received: 16:40:42 INFO - Usage: mozinstall [options] installer 16:40:42 INFO - Options: 16:40:42 INFO - -h, --help show this help message and exit 16:40:42 INFO - -d DEST, --destination=DEST 16:40:42 INFO - Directory to install application into. [default: 16:40:42 INFO - "/builds/slave/test"] 16:40:42 INFO - --app=APP Application being installed. [default: firefox] 16:40:42 INFO - mkdir: /builds/slave/test/build/application 16:40:42 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'] 16:40:42 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 16:41:16 INFO - Reading from file tmpfile_stdout 16:41:16 INFO - Output received: 16:41:16 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 16:41:16 INFO - Running post-action listener: _resource_record_post_action 16:41:16 INFO - [mozharness: 2016-11-17 00:41:16.240194Z] Finished install step (success) 16:41:16 INFO - [mozharness: 2016-11-17 00:41:16.240352Z] Running run-tests step. 16:41:16 INFO - Running pre-action listener: _resource_record_pre_action 16:41:16 INFO - Running main action method: run_tests 16:41:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:41:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:41:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:41:16 INFO - 2016-11-16 16:41:16.254 screenresolution[1624:7533] starting screenresolution argv=screenresolution get 16:41:16 INFO - 2016-11-16 16:41:16.257 screenresolution[1624:7533] Display 0: 1600x1200x32@60 16:41:16 INFO - 2016-11-16 16:41:16.262 screenresolution[1625:7535] starting screenresolution argv=screenresolution list 16:41:16 INFO - Available Modes on Display 0 16:41:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:41:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:41:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:41:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:41:16 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:41:16 INFO - Intel Iris: 16:41:16 INFO - Chipset Model: Intel Iris 16:41:16 INFO - Type: GPU 16:41:16 INFO - Bus: Built-In 16:41:16 INFO - VRAM (Dynamic, Max): 1536 MB 16:41:16 INFO - Vendor: Intel (0x8086) 16:41:16 INFO - Device ID: 0x0a2e 16:41:16 INFO - Revision ID: 0x0009 16:41:16 INFO - Displays: 16:41:16 INFO - Display: 16:41:16 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:41:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:41:16 INFO - Display Serial Number: 1600x1200 60 16:41:16 INFO - Main Display: Yes 16:41:16 INFO - Mirror: Off 16:41:16 INFO - Online: Yes 16:41:16 INFO - Rotation: Supported 16:41:16 INFO - Return code: 0 16:41:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:41:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:41:16 INFO - Python 2.7.3 16:41:16 INFO - Return code: 0 16:41:16 INFO - grabbing minidump binary from tooltool 16:41:16 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')]} 16:41:16 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 0x10c59fd30>, '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 0x10c406db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c597b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:41:16 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 16:41:16 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 16:41:16 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 16:41:16 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:41:16 INFO - Return code: 0 16:41:16 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:41:16 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:41:16 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:41:16 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:41:16 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:41:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/R9QhHUCsQU-P8cyv1cXmnw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0352', '--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 16:41:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Larch --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/R9QhHUCsQU-P8cyv1cXmnw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0352 --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 16:41:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nduNWQrzEH/Render', 16:41:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:41:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:41:16 INFO - 'HOME': '/Users/cltbld', 16:41:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:41:16 INFO - 'LOGNAME': 'cltbld', 16:41:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:41:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:41:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:41:16 INFO - 'MOZ_NO_REMOTE': '1', 16:41:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:41:16 INFO - 'NO_EM_RESTART': '1', 16:41:16 INFO - 'PAGER': '/bin/cat', 16:41:16 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:41:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:41:16 INFO - 'PWD': '/builds/slave/test', 16:41:16 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:41:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:41:16 INFO - 'SHELL': '/bin/bash', 16:41:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aIoDmv8vYM/Listeners', 16:41:16 INFO - 'TMPDIR': '/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/', 16:41:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:41:16 INFO - 'USER': 'cltbld', 16:41:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:41:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:41:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:41:16 INFO - 'XPC_FLAGS': '0x0', 16:41:16 INFO - 'XPC_SERVICE_NAME': '0', 16:41:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:41:16 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/R9QhHUCsQU-P8cyv1cXmnw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0352', '--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 16:41:16 INFO - mozversion application_buildid: 20161116124710 16:41:16 INFO - mozversion application_changeset: fbbcd82b29623828d64038b7272da2906f4bd8f5 16:41:16 INFO - mozversion application_display_name: Nightly 16:41:16 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:41:16 INFO - mozversion application_name: Firefox 16:41:16 INFO - mozversion application_remotingname: firefox 16:41:16 INFO - mozversion application_repository: https://hg.mozilla.org/projects/larch 16:41:16 INFO - mozversion application_vendor: Mozilla 16:41:16 INFO - mozversion application_version: 53.0a1 16:41:16 INFO - mozversion platform_buildid: 20161116124710 16:41:16 INFO - mozversion platform_changeset: fbbcd82b29623828d64038b7272da2906f4bd8f5 16:41:16 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/larch 16:41:16 INFO - mozversion platform_version: 53.0a1 16:41:16 INFO - using testdate: 1479343276 16:41:16 INFO - actual date: 1479343276 16:41:16 INFO - starting webserver on 'localhost:49243' 16:41:16 INFO - SUITE-START | Running 2 tests 16:41:16 INFO - TEST-START | dromaeo_css 16:41:16 INFO - operating with platform_type : mac_ 16:41:16 INFO - Initialising browser for dromaeo_css test... 16:41:16 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/tmp59kpKe/profile http://localhost:49243/getInfo.html) 16:41:19 INFO - PROCESS | 1635 | 2016-11-16 16:41:19.623 plugin-container[1637:7707] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f43, name = 'com.apple.tsm.portname' 16:41:19 INFO - PROCESS | 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:41:19 INFO - PROCESS | 1635 | 2016-11-16 16:41:19.626 plugin-container[1637:7707] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e53, name = 'com.apple.CFPasteboardClient' 16:41:19 INFO - PROCESS | 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:41:19 INFO - PROCESS | 1635 | 2016-11-16 16:41:19.626 plugin-container[1637:7707] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:41:19 INFO - PROCESS | 1635 | __metrics Screen width/height:1600/1200 16:41:19 INFO - PROCESS | 1635 | colorDepth:24 16:41:19 INFO - PROCESS | 1635 | Browser inner width/height: 1024/689 16:41:19 INFO - PROCESS | 1635 | __metrics 16:41:19 ERROR - PROCESS | 1635 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3179: TypeError: this.tabs is undefined 16:41:20 INFO - TEST-INFO | 1635: exit 0 16:41:20 INFO - Browser initialized. 16:41:20 INFO - Running cycle 1/1 for dromaeo_css test... 16:41:20 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/tmp59kpKe/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:41:22 INFO - PROCESS | 1638 | 2016-11-16 16:41:22.876 plugin-container[1639:7828] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4347, name = 'com.apple.tsm.portname' 16:41:22 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:41:22 INFO - PROCESS | 1638 | 2016-11-16 16:41:22.877 plugin-container[1639:7828] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4617, name = 'com.apple.CFPasteboardClient' 16:41:22 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:41:22 INFO - PROCESS | 1638 | 2016-11-16 16:41:22.877 plugin-container[1639:7828] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:41:32 INFO - PROCESS | 1638 | RSS: Main: 242544640 16:41:32 INFO - PROCESS | 1638 | 16:42:22 INFO - PROCESS | 1638 | 1479343342133 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] 16:44:35 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49243/tests/dromaeo/cssquery-ext.html) 16:44:37 INFO - PROCESS | 1638 | RSS: Main: 256946176 16:44:37 INFO - PROCESS | 1638 | 16:47:40 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-ext.html (next: http://localhost:49243/tests/dromaeo/cssquery-jquery.html) 16:47:40 INFO - PROCESS | 1638 | RSS: Main: 267952128 16:47:40 INFO - PROCESS | 1638 | 16:50:42 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49243/tests/dromaeo/cssquery-mootools.html) 16:50:43 INFO - PROCESS | 1638 | RSS: Main: 272388096 16:50:43 INFO - PROCESS | 1638 | 16:53:45 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49243/tests/dromaeo/cssquery-prototype.html) 16:53:46 INFO - PROCESS | 1638 | RSS: Main: 270897152 16:53:46 INFO - PROCESS | 1638 | 16:56:48 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49243/tests/dromaeo/cssquery-yui.html) 16:56:49 INFO - PROCESS | 1638 | RSS: Main: 275238912 16:56:49 INFO - PROCESS | 1638 | 16:59:52 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-yui.html (next: http://localhost:49243/tests/dromaeo/cssquery-dojo.html) 16:59:52 INFO - PROCESS | 1638 | RSS: Main: 277110784 16:59:52 INFO - PROCESS | 1638 | 16:59:52 INFO - PROCESS | 1638 | __start_tp_report 16:59:52 INFO - PROCESS | 1638 | _x_x_mozilla_page_load 16:59:52 INFO - PROCESS | 1638 | _x_x_mozilla_page_load_details 16:59:52 INFO - PROCESS | 1638 | |i|pagename|runs| 16:59:52 INFO - PROCESS | 1638 | |0;dojo.html;3271.721903638191;3317.6350601433837;3366.511855780912;3369.932601347974;3385.9492107618375;5480.314960629921;5488.259085023521;5498.4501549845;5503.284573005509;5603.467670571289;5436.3476382834015;5473.917891231635;5513.2005859150395;5549.722513874301;5551.472610102044;5062.873428164288;5072.061668591304;5107.489251074881;5193.143131383336;5206.661566998152;4994.700317980933;5146.022255771391;5171.818986335479;5218.921716174261;5245.527902488775;4998.375203099613;5039.168537191359;5127.102757017545;5134.1015322318635;5136.229565565158;5085.262636917663;5094.694318340911;5111.948880511184;5115.283860259567;5147.922781158286;5032.823851165228;5057.4689657585795;5061.822836200752;5078.873028174326;5112.744362781883;4302.859742168328;4472.329150627399;4504.324351347292;4936.728479933602;5471.863203419906;5571.637843540157;5713.628614140109;6028.1861948636815;6044.184035155242;6048.39516048396;6097.939020609781;6101.206843110412;6119.289302195927;6176.81469555914;6179;4886.731229782396;5474.178873169057;5684.459976302286;5699.914501282484;5707.14392841073;5142.434332223453;6217.906731399033;6278.937210627927;6289.056641503719;6296.087067375296;5864.706764661835;5925.259342582177;5931.703414829241;5959.612625179349;5963.671998040063;6026.5178785697035;6046.667433291212;6097.817065488042;6100.359462256399;6154.7230374633245;5854.326752423441;5921.259842519685;5921.407859214044;5939.524838012949;6040;6066.059760737093;6094.329623741385;6109.313572000199;6116.5718399711595;6140.355262697352;4734.479207287196;4773.64197685175;4780.4741478437345;4792.305115758196;4815.494373090845;4220.11377610699;4222.239996800606;4243.554426785205;4267.423897773823;4279.700420970502;5885.205497257815;5895;5898.262717160355;5907;5946.43508866657;6136.417040381157;6180.721867515973;6199.845003874939;6201.1938448002375;6202.28673702521;5201.349831271091;5619.606627536115;5856.092305692625;5875.383084776123;5875.764969401262;4008.7394319369732;5208.557272631793;5216.426193118754;5232.633715639869;5249.763760630781;6237.96881015592;6277.2153480814895;6289.559730818799;6321.209848768904;6368.681565921778;6113.449789518964;6175.505959523138;6183.505319574513;6192.040233763774;6203.472704820141;6185.536084793568;6214.471769899428;6230.43926046658;6234.470070044097;6243.719032643633;6069.696515174312;6078;6083.6349819011;6104;6107.297660769068;6001.129836173817;6148.815535533941;6166.352532983972;6173.197484327009;6182.1345011699295;5746.511546518593;5753.769849206153;5777.566682498746;5845.386234445492;5895.41045895407;5830.679312637676;5861.35525092248;5869.295684517885;5871.060630299303;5884.852878678067;5613.719314034201;5723.170140329546;5744.454276843693;5747.540196784331;5765.1928729977;5827.76688932438;5848.181254624271;5859.150423188528;5877.559183061373;5901.822945311647;5767.134929760569;5772.365039845529;5783.450572195635;5799.304083509837;5800.4489573490455 16:59:52 INFO - PROCESS | 1638 | |1;ext.html;435.6819521749123;484.36548121960215;510.72420892717923;530.501328750974;550.9834704958853;27982;38438.80780596097;38653.22693546127;38696.03259918503;38812.41781373275;39000.21999560007;39520.01199970002;39552.604473955325;39657.60342396575;39803.40294895579;7903.644336004865;8923.509206993613;9070.818583628357;9117.635294588208;9184.035676254014;9229.353945223836;9307.720768376927;9310.674126405576;9323.440593564408;9355.438673679568;9331.53342332884;9367.063293670646;9403.341766076377;9415;9415.246780257597;8062.959685201595;8170.509769413854;9281.257499400032;9324.90675093254;9332.766680832965;6601.2738598754095;7216.567005979606;8106.554139522324;8686.174813392718;8701.912980870173;37478;37750.62249377499;37783.62216377829;37839.432408513894;37893.81053094744;3236.449803533405;3243.172990887332;3264.6408895021527;3279.0818570800216;3402.4896265560114;29146.125616231548;29302.85348573265;29470.115896522922;30222.84888575565;30277.243068923453;3167.1624151475676;3194.801949269024;3240.7245384142375;3264.5266436366833;3380.915477113092;425736;483562;489609;491097;491822.54088729684;59796.80406391848;60076;60103.69948150319;60214;60273.397266027656;62457.063144052874;62796.744065118444;63002;63010;63217.367826322065;15662.530124096294;15978.121203333696;15989.840101599068;15991.600209994844;16001.519954401387;12212.57255996036;12297.692557686129;12322.568710095104;12327.630171094881;12337.69155771095;17326.072564176797;18437.446876593724;18483.353082642043;18613.90693046553;18710.53223669419;5601.355844077902;6937.861242775116;7383.187849336568;7591.924080759121;7634.198409167068;834.3992325525612;849.8300339932039;949.5993409720606;989.7228775942748;992.8560358747941;27194.048208312615;28174.71825281721;28352.574711379344;28536.714632853407;29010.854945725558;991.9206463483048;993.5529011944653;1017.6692574913183;1056.6460235820932;1098.4617537406773;14879.479218227527;14916.179610121548;14966.775498367315;15129.621759456102;15153.772693409608;669032;674470;676199;678222;679131;55695.88608227813;55896.44103558912;56071.719641401534;56361.71819140878;56519.43480565224;1138.9943050284862;1281.474595415875;1318.2683610596212;1331.7203387288778;1337.5786627212397;24068.638970415806;26660.60009099904;28563.001179835257;28801.855990719912;29032.709672903;48038.5196148034;48042.5195748052;48608.756956214995;48639;48794.78013049702;607597;610474;611155;611268;611535;25320.746792532576;25952.8702356487;25964.091256806678;26287.868560657073;26322.73677263203;44224.69934875529;45214.32178517259;45422;45451;45530.31704524368;29788.957386492235;34806.303873921875;36836;37574.43638345372;38170;27353.726462735118;32967.67032329646;36749.81625091857;37751;38189.618103818604;24623.63064554069;30415.391692166475;32213.87251446188;37096.814515927246;38197.045073872265;31522.527162092127;33590;33595.65617375277;33627;33778;19861.404157875288;22969.081236750342;24243.151489697775;24719.62920556157;25315.240542783748 16:59:52 INFO - PROCESS | 1638 | |2;jquery.html;317.36051855511147;333.849767604578;335.3133737301374;348.98778542751006;380.51294343240625;18801.247950081997;19093.80906190938;20468.28361007365;20477.180912763473;20685.689714654254;18668.813311866877;18777.624447511076;19948.90025549874;20487.48781280465;20840;5665.773369065243;5876.294844618652;5949.226600541923;5951.047832346825;5967.671778052206;5176.482351764831;5764.538836893039;5809.186713860063;5809.941900580982;5883.617564858292;5825.504832089277;5841.773227622183;5879.029960056586;5885.470307672309;5897.911531327012;5560.527355174815;5862.706864656771;5930.644161350311;5940.554458415636;5972.432618901197;5522.834314970557;5710.229119068914;5712.514436272881;5747.3103227612955;5782.624129431616;11118.110551155889;13163.14439561435;13239.338033098307;13326.333683315795;13362.933185334108;2231.977680223193;2236.5303286309895;2289.919852805144;2589.41351864052;2598.8960441582312;9776.16902563283;9809.411435313905;9943.850842237372;10295.227857910686;10370.429626370547;1845.935392261278;1862.3936680603965;1888.1880791259753;1908.2271679969635;1910.169076451734;105198;119539.80460195374;122942.77057229403;123189;123546.38226808899;10798.244122911321;10894.509747061402;11531.365774882463;11554.711132221762;11620;13773.517926872511;14115.647108822362;14170.291485425687;14172.503962361268;14196.219207943455;7626.69493220276;7937.880931786028;8008.639611217509;8083.393745469113;8106.756797296091;7655.885161722578;7658.617069146632;7660.004199454035;7790.415718821111;7881.763547093597;4379.642310883636;4434.467863856358;4473.4408198975125;4489.842855500042;4568.451785785727;1641.9507414777577;1658.8424099710508;1880.5580688538182;1966.9803301966797;1978.2878163860998;811.4036183405193;836.8451536879068;859.5573279760928;890.8040231149137;997.2171845101706;19475.41212978304;20789;21553.491255611956;21560.137594496045;21687.78312216889;859.9981022109173;884.0982198157843;888.9466632002101;914.4147745442922;936.5972631768269;9074.820273364421;10117;11238.831417528743;11264.267822591506;11293.096552275798;594892;596823;605647;607080;610951;97740.51129744305;119880.40059799646;121707.78652668417;124969;128872.35563822121;869.4696235296464;869.8173383589517;928.5310917986374;976.4580657735025;979.6228452046014;18483;19109.426717198505;19756.60486790285;19779.505512362306;19850.404487865388;37152.81423592865;43735.56264437315;47796;52325.47674523206;57217.713911430175;81029;82829.58585207035;84812.57593711992;84813;85387;6749.527533072835;7926.286634202949;7968.402369822171;8091.757247282591;8174.959125204574;10061.597536098767;11082.889171108185;12772.61682149537;12994.545190918272;13158.210507369206;8426.988761348677;10420.478976051016;10789.136869050613;11381.43092845371;11508.769824603294;9668.081532254424;9889.307748457826;10985.066269366873;11303.604373846876;11482.368469734245;11309.434528273718;11647.30116193031;11738.354390508262;11831.230969986924;11845.703857403634;7889.64496597643;7929.048514178336;8201.876971845308;8333.250007499355;8343.624536895733;10186;11160.944195278971;11253.043491303331;11439.798821123995;11638.06895448379 16:59:52 INFO - PROCESS | 1638 | |3;mootools.html;1462.5904746670928;1502.384022550754;1551.3174203350532;1645.786047813784;1753.5703752580619;7572.772816815491;7827.21727827217;8105.87841182382;8577.013643431008;8607.35444841637;8000.8799868002025;8269.710560130397;8313.95843020784;8420.24217820396;8437.578121093951;2272.556851413975;2305.3429772514837;2307.792298693117;2310.330004298751;2316.6061769499224;2310.688057112285;2348.9177878774244;2367.573836709392;2373.299876536426;2380.9166679166233;2305.5504176685554;2306.3888069661552;2320.106758897828;2322.1756276521846;2324.081987614891;2305.976940230593;2307.2732089391957;2311.8150547956125;2327.813774898004;2338.0297176671747;2310.584094862924;2339.099446713011;2342.730585982617;2349.0955981946904;2353.940726673004;8427.578621068984;8451.873221901677;8489.235968762841;8548.829023419497;8549.615267312985;5092;5235.973820130913;5250.5274525292525;5258.237555554423;5267.499587539178;3283.589551306087;3439.9312013759586;3564.518789963348;3633.291508155911;3633.836477358499;1013.0932815130491;1040.0075420394219;1071.1858987169728;1077.5258886090173;1077.9083777878889;9994.350367226107;10355.27513074085;10967.067799237075;11044.282121662067;11047.72380690489;4456.264716321835;4568.360429539867;4650.953490465052;4663.603593694506;4733.242681170997;9424.057594240658;9582.616695332248;9587.280953928483;9599.760005999766;9617.807643847083;5174.43081261061;5188.273641690166;5212.609054320941;5240.947590524046;5249.477651481104;3621.5473065866768;4298.871033868989;4307.461567304087;4612.685384665354;4811.037792441526;3292.604887413526;3347.43093674076;3758.1919887224376;3760.4735337052834;3865.6520913117956;978.5889926231;1087.505185140759;1241.4971936365787;1262.8232047513357;1268.6764874956827;805.2712295372697;813.9348852091819;863.9179277968709;899.4648184330313;931.4271722890373;5311.3891902431715;5589.5807814413265;5701.458361455655;5735.684537350402;5894.145348924468;425.23670012327943;434.5589226934664;435.60577677202184;435.7712201094451;442.7255101836882;3056.0526236866644;3250.821204833757;3799.9240015200094;3864.091938394475;3907.374820028782;9753.902460975441;10235.7441063974;10261.333013354108;10312.02035806597;10336.948315258525;10220.02909723575;10273.178145748323;10283.177345812317;10287.9485602573;10306.433146177033;7096.50324477292;7324.523905946096;7341.74303899361;7549.698012079565;7942.404319675932;5641.323041235078;5677.261955945701;5743.741531631003;5770.394108618535;5811.709414529341;7981.600919954095;9238.168564829197;9362.251019918527;9443;9464.479453630116;9456.905430945602;9611.855822162534;9692;9747;9750.366226195274;5941.465268125717;6896.896546551906;7091.361777440055;7213.927860721536;7410.777676669709;6703.899441508479;7863.921360786548;7969.242921922408;8109.189081091844;8119.837603247784;7122.679479423542;7272.672729727069;7333.596652183967;7345.081864766904;7361.1166660001145;7097.822554435974;7114.6442677866935;7170.96414517924;7288.052553168054;7308.196098429062;6742.157230346207;6900;6900.171979362509;6900.9654951726925;6907.620080895599;4776.401532109525;4852.223644216909;4886.120498310338;4940.654154209171;4953.380827396575;4819.349387832598;4841.564259216688;4892.657513973988;4967.453580106113;4976 16:59:52 INFO - PROCESS | 1638 | |4;prototype.html;291.4215282663912;303.63563723531763;304.31984514609854;309.7939869986459;313.33416489958836;5110.923336149961;5202.193659982709;5358.356997160341;5401.729913504324;5416.808302173526;4443.533428989958;5282.471752824726;5288.497592728685;5293.417724050351;5300.575953923687;3776.8300426480737;3989.2021595680835;4066.2274167908004;4082.1835632873394;4104.651104656108;3890.455336252927;4073.5111786585653;4109.219248342805;4109.732867363627;4113.565173080467;3543.0787995121195;3715.18265981485;4042.939355909664;4073.8370465181356;4095.1809638072355;2974.8363839988792;3711.3134070196966;4071.9592804071876;4078.5921407859273;4096.262672718909;3220.7439098751506;3486.9302613947834;3552.8223588820456;3573.249617580311;3587.493252833816;5029.748512574393;5088.567471764904;5185.688858668492;5200.583953283766;5241.266222728821;3685.4840322354894;3761.4169803680475;4594.104149690812;4627.699199552018;4654.650901182391;4217.641500472463;5538.390777014525;5610.971945140289;5635.408282130358;5675.042110471881;3126.9530957035663;3189.8245596492184;3506.7895926244505;3795.6394142556414;3897.9220415591813;5812.273465816773;5865.208196893407;5866.70666466675;5912.704364781744;5952.226210592638;3893.5327760668333;3896.4155376693725;3897.512810898638;3900.7464514806447;3918.314294998364;3791.6777073949024;3911.647951684305;3942.64516193544;3955.8219880105466;3969.3053715599654;4531.501534831166;4559.361689363492;4575.748333841604;4588.931166032512;4609.216433206363;5421.186821976735;5651.519620832193;5667;5670.404607516234;5689.601727879091;938.9906100938903;1228.5454381878762;1248.0015987210195;1249.9812502812465;1274.847018357803;779.7699678594828;783.5964478293683;812.1229072601576;817.5952903312898;870.3298460185622;4054.959450405458;4721.981016258929;4763.642726795435;4885.8045678173185;5103.106956282636;4458.576435238647;5214.97392513035;5361.463853614685;5470.8937059232485;5492.791585851106;3999.24014437256;4018.6182312680835;4353.673474489426;4355.912881742347;4435.806219521939;2383.32075358522;2623.580227163683;2810.622794830518;2852.2584128126837;2867.5411934090444;5405.70268635221;5729.997250481149;5747.252857128547;5757.107648314518;5770.52851522859;4588.655850811202;5137.460566640524;5192.584593232608;5308.991291654651;5350.732463376893;4877.6829506081995;5100.38795344561;5160.922586161136;5486.448801307331;5758.395368486249;4771.856844294677;5278.868028299324;5394.190871369326;5397;5438;4499.730016199039;4987.451380348086;5053.862880851779;5432.212329212163;5438.864028399322;5322.175062865262;5451.827857010603;5638.746256418389;5672.971635141798;5739.8852022960145;3136.357046805354;3149.4331020416544;3149.874005039864;3658.0306218851492;3675.852965881335;4366.318967197715;5320.52115309624;5362.276092727588;5426.891462170814;5428.809991650273;4495.179452321815;5344.3853956795465;5442.455754424526;5613.129964855454;5618.241537392563;5612.971935140298;5626.483447845097;5637;5639.7744090237575;5658.5473162147755;4836.516348365135;5246;5369.844948849149;5477.72611369422;5548.639338442988;5585.553155747449;5621.887562248814;5626.718664066862;5633.408632029313;5673.234113394639;4992.751812046989;5146.279520867007;5496.780128794963;5520.1443776214755;5554.611177217556 16:59:52 INFO - PROCESS | 1638 | |5;yui.html;653.8855700252454;672.4217173231021;681.6727970574121;728.2608152724988;784.4548039112815;14177.929110354447;14739.631509212275;14780;14823.703525929475;14833.11001339919;13327.46690132396;13445;13485.516593174767;13496.122752021112;13538.39077241524;587.2952457051534;636.6657504809965;791.6714563456159;802.6348011654698;824.3528829868554;710.5788423153692;811.8701007838747;812.305478815613;815.0179034263698;829.2163905109674;840.6007146605375;867.3624885708999;872.4634349874844;880.1858281089998;883.6200433813458;866.770305868942;873.8732883731823;876.5660997806095;876.7457437343163;878.8066625342415;637.2607774981;644.7163248170837;648.9415952564292;649.0070192605322;651.9967400163016;12975.286359250238;13161.881240094603;14460.06009609372;14582.416703331854;14592.124472531683;13118.409671564803;13465.326733663278;13649;13667.931660341636;14110.73003429686;9157.679481218192;11312.038476729487;11346;11347.375894325809;11692;4356.346548017791;4402.603765661106;4424.646028317727;4434.246178149722;4463.553644635543;62783.99999999954;83096;83635;84144.57927710323;88334.5583272086;10249.180065594735;10402.48542604806;10512.211584131068;10712.78574428507;10770.353778773298;19988.600227995357;20067.698984515246;20076;20119.0946407412;20150.596988060155;7614.8096297593;7749.573773442515;7754.030746156731;7765.339946104531;7951.960240198762;4241.300185469363;4282.207791558571;4328.264195086843;4370.38814565961;4391.956080439219;3354.3962086824604;3457.75795694299;3466.9826650866585;3472.756907016535;3473.14907847579;505.5096556340344;601.801405536172;605.198112500938;627.4635186915214;659.5416185750953;536.1823219590078;551.3686828581252;567.1322875999728;607.3349682098095;635.1171871099176;8831.867521987175;9193;9246.398984066014;9256.074392560824;9492.525373731285;529.6054439442563;607.1166452811237;610.3073012131263;656.6454114777966;670.7987603718865;3633.4731463937583;4461.330800379969;4484.771083621712;4492.573205545468;4502.392177056121;64674;84209;84307.15692843116;103041;113165;36500.45249321262;59504;72533;101775;103089;92168.54820162659;93380.99999999865;93527;94238.0576194243;94819.52590237143;8684.696035638975;8737.91262087371;8738.650453981769;8770.166834150745;8866.778330541789;55890;56615.15077273927;57120.71439642775;57288.85422291447;61150;77399.61300193463;79376.80934786098;80361.59819200866;81761.59119204366;82302.58848705718;11305.982461578498;11404.600838970597;11453.255538389978;11802.409879506162;12122;12533.686657833627;12568.120231583702;12676.87323126757;12858.935705321413;13020.28388438607;11611.303321800719;11753.941230293794;11916.582919598119;12380.195287306657;12389.318587477776;11610.24533405326;11711.238769479956;12197;12357.443915023665;12384.938075309567;10746.8387974182;11279.718007049889;11604.94197529007;11778.078914320226;11934.104942129201;9673.322867399214;9907.950460247652;10013.24900632441;10127.696169114939;10383.117435018108;8088;9257.537123143951;9265.86101208469;9621.951890240503;10353.585856565955 16:59:52 INFO - PROCESS | 1638 | __end_tp_report 16:59:52 INFO - PROCESS | 1638 | __start_cc_report 16:59:52 INFO - PROCESS | 1638 | _x_x_mozilla_cycle_collect,381 16:59:52 INFO - PROCESS | 1638 | __end_cc_report 16:59:52 INFO - PROCESS | 1638 | __startTimestamp1479344392427__endTimestamp 16:59:52 INFO - PROCESS | 1638 | 16:59:52 INFO - PROCESS | 1638 | ------- Summary: start ------- 16:59:52 INFO - PROCESS | 1638 | Number of tests: 6 16:59:52 INFO - PROCESS | 1638 | 16:59:52 INFO - PROCESS | 1638 | [#0] dojo.html Cycles:165 Average:5575.52 Median:5800.45 stddev:664.61 (11.5%) stddev-sans-first:641.61 16:59:52 INFO - PROCESS | 1638 | Values: 3271.7 3317.6 3366.5 3369.9 3385.9 5480.3 5488.3 5498.5 5503.3 5603.5 5436.3 5473.9 5513.2 5549.7 5551.5 5062.9 5072.1 5107.5 5193.1 5206.7 4994.7 5146.0 5171.8 5218.9 5245.5 4998.4 5039.2 5127.1 5134.1 5136.2 5085.3 5094.7 5111.9 5115.3 5147.9 5032.8 5057.5 5061.8 5078.9 5112.7 4302.9 4472.3 4504.3 4936.7 5471.9 5571.6 5713.6 6028.2 6044.2 6048.4 6097.9 6101.2 6119.3 6176.8 6179.0 4886.7 5474.2 5684.5 5699.9 5707.1 5142.4 6217.9 6278.9 6289.1 6296.1 5864.7 5925.3 5931.7 5959.6 5963.7 6026.5 6046.7 6097.8 6100.4 6154.7 5854.3 5921.3 5921.4 5939.5 6040.0 6066.1 6094.3 6109.3 6116.6 6140.4 4734.5 4773.6 4780.5 4792.3 4815.5 4220.1 4222.2 4243.6 4267.4 4279.7 5885.2 5895.0 5898.3 5907.0 5946.4 6136.4 6180.7 6199.8 6201.2 6202.3 5201.3 5619.6 5856.1 5875.4 5875.8 4008.7 5208.6 5216.4 5232.6 5249.8 6238.0 6277.2 6289.6 6321.2 6368.7 6113.4 6175.5 6183.5 6192.0 6203.5 6185.5 6214.5 6230.4 6234.5 6243.7 6069.7 6078.0 6083.6 6104.0 6107.3 6001.1 6148.8 6166.4 6173.2 6182.1 5746.5 5753.8 5777.6 5845.4 5895.4 5830.7 5861.4 5869.3 5871.1 5884.9 5613.7 5723.2 5744.5 5747.5 5765.2 5827.8 5848.2 5859.2 5877.6 5901.8 5767.1 5772.4 5783.5 5799.3 5800.4 16:59:52 INFO - PROCESS | 1638 | 16:59:52 INFO - PROCESS | 1638 | [#1] ext.html Cycles:180 Average:70618.05 Median:38674.63 stddev:159085.60 (411.3%) stddev-sans-first:159444.61 16:59:52 INFO - PROCESS | 1638 | Values: 435.7 484.4 510.7 530.5 551.0 27982.0 38438.8 38653.2 38696.0 38812.4 39000.2 39520.0 39552.6 39657.6 39803.4 7903.6 8923.5 9070.8 9117.6 9184.0 9229.4 9307.7 9310.7 9323.4 9355.4 9331.5 9367.1 9403.3 9415.0 9415.2 8063.0 8170.5 9281.3 9324.9 9332.8 6601.3 7216.6 8106.6 8686.2 8701.9 37478.0 37750.6 37783.6 37839.4 37893.8 3236.4 3243.2 3264.6 3279.1 3402.5 29146.1 29302.9 29470.1 30222.8 30277.2 3167.2 3194.8 3240.7 3264.5 3380.9 425736.0 483562.0 489609.0 491097.0 491822.5 59796.8 60076.0 60103.7 60214.0 60273.4 62457.1 62796.7 63002.0 63010.0 63217.4 15662.5 15978.1 15989.8 15991.6 16001.5 12212.6 12297.7 12322.6 12327.6 12337.7 17326.1 18437.4 18483.4 18613.9 18710.5 5601.4 6937.9 7383.2 7591.9 7634.2 834.4 849.8 949.6 989.7 992.9 27194.0 28174.7 28352.6 28536.7 29010.9 991.9 993.6 1017.7 1056.6 1098.5 14879.5 14916.2 14966.8 15129.6 15153.8 669032.0 674470.0 676199.0 678222.0 679131.0 55695.9 55896.4 56071.7 56361.7 56519.4 1139.0 1281.5 1318.3 1331.7 1337.6 24068.6 26660.6 28563.0 28801.9 29032.7 48038.5 48042.5 48608.8 48639.0 48794.8 607597.0 610474.0 611155.0 611268.0 611535.0 25320.7 25952.9 25964.1 26287.9 26322.7 44224.7 45214.3 45422.0 45451.0 45530.3 29789.0 34806.3 36836.0 37574.4 38170.0 27353.7 32967.7 36749.8 37751.0 38189.6 24623.6 30415.4 32213.9 37096.8 38197.0 31522.5 33590.0 33595.7 33627.0 33778.0 19861.4 22969.1 24243.2 24719.6 25315.2 16:59:52 INFO - PROCESS | 1638 | 16:59:52 INFO - PROCESS | 1638 | [#2] jquery.html Cycles:180 Average:34475.36 Median:18750.90 stddev:100566.11 (536.3%) stddev-sans-first:100815.52 16:59:52 INFO - PROCESS | 1638 | Values: 317.4 333.8 335.3 349.0 380.5 18801.2 19093.8 20468.3 20477.2 20685.7 18668.8 18777.6 19948.9 20487.5 20840.0 5665.8 5876.3 5949.2 5951.0 5967.7 5176.5 5764.5 5809.2 5809.9 5883.6 5825.5 5841.8 5879.0 5885.5 5897.9 5560.5 5862.7 5930.6 5940.6 5972.4 5522.8 5710.2 5712.5 5747.3 5782.6 11118.1 13163.1 13239.3 13326.3 13362.9 2232.0 2236.5 2289.9 2589.4 2598.9 9776.2 9809.4 9943.9 10295.2 10370.4 1845.9 1862.4 1888.2 1908.2 1910.2 105198.0 119539.8 122942.8 123189.0 123546.4 10798.2 10894.5 11531.4 11554.7 11620.0 13773.5 14115.6 14170.3 14172.5 14196.2 7626.7 7937.9 8008.6 8083.4 8106.8 7655.9 7658.6 7660.0 7790.4 7881.8 4379.6 4434.5 4473.4 4489.8 4568.5 1642.0 1658.8 1880.6 1967.0 1978.3 811.4 836.8 859.6 890.8 997.2 19475.4 20789.0 21553.5 21560.1 21687.8 860.0 884.1 888.9 914.4 936.6 9074.8 10117.0 11238.8 11264.3 11293.1 594892.0 596823.0 605647.0 607080.0 610951.0 97740.5 119880.4 121707.8 124969.0 128872.4 869.5 869.8 928.5 976.5 979.6 18483.0 19109.4 19756.6 19779.5 19850.4 37152.8 43735.6 47796.0 52325.5 57217.7 81029.0 82829.6 84812.6 84813.0 85387.0 6749.5 7926.3 7968.4 8091.8 8175.0 10061.6 11082.9 12772.6 12994.5 13158.2 8427.0 10420.5 10789.1 11381.4 11508.8 9668.1 9889.3 10985.1 11303.6 11482.4 11309.4 11647.3 11738.4 11831.2 11845.7 7889.6 7929.0 8201.9 8333.3 8343.6 10186.0 11160.9 11253.0 11439.8 11638.1 16:59:52 INFO - PROCESS | 1638 | 16:59:52 INFO - PROCESS | 1638 | [#3] mootools.html Cycles:180 Average:5438.86 Median:4971.73 stddev:3027.96 (60.9%) stddev-sans-first:3021.70 16:59:52 INFO - PROCESS | 1638 | Values: 1462.6 1502.4 1551.3 1645.8 1753.6 7572.8 7827.2 8105.9 8577.0 8607.4 8000.9 8269.7 8314.0 8420.2 8437.6 2272.6 2305.3 2307.8 2310.3 2316.6 2310.7 2348.9 2367.6 2373.3 2380.9 2305.6 2306.4 2320.1 2322.2 2324.1 2306.0 2307.3 2311.8 2327.8 2338.0 2310.6 2339.1 2342.7 2349.1 2353.9 8427.6 8451.9 8489.2 8548.8 8549.6 5092.0 5236.0 5250.5 5258.2 5267.5 3283.6 3439.9 3564.5 3633.3 3633.8 1013.1 1040.0 1071.2 1077.5 1077.9 9994.4 10355.3 10967.1 11044.3 11047.7 4456.3 4568.4 4651.0 4663.6 4733.2 9424.1 9582.6 9587.3 9599.8 9617.8 5174.4 5188.3 5212.6 5240.9 5249.5 3621.5 4298.9 4307.5 4612.7 4811.0 3292.6 3347.4 3758.2 3760.5 3865.7 978.6 1087.5 1241.5 1262.8 1268.7 805.3 813.9 863.9 899.5 931.4 5311.4 5589.6 5701.5 5735.7 5894.1 425.2 434.6 435.6 435.8 442.7 3056.1 3250.8 3799.9 3864.1 3907.4 9753.9 10235.7 10261.3 10312.0 10336.9 10220.0 10273.2 10283.2 10287.9 10306.4 7096.5 7324.5 7341.7 7549.7 7942.4 5641.3 5677.3 5743.7 5770.4 5811.7 7981.6 9238.2 9362.3 9443.0 9464.5 9456.9 9611.9 9692.0 9747.0 9750.4 5941.5 6896.9 7091.4 7213.9 7410.8 6703.9 7863.9 7969.2 8109.2 8119.8 7122.7 7272.7 7333.6 7345.1 7361.1 7097.8 7114.6 7171.0 7288.1 7308.2 6742.2 6900.0 6900.2 6901.0 6907.6 4776.4 4852.2 4886.1 4940.7 4953.4 4819.3 4841.6 4892.7 4967.5 4976.0 16:59:52 INFO - PROCESS | 1638 | 16:59:52 INFO - PROCESS | 1638 | [#4] prototype.html Cycles:180 Average:4407.18 Median:5041.81 stddev:1379.66 (27.4%) stddev-sans-first:1348.50 16:59:52 INFO - PROCESS | 1638 | Values: 291.4 303.6 304.3 309.8 313.3 5110.9 5202.2 5358.4 5401.7 5416.8 4443.5 5282.5 5288.5 5293.4 5300.6 3776.8 3989.2 4066.2 4082.2 4104.7 3890.5 4073.5 4109.2 4109.7 4113.6 3543.1 3715.2 4042.9 4073.8 4095.2 2974.8 3711.3 4072.0 4078.6 4096.3 3220.7 3486.9 3552.8 3573.2 3587.5 5029.7 5088.6 5185.7 5200.6 5241.3 3685.5 3761.4 4594.1 4627.7 4654.7 4217.6 5538.4 5611.0 5635.4 5675.0 3127.0 3189.8 3506.8 3795.6 3897.9 5812.3 5865.2 5866.7 5912.7 5952.2 3893.5 3896.4 3897.5 3900.7 3918.3 3791.7 3911.6 3942.6 3955.8 3969.3 4531.5 4559.4 4575.7 4588.9 4609.2 5421.2 5651.5 5667.0 5670.4 5689.6 939.0 1228.5 1248.0 1250.0 1274.8 779.8 783.6 812.1 817.6 870.3 4055.0 4722.0 4763.6 4885.8 5103.1 4458.6 5215.0 5361.5 5470.9 5492.8 3999.2 4018.6 4353.7 4355.9 4435.8 2383.3 2623.6 2810.6 2852.3 2867.5 5405.7 5730.0 5747.3 5757.1 5770.5 4588.7 5137.5 5192.6 5309.0 5350.7 4877.7 5100.4 5160.9 5486.4 5758.4 4771.9 5278.9 5394.2 5397.0 5438.0 4499.7 4987.5 5053.9 5432.2 5438.9 5322.2 5451.8 5638.7 5673.0 5739.9 3136.4 3149.4 3149.9 3658.0 3675.9 4366.3 5320.5 5362.3 5426.9 5428.8 4495.2 5344.4 5442.5 5613.1 5618.2 5613.0 5626.5 5637.0 5639.8 5658.5 4836.5 5246.0 5369.8 5477.7 5548.6 5585.6 5621.9 5626.7 5633.4 5673.2 4992.8 5146.3 5496.8 5520.1 5554.6 16:59:52 INFO - PROCESS | 1638 | 16:59:52 INFO - PROCESS | 1638 | [#5] yui.html Cycles:180 Average:19487.96 Median:543.78 stddev:28265.88 (5198.1%) stddev-sans-first:28309.80 16:59:52 INFO - PROCESS | 1638 | Values: 653.9 672.4 681.7 728.3 784.5 14177.9 14739.6 14780.0 14823.7 14833.1 13327.5 13445.0 13485.5 13496.1 13538.4 587.3 636.7 791.7 802.6 824.4 710.6 811.9 812.3 815.0 829.2 840.6 867.4 872.5 880.2 883.6 866.8 873.9 876.6 876.7 878.8 637.3 644.7 648.9 649.0 652.0 12975.3 13161.9 14460.1 14582.4 14592.1 13118.4 13465.3 13649.0 13667.9 14110.7 9157.7 11312.0 11346.0 11347.4 11692.0 4356.3 4402.6 4424.6 4434.2 4463.6 62784.0 83096.0 83635.0 84144.6 88334.6 10249.2 10402.5 10512.2 10712.8 10770.4 19988.6 20067.7 20076.0 20119.1 20150.6 7614.8 7749.6 7754.0 7765.3 7952.0 4241.3 4282.2 4328.3 4370.4 4392.0 3354.4 3457.8 3467.0 3472.8 3473.1 505.5 601.8 605.2 627.5 659.5 536.2 551.4 567.1 607.3 635.1 8831.9 9193.0 9246.4 9256.1 9492.5 529.6 607.1 610.3 656.6 670.8 3633.5 4461.3 4484.8 4492.6 4502.4 64674.0 84209.0 84307.2 103041.0 113165.0 36500.5 59504.0 72533.0 101775.0 103089.0 92168.5 93381.0 93527.0 94238.1 94819.5 8684.7 8737.9 8738.7 8770.2 8866.8 55890.0 56615.2 57120.7 57288.9 61150.0 77399.6 79376.8 80361.6 81761.6 82302.6 11306.0 11404.6 11453.3 11802.4 12122.0 12533.7 12568.1 12676.9 12858.9 13020.3 11611.3 11753.9 11916.6 12380.2 12389.3 11610.2 11711.2 12197.0 12357.4 12384.9 10746.8 11279.7 11604.9 11778.1 11934.1 9673.3 9908.0 10013.2 10127.7 10383.1 8088.0 9257.5 9265.9 9622.0 10353.6 16:59:52 INFO - PROCESS | 1638 | -------- Summary: end -------- 16:59:52 INFO - PROCESS | 1638 | 16:59:53 INFO - TEST-INFO | 1638: exit 0 16:59:53 INFO - TEST-OK | dromaeo_css | took 1116284ms 16:59:53 INFO - TEST-START | kraken 16:59:53 INFO - operating with platform_type : mac_ 16:59:53 INFO - Initialising browser for kraken test... 16:59:53 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/tmpwdgjdw/profile http://localhost:49243/getInfo.html) 16:59:56 INFO - PROCESS | 1650 | 2016-11-16 16:59:56.035 plugin-container[1651:10336] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 16:59:56 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:59:56 INFO - PROCESS | 1650 | 2016-11-16 16:59:56.039 plugin-container[1651:10336] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 16:59:56 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:59:56 INFO - PROCESS | 1650 | 2016-11-16 16:59:56.039 plugin-container[1651:10336] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:59:56 INFO - PROCESS | 1650 | __metrics Screen width/height:1600/1200 16:59:56 INFO - PROCESS | 1650 | colorDepth:24 16:59:56 INFO - PROCESS | 1650 | Browser inner width/height: 1024/689 16:59:56 INFO - PROCESS | 1650 | __metrics 16:59:56 ERROR - PROCESS | 1650 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3179: TypeError: this.tabs is undefined 16:59:56 INFO - TEST-INFO | 1650: exit 0 16:59:56 INFO - Browser initialized. 16:59:56 INFO - Running cycle 1/1 for kraken test... 16:59:56 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ng/csmfm9x9681blsv1wkb387f800000w/T/tmpwdgjdw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:59:59 INFO - PROCESS | 1652 | 2016-11-16 16:59:59.495 plugin-container[1653:10459] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.tsm.portname' 16:59:59 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:59:59 INFO - PROCESS | 1652 | 2016-11-16 16:59:59.496 plugin-container[1653:10459] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 16:59:59 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:59:59 INFO - PROCESS | 1652 | 2016-11-16 16:59:59.496 plugin-container[1653:10459] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:00:09 INFO - PROCESS | 1652 | RSS: Main: 242651136 17:00:09 INFO - PROCESS | 1652 | 17:00:11 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection) 17:00:12 INFO - PROCESS | 1652 | RSS: Main: 253468672 17:00:12 INFO - PROCESS | 1652 | 17:00:15 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-dft) 17:00:16 INFO - PROCESS | 1652 | RSS: Main: 261099520 17:00:16 INFO - PROCESS | 1652 | 17:00:19 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-fft) 17:00:20 INFO - PROCESS | 1652 | RSS: Main: 261574656 17:00:20 INFO - PROCESS | 1652 | 17:00:22 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator) 17:00:22 INFO - PROCESS | 1652 | RSS: Main: 262660096 17:00:22 INFO - PROCESS | 1652 | 17:00:24 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:00:25 INFO - PROCESS | 1652 | RSS: Main: 262758400 17:00:25 INFO - PROCESS | 1652 | 17:00:28 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom) 17:00:28 INFO - PROCESS | 1652 | RSS: Main: 269398016 17:00:28 INFO - PROCESS | 1652 | 17:00:31 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate) 17:00:32 INFO - PROCESS | 1652 | RSS: Main: 269295616 17:00:32 INFO - PROCESS | 1652 | 17:00:35 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial) 17:00:36 INFO - PROCESS | 1652 | RSS: Main: 268886016 17:00:36 INFO - PROCESS | 1652 | 17:00:37 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:00:38 INFO - PROCESS | 1652 | RSS: Main: 267866112 17:00:38 INFO - PROCESS | 1652 | 17:00:40 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:00:40 INFO - PROCESS | 1652 | RSS: Main: 268189696 17:00:40 INFO - PROCESS | 1652 | 17:00:43 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:00:43 INFO - PROCESS | 1652 | RSS: Main: 267927552 17:00:43 INFO - PROCESS | 1652 | 17:00:46 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:00:47 INFO - PROCESS | 1652 | RSS: Main: 268353536 17:00:47 INFO - PROCESS | 1652 | 17:00:50 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:00:51 INFO - PROCESS | 1652 | RSS: Main: 268505088 17:00:51 INFO - PROCESS | 1652 | 17:00:53 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49243/tests/kraken/driver.html?testName=ai-astar) 17:00:53 INFO - PROCESS | 1652 | RSS: Main: 270028800 17:00:53 INFO - PROCESS | 1652 | 17:00:53 INFO - PROCESS | 1652 | __start_tp_report 17:00:53 INFO - PROCESS | 1652 | _x_x_mozilla_page_load 17:00:53 INFO - PROCESS | 1652 | _x_x_mozilla_page_load_details 17:00:53 INFO - PROCESS | 1652 | |i|pagename|runs| 17:00:53 INFO - PROCESS | 1652 | |0;ai-astar;114;119;118;113;119;123;176;135;119;120 17:00:53 INFO - PROCESS | 1652 | |1;audio-beat-detection;154;114;117;153;115;116;117;116;149;116 17:00:53 INFO - PROCESS | 1652 | |2;audio-dft;195;198;211;205;210;196;231;282;199;203 17:00:53 INFO - PROCESS | 1652 | |3;audio-fft;94;53;52;75;84;83;50;72;110;85 17:00:53 INFO - PROCESS | 1652 | |4;audio-oscillator;83;71;88;71;73;90;72;91;59;93 17:00:53 INFO - PROCESS | 1652 | |5;imaging-gaussian-blur;91;88;90;87;97;87;95;87;93;117 17:00:53 INFO - PROCESS | 1652 | |6;imaging-darkroom;97;96;96;95;96;153;119;95;94;111 17:00:53 INFO - PROCESS | 1652 | |7;imaging-desaturate;93;97;100;92;84;77;79;140;145;152 17:00:53 INFO - PROCESS | 1652 | |8;json-parse-financial;56;57;56;58;59;72;58;57;58;55 17:00:53 INFO - PROCESS | 1652 | |9;json-stringify-tinderbox;69;69;71;75;84;76;71;70;73;73 17:00:53 INFO - PROCESS | 1652 | |10;stanford-crypto-aes;87;87;88;89;109;112;88;91;92;77 17:00:53 INFO - PROCESS | 1652 | |11;stanford-crypto-ccm;168;168;142;151;156;160;171;170;203;147 17:00:53 INFO - PROCESS | 1652 | |12;stanford-crypto-pbkdf2;183;184;180;182;187;184;186;187;184;183 17:00:53 INFO - PROCESS | 1652 | |13;stanford-crypto-sha256-iterative;70;70;71;71;84;92;93;90;93;70 17:00:53 INFO - PROCESS | 1652 | __end_tp_report 17:00:53 INFO - PROCESS | 1652 | __start_cc_report 17:00:53 INFO - PROCESS | 1652 | _x_x_mozilla_cycle_collect,982 17:00:53 INFO - PROCESS | 1652 | __end_cc_report 17:00:53 INFO - PROCESS | 1652 | __startTimestamp1479344453337__endTimestamp 17:00:53 INFO - PROCESS | 1652 | 17:00:53 INFO - PROCESS | 1652 | ------- Summary: start ------- 17:00:53 INFO - PROCESS | 1652 | Number of tests: 14 17:00:53 INFO - PROCESS | 1652 | 17:00:53 INFO - PROCESS | 1652 | [#0] ai-astar Cycles:10 Average:125.60 Median:119.50 stddev:18.70 (15.7%) stddev-sans-first:19.36 17:00:53 INFO - PROCESS | 1652 | Values: 114.0 119.0 118.0 113.0 119.0 123.0 176.0 135.0 119.0 120.0 17:00:53 INFO - PROCESS | 1652 | 17:00:53 INFO - PROCESS | 1652 | [#1] audio-beat-detection Cycles:10 Average:126.70 Median:117.00 stddev:17.52 (15.0%) stddev-sans-first:15.56 17:00:53 INFO - PROCESS | 1652 | Values: 154.0 114.0 117.0 153.0 115.0 116.0 117.0 116.0 149.0 116.0 17:00:53 INFO - PROCESS | 1652 | 17:00:53 INFO - PROCESS | 1652 | [#2] audio-dft Cycles:10 Average:213.00 Median:207.50 stddev:26.45 (12.7%) stddev-sans-first:27.24 17:00:53 INFO - PROCESS | 1652 | Values: 195.0 198.0 211.0 205.0 210.0 196.0 231.0 282.0 199.0 203.0 17:00:53 INFO - PROCESS | 1652 | 17:00:53 INFO - PROCESS | 1652 | [#3] audio-fft Cycles:10 Average:75.80 Median:79.00 stddev:19.64 (24.9%) stddev-sans-first:19.70 17:00:53 INFO - PROCESS | 1652 | Values: 94.0 53.0 52.0 75.0 84.0 83.0 50.0 72.0 110.0 85.0 17:00:53 INFO - PROCESS | 1652 | 17:00:53 INFO - PROCESS | 1652 | [#4] audio-oscillator Cycles:10 Average:79.10 Median:85.50 stddev:11.41 (13.3%) stddev-sans-first:12.01 17:00:53 INFO - PROCESS | 1652 | Values: 83.0 71.0 88.0 71.0 73.0 90.0 72.0 91.0 59.0 93.0 17:00:53 INFO - PROCESS | 1652 | 17:00:53 INFO - PROCESS | 1652 | [#5] imaging-gaussian-blur Cycles:10 Average:93.20 Median:90.50 stddev:9.08 (10.0%) stddev-sans-first:9.59 17:00:53 INFO - PROCESS | 1652 | Values: 91.0 88.0 90.0 87.0 97.0 87.0 95.0 87.0 93.0 117.0 17:00:53 INFO - PROCESS | 1652 | 17:00:53 INFO - PROCESS | 1652 | [#6] imaging-darkroom Cycles:10 Average:105.20 Median:95.50 stddev:18.75 (19.6%) stddev-sans-first:19.65 17:00:53 INFO - PROCESS | 1652 | Values: 97.0 96.0 96.0 95.0 96.0 153.0 119.0 95.0 94.0 111.0 17:00:53 INFO - PROCESS | 1652 | 17:00:53 INFO - PROCESS | 1652 | [#7] imaging-desaturate Cycles:10 Average:105.90 Median:81.50 stddev:28.54 (35.0%) stddev-sans-first:29.88 17:00:53 INFO - PROCESS | 1652 | Values: 93.0 97.0 100.0 92.0 84.0 77.0 79.0 140.0 145.0 152.0 17:00:53 INFO - PROCESS | 1652 | 17:00:53 INFO - PROCESS | 1652 | [#8] json-parse-financial Cycles:10 Average:58.60 Median:58.00 stddev:4.86 (8.4%) stddev-sans-first:5.06 17:00:53 INFO - PROCESS | 1652 | Values: 56.0 57.0 56.0 58.0 59.0 72.0 58.0 57.0 58.0 55.0 17:00:53 INFO - PROCESS | 1652 | 17:00:53 INFO - PROCESS | 1652 | [#9] json-stringify-tinderbox Cycles:10 Average:73.10 Median:73.00 stddev:4.51 (6.2%) stddev-sans-first:4.53 17:00:53 INFO - PROCESS | 1652 | Values: 69.0 69.0 71.0 75.0 84.0 76.0 71.0 70.0 73.0 73.0 17:00:53 INFO - PROCESS | 1652 | 17:00:53 INFO - PROCESS | 1652 | [#10] stanford-crypto-aes Cycles:10 Average:92.00 Median:88.00 stddev:10.57 (12.0%) stddev-sans-first:11.06 17:00:53 INFO - PROCESS | 1652 | Values: 87.0 87.0 88.0 89.0 109.0 112.0 88.0 91.0 92.0 77.0 17:00:53 INFO - PROCESS | 1652 | 17:00:53 INFO - PROCESS | 1652 | [#11] stanford-crypto-ccm Cycles:10 Average:163.60 Median:168.00 stddev:17.19 (10.2%) stddev-sans-first:18.16 17:00:53 INFO - PROCESS | 1652 | Values: 168.0 168.0 142.0 151.0 156.0 160.0 171.0 170.0 203.0 147.0 17:00:53 INFO - PROCESS | 1652 | 17:00:53 INFO - PROCESS | 1652 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:184.00 Median:184.00 stddev:2.21 (1.2%) stddev-sans-first:2.32 17:00:53 INFO - PROCESS | 1652 | Values: 183.0 184.0 180.0 182.0 187.0 184.0 186.0 187.0 184.0 183.0 17:00:53 INFO - PROCESS | 1652 | 17:00:53 INFO - PROCESS | 1652 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:80.40 Median:87.00 stddev:10.84 (12.5%) stddev-sans-first:10.83 17:00:53 INFO - PROCESS | 1652 | Values: 70.0 70.0 71.0 71.0 84.0 92.0 93.0 90.0 93.0 70.0 17:00:53 INFO - PROCESS | 1652 | -------- Summary: end -------- 17:00:53 INFO - PROCESS | 1652 | 17:00:54 INFO - TEST-INFO | 1652: exit 0 17:00:54 INFO - TEST-OK | kraken | took 61098ms 17:00:54 INFO - SUITE-END | took 1177s 17:00:54 INFO - Completed test suite (00:19:37) 17:00:54 INFO - javascript benchmark 17:00:54 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6994.682688927611, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3271.721903638191, 3317.6350601433837, 3366.511855780912, 3369.932601347974, 3385.9492107618375, 5480.314960629921, 5488.259085023521, 5498.4501549845, 5503.284573005509, 5603.467670571289, 5436.3476382834015, 5473.917891231635, 5513.2005859150395, 5549.722513874301, 5551.472610102044, 5062.873428164288, 5072.061668591304, 5107.489251074881, 5193.143131383336, 5206.661566998152, 4994.700317980933, 5146.022255771391, 5171.818986335479, 5218.921716174261, 5245.527902488775, 4998.375203099613, 5039.168537191359, 5127.102757017545, 5134.1015322318635, 5136.229565565158, 5085.262636917663, 5094.694318340911, 5111.948880511184, 5115.283860259567, 5147.922781158286, 5032.823851165228, 5057.4689657585795, 5061.822836200752, 5078.873028174326, 5112.744362781883, 4302.859742168328, 4472.329150627399, 4504.324351347292, 4936.728479933602, 5471.863203419906, 5571.637843540157, 5713.628614140109, 6028.1861948636815, 6044.184035155242, 6048.39516048396, 6097.939020609781, 6101.206843110412, 6119.289302195927, 6176.81469555914, 6179.0, 4886.731229782396, 5474.178873169057, 5684.459976302286, 5699.914501282484, 5707.14392841073, 5142.434332223453, 6217.906731399033, 6278.937210627927, 6289.056641503719, 6296.087067375296, 5864.706764661835, 5925.259342582177, 5931.703414829241, 5959.612625179349, 5963.671998040063, 6026.5178785697035, 6046.667433291212, 6097.817065488042, 6100.359462256399, 6154.7230374633245, 5854.326752423441, 5921.259842519685, 5921.407859214044, 5939.524838012949, 6040.0, 6066.059760737093, 6094.329623741385, 6109.313572000199, 6116.5718399711595, 6140.355262697352, 4734.479207287196, 4773.64197685175, 4780.4741478437345, 4792.305115758196, 4815.494373090845, 4220.11377610699, 4222.239996800606, 4243.554426785205, 4267.423897773823, 4279.700420970502, 5885.205497257815, 5895.0, 5898.262717160355, 5907.0, 5946.43508866657, 6136.417040381157, 6180.721867515973, 6199.845003874939, 6201.1938448002375, 6202.28673702521, 5201.349831271091, 5619.606627536115, 5856.092305692625, 5875.383084776123, 5875.764969401262, 4008.7394319369732, 5208.557272631793, 5216.426193118754, 5232.633715639869, 5249.763760630781, 6237.96881015592, 6277.2153480814895, 6289.559730818799, 6321.209848768904, 6368.681565921778, 6113.449789518964, 6175.505959523138, 6183.505319574513, 6192.040233763774, 6203.472704820141, 6185.536084793568, 6214.471769899428, 6230.43926046658, 6234.470070044097, 6243.719032643633, 6069.696515174312, 6078.0, 6083.6349819011, 6104.0, 6107.297660769068, 6001.129836173817, 6148.815535533941, 6166.352532983972, 6173.197484327009, 6182.1345011699295, 5746.511546518593, 5753.769849206153, 5777.566682498746, 5845.386234445492, 5895.41045895407, 5830.679312637676, 5861.35525092248, 5869.295684517885, 5871.060630299303, 5884.852878678067, 5613.719314034201, 5723.170140329546, 5744.454276843693, 5747.540196784331, 5765.1928729977, 5827.76688932438, 5848.181254624271, 5859.150423188528, 5877.559183061373, 5901.822945311647, 5767.134929760569, 5772.365039845529, 5783.450572195635, 5799.304083509837, 5800.4489573490455], "value": 5532.552734554186, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [435.6819521749123, 484.36548121960215, 510.72420892717923, 530.501328750974, 550.9834704958853, 27982.0, 38438.80780596097, 38653.22693546127, 38696.03259918503, 38812.41781373275, 39000.21999560007, 39520.01199970002, 39552.604473955325, 39657.60342396575, 39803.40294895579, 7903.644336004865, 8923.509206993613, 9070.818583628357, 9117.635294588208, 9184.035676254014, 9229.353945223836, 9307.720768376927, 9310.674126405576, 9323.440593564408, 9355.438673679568, 9331.53342332884, 9367.063293670646, 9403.341766076377, 9415.0, 9415.246780257597, 8062.959685201595, 8170.509769413854, 9281.257499400032, 9324.90675093254, 9332.766680832965, 6601.2738598754095, 7216.567005979606, 8106.554139522324, 8686.174813392718, 8701.912980870173, 37478.0, 37750.62249377499, 37783.62216377829, 37839.432408513894, 37893.81053094744, 3236.449803533405, 3243.172990887332, 3264.6408895021527, 3279.0818570800216, 3402.4896265560114, 29146.125616231548, 29302.85348573265, 29470.115896522922, 30222.84888575565, 30277.243068923453, 3167.1624151475676, 3194.801949269024, 3240.7245384142375, 3264.5266436366833, 3380.915477113092, 425736.0, 483562.0, 489609.0, 491097.0, 491822.54088729684, 59796.80406391848, 60076.0, 60103.69948150319, 60214.0, 60273.397266027656, 62457.063144052874, 62796.744065118444, 63002.0, 63010.0, 63217.367826322065, 15662.530124096294, 15978.121203333696, 15989.840101599068, 15991.600209994844, 16001.519954401387, 12212.57255996036, 12297.692557686129, 12322.568710095104, 12327.630171094881, 12337.69155771095, 17326.072564176797, 18437.446876593724, 18483.353082642043, 18613.90693046553, 18710.53223669419, 5601.355844077902, 6937.861242775116, 7383.187849336568, 7591.924080759121, 7634.198409167068, 834.3992325525612, 849.8300339932039, 949.5993409720606, 989.7228775942748, 992.8560358747941, 27194.048208312615, 28174.71825281721, 28352.574711379344, 28536.714632853407, 29010.854945725558, 991.9206463483048, 993.5529011944653, 1017.6692574913183, 1056.6460235820932, 1098.4617537406773, 14879.479218227527, 14916.179610121548, 14966.775498367315, 15129.621759456102, 15153.772693409608, 669032.0, 674470.0, 676199.0, 678222.0, 679131.0, 55695.88608227813, 55896.44103558912, 56071.719641401534, 56361.71819140878, 56519.43480565224, 1138.9943050284862, 1281.474595415875, 1318.2683610596212, 1331.7203387288778, 1337.5786627212397, 24068.638970415806, 26660.60009099904, 28563.001179835257, 28801.855990719912, 29032.709672903, 48038.5196148034, 48042.5195748052, 48608.756956214995, 48639.0, 48794.78013049702, 607597.0, 610474.0, 611155.0, 611268.0, 611535.0, 25320.746792532576, 25952.8702356487, 25964.091256806678, 26287.868560657073, 26322.73677263203, 44224.69934875529, 45214.32178517259, 45422.0, 45451.0, 45530.31704524368, 29788.957386492235, 34806.303873921875, 36836.0, 37574.43638345372, 38170.0, 27353.726462735118, 32967.67032329646, 36749.81625091857, 37751.0, 38189.618103818604, 24623.63064554069, 30415.391692166475, 32213.87251446188, 37096.814515927246, 38197.045073872265, 31522.527162092127, 33590.0, 33595.65617375277, 33627.0, 33778.0, 19861.404157875288, 22969.081236750342, 24243.151489697775, 24719.62920556157, 25315.240542783748], "value": 19065.75322573504, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [317.36051855511147, 333.849767604578, 335.3133737301374, 348.98778542751006, 380.51294343240625, 18801.247950081997, 19093.80906190938, 20468.28361007365, 20477.180912763473, 20685.689714654254, 18668.813311866877, 18777.624447511076, 19948.90025549874, 20487.48781280465, 20840.0, 5665.773369065243, 5876.294844618652, 5949.226600541923, 5951.047832346825, 5967.671778052206, 5176.482351764831, 5764.538836893039, 5809.186713860063, 5809.941900580982, 5883.617564858292, 5825.504832089277, 5841.773227622183, 5879.029960056586, 5885.470307672309, 5897.911531327012, 5560.527355174815, 5862.706864656771, 5930.644161350311, 5940.554458415636, 5972.432618901197, 5522.834314970557, 5710.229119068914, 5712.514436272881, 5747.3103227612955, 5782.624129431616, 11118.110551155889, 13163.14439561435, 13239.338033098307, 13326.333683315795, 13362.933185334108, 2231.977680223193, 2236.5303286309895, 2289.919852805144, 2589.41351864052, 2598.8960441582312, 9776.16902563283, 9809.411435313905, 9943.850842237372, 10295.227857910686, 10370.429626370547, 1845.935392261278, 1862.3936680603965, 1888.1880791259753, 1908.2271679969635, 1910.169076451734, 105198.0, 119539.80460195374, 122942.77057229403, 123189.0, 123546.38226808899, 10798.244122911321, 10894.509747061402, 11531.365774882463, 11554.711132221762, 11620.0, 13773.517926872511, 14115.647108822362, 14170.291485425687, 14172.503962361268, 14196.219207943455, 7626.69493220276, 7937.880931786028, 8008.639611217509, 8083.393745469113, 8106.756797296091, 7655.885161722578, 7658.617069146632, 7660.004199454035, 7790.415718821111, 7881.763547093597, 4379.642310883636, 4434.467863856358, 4473.4408198975125, 4489.842855500042, 4568.451785785727, 1641.9507414777577, 1658.8424099710508, 1880.5580688538182, 1966.9803301966797, 1978.2878163860998, 811.4036183405193, 836.8451536879068, 859.5573279760928, 890.8040231149137, 997.2171845101706, 19475.41212978304, 20789.0, 21553.491255611956, 21560.137594496045, 21687.78312216889, 859.9981022109173, 884.0982198157843, 888.9466632002101, 914.4147745442922, 936.5972631768269, 9074.820273364421, 10117.0, 11238.831417528743, 11264.267822591506, 11293.096552275798, 594892.0, 596823.0, 605647.0, 607080.0, 610951.0, 97740.51129744305, 119880.40059799646, 121707.78652668417, 124969.0, 128872.35563822121, 869.4696235296464, 869.8173383589517, 928.5310917986374, 976.4580657735025, 979.6228452046014, 18483.0, 19109.426717198505, 19756.60486790285, 19779.505512362306, 19850.404487865388, 37152.81423592865, 43735.56264437315, 47796.0, 52325.47674523206, 57217.713911430175, 81029.0, 82829.58585207035, 84812.57593711992, 84813.0, 85387.0, 6749.527533072835, 7926.286634202949, 7968.402369822171, 8091.757247282591, 8174.959125204574, 10061.597536098767, 11082.889171108185, 12772.61682149537, 12994.545190918272, 13158.210507369206, 8426.988761348677, 10420.478976051016, 10789.136869050613, 11381.43092845371, 11508.769824603294, 9668.081532254424, 9889.307748457826, 10985.066269366873, 11303.604373846876, 11482.368469734245, 11309.434528273718, 11647.30116193031, 11738.354390508262, 11831.230969986924, 11845.703857403634, 7889.64496597643, 7929.048514178336, 8201.876971845308, 8333.250007499355, 8343.624536895733, 10186.0, 11160.944195278971, 11253.043491303331, 11439.798821123995, 11638.06895448379], "value": 9194.570095823565, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1462.5904746670928, 1502.384022550754, 1551.3174203350532, 1645.786047813784, 1753.5703752580619, 7572.772816815491, 7827.21727827217, 8105.87841182382, 8577.013643431008, 8607.35444841637, 8000.8799868002025, 8269.710560130397, 8313.95843020784, 8420.24217820396, 8437.578121093951, 2272.556851413975, 2305.3429772514837, 2307.792298693117, 2310.330004298751, 2316.6061769499224, 2310.688057112285, 2348.9177878774244, 2367.573836709392, 2373.299876536426, 2380.9166679166233, 2305.5504176685554, 2306.3888069661552, 2320.106758897828, 2322.1756276521846, 2324.081987614891, 2305.976940230593, 2307.2732089391957, 2311.8150547956125, 2327.813774898004, 2338.0297176671747, 2310.584094862924, 2339.099446713011, 2342.730585982617, 2349.0955981946904, 2353.940726673004, 8427.578621068984, 8451.873221901677, 8489.235968762841, 8548.829023419497, 8549.615267312985, 5092.0, 5235.973820130913, 5250.5274525292525, 5258.237555554423, 5267.499587539178, 3283.589551306087, 3439.9312013759586, 3564.518789963348, 3633.291508155911, 3633.836477358499, 1013.0932815130491, 1040.0075420394219, 1071.1858987169728, 1077.5258886090173, 1077.9083777878889, 9994.350367226107, 10355.27513074085, 10967.067799237075, 11044.282121662067, 11047.72380690489, 4456.264716321835, 4568.360429539867, 4650.953490465052, 4663.603593694506, 4733.242681170997, 9424.057594240658, 9582.616695332248, 9587.280953928483, 9599.760005999766, 9617.807643847083, 5174.43081261061, 5188.273641690166, 5212.609054320941, 5240.947590524046, 5249.477651481104, 3621.5473065866768, 4298.871033868989, 4307.461567304087, 4612.685384665354, 4811.037792441526, 3292.604887413526, 3347.43093674076, 3758.1919887224376, 3760.4735337052834, 3865.6520913117956, 978.5889926231, 1087.505185140759, 1241.4971936365787, 1262.8232047513357, 1268.6764874956827, 805.2712295372697, 813.9348852091819, 863.9179277968709, 899.4648184330313, 931.4271722890373, 5311.3891902431715, 5589.5807814413265, 5701.458361455655, 5735.684537350402, 5894.145348924468, 425.23670012327943, 434.5589226934664, 435.60577677202184, 435.7712201094451, 442.7255101836882, 3056.0526236866644, 3250.821204833757, 3799.9240015200094, 3864.091938394475, 3907.374820028782, 9753.902460975441, 10235.7441063974, 10261.333013354108, 10312.02035806597, 10336.948315258525, 10220.02909723575, 10273.178145748323, 10283.177345812317, 10287.9485602573, 10306.433146177033, 7096.50324477292, 7324.523905946096, 7341.74303899361, 7549.698012079565, 7942.404319675932, 5641.323041235078, 5677.261955945701, 5743.741531631003, 5770.394108618535, 5811.709414529341, 7981.600919954095, 9238.168564829197, 9362.251019918527, 9443.0, 9464.479453630116, 9456.905430945602, 9611.855822162534, 9692.0, 9747.0, 9750.366226195274, 5941.465268125717, 6896.896546551906, 7091.361777440055, 7213.927860721536, 7410.777676669709, 6703.899441508479, 7863.921360786548, 7969.242921922408, 8109.189081091844, 8119.837603247784, 7122.679479423542, 7272.672729727069, 7333.596652183967, 7345.081864766904, 7361.1166660001145, 7097.822554435974, 7114.6442677866935, 7170.96414517924, 7288.052553168054, 7308.196098429062, 6742.157230346207, 6900.0, 6900.171979362509, 6900.9654951726925, 6907.620080895599, 4776.401532109525, 4852.223644216909, 4886.120498310338, 4940.654154209171, 4953.380827396575, 4819.349387832598, 4841.564259216688, 4892.657513973988, 4967.453580106113, 4976.0], "value": 4317.289946923215, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [291.4215282663912, 303.63563723531763, 304.31984514609854, 309.7939869986459, 313.33416489958836, 5110.923336149961, 5202.193659982709, 5358.356997160341, 5401.729913504324, 5416.808302173526, 4443.533428989958, 5282.471752824726, 5288.497592728685, 5293.417724050351, 5300.575953923687, 3776.8300426480737, 3989.2021595680835, 4066.2274167908004, 4082.1835632873394, 4104.651104656108, 3890.455336252927, 4073.5111786585653, 4109.219248342805, 4109.732867363627, 4113.565173080467, 3543.0787995121195, 3715.18265981485, 4042.939355909664, 4073.8370465181356, 4095.1809638072355, 2974.8363839988792, 3711.3134070196966, 4071.9592804071876, 4078.5921407859273, 4096.262672718909, 3220.7439098751506, 3486.9302613947834, 3552.8223588820456, 3573.249617580311, 3587.493252833816, 5029.748512574393, 5088.567471764904, 5185.688858668492, 5200.583953283766, 5241.266222728821, 3685.4840322354894, 3761.4169803680475, 4594.104149690812, 4627.699199552018, 4654.650901182391, 4217.641500472463, 5538.390777014525, 5610.971945140289, 5635.408282130358, 5675.042110471881, 3126.9530957035663, 3189.8245596492184, 3506.7895926244505, 3795.6394142556414, 3897.9220415591813, 5812.273465816773, 5865.208196893407, 5866.70666466675, 5912.704364781744, 5952.226210592638, 3893.5327760668333, 3896.4155376693725, 3897.512810898638, 3900.7464514806447, 3918.314294998364, 3791.6777073949024, 3911.647951684305, 3942.64516193544, 3955.8219880105466, 3969.3053715599654, 4531.501534831166, 4559.361689363492, 4575.748333841604, 4588.931166032512, 4609.216433206363, 5421.186821976735, 5651.519620832193, 5667.0, 5670.404607516234, 5689.601727879091, 938.9906100938903, 1228.5454381878762, 1248.0015987210195, 1249.9812502812465, 1274.847018357803, 779.7699678594828, 783.5964478293683, 812.1229072601576, 817.5952903312898, 870.3298460185622, 4054.959450405458, 4721.981016258929, 4763.642726795435, 4885.8045678173185, 5103.106956282636, 4458.576435238647, 5214.97392513035, 5361.463853614685, 5470.8937059232485, 5492.791585851106, 3999.24014437256, 4018.6182312680835, 4353.673474489426, 4355.912881742347, 4435.806219521939, 2383.32075358522, 2623.580227163683, 2810.622794830518, 2852.2584128126837, 2867.5411934090444, 5405.70268635221, 5729.997250481149, 5747.252857128547, 5757.107648314518, 5770.52851522859, 4588.655850811202, 5137.460566640524, 5192.584593232608, 5308.991291654651, 5350.732463376893, 4877.6829506081995, 5100.38795344561, 5160.922586161136, 5486.448801307331, 5758.395368486249, 4771.856844294677, 5278.868028299324, 5394.190871369326, 5397.0, 5438.0, 4499.730016199039, 4987.451380348086, 5053.862880851779, 5432.212329212163, 5438.864028399322, 5322.175062865262, 5451.827857010603, 5638.746256418389, 5672.971635141798, 5739.8852022960145, 3136.357046805354, 3149.4331020416544, 3149.874005039864, 3658.0306218851492, 3675.852965881335, 4366.318967197715, 5320.52115309624, 5362.276092727588, 5426.891462170814, 5428.809991650273, 4495.179452321815, 5344.3853956795465, 5442.455754424526, 5613.129964855454, 5618.241537392563, 5612.971935140298, 5626.483447845097, 5637.0, 5639.7744090237575, 5658.5473162147755, 4836.516348365135, 5246.0, 5369.844948849149, 5477.72611369422, 5548.639338442988, 5585.553155747449, 5621.887562248814, 5626.718664066862, 5633.408632029313, 5673.234113394639, 4992.751812046989, 5146.279520867007, 5496.780128794963, 5520.1443776214755, 5554.611177217556], "value": 3961.968219013941, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [653.8855700252454, 672.4217173231021, 681.6727970574121, 728.2608152724988, 784.4548039112815, 14177.929110354447, 14739.631509212275, 14780.0, 14823.703525929475, 14833.11001339919, 13327.46690132396, 13445.0, 13485.516593174767, 13496.122752021112, 13538.39077241524, 587.2952457051534, 636.6657504809965, 791.6714563456159, 802.6348011654698, 824.3528829868554, 710.5788423153692, 811.8701007838747, 812.305478815613, 815.0179034263698, 829.2163905109674, 840.6007146605375, 867.3624885708999, 872.4634349874844, 880.1858281089998, 883.6200433813458, 866.770305868942, 873.8732883731823, 876.5660997806095, 876.7457437343163, 878.8066625342415, 637.2607774981, 644.7163248170837, 648.9415952564292, 649.0070192605322, 651.9967400163016, 12975.286359250238, 13161.881240094603, 14460.06009609372, 14582.416703331854, 14592.124472531683, 13118.409671564803, 13465.326733663278, 13649.0, 13667.931660341636, 14110.73003429686, 9157.679481218192, 11312.038476729487, 11346.0, 11347.375894325809, 11692.0, 4356.346548017791, 4402.603765661106, 4424.646028317727, 4434.246178149722, 4463.553644635543, 62783.99999999954, 83096.0, 83635.0, 84144.57927710323, 88334.5583272086, 10249.180065594735, 10402.48542604806, 10512.211584131068, 10712.78574428507, 10770.353778773298, 19988.600227995357, 20067.698984515246, 20076.0, 20119.0946407412, 20150.596988060155, 7614.8096297593, 7749.573773442515, 7754.030746156731, 7765.339946104531, 7951.960240198762, 4241.300185469363, 4282.207791558571, 4328.264195086843, 4370.38814565961, 4391.956080439219, 3354.3962086824604, 3457.75795694299, 3466.9826650866585, 3472.756907016535, 3473.14907847579, 505.5096556340344, 601.801405536172, 605.198112500938, 627.4635186915214, 659.5416185750953, 536.1823219590078, 551.3686828581252, 567.1322875999728, 607.3349682098095, 635.1171871099176, 8831.867521987175, 9193.0, 9246.398984066014, 9256.074392560824, 9492.525373731285, 529.6054439442563, 607.1166452811237, 610.3073012131263, 656.6454114777966, 670.7987603718865, 3633.4731463937583, 4461.330800379969, 4484.771083621712, 4492.573205545468, 4502.392177056121, 64674.0, 84209.0, 84307.15692843116, 103041.0, 113165.0, 36500.45249321262, 59504.0, 72533.0, 101775.0, 103089.0, 92168.54820162659, 93380.99999999865, 93527.0, 94238.0576194243, 94819.52590237143, 8684.696035638975, 8737.91262087371, 8738.650453981769, 8770.166834150745, 8866.778330541789, 55890.0, 56615.15077273927, 57120.71439642775, 57288.85422291447, 61150.0, 77399.61300193463, 79376.80934786098, 80361.59819200866, 81761.59119204366, 82302.58848705718, 11305.982461578498, 11404.600838970597, 11453.255538389978, 11802.409879506162, 12122.0, 12533.686657833627, 12568.120231583702, 12676.87323126757, 12858.935705321413, 13020.28388438607, 11611.303321800719, 11753.941230293794, 11916.582919598119, 12380.195287306657, 12389.318587477776, 11610.24533405326, 11711.238769479956, 12197.0, 12357.443915023665, 12384.938075309567, 10746.8387974182, 11279.718007049889, 11604.94197529007, 11778.078914320226, 11934.104942129201, 9673.322867399214, 9907.950460247652, 10013.24900632441, 10127.696169114939, 10383.117435018108, 8088.0, 9257.537123143951, 9265.86101208469, 9621.951890240503, 10353.585856565955], "value": 7058.740993113124, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1576.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 119.0, 118.0, 113.0, 119.0, 123.0, 176.0, 135.0, 119.0, 120.0], "value": 125.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 114.0, 117.0, 153.0, 115.0, 116.0, 117.0, 116.0, 149.0, 116.0], "value": 126.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 198.0, 211.0, 205.0, 210.0, 196.0, 231.0, 282.0, 199.0, 203.0], "value": 213.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 53.0, 52.0, 75.0, 84.0, 83.0, 50.0, 72.0, 110.0, 85.0], "value": 75.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 71.0, 88.0, 71.0, 73.0, 90.0, 72.0, 91.0, 59.0, 93.0], "value": 79.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 88.0, 90.0, 87.0, 97.0, 87.0, 95.0, 87.0, 93.0, 117.0], "value": 93.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 96.0, 96.0, 95.0, 96.0, 153.0, 119.0, 95.0, 94.0, 111.0], "value": 105.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 97.0, 100.0, 92.0, 84.0, 77.0, 79.0, 140.0, 145.0, 152.0], "value": 105.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 57.0, 56.0, 58.0, 59.0, 72.0, 58.0, 57.0, 58.0, 55.0], "value": 58.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 71.0, 75.0, 84.0, 76.0, 71.0, 70.0, 73.0, 73.0], "value": 73.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 87.0, 88.0, 89.0, 109.0, 112.0, 88.0, 91.0, 92.0, 77.0], "value": 92.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 168.0, 142.0, 151.0, 156.0, 160.0, 171.0, 170.0, 203.0, 147.0], "value": 163.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 184.0, 180.0, 182.0, 187.0, 184.0, 186.0, 187.0, 184.0, 183.0], "value": 184.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 70.0, 71.0, 71.0, 84.0, 92.0, 93.0, 90.0, 93.0, 70.0], "value": 80.4, "unit": "score"}]}]} 17:00:54 INFO - Return code: 0 17:00:54 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:00:54 ERROR - # TBPL SUCCESS # 17:00:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:00:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:00:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:00:54 INFO - 2016-11-16 17:00:54.663 screenresolution[1657:10853] starting screenresolution argv=screenresolution get 17:00:54 INFO - 2016-11-16 17:00:54.666 screenresolution[1657:10853] Display 0: 1600x1200x32@60 17:00:54 INFO - 2016-11-16 17:00:54.672 screenresolution[1658:10856] starting screenresolution argv=screenresolution list 17:00:54 INFO - Available Modes on Display 0 17:00:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:00:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:00:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:00:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:00:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:00:54 INFO - Intel Iris: 17:00:54 INFO - Chipset Model: Intel Iris 17:00:54 INFO - Type: GPU 17:00:54 INFO - Bus: Built-In 17:00:54 INFO - VRAM (Dynamic, Max): 1536 MB 17:00:54 INFO - Vendor: Intel (0x8086) 17:00:54 INFO - Device ID: 0x0a2e 17:00:54 INFO - Revision ID: 0x0009 17:00:54 INFO - Displays: 17:00:54 INFO - Display: 17:00:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:00:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:00:54 INFO - Display Serial Number: 1600x1200 60 17:00:54 INFO - Main Display: Yes 17:00:54 INFO - Mirror: Off 17:00:54 INFO - Online: Yes 17:00:54 INFO - Rotation: Supported 17:00:54 INFO - Return code: 0 17:00:54 INFO - Running post-action listener: _resource_record_post_action 17:00:54 INFO - [mozharness: 2016-11-17 01:00:54.913041Z] Finished run-tests step (success) 17:00:54 INFO - Running post-run listener: _resource_record_post_run 17:00:54 INFO - Total resource usage - Wall time: 1212s; CPU: 55.0%; Read bytes: 38431232; Write bytes: 703235584; Read time: 248; Write time: 1982 17:00:54 INFO - TinderboxPrint: CPU usage
54.7% 17:00:54 INFO - TinderboxPrint: I/O read bytes / time
38,431,232 / 248 17:00:54 INFO - TinderboxPrint: I/O write bytes / time
703,235,584 / 1,982 17:00:54 INFO - TinderboxPrint: CPU idle
2,196.7 (45.3%) 17:00:54 INFO - TinderboxPrint: CPU system
986.5 (20.3%) 17:00:54 INFO - TinderboxPrint: CPU user
1,666.6 (34.4%) 17:00:54 INFO - TinderboxPrint: Swap in / out
437,694,464 / 0 17:00:54 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 399946240; Write bytes: 402396672; Read time: 29887; Write time: 822 17:00:54 INFO - run-tests - Wall time: 1179s; CPU: 55.0%; Read bytes: 35367424; Write bytes: 299532288; Read time: 225; Write time: 1154 17:00:55 INFO - Running post-run listener: _upload_blobber_files 17:00:55 INFO - Blob upload gear active. 17:00:55 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:00:55 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 17:00:55 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', 'Larch', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:00:55 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 Larch -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:00:55 INFO - (blobuploader) - INFO - Open directory for files ... 17:00:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 17:00:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:00:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:00:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 17:00:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:00:57 INFO - (blobuploader) - INFO - Done attempting. 17:00:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 17:00:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:00:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:00:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 17:00:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:00:58 INFO - (blobuploader) - INFO - Done attempting. 17:00:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:00:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:00:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:01:00 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:01:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:01:00 INFO - (blobuploader) - INFO - Done attempting. 17:01:00 INFO - (blobuploader) - INFO - Iteration through files over. 17:01:00 INFO - Return code: 0 17:01:00 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:01:00 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:01:00 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/ee9fb927be0338511196533d3371be942d25e97313438a48a1b478ba4003686103ed188e0a132f9f6b26ad10014988767b7f8906f7d33c974e9124895f83b92f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/233d0dfa7aec96ff43738c1754493d3490c9c37d0cbb58c9fbc6046e3d42249132d2015251d84165ad20f30464fe0c8c30347b663b553c701867c0d8b87df2b6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/8ddb540cae2ba290237c9d800bd6d986626dc8f3a096de65d2fc940238cc419692ef8c551febbd79291fd402ec647ec91600a178eb4c5b033c983489758a1fca"} 17:01:00 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:01:00 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:01:00 INFO - Contents: 17:01:00 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/ee9fb927be0338511196533d3371be942d25e97313438a48a1b478ba4003686103ed188e0a132f9f6b26ad10014988767b7f8906f7d33c974e9124895f83b92f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/233d0dfa7aec96ff43738c1754493d3490c9c37d0cbb58c9fbc6046e3d42249132d2015251d84165ad20f30464fe0c8c30347b663b553c701867c0d8b87df2b6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/8ddb540cae2ba290237c9d800bd6d986626dc8f3a096de65d2fc940238cc419692ef8c551febbd79291fd402ec647ec91600a178eb4c5b033c983489758a1fca"} 17:01:00 INFO - Running post-run listener: copy_logs_to_upload_dir 17:01:00 INFO - Copying logs to upload dir... 17:01:00 INFO - mkdir: /builds/slave/test/build/upload/logs 17:01:00 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.997344 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-11-16 17:01:00.499955) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-16 17:01:00.507563) ========= 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.nduNWQrzEH/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.aIoDmv8vYM/Listeners TMPDIR=/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/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/Larch/sha512/ee9fb927be0338511196533d3371be942d25e97313438a48a1b478ba4003686103ed188e0a132f9f6b26ad10014988767b7f8906f7d33c974e9124895f83b92f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/233d0dfa7aec96ff43738c1754493d3490c9c37d0cbb58c9fbc6046e3d42249132d2015251d84165ad20f30464fe0c8c30347b663b553c701867c0d8b87df2b6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/8ddb540cae2ba290237c9d800bd6d986626dc8f3a096de65d2fc940238cc419692ef8c551febbd79291fd402ec647ec91600a178eb4c5b033c983489758a1fca"} build_url:https://queue.taskcluster.net/v1/task/R9QhHUCsQU-P8cyv1cXmnw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011401 build_url: 'https://queue.taskcluster.net/v1/task/R9QhHUCsQU-P8cyv1cXmnw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/ee9fb927be0338511196533d3371be942d25e97313438a48a1b478ba4003686103ed188e0a132f9f6b26ad10014988767b7f8906f7d33c974e9124895f83b92f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/233d0dfa7aec96ff43738c1754493d3490c9c37d0cbb58c9fbc6046e3d42249132d2015251d84165ad20f30464fe0c8c30347b663b553c701867c0d8b87df2b6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/8ddb540cae2ba290237c9d800bd6d986626dc8f3a096de65d2fc940238cc419692ef8c551febbd79291fd402ec647ec91600a178eb4c5b033c983489758a1fca"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-16 17:01:00.543717) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-16 17:01:00.544016) ========= 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.nduNWQrzEH/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.aIoDmv8vYM/Listeners TMPDIR=/var/folders/ng/csmfm9x9681blsv1wkb387f800000w/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.005055 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-16 17:01:00.591859) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-16 17:01:00.592171) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-16 17:01:00.592473) ========= ========= Total master_lag: 0.25 =========