builder: graphics_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0200 starttime: 1479342639.79 results: success (0) buildid: 20161116105708 builduid: 95ba28a1f38149bd8352cfdd3edb1c19 revision: cc3d0823289f64804fce57ac51a03b0b17d0189e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-16 16:30:39.785370) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-16 16:30:39.785828) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-16 16:30:39.786114) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-16 16:30:39.811009) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-16 16:30:39.811339) ========= 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.wO3xnhE7Wi/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.P8kpWEIE99/Listeners TMPDIR=/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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:30:39-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 14.5M=0.001s 2016-11-16 16:30:39 (14.5 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.118481 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-16 16:30:39.952441) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-16 16:30:39.952756) ========= 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.wO3xnhE7Wi/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.P8kpWEIE99/Listeners TMPDIR=/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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.027732 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-16 16:30:40.002934) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-16 16:30:40.003295) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev cc3d0823289f64804fce57ac51a03b0b17d0189e --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/graphics --rev cc3d0823289f64804fce57ac51a03b0b17d0189e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.wO3xnhE7Wi/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.P8kpWEIE99/Listeners TMPDIR=/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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:30:40,104 truncating revision to first 12 chars 2016-11-16 16:30:40,104 Setting DEBUG logging. 2016-11-16 16:30:40,104 attempt 1/10 2016-11-16 16:30:40,104 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/cc3d0823289f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-16 16:30:41,032 unpacking tar archive at: graphics-cc3d0823289f/testing/mozharness/ program finished with exit code 0 elapsedTime=1.207522 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-16 16:30:41.232652) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-16 16:30:41.233000) ========= script_repo_revision: cc3d0823289f64804fce57ac51a03b0b17d0189e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-16 16:30:41.233374) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-16 16:30:41.233641) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-16 16:30:41.252005) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-11-16 16:30:41.252441) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Graphics --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 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', 'Graphics', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Graphics'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.wO3xnhE7Wi/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.P8kpWEIE99/Listeners TMPDIR=/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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:30:41 INFO - MultiFileLogger online at 20161116 16:30:41 in /builds/slave/test 16:30:41 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Graphics --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 16:30:41 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:30:41 INFO - {'append_to_log': False, 16:30:41 INFO - 'base_work_dir': '/builds/slave/test', 16:30:41 INFO - 'blob_upload_branch': 'Graphics', 16:30:41 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:30:41 INFO - 'branch': 'Graphics', 16:30:41 INFO - 'buildbot_json_path': 'buildprops.json', 16:30:41 INFO - 'config_files': ('talos/mac_config.py',), 16:30:41 INFO - 'default_actions': ('clobber', 16:30:41 INFO - 'read-buildbot-config', 16:30:41 INFO - 'download-and-extract', 16:30:41 INFO - 'populate-webroot', 16:30:41 INFO - 'create-virtualenv', 16:30:41 INFO - 'install', 16:30:41 INFO - 'run-tests'), 16:30:41 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:30:41 INFO - 'download_minidump_stackwalk': True, 16:30:41 INFO - 'download_symbols': 'ondemand', 16:30:41 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:30:41 INFO - 'tooltool.py': '/tools/tooltool.py', 16:30:41 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:30:41 INFO - '/tools/misc-python/virtualenv.py')}, 16:30:41 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:30:41 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:30:41 INFO - 'installer_path': 'installer.exe', 16:30:41 INFO - 'log_level': 'info', 16:30:41 INFO - 'log_name': 'talos', 16:30:41 INFO - 'log_to_console': True, 16:30:41 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:30:41 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:30:41 INFO - 'opt_config_files': (), 16:30:41 INFO - 'pip_index': False, 16:30:41 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:30:41 INFO - 'cmd': ('bash', 16:30:41 INFO - '-c', 16:30:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:30:41 INFO - 'enabled': True, 16:30:41 INFO - 'halt_on_failure': False, 16:30:41 INFO - 'name': 'check_screen_resolution'},), 16:30:41 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:30:41 INFO - 'cmd': ('bash', 16:30:41 INFO - '-c', 16:30:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:30:41 INFO - 'enabled': True, 16:30:41 INFO - 'halt_on_failure': False, 16:30:41 INFO - 'name': 'check_screen_resolution'},), 16:30:41 INFO - 'run_cmd_checks_enabled': True, 16:30:41 INFO - 'sps_profile': False, 16:30:41 INFO - 'sps_profile_interval': 0, 16:30:41 INFO - 'suite': 'dromaeojs-e10s', 16:30:41 INFO - 'system_bits': '32', 16:30:41 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:30:41 INFO - 'title': 't-yosemite-r7-0200', 16:30:41 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:30:41 INFO - 'use_talos_json': True, 16:30:41 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:30:41 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:30:41 INFO - 'work_dir': 'build'} 16:30:41 INFO - [mozharness: 2016-11-17 00:30:41.454049Z] Running clobber step. 16:30:41 INFO - Running pre-action listener: _resource_record_pre_action 16:30:41 INFO - Running main action method: clobber 16:30:41 INFO - rmtree: /builds/slave/test/build 16:30:41 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:30:44 INFO - Running post-action listener: _resource_record_post_action 16:30:44 INFO - [mozharness: 2016-11-17 00:30:44.422145Z] Finished clobber step (success) 16:30:44 INFO - [mozharness: 2016-11-17 00:30:44.422364Z] Running read-buildbot-config step. 16:30:44 INFO - Running pre-action listener: _resource_record_pre_action 16:30:44 INFO - Running main action method: read_buildbot_config 16:30:44 INFO - Using buildbot properties: 16:30:44 INFO - { 16:30:44 INFO - "project": "", 16:30:44 INFO - "product": "firefox", 16:30:44 INFO - "script_repo_revision": "default", 16:30:44 INFO - "builddir": "graphics_yosemite_r7_test-dromaeojs-e10s", 16:30:44 INFO - "repository": "", 16:30:44 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 graphics talos dromaeojs-e10s", 16:30:44 INFO - "buildid": "20161116105708", 16:30:44 INFO - "pgo_build": "False", 16:30:44 INFO - "basedir": "/builds/slave/test", 16:30:44 INFO - "buildnumber": 1, 16:30:44 INFO - "slavename": "t-yosemite-r7-0200", 16:30:44 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 16:30:44 INFO - "platform": "yosemite_r7", 16:30:44 INFO - "scheduler": "tests-graphics-macosx64-talos", 16:30:44 INFO - "branch": "graphics", 16:30:44 INFO - "repo_path": "projects/graphics", 16:30:44 INFO - "revision": "cc3d0823289f64804fce57ac51a03b0b17d0189e", 16:30:44 INFO - "stage_platform": "macosx64", 16:30:44 INFO - "builduid": "95ba28a1f38149bd8352cfdd3edb1c19", 16:30:44 INFO - "slavebuilddir": "test" 16:30:44 INFO - } 16:30:44 INFO - Found installer url https://queue.taskcluster.net/v1/task/DUesqtwSSKipEzq01bzPEw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 16:30:44 INFO - Running post-action listener: _resource_record_post_action 16:30:44 INFO - [mozharness: 2016-11-17 00:30:44.427259Z] Finished read-buildbot-config step (success) 16:30:44 INFO - [mozharness: 2016-11-17 00:30:44.427329Z] Running download-and-extract step. 16:30:44 INFO - Running pre-action listener: _resource_record_pre_action 16:30:44 INFO - Running main action method: download_and_extract 16:30:44 INFO - mkdir: /builds/slave/test/build/tests 16:30:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:30:44 INFO - trying https://queue.taskcluster.net/v1/task/DUesqtwSSKipEzq01bzPEw/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 16:30:44 INFO - Downloading https://queue.taskcluster.net/v1/task/DUesqtwSSKipEzq01bzPEw/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:30:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DUesqtwSSKipEzq01bzPEw/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:30:45 INFO - Downloaded 1199 bytes. 16:30:45 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 16:30:45 INFO - Using the following test package requirements: 16:30:45 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 16:30:45 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 16:30:45 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 16:30:45 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 16:30:45 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 16:30:45 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 16:30:45 INFO - u'jsshell-mac.zip'], 16:30:45 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 16:30:45 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 16:30:45 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 16:30:45 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 16:30:45 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 16:30:45 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 16:30:45 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 16:30:45 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 16:30:45 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 16:30:45 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 16:30:45 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 16:30:45 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 16:30:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DUesqtwSSKipEzq01bzPEw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 16:30:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DUesqtwSSKipEzq01bzPEw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:30:45 INFO - Fetch https://queue.taskcluster.net/v1/task/DUesqtwSSKipEzq01bzPEw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 16:30:45 INFO - Http code: 200 16:30:45 INFO - via: 1.1 455cf6ccbccc261c46d02110de1c0237.cloudfront.net (CloudFront) 16:30:45 INFO - x-amz-cf-id: oLtHTeFWbySkD6kkn626CumKs9icKaNBU8HLOW9aoFHDTPNHx9rZoA== 16:30:45 INFO - x-amz-version-id: A7HaDupdhWzAUX_9OeHB11okJbbqKRsW 16:30:46 INFO - Expected file size: 16268141 16:30:46 INFO - Obtained file size: 16268141 16:30:52 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:30:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DUesqtwSSKipEzq01bzPEw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 16:30:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DUesqtwSSKipEzq01bzPEw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:30:52 INFO - Fetch https://queue.taskcluster.net/v1/task/DUesqtwSSKipEzq01bzPEw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 16:30:53 INFO - Http code: 200 16:30:53 INFO - via: 1.1 f1a40337a32137e1c23ceffead6a50d5.cloudfront.net (CloudFront) 16:30:53 INFO - x-amz-cf-id: 493n-gR0Wep_tpYri0rtML65GVosqipMMQi07bG6epjd2ig0htfjmg== 16:30:53 INFO - x-amz-version-id: A7HaDupdhWzAUX_9OeHB11okJbbqKRsW 16:30:53 INFO - Expected file size: 16268141 16:30:53 INFO - Obtained file size: 16268141 16:31:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DUesqtwSSKipEzq01bzPEw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 16:31:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DUesqtwSSKipEzq01bzPEw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 16:31:00 INFO - Fetch https://queue.taskcluster.net/v1/task/DUesqtwSSKipEzq01bzPEw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 16:31:00 INFO - Http code: 200 16:31:00 INFO - via: 1.1 c54d7f08e2f3dab1918454910cc8aad0.cloudfront.net (CloudFront) 16:31:00 INFO - x-amz-cf-id: RtGWH14xslZMLA6FEJ_zJq63Fw1-oXnyIN8Q0SQZZ8_LDJ0WT4XUeA== 16:31:00 INFO - x-amz-version-id: 9dBlYqAlyX4FEvwxPsHiopA5ZHk9HMfX 16:31:01 INFO - Expected file size: 14409366 16:31:01 INFO - Obtained file size: 14409366 16:31:01 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:31:01 INFO - trying https://queue.taskcluster.net/v1/task/DUesqtwSSKipEzq01bzPEw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 16:31:01 INFO - Downloading https://queue.taskcluster.net/v1/task/DUesqtwSSKipEzq01bzPEw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 16:31:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DUesqtwSSKipEzq01bzPEw/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:31:04 INFO - Downloaded 111135044 bytes. 16:31:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DUesqtwSSKipEzq01bzPEw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 16:31:04 INFO - mkdir: /builds/slave/test/properties 16:31:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:31:04 INFO - Writing to file /builds/slave/test/properties/build_url 16:31:04 INFO - Contents: 16:31:04 INFO - build_url:https://queue.taskcluster.net/v1/task/DUesqtwSSKipEzq01bzPEw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 16:31:04 INFO - Running post-action listener: _resource_record_post_action 16:31:04 INFO - Running post-action listener: set_extra_try_arguments 16:31:04 INFO - [mozharness: 2016-11-17 00:31:04.896694Z] Finished download-and-extract step (success) 16:31:04 INFO - [mozharness: 2016-11-17 00:31:04.896840Z] Running populate-webroot step. 16:31:04 INFO - Running pre-action listener: _resource_record_pre_action 16:31:04 INFO - Running main action method: populate_webroot 16:31:04 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:31:04 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:31:04 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:31:04 INFO - '--tppagecycles', 16:31:04 INFO - '7'], 16:31:04 INFO - 'tests': ['tsvgm']}}, 16:31:04 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:31:04 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:31:04 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:31:04 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:31:04 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:31:04 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:31:04 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 16:31:04 INFO - 'talos_options': ['--disable-e10s'], 16:31:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:31:04 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:31:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:31:04 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 16:31:04 INFO - 'talos_options': ['--disable-e10s'], 16:31:04 INFO - 'tests': ['damp', 'tps']}, 16:31:04 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:31:04 INFO - 'tests': ['damp', 'tps']}, 16:31:04 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:31:04 INFO - 'tests': ['dromaeo_dom']}, 16:31:04 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:31:04 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:31:04 INFO - 'tests': ['basic_compositor_video']}, 16:31:04 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:31:04 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:31:04 INFO - 'tests': ['a11yr', 16:31:04 INFO - 'ts_paint', 16:31:04 INFO - 'tpaint', 16:31:04 INFO - 'sessionrestore', 16:31:04 INFO - 'sessionrestore_no_auto_restore', 16:31:04 INFO - 'tabpaint']}, 16:31:04 INFO - 'other-e10s': {'tests': ['a11yr', 16:31:04 INFO - 'ts_paint', 16:31:04 INFO - 'tpaint', 16:31:04 INFO - 'sessionrestore', 16:31:04 INFO - 'sessionrestore_no_auto_restore', 16:31:04 INFO - 'tabpaint']}, 16:31:04 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:31:04 INFO - 'ts_paint', 16:31:04 INFO - 'tpaint', 16:31:04 INFO - 'sessionrestore', 16:31:04 INFO - 'sessionrestore_no_auto_restore', 16:31:04 INFO - 'tabpaint']}, 16:31:04 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:31:04 INFO - 'ts_paint', 16:31:04 INFO - 'tpaint', 16:31:04 INFO - 'sessionrestore', 16:31:04 INFO - 'sessionrestore_no_auto_restore', 16:31:04 INFO - 'tabpaint']}, 16:31:04 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:31:04 INFO - 'tests': ['a11yr', 16:31:04 INFO - 'ts_paint', 16:31:04 INFO - 'tpaint', 16:31:04 INFO - 'sessionrestore', 16:31:04 INFO - 'sessionrestore_no_auto_restore', 16:31:04 INFO - 'tabpaint']}, 16:31:04 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:31:04 INFO - 'tests': ['a11yr', 16:31:04 INFO - 'ts_paint', 16:31:04 INFO - 'tpaint', 16:31:04 INFO - 'sessionrestore', 16:31:04 INFO - 'sessionrestore_no_auto_restore', 16:31:04 INFO - 'tabpaint']}, 16:31:04 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:31:04 INFO - 'tests': ['tsvgx', 16:31:04 INFO - 'tsvgr_opacity', 16:31:04 INFO - 'tart', 16:31:04 INFO - 'tscrollx', 16:31:04 INFO - 'cart']}, 16:31:04 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:31:04 INFO - 'tsvgr_opacity', 16:31:04 INFO - 'tart', 16:31:04 INFO - 'tscrollx', 16:31:04 INFO - 'cart']}, 16:31:04 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 16:31:04 INFO - 'talos_options': ['--disable-e10s'], 16:31:04 INFO - 'tests': ['tp5o']}, 16:31:04 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:31:04 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 16:31:04 INFO - 'talos_options': ['--disable-e10s', 16:31:04 INFO - '--xperf_path', 16:31:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:31:04 INFO - 'tests': ['tp5n']}, 16:31:04 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:31:04 INFO - 'talos_options': ['--xperf_path', 16:31:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:31:04 INFO - 'tests': ['tp5n']}}, 16:31:04 INFO - 'talos.zip': {'path': '', 16:31:04 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:31:04 INFO - Running post-action listener: _resource_record_post_action 16:31:04 INFO - [mozharness: 2016-11-17 00:31:04.914856Z] Finished populate-webroot step (success) 16:31:04 INFO - [mozharness: 2016-11-17 00:31:04.914982Z] Running create-virtualenv step. 16:31:04 INFO - Running pre-action listener: _resource_record_pre_action 16:31:04 INFO - Running main action method: create_virtualenv 16:31:04 INFO - Creating virtualenv /builds/slave/test/build/venv 16:31:04 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:31:04 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:31:05 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:31:05 INFO - Using real prefix '/tools/python27' 16:31:05 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:31:05 INFO - Installing distribute.............................................................................................................................................................................................done. 16:31:08 INFO - Installing pip.................done. 16:31:08 INFO - Return code: 0 16:31:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:31:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:31:08 INFO - Reading from file tmpfile_stdout 16:31:08 INFO - Output received: 16:31:08 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:31:08 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:31:08 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:31:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:31:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:08 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:31:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:31:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:08 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 0x1057653f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1056db570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbf0959a750>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10557bd30>, '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 0x1053e2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105573b20>, '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.wO3xnhE7Wi/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.P8kpWEIE99/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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:31:08 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:31:08 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:31:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wO3xnhE7Wi/Render', 16:31:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:31:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:31:08 INFO - 'HOME': '/Users/cltbld', 16:31:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:31:08 INFO - 'LOGNAME': 'cltbld', 16:31:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:31:08 INFO - 'MOZ_NO_REMOTE': '1', 16:31:08 INFO - 'NO_EM_RESTART': '1', 16:31:08 INFO - 'PAGER': '/bin/cat', 16:31:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:31:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:31:08 INFO - 'PWD': '/builds/slave/test', 16:31:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:31:08 INFO - 'SHELL': '/bin/bash', 16:31:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.P8kpWEIE99/Listeners', 16:31:08 INFO - 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', 16:31:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:31:08 INFO - 'USER': 'cltbld', 16:31:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:31:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:31:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:31:08 INFO - 'XPC_FLAGS': '0x0', 16:31:08 INFO - 'XPC_SERVICE_NAME': '0', 16:31:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:31:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:31:09 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:31:09 INFO - Cleaning up... 16:31:09 INFO - Return code: 0 16:31:09 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:31:09 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:31:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:31:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:09 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:31:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:31:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:09 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 0x1057653f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1056db570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbf0959a750>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10557bd30>, '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 0x1053e2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105573b20>, '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.wO3xnhE7Wi/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.P8kpWEIE99/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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:31:09 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:31:09 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:31:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wO3xnhE7Wi/Render', 16:31:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:31:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:31:09 INFO - 'HOME': '/Users/cltbld', 16:31:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:31:09 INFO - 'LOGNAME': 'cltbld', 16:31:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:31:09 INFO - 'MOZ_NO_REMOTE': '1', 16:31:09 INFO - 'NO_EM_RESTART': '1', 16:31:09 INFO - 'PAGER': '/bin/cat', 16:31:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:31:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:31:09 INFO - 'PWD': '/builds/slave/test', 16:31:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:31:09 INFO - 'SHELL': '/bin/bash', 16:31:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.P8kpWEIE99/Listeners', 16:31:09 INFO - 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', 16:31:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:31:09 INFO - 'USER': 'cltbld', 16:31:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:31:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:31:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:31:09 INFO - 'XPC_FLAGS': '0x0', 16:31:09 INFO - 'XPC_SERVICE_NAME': '0', 16:31:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:31:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:31:09 INFO - Downloading/unpacking psutil>=3.1.1 16:31:09 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:31:09 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:31:09 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:31:09 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:31:09 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:31:09 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:31:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:31:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:31:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:31:12 INFO - Installing collected packages: psutil 16:31:12 INFO - Running setup.py install for psutil 16:31:12 INFO - building 'psutil._psutil_osx' extension 16:31:12 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:31:13 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:31:13 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:31:13 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:31:13 INFO - building 'psutil._psutil_posix' extension 16:31:13 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:31:13 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:31:13 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:31:13 INFO - ^ 16:31:13 INFO - 1 warning generated. 16:31:13 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:31:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:31:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:31:14 INFO - Successfully installed psutil 16:31:14 INFO - Cleaning up... 16:31:14 INFO - Return code: 0 16:31:14 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:31:14 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:31:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:31:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:14 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:31:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:31:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1057653f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1056db570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbf0959a750>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10557bd30>, '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 0x1053e2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105573b20>, '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.wO3xnhE7Wi/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.P8kpWEIE99/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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:31: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 16:31: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 mozsystemmonitor==0.3 16:31:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wO3xnhE7Wi/Render', 16:31:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:31:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:31:14 INFO - 'HOME': '/Users/cltbld', 16:31:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:31:14 INFO - 'LOGNAME': 'cltbld', 16:31:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:31:14 INFO - 'MOZ_NO_REMOTE': '1', 16:31:14 INFO - 'NO_EM_RESTART': '1', 16:31:14 INFO - 'PAGER': '/bin/cat', 16:31:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:31:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:31:14 INFO - 'PWD': '/builds/slave/test', 16:31:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:31:14 INFO - 'SHELL': '/bin/bash', 16:31:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.P8kpWEIE99/Listeners', 16:31:14 INFO - 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', 16:31:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:31:14 INFO - 'USER': 'cltbld', 16:31:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:31:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:31:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:31:14 INFO - 'XPC_FLAGS': '0x0', 16:31:14 INFO - 'XPC_SERVICE_NAME': '0', 16:31:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:31:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:31:14 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:31: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:31: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:31: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:31: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:31: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:31: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:31:16 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:31:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:31:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:31:17 INFO - Installing collected packages: mozsystemmonitor 16:31:17 INFO - Running setup.py install for mozsystemmonitor 16:31:17 INFO - Successfully installed mozsystemmonitor 16:31:17 INFO - Cleaning up... 16:31:17 INFO - Return code: 0 16:31:17 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:31:17 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:31:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:31:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:17 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:31:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:31:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:17 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 0x1057653f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1056db570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbf0959a750>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10557bd30>, '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 0x1053e2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105573b20>, '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.wO3xnhE7Wi/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.P8kpWEIE99/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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:31:17 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:31:17 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:31:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wO3xnhE7Wi/Render', 16:31:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:31:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:31:17 INFO - 'HOME': '/Users/cltbld', 16:31:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:31:17 INFO - 'LOGNAME': 'cltbld', 16:31:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:31:17 INFO - 'MOZ_NO_REMOTE': '1', 16:31:17 INFO - 'NO_EM_RESTART': '1', 16:31:17 INFO - 'PAGER': '/bin/cat', 16:31:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:31:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:31:17 INFO - 'PWD': '/builds/slave/test', 16:31:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:31:17 INFO - 'SHELL': '/bin/bash', 16:31:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.P8kpWEIE99/Listeners', 16:31:17 INFO - 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', 16:31:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:31:17 INFO - 'USER': 'cltbld', 16:31:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:31:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:31:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:31:17 INFO - 'XPC_FLAGS': '0x0', 16:31:17 INFO - 'XPC_SERVICE_NAME': '0', 16:31:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:31:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:31:17 INFO - Downloading/unpacking jsonschema==2.5.1 16:31:17 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:31:17 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:31:17 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:31:17 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:31:17 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:31:17 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:31:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:31:20 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:31:20 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:31:20 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:31:20 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:31:20 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:31:20 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:31:20 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:31:20 INFO - Downloading functools32-3.2.3-2.tar.gz 16:31:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:31:20 INFO - warning: no files found matching '*.txt' 16:31:20 INFO - no previously-included directories found matching 'build' 16:31:20 INFO - no previously-included directories found matching 'dist' 16:31:20 INFO - no previously-included directories found matching '.git*' 16:31:20 INFO - Installing collected packages: jsonschema, functools32 16:31:20 INFO - Running setup.py install for functools32 16:31:20 INFO - warning: no files found matching '*.txt' 16:31:20 INFO - no previously-included directories found matching 'build' 16:31:20 INFO - no previously-included directories found matching 'dist' 16:31:20 INFO - no previously-included directories found matching '.git*' 16:31:20 INFO - Successfully installed jsonschema functools32 16:31:20 INFO - Cleaning up... 16:31:20 INFO - Return code: 0 16:31:20 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:31:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:31:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:31:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:31:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:31:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:20 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 0x1057653f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1056db570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbf0959a750>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10557bd30>, '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 0x1053e2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105573b20>, '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.wO3xnhE7Wi/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.P8kpWEIE99/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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:31:20 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:31:20 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:31:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wO3xnhE7Wi/Render', 16:31:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:31:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:31:20 INFO - 'HOME': '/Users/cltbld', 16:31:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:31:20 INFO - 'LOGNAME': 'cltbld', 16:31:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:31:20 INFO - 'MOZ_NO_REMOTE': '1', 16:31:20 INFO - 'NO_EM_RESTART': '1', 16:31:20 INFO - 'PAGER': '/bin/cat', 16:31:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:31:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:31:20 INFO - 'PWD': '/builds/slave/test', 16:31:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:31:20 INFO - 'SHELL': '/bin/bash', 16:31:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.P8kpWEIE99/Listeners', 16:31:20 INFO - 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', 16:31:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:31:20 INFO - 'USER': 'cltbld', 16:31:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:31:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:31:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:31:20 INFO - 'XPC_FLAGS': '0x0', 16:31:20 INFO - 'XPC_SERVICE_NAME': '0', 16:31:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:31:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:31:21 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:31:21 INFO - Cleaning up... 16:31:21 INFO - Return code: 0 16:31:21 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:31:21 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:31:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:31:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:21 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:31:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:31:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:21 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 0x1057653f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1056db570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbf0959a750>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10557bd30>, '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 0x1053e2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105573b20>, '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.wO3xnhE7Wi/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.P8kpWEIE99/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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:31:21 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:31:21 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:31:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wO3xnhE7Wi/Render', 16:31:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:31:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:31:21 INFO - 'HOME': '/Users/cltbld', 16:31:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:31:21 INFO - 'LOGNAME': 'cltbld', 16:31:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:31:21 INFO - 'MOZ_NO_REMOTE': '1', 16:31:21 INFO - 'NO_EM_RESTART': '1', 16:31:21 INFO - 'PAGER': '/bin/cat', 16:31:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:31:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:31:21 INFO - 'PWD': '/builds/slave/test', 16:31:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:31:21 INFO - 'SHELL': '/bin/bash', 16:31:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.P8kpWEIE99/Listeners', 16:31:21 INFO - 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', 16:31:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:31:21 INFO - 'USER': 'cltbld', 16:31:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:31:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:31:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:31:21 INFO - 'XPC_FLAGS': '0x0', 16:31:21 INFO - 'XPC_SERVICE_NAME': '0', 16:31:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:31:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:31:21 INFO - Downloading/unpacking blobuploader==1.2.4 16:31:21 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:31:21 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:31:21 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:31:21 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:31:21 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:31:21 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:31:23 INFO - Downloading blobuploader-1.2.4.tar.gz 16:31:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:31:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:31:23 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:31:23 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:31:23 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:31:23 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:31:23 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:31:23 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:31:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:31:24 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:31:24 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:31:24 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:31:24 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:31:24 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:31:24 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:31:24 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:31:25 INFO - Downloading docopt-0.6.1.tar.gz 16:31:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:31:25 INFO - Installing collected packages: blobuploader, requests, docopt 16:31:25 INFO - Running setup.py install for blobuploader 16:31:25 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:31:25 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:31:25 INFO - Running setup.py install for requests 16:31:25 INFO - Running setup.py install for docopt 16:31:26 INFO - Successfully installed blobuploader requests docopt 16:31:26 INFO - Cleaning up... 16:31:26 INFO - Return code: 0 16:31:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:31: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:31:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:31:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31: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:31:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:31:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:26 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 0x1057653f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1056db570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbf0959a750>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10557bd30>, '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 0x1053e2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105573b20>, '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.wO3xnhE7Wi/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.P8kpWEIE99/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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:31:26 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:31:26 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:31:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wO3xnhE7Wi/Render', 16:31:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:31:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:31:26 INFO - 'HOME': '/Users/cltbld', 16:31:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:31:26 INFO - 'LOGNAME': 'cltbld', 16:31:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:31:26 INFO - 'MOZ_NO_REMOTE': '1', 16:31:26 INFO - 'NO_EM_RESTART': '1', 16:31:26 INFO - 'PAGER': '/bin/cat', 16:31:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:31:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:31:26 INFO - 'PWD': '/builds/slave/test', 16:31:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:31:26 INFO - 'SHELL': '/bin/bash', 16:31:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.P8kpWEIE99/Listeners', 16:31:26 INFO - 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', 16:31:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:31:26 INFO - 'USER': 'cltbld', 16:31:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:31:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:31:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:31:26 INFO - 'XPC_FLAGS': '0x0', 16:31:26 INFO - 'XPC_SERVICE_NAME': '0', 16:31:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:31:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:31:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:31:26 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-zW7IUn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:31:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:31:26 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-Xb9xgl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:31:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:31:26 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-ueEiGm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:31:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:31:26 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-uhun1P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:31:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:31:26 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-sRnCWa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:31:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:31:26 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-moZ0PX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:31:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:31:27 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-CPOJj3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:31:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:31:27 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-Mq5u2M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:31:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:31:27 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-wQXgJ4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:31:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:31:27 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-Ca0a5i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:31:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:31:27 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-b8iTK6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:31:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:31:27 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-1CGXIl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:31:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:31:27 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-91HoV5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:31:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:31:27 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-kOKQh1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:31:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:31:27 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-uDIMbc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:31:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:31:27 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-fqhZay-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:31:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:31:28 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-x7AIoj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:31:28 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:31:28 INFO - Running setup.py install for manifestparser 16:31:28 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:31:28 INFO - Running setup.py install for mozcrash 16:31:28 INFO - Running setup.py install for mozdebug 16:31:28 INFO - Running setup.py install for mozdevice 16:31:28 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:31:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:31:28 INFO - Running setup.py install for mozfile 16:31:28 INFO - Running setup.py install for mozhttpd 16:31:29 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:31:29 INFO - Running setup.py install for mozinfo 16:31:29 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:31:29 INFO - Running setup.py install for mozInstall 16:31:29 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:31:29 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:31:29 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:31:29 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:31:29 INFO - Running setup.py install for mozleak 16:31:29 INFO - Running setup.py install for mozlog 16:31:29 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:31:29 INFO - Running setup.py install for moznetwork 16:31:29 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:31:29 INFO - Running setup.py install for mozprocess 16:31:29 INFO - Running setup.py install for mozprofile 16:31:30 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:31:30 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:31:30 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:31:30 INFO - Running setup.py install for mozrunner 16:31:30 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:31:30 INFO - Running setup.py install for mozscreenshot 16:31:30 INFO - Running setup.py install for moztest 16:31:30 INFO - Running setup.py install for mozversion 16:31:30 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:31:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:31:30 INFO - Cleaning up... 16:31:30 INFO - Return code: 0 16:31:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:31:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:31:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:31:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:31:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:31:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:30 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 0x1057653f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1056db570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbf0959a750>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10557bd30>, '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 0x1053e2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105573b20>, '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.wO3xnhE7Wi/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.P8kpWEIE99/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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:31:30 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:31:30 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:31:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wO3xnhE7Wi/Render', 16:31:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:31:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:31:30 INFO - 'HOME': '/Users/cltbld', 16:31:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:31:30 INFO - 'LOGNAME': 'cltbld', 16:31:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:31:30 INFO - 'MOZ_NO_REMOTE': '1', 16:31:30 INFO - 'NO_EM_RESTART': '1', 16:31:30 INFO - 'PAGER': '/bin/cat', 16:31:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:31:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:31:30 INFO - 'PWD': '/builds/slave/test', 16:31:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:31:30 INFO - 'SHELL': '/bin/bash', 16:31:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.P8kpWEIE99/Listeners', 16:31:30 INFO - 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', 16:31:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:31:30 INFO - 'USER': 'cltbld', 16:31:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:31:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:31:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:31:30 INFO - 'XPC_FLAGS': '0x0', 16:31:30 INFO - 'XPC_SERVICE_NAME': '0', 16:31:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:31:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:31:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:31:31 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-NAyu1L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:31:31 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:31:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:31:31 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-NalrIf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:31:31 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:31:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:31:31 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-TgGxrI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:31:31 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:31:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:31:31 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-qo13Yy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:31:31 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:31:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:31:31 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-ekvf1w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:31:31 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:31:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:31:31 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-HG9uXA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:31:31 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:31:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:31:31 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-oZI4rj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:31:31 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:31:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:31:31 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-KvIUhD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:31:31 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:31:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:31:31 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-Tsa2O7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:31:31 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:31:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:31:31 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-XJyorU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:31:32 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:31:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:31:32 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-dqVgh0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:31:32 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:31:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:31:32 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-3H_Owx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:31:32 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:31:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:31:32 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-Afu5xE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:31:32 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:31:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:31:32 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-9IvxHz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:31:32 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:31:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:31:32 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-K5sXvd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:31:32 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:31:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:31:32 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-XOzVxj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:31:32 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:31:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:31:32 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-3ou9KG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:31:32 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:31:32 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:31:32 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:31:32 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:31:32 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:31:32 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:31:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:31:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:31:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:31:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:31:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:31:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:31:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:31:35 INFO - Downloading blessings-1.6.tar.gz 16:31:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:31:35 INFO - Installing collected packages: blessings 16:31:35 INFO - Running setup.py install for blessings 16:31:35 INFO - Successfully installed blessings 16:31:35 INFO - Cleaning up... 16:31:35 INFO - Return code: 0 16:31:35 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:31:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:31:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:31:36 INFO - Reading from file tmpfile_stdout 16:31:36 INFO - Current package versions: 16:31:36 INFO - blessings == 1.6 16:31:36 INFO - blobuploader == 1.2.4 16:31:36 INFO - docopt == 0.6.1 16:31:36 INFO - functools32 == 3.2.3-2 16:31:36 INFO - jsonschema == 2.5.1 16:31:36 INFO - manifestparser == 1.1 16:31:36 INFO - mozInstall == 1.12 16:31:36 INFO - mozcrash == 1.0 16:31:36 INFO - mozdebug == 0.1 16:31:36 INFO - mozdevice == 0.48 16:31:36 INFO - mozfile == 1.2 16:31:36 INFO - mozhttpd == 0.7 16:31:36 INFO - mozinfo == 0.9 16:31:36 INFO - mozleak == 0.1 16:31:36 INFO - mozlog == 3.4 16:31:36 INFO - moznetwork == 0.27 16:31:36 INFO - mozprocess == 0.23 16:31:36 INFO - mozprofile == 0.28 16:31:36 INFO - mozrunner == 6.13 16:31:36 INFO - mozscreenshot == 0.1 16:31:36 INFO - mozsystemmonitor == 0.3 16:31:36 INFO - moztest == 0.7 16:31:36 INFO - mozversion == 1.4 16:31:36 INFO - psutil == 3.1.1 16:31:36 INFO - requests == 1.2.3 16:31:36 INFO - wsgiref == 0.1.2 16:31:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:31: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:31:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:31:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31: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:31:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:31:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:36 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 0x1057653f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1056db570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbf0959a750>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10557bd30>, '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 0x1053e2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105573b20>, '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.wO3xnhE7Wi/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.P8kpWEIE99/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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:31:36 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:31:36 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:31:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wO3xnhE7Wi/Render', 16:31:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:31:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:31:36 INFO - 'HOME': '/Users/cltbld', 16:31:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:31:36 INFO - 'LOGNAME': 'cltbld', 16:31:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:31:36 INFO - 'MOZ_NO_REMOTE': '1', 16:31:36 INFO - 'NO_EM_RESTART': '1', 16:31:36 INFO - 'PAGER': '/bin/cat', 16:31:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:31:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:31:36 INFO - 'PWD': '/builds/slave/test', 16:31:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:31:36 INFO - 'SHELL': '/bin/bash', 16:31:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.P8kpWEIE99/Listeners', 16:31:36 INFO - 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', 16:31:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:31:36 INFO - 'USER': 'cltbld', 16:31:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:31:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:31:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:31:36 INFO - 'XPC_FLAGS': '0x0', 16:31:36 INFO - 'XPC_SERVICE_NAME': '0', 16:31:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:31:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:31:36 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:31:36 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:31:36 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:31:36 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:31:36 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:31:36 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:31:36 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:31:36 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:31:36 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:31:36 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:31:36 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:31:36 INFO - Cleaning up... 16:31:36 INFO - Return code: 0 16:31:36 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:31: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:31:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:31:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:31:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:31:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1057653f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1056db570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbf0959a750>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10557bd30>, '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 0x1053e2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105573b20>, '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.wO3xnhE7Wi/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.P8kpWEIE99/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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:31:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:31:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:31:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wO3xnhE7Wi/Render', 16:31:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:31:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:31:37 INFO - 'HOME': '/Users/cltbld', 16:31:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:31:37 INFO - 'LOGNAME': 'cltbld', 16:31:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:31:37 INFO - 'MOZ_NO_REMOTE': '1', 16:31:37 INFO - 'NO_EM_RESTART': '1', 16:31:37 INFO - 'PAGER': '/bin/cat', 16:31:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:31:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:31:37 INFO - 'PWD': '/builds/slave/test', 16:31:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:31:37 INFO - 'SHELL': '/bin/bash', 16:31:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.P8kpWEIE99/Listeners', 16:31:37 INFO - 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', 16:31:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:31:37 INFO - 'USER': 'cltbld', 16:31:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:31:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:31:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:31:37 INFO - 'XPC_FLAGS': '0x0', 16:31:37 INFO - 'XPC_SERVICE_NAME': '0', 16:31:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:31:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:31:37 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:31:37 INFO - Cleaning up... 16:31:37 INFO - Return code: 0 16:31:37 INFO - Running post-action listener: _resource_record_post_action 16:31:37 INFO - Running post-action listener: _start_resource_monitoring 16:31:37 INFO - Starting resource monitoring. 16:31:37 INFO - [mozharness: 2016-11-17 00:31:37.758391Z] Finished create-virtualenv step (success) 16:31:37 INFO - [mozharness: 2016-11-17 00:31:37.759352Z] Running install step. 16:31:37 INFO - Running pre-action listener: _resource_record_pre_action 16:31:37 INFO - Running main action method: install 16:31:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:31:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:31:38 INFO - Reading from file tmpfile_stdout 16:31:38 INFO - Detecting whether we're running mozinstall >=1.0... 16:31:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:31:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:31:38 INFO - Reading from file tmpfile_stdout 16:31:38 INFO - Output received: 16:31:38 INFO - Usage: mozinstall [options] installer 16:31:38 INFO - Options: 16:31:38 INFO - -h, --help show this help message and exit 16:31:38 INFO - -d DEST, --destination=DEST 16:31:38 INFO - Directory to install application into. [default: 16:31:38 INFO - "/builds/slave/test"] 16:31:38 INFO - --app=APP Application being installed. [default: firefox] 16:31:38 INFO - mkdir: /builds/slave/test/build/application 16:31:38 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:31:38 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:32:11 INFO - Reading from file tmpfile_stdout 16:32:11 INFO - Output received: 16:32:11 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 16:32:11 INFO - Running post-action listener: _resource_record_post_action 16:32:11 INFO - [mozharness: 2016-11-17 00:32:11.538112Z] Finished install step (success) 16:32:11 INFO - [mozharness: 2016-11-17 00:32:11.538284Z] Running run-tests step. 16:32:11 INFO - Running pre-action listener: _resource_record_pre_action 16:32:11 INFO - Running main action method: run_tests 16:32:11 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:32:11 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:32:11 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:32:11 INFO - 2016-11-16 16:32:11.552 screenresolution[1623:7304] starting screenresolution argv=screenresolution get 16:32:11 INFO - 2016-11-16 16:32:11.555 screenresolution[1623:7304] Display 0: 1600x1200x32@60 16:32:11 INFO - 2016-11-16 16:32:11.561 screenresolution[1624:7306] starting screenresolution argv=screenresolution list 16:32:11 INFO - Available Modes on Display 0 16:32:11 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:32:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:32:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:32:11 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:32:11 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:32:11 INFO - Intel Iris: 16:32:11 INFO - Chipset Model: Intel Iris 16:32:11 INFO - Type: GPU 16:32:11 INFO - Bus: Built-In 16:32:11 INFO - VRAM (Dynamic, Max): 1536 MB 16:32:11 INFO - Vendor: Intel (0x8086) 16:32:11 INFO - Device ID: 0x0a2e 16:32:11 INFO - Revision ID: 0x0009 16:32:11 INFO - Displays: 16:32:11 INFO - Display: 16:32:11 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:32:11 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:32:11 INFO - Display Serial Number: 1600x1200 60 16:32:11 INFO - Main Display: Yes 16:32:11 INFO - Mirror: Off 16:32:11 INFO - Online: Yes 16:32:11 INFO - Rotation: Supported 16:32:11 INFO - Return code: 0 16:32:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:32:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:32:11 INFO - Python 2.7.3 16:32:11 INFO - Return code: 0 16:32:11 INFO - grabbing minidump binary from tooltool 16:32:11 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:32:11 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 0x10557bd30>, '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 0x1053e2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105573b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:32:11 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:32:11 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:32:11 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:32:11 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:32:11 INFO - Return code: 0 16:32:11 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:32:11 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:32:11 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:32:11 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:32:11 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:32:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DUesqtwSSKipEzq01bzPEw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0200', '--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:32:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Graphics --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/DUesqtwSSKipEzq01bzPEw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0200 --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:32:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wO3xnhE7Wi/Render', 16:32:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:32:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:32:11 INFO - 'HOME': '/Users/cltbld', 16:32:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:32:11 INFO - 'LOGNAME': 'cltbld', 16:32:11 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:32:11 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:32:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:32:11 INFO - 'MOZ_NO_REMOTE': '1', 16:32:11 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:32:11 INFO - 'NO_EM_RESTART': '1', 16:32:11 INFO - 'PAGER': '/bin/cat', 16:32:11 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:32:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:32:11 INFO - 'PWD': '/builds/slave/test', 16:32:11 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:32:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:32:11 INFO - 'SHELL': '/bin/bash', 16:32:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.P8kpWEIE99/Listeners', 16:32:11 INFO - 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', 16:32:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:32:11 INFO - 'USER': 'cltbld', 16:32:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:32:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:32:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:32:11 INFO - 'XPC_FLAGS': '0x0', 16:32:11 INFO - 'XPC_SERVICE_NAME': '0', 16:32:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:32:11 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DUesqtwSSKipEzq01bzPEw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0200', '--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:32:12 INFO - mozversion application_buildid: 20161116105708 16:32:12 INFO - mozversion application_changeset: cc3d0823289f64804fce57ac51a03b0b17d0189e 16:32:12 INFO - mozversion application_display_name: Nightly 16:32:12 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:32:12 INFO - mozversion application_name: Firefox 16:32:12 INFO - mozversion application_remotingname: firefox 16:32:12 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 16:32:12 INFO - mozversion application_vendor: Mozilla 16:32:12 INFO - mozversion application_version: 53.0a1 16:32:12 INFO - mozversion platform_buildid: 20161116105708 16:32:12 INFO - mozversion platform_changeset: cc3d0823289f64804fce57ac51a03b0b17d0189e 16:32:12 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 16:32:12 INFO - mozversion platform_version: 53.0a1 16:32:12 INFO - using testdate: 1479342732 16:32:12 INFO - actual date: 1479342732 16:32:12 INFO - starting webserver on 'localhost:49249' 16:32:12 INFO - SUITE-START | Running 2 tests 16:32:12 INFO - TEST-START | dromaeo_css 16:32:12 INFO - operating with platform_type : mac_ 16:32:12 INFO - Initialising browser for dromaeo_css test... 16:32:12 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/tmpwaBwM_/profile http://localhost:49249/getInfo.html) 16:32:14 INFO - PROCESS | 1634 | 2016-11-16 16:32:14.903 plugin-container[1636:7471] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d3f, name = 'com.apple.tsm.portname' 16:32:14 INFO - PROCESS | 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:32:14 INFO - PROCESS | 1634 | 2016-11-16 16:32:14.907 plugin-container[1636:7471] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x930b, name = 'com.apple.CFPasteboardClient' 16:32:14 INFO - PROCESS | 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:32:14 INFO - PROCESS | 1634 | 2016-11-16 16:32:14.907 plugin-container[1636:7471] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:32:14 INFO - PROCESS | 1634 | __metrics Screen width/height:1600/1200 16:32:14 INFO - PROCESS | 1634 | colorDepth:24 16:32:14 INFO - PROCESS | 1634 | Browser inner width/height: 1024/689 16:32:14 INFO - PROCESS | 1634 | __metrics 16:32:15 ERROR - PROCESS | 1634 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3179: TypeError: this.tabs is undefined 16:32:15 INFO - TEST-INFO | 1634: exit 0 16:32:15 INFO - Browser initialized. 16:32:15 INFO - Running cycle 1/1 for dromaeo_css test... 16:32:15 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/tmpwaBwM_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:32:18 INFO - PROCESS | 1637 | 2016-11-16 16:32:18.519 plugin-container[1638:7596] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 16:32:18 INFO - PROCESS | 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:32:18 INFO - PROCESS | 1637 | 2016-11-16 16:32:18.520 plugin-container[1638:7596] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x431f, name = 'com.apple.CFPasteboardClient' 16:32:18 INFO - PROCESS | 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:32:18 INFO - PROCESS | 1637 | 2016-11-16 16:32:18.520 plugin-container[1638:7596] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:32:28 INFO - PROCESS | 1637 | RSS: Main: 237834240 16:32:28 INFO - PROCESS | 1637 | 16:33:17 INFO - PROCESS | 1637 | 1479342797716 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:35:30 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49249/tests/dromaeo/cssquery-ext.html) 16:35:32 INFO - PROCESS | 1637 | RSS: Main: 251428864 16:35:32 INFO - PROCESS | 1637 | 16:38:35 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-ext.html (next: http://localhost:49249/tests/dromaeo/cssquery-jquery.html) 16:38:35 INFO - PROCESS | 1637 | RSS: Main: 264032256 16:38:35 INFO - PROCESS | 1637 | 16:41:37 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49249/tests/dromaeo/cssquery-mootools.html) 16:41:38 INFO - PROCESS | 1637 | RSS: Main: 267558912 16:41:38 INFO - PROCESS | 1637 | 16:44:40 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49249/tests/dromaeo/cssquery-prototype.html) 16:44:41 INFO - PROCESS | 1637 | RSS: Main: 266027008 16:44:41 INFO - PROCESS | 1637 | 16:47:43 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49249/tests/dromaeo/cssquery-yui.html) 16:47:44 INFO - PROCESS | 1637 | RSS: Main: 271511552 16:47:44 INFO - PROCESS | 1637 | 16:50:47 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-yui.html (next: http://localhost:49249/tests/dromaeo/cssquery-dojo.html) 16:50:47 INFO - PROCESS | 1637 | RSS: Main: 274546688 16:50:47 INFO - PROCESS | 1637 | 16:50:47 INFO - PROCESS | 1637 | __start_tp_report 16:50:47 INFO - PROCESS | 1637 | _x_x_mozilla_page_load 16:50:47 INFO - PROCESS | 1637 | _x_x_mozilla_page_load_details 16:50:47 INFO - PROCESS | 1637 | |i|pagename|runs| 16:50:47 INFO - PROCESS | 1637 | |0;dojo.html;2847.4874522585937;3266.7223286020685;3309.8841540546077;3403.2682973160768;3407.2504049109193;5160.3033590465275;5607.495325420711;5675.5459563234945;5679.574031947605;5750.511206547438;5546.946080244761;5744.942550574503;5754.223179870716;5780.450857168573;5793.594448388604;5045.545900868921;5134.204198349262;5144.639875208737;5262.131748261551;5274.05067087924;5057.797688092491;5268.578513718912;5275.657082289658;5289.841304760864;5300.761465734032;5208.010478009166;5265.842024739245;5276.7889284428575;5288.60335474839;5293.15309550472;4859.58693511052;5235.895282094356;5283.577313814885;5317.335333083364;5382.327209098863;5141.74291285438;5219.086659834514;5230.476952304777;5239.947600524022;5287.576993840484;5387.865303367408;5716.885662286731;5758.078707406795;5860.915730589854;5865.941340586583;5850.3564607893095;6126.111713801519;6127.586602260224;6206.069089636546;6235.844103897439;6178.691588785046;6219.471344935686;6221.87556248877;6224.626522408625;6234.719437625273;5668.914966275509;5756.194132821408;5778.595498315161;5778.624389414675;5794.565407594448;5077;5244.239585260116;6172.876542469216;6201.162843016225;6233.2208473940755;4895.926561101586;6118.816435506942;6123.663198524035;6128.816135515853;6193.752249910043;6183.752649894044;6204.37956204385;6214;6224.875502490016;6246.906296405557;6077.118817771487;6092.634441933498;6130.01919692847;6152.938470615237;6155.599886007485;5485.424030476823;6218.098375735493;6219.564630475914;6244.937550624436;6246.187995560639;4291.570842915684;4551.748269225989;4642.9535704643195;4720.716756994591;4984.77568509418;3391.491276308524;3996.741026576619;4340.587644173798;4348.67384946124;4373.190959672471;5919.348871624118;5926.970365148233;5928.584999050111;5937.881242375129;5938.198343223609;5842.415758424124;5961.642301461926;6130.264368275835;6153.384661533901;6228.844278893064;5667.688277144796;5713.542916566665;5713.685747283939;5771.422857714279;5803.970980145073;5312.654677445954;5325.174597937326;5332;5340.973295133499;5344.545713614309;6170.7223174958135;6281.560290779602;6286.025666021774;6300.464460520907;6320.841978950563;6150;6170.753169873155;6172.166757487861;6197.4732147767945;6223.439890409885;6388.712507937061;6395.968020159869;6435.163428754232;6436.066770318184;6437.935620643733;6153.446189842935;6202.658853763086;6211.844703882439;6221.502279817694;6224.844378890382;6096.786612468542;6110.6195130195665;6110.938890611037;6139.833431647972;6215.285242197205;6109.572329937039;6114.652213009299;6137.140800288053;6141.486007784186;6179;5054.342935418372;6124.908126378197;6125.111858780541;6126.6017708848785;6134;5170.939957308818;5359.651622644516;5879.970600146972;5915.733791979457;5943.465088142088;6005.429484198994;6107.735039974651;6118.131909153847;6118.449339559461;6121.755129794937;5019.67372120811;5934.703264836828;6050.2437195350585;6056.909756243742;6088.117223002729 16:50:47 INFO - PROCESS | 1637 | |1;ext.html;446.7096387348223;460.92144382145773;462.8105768175785;501.6588719670624;517.5419753518137;36167;37502.81248593757;39635.405468917954;39825.2034959301;39993.800030999875;39734.99999999993;40387.59612403875;40582;40832.79583602078;40841.795791021075;9189.448633082036;9223.631054757801;9272.67545635903;9382.061793820598;9391.624335026625;9320.906790932071;9364.391314564531;9398.765030874214;9415.199708024791;9429.905700943005;9395;9433.198178154866;9445.669401570947;9449.622015119421;9454.4800035998;9162.587683554257;9323.72028839136;9334.533273336307;9346.579403926806;9431.764205894908;9092.27261819053;9215.262778977665;9245.676401325922;9262.610608408726;9286.95356523213;38487.615123848685;38766;38855.417168742206;38867.22265554673;38932.832015039596;3223.2264256578487;3330.200751819546;3332.900012999614;3467.6705712957228;3877.689784817208;29293.974710885017;29385;29834.10497685051;29925.551116733266;30692.53961190584;3041.0268714011304;3055.663876973531;3092.5361195820583;3192.4037981009496;3231.402190594724;438469;442479;443357;444069;444831.55168448226;46974.53025469701;53156.468435315925;55053;55804.720976394856;55810;57943.4205657938;58107;58363.12455313174;58391.70804145952;58573.70713146407;15078.321475533627;15284.617884552976;15494.5351639451;15564.92217538905;15621;11979.401029948467;11982.940085299517;12095.75808483843;12122.454489547992;12167.695807604881;14541.78187327191;15636.843631563766;15683.294251758698;15688.686226275411;15849.603759905864;3079.153232860981;5790.841831633606;6019.819405417845;6039.973204555236;6045.21412216409;730.0764532865929;858.7853036740814;906.759708677201;1018.0888105145852;1044.9791004180026;22495.32514024582;22512.887435562716;28593.714062859523;28681.56977645337;29059.12822615325;966.8453047512364;1017.6234793126442;1025.600015993763;1049.6850944716707;1055.488088277185;14430.639234019234;14722.484713034992;14836.3323650436;15169;15189.468368607046;467657.66171169607;522525;541942;546096;548010;44641.88395290144;52178.73910630422;55510;55563.88872222533;55630.72184639051;1285.710715089116;1290.8709129087204;1312.9409176587267;1315.0925861155963;1316.795896636023;22156.224532141943;27622.5856612155;28700.13899583016;28871.85564072166;30290.394192116473;47208.29187562121;47872.521274788196;47881.281780774014;47951.5204847947;48106.75946620244;522377;523622.38188808813;525038;526299;526324;25320.240392788244;25334.746652533977;25441.618375724007;25797.613035804854;25822.61266081048;43851.342229867216;44517.33224001578;44602.10795784131;44800.77599611981;44837.32744008908;36555.634443655224;37238.44142337813;38172.80913595414;38762;40091.59908400878;29545.965891193704;36551.90344289676;36998.70504532329;37113.814430927676;37708;35818.82090589531;36232;36914.44628330631;37024.62975370212;37259.254814904096;29635;30060.849695752255;32171.67828321687;33604.83197583997;33698.66301336955;21210.893945530792;23581.998914877735;24876.751232487444;24932.626010609492;25340.493190135727 16:50:47 INFO - PROCESS | 1637 | |2;jquery.html;365.12006065382434;367.7793324005596;374.2440270653281;378.33790865984525;395.30031843636766;18771.4368568943;19102;19498.415047548595;19808.108635111417;20077.196912123498;11252.324860508355;11655;11683.06535477162;11703.473343699532;11706.707332316697;4945.109880221558;5254.658447200939;5308.283381743473;5334.866628334304;5340.958513089949;3840.577536470986;4053.493313335833;5225.033368826779;5283.973580132113;6272.40412160844;6501.869962600746;6592.04415359775;6599;6604.207495100594;6609.372109649576;6317.241930968267;6474.870502589969;6535.052417399498;6543;6548.803535893907;5523.696196709219;5593.776248950037;5736.4550367715;5743.971280143573;5766.88466230673;9093.45439273639;9348.205402540792;10294.374934391757;12702.555410560586;12735.681607959876;2041.2957529652247;2146.162996431393;2159.75162856272;2205.4376134085865;2682.9865850670817;9343.532823358873;9821.558029888673;9841.08673601303;10214.080732734015;10246.53890574926;1779.1904683369037;1795.4523870219577;1835.8714889957707;1851.333519932823;1857.2478146350616;94908.52545737296;103553.48223258836;117997;122918;125716.37141814324;8698.956505217433;10751.51618177184;10939.890601094046;11181.664550063511;11344.829827552594;12001.979831714401;13061.21632702041;13900.721985560232;13917.956153288342;13941.721165576835;7902.24928631779;8184.713535026245;8207.384446166441;8277.793055173668;8281.585920703941;8045.517268963881;8069.435139540175;8090.959545202236;8201.13888041747;8274.793130171673;4388.714733542373;4402.361657559636;4424.977875110668;4440.955590444119;4502.482214545369;1561.773542836305;1687.5021868548808;1688.729803231477;1871.2514994002509;1927.0172212171613;834.8383309203615;865.523961820996;866.6663334616218;868.1708967935606;900.5092224737534;19846.70270918488;19922.701159482618;20418.387448376572;20499.18003279882;20563.383098507067;927.5918595817818;928.2295694573488;932.2635118256501;974.0211087856659;1041.7499800047933;10697.946510267398;10856.511456984455;10904.781904362027;10912.34525928431;10948.726281843017;492192;498710;498878;500172;500591;93164.53417732868;103016;105566.47216763867;114884.85115148909;115284.42357788325;947.9668211612423;971.7910649210312;993.3741742702196;997.2470784557613;1001.2040427859869;13712.17726936387;16688.83311166873;17705.468835934942;18015;18300.542486437946;38291.555244545074;52064;52402.213966789765;52477.47522524726;53160;73848.26151738413;74861.62569187119;75171;75770.62114689391;76135.23864761282;6955.269696681776;7058.153021637231;7085.610291434021;7143;7259;7830.3735701144915;8889.244414224864;12330.581983072061;12455.813162802746;12563.371831408576;10516.68449946503;10567.524461399102;10622.73443163927;10667.786644267226;10963.725906852393;10337.586496540054;10349.586016559253;10368.429736364573;10899.019088282093;10974.122070234522;8886.066962968795;9398.483083430478;10919.945400273265;11200.607978720705;11303.03924166455;5517.972410137923;6475.381154711322;7059.5764254144915;7198.964005179941;7229.671840443133;8782.29741620656;9313.813723725352;10771.553103006963;10826.242163048826;10938.45307734626 16:50:47 INFO - PROCESS | 1637 | |3;mootools.html;1473.175021987687;1507.6532397548563;1682.6045879218375;1690.1802625726527;1717.9348803741675;6403.775867844626;7375.225601311866;7672.424568157397;8280.875786863195;8320.958395208023;7808.219178082203;7994.280514753671;8114.107448180695;8186.836263274731;8210.876836847452;1881.9435416937513;2272.2388000020014;2304.8617082975074;2315.131825565413;2321.199186280739;2288.302067869299;2313;2326.0463210083867;2341.075275266269;2342.590046741822;2292.793648571628;2293.529826385587;2302.2057390200353;2306.6540018997202;2311.8150547956207;2266.954660906781;2269.353234328225;2274.715660542432;2294.598445272079;2298.4139044543617;2284.8971796269207;2312.7571604981404;2316.2240649382306;2320.3039088273617;2320.5938960681983;7714.228577142297;7779.533228006338;7815;7843.9215607844335;7847.725329613436;4979.651424400294;5075.111855425323;5130.53825155734;5138.743062846843;5158.793648254065;3250.2686895448574;3327;3594.5686517618055;3599.5320608321013;3615.7468977171607;1028.9742756431074;1034.4362322534237;1064.2018486135398;1081.9026287634151;1092.322759888874;10393.480325983746;10610.628627997983;10637.734056648645;10666.786664266749;10684.626038088629;3867.0719027433215;4217;4313.029568347097;4426.800793964215;4440.68915175941;8745.781355466164;9127.269818414643;9206.493642849708;9207.171354577986;9231.492267925329;4604.608608268268;5077.1876499759865;5120.359955005624;5122.308488354099;5124.948750512522;4184.518780340239;4402.185595664812;4667.813287468532;4668.766561671903;4678.742669153229;3693.759905606127;3721.4789929409903;3777.8866634001024;3827.024108852225;3827.8086095695103;1250.64929875734;1253.717913469462;1260.4580030586753;1271.5358894003807;1276.9361531923366;840.218596705058;850.566211232276;871.6992637540038;875.2079368171687;893.6872094766779;5146.0702236661255;5260.158374660036;5497.038018346773;5567.66594004361;5601.8599535011135;896.1934259166671;937.6952490440634;943.528235882059;961.7307153996892;981.4258658684606;3735.9626403736156;3740.4389341598976;3750.718696097749;3845.3655146900446;3862;9276.860847087299;9286.024967378327;9580.808383832424;9618.855717164108;9907.157891579152;9726.659566915125;9728.902710972941;9752.804943901225;9757.36577122499;9766.316357855025;7363.263673632701;7683.270089341504;7727.65225564851;7737.833406646036;7739.955106060723;5449.128139497661;5485.835424937259;5534.1975413565615;5653.519450846724;5664.207011018379;8865.335099867665;8990.910090899008;9022;9091.31815113856;9131.269498440242;9138.31462640318;9147.451152930846;9165.633374664938;9167.220786233245;9346.485943273185;6520.934790652223;6710.295418981132;6838.692258848463;7023.157221133497;7178.5692858428665;7666;7800.45396822217;7857.9214207857185;7868.134505204309;7873.645685944261;6939.757108501179;6974.930250697428;7002.474814388839;7025.718971241093;7187;5794.62334948227;6930.5148639594745;6972.930270697431;7020.7542736006035;7033.894491582528;6361.61830290184;6437.678116094083;6497.707603157774;6512.283648798533;6514.674266286762;4568.862934111982;4634.609617114919;4640.93038604428;4658.813647454064;4687.554682305175;4293.592108749664;4624.1445332613575;4676.672632915664;4719.362886010482;4770.546798054179 16:50:47 INFO - PROCESS | 1637 | |4;prototype.html;302.3212887068531;306.2573259844877;307.5141276782684;310.1950438611802;312.69512225580047;4476.350929115284;4942.802287908484;5452.672839629625;5561.165825126233;5590.763694522193;4747.382840230765;5471.206675032118;5505.3393592768825;5516.393196748364;5558.77764889405;3233.9353212935725;3971.3844354125085;4012.2376748417705;4094.406311084891;4109.589041095882;3609.9458508122266;4153.958460415387;4154.646855017327;4162.250794856925;4199.832006719727;3911.3155197840406;3976.6222208890254;4120.958790412087;4166.541680415152;4176.477940257468;3794.9184482422515;3844.7308688391686;3855.74937629056;4089.9386509202386;4152.6055024772595;3589.228315912065;3596.0332725501567;3608.1881576645305;3611.9638803611892;3655.3603119454256;4413.558644135593;4878.365812444395;5331.360236771573;5340.652857564246;5437.945620543784;4620.283856002325;4807.663463557552;4817.494902650602;4824.493428190025;4871.634627402959;5710.917283617584;5833.620814647077;5838.299404071496;5862.413758624146;5877.707693999344;4017.296973029708;4036.3945408188715;4051.9392209116886;4054.2904991626347;4060;5633.549316054747;5733.913991290134;5915.881682366372;6024.855277497305;6160.2915664698685;3897.6881849451975;3903.219356128729;3913.845415602404;3940.271049855786;3941.964687190847;3425.9486107708403;3584.283143371284;3973.2252210818615;3982.2234664240773;3993.4409182714444;4665.9501612137;4705.929411058837;4721.527847215251;4745.667803253739;4749.715017098985;4364.585364390377;5091.821786237538;5547.417521160301;5888.676122813201;5945.435183657545;1345.8627459796455;1368.473137841938;1372.457879137735;1379.9931000344934;1383.3843939447052;741.7848823841133;798.6525861250375;859.0421679827073;881.6649673124172;922.589447695769;4826.855194344175;4927.827526036572;5353.894741050181;5500.697461639648;5516;5687.7156142192725;5772.971135144381;5968.522518198533;6044.667543285162;6064.787732429344;4782.808687652525;4922.507749225048;5121.769520371592;5464.672119672832;5514.531264842453;2690.367763575597;2818.42222344423;2853.615996241821;2861.0987538925174;3005.3238021445;5287.769784172647;5598.888022239533;6049.78825741097;6058;6069.453749162597;4990.451050384455;5180.481951804864;5253.684778913277;5486.052451379703;5932.970335148297;5171.560417364566;5734.627249228954;5766.52953496864;5807.912881306868;6065.848353791191;5185.27406163145;5339;5617.640590951892;5766.394528574439;5792.4497172768515;4718.693284936468;6001.219841420588;6054.576179667381;6056.091586262096;6065.120557519223;5014.456134744194;5214.895702085862;5533.197686335539;6205.627662340094;6414.166158399565;3426.674465925733;3531.5585551806025;3854.383298672199;3902.765834049966;4000.3399439093387;5260;6037.27552693662;6073.969630151821;6085.300190478151;6126.540509461897;5096.643234973516;5878.441548053099;5910.261217347832;5952.404759524013;5961.254843144607;4639.559241872017;5503.917441238465;5833.620814647034;5846.795362162304;5887.381824908323;4811.903761924812;5813.709314534171;5828.6502809831545;5846.619969701956;5856.765729370948;5113.335266415491;5150.6137039721425;5611.512949068582;5707.4577915098;5883.529317654492;4479.14115642018;4830.81644996978;5698.8290351289525;5714.799982000776;5733.327338219338 16:50:47 INFO - PROCESS | 1637 | |5;yui.html;687.9036934829124;708.4863473981364;728.3699599846133;735.5182355557112;744.7542311037358;13879.375428105734;14780.704385912302;14805.259737013146;14810.629734256636;14829.480968166117;13283.535076272332;13294.800577991313;13344.199348039125;13391.196528208315;13436.798448023288;629.0375725140519;655.5640499068138;699.1679900917892;776.9689212431496;799.8280369720524;673.387217631955;799.8520273749375;809.8299357135008;811.2495941254346;812.1229072601606;862.7670528957209;863.4560227056945;863.5941107679412;864.023653271802;865.4893612768466;651.5016012750202;742.9925700743031;831.501099340397;843.3759018326425;862.1378621378622;638.5530128909735;638.7987783848073;638.9233292004989;639.0350570638323;640.0271587187449;11070.335779853154;13040.739185216242;13365.532206372827;13388.531401400904;13515.526956556474;12689.175203611736;13292.202467851863;13339.599812005656;13427.12723672958;13452.327383630876;10741.785164296749;10807.4055926924;10811.297265677706;10829.29609575375;11014.148945183457;2937.8384188869604;3621.149029977953;3647.7628954118163;3653.3789255826573;3705.314516814398;61221.69389153026;90246;91841.54079229628;93346;94534.52732736361;10583;10600;10640.201984851166;10666.093382062612;10732.463376831127;20317.898410507853;20552.486187845425;20568.588628227353;20576.485587860123;20605.89697051535;7334.779956601311;7445.106587209461;7526.360259378014;7546.886796698054;7703.653335599912;3940.408938659224;4015.404006436566;4033.3344998075577;4034.6570541503716;4105.487390075954;3244.8864289749745;3336.966630333714;3338.2822693121097;3351.6648335166774;3358.009387230773;592.8784599157162;613.9017757158833;625.7027911741886;625.8122563231012;627.4885967936117;623.6694551887507;631.0271499925801;631.4474834519796;635.8124353315691;641.197431240009;7070.39901608369;8703.390762646553;8772.736817895473;9105.271578273721;9202.401843880127;508.49404842182264;560.1905246918245;577.4341145677259;615.0528186592686;621.3134486392562;4227.427669804151;4276.550962148992;4389.31965545341;4487.147441986012;4528.682992190581;89755.55122224348;101216.98783012223;115165;119319.40340298417;121059.39470302768;77888;79397.60301198457;104807;105239.4738026305;106267.46866265619;91720.62419063719;92270.53864730634;93819;94180.52909735407;94420;8626.439281446937;8630.741077767678;8655.0479447262;8664.523451210243;8793.164649358301;50199.74900125476;52447.475525244256;54501.63745906384;60073.699631501564;61398.69300653468;56919.14621280767;61559.38440615537;66802.3319766796;72123;76873.23126768884;10854.565817367533;12266.877331226573;12450.128491005542;12525.874741252472;12987.415566299727;13895.930520347334;14125.223112728898;14390.280485975869;14481.203533805741;14731.337089831197;13037.82659560644;13135.21188728641;13461.730765384833;13498.797518037434;13665;13453;13471.849531033584;13483.932580337036;13488.393022314216;13873.125102402246;12545.018904203815;12622.368881556069;12827.935860320638;13068.346582671018;13234;9861.408315501092;9966.700998970042;9976.60093596248;10124.088832005154;10158.39049657023;8565.77882973282;10189.54147063369;10251.28241023151;11402.82895756581;11498.597549085742 16:50:47 INFO - PROCESS | 1637 | __end_tp_report 16:50:47 INFO - PROCESS | 1637 | __start_cc_report 16:50:47 INFO - PROCESS | 1637 | _x_x_mozilla_cycle_collect,374 16:50:47 INFO - PROCESS | 1637 | __end_cc_report 16:50:47 INFO - PROCESS | 1637 | __startTimestamp1479343847373__endTimestamp 16:50:47 INFO - PROCESS | 1637 | 16:50:47 INFO - PROCESS | 1637 | ------- Summary: start ------- 16:50:47 INFO - PROCESS | 1637 | Number of tests: 6 16:50:47 INFO - PROCESS | 1637 | 16:50:47 INFO - PROCESS | 1637 | [#0] dojo.html Cycles:165 Average:5672.23 Median:5919.35 stddev:691.25 (11.7%) stddev-sans-first:656.89 16:50:47 INFO - PROCESS | 1637 | Values: 2847.5 3266.7 3309.9 3403.3 3407.3 5160.3 5607.5 5675.5 5679.6 5750.5 5546.9 5744.9 5754.2 5780.5 5793.6 5045.5 5134.2 5144.6 5262.1 5274.1 5057.8 5268.6 5275.7 5289.8 5300.8 5208.0 5265.8 5276.8 5288.6 5293.2 4859.6 5235.9 5283.6 5317.3 5382.3 5141.7 5219.1 5230.5 5239.9 5287.6 5387.9 5716.9 5758.1 5860.9 5865.9 5850.4 6126.1 6127.6 6206.1 6235.8 6178.7 6219.5 6221.9 6224.6 6234.7 5668.9 5756.2 5778.6 5778.6 5794.6 5077.0 5244.2 6172.9 6201.2 6233.2 4895.9 6118.8 6123.7 6128.8 6193.8 6183.8 6204.4 6214.0 6224.9 6246.9 6077.1 6092.6 6130.0 6152.9 6155.6 5485.4 6218.1 6219.6 6244.9 6246.2 4291.6 4551.7 4643.0 4720.7 4984.8 3391.5 3996.7 4340.6 4348.7 4373.2 5919.3 5927.0 5928.6 5937.9 5938.2 5842.4 5961.6 6130.3 6153.4 6228.8 5667.7 5713.5 5713.7 5771.4 5804.0 5312.7 5325.2 5332.0 5341.0 5344.5 6170.7 6281.6 6286.0 6300.5 6320.8 6150.0 6170.8 6172.2 6197.5 6223.4 6388.7 6396.0 6435.2 6436.1 6437.9 6153.4 6202.7 6211.8 6221.5 6224.8 6096.8 6110.6 6110.9 6139.8 6215.3 6109.6 6114.7 6137.1 6141.5 6179.0 5054.3 6124.9 6125.1 6126.6 6134.0 5170.9 5359.7 5880.0 5915.7 5943.5 6005.4 6107.7 6118.1 6118.4 6121.8 5019.7 5934.7 6050.2 6056.9 6088.1 16:50:47 INFO - PROCESS | 1637 | 16:50:47 INFO - PROCESS | 1637 | [#1] ext.html Cycles:180 Average:62853.27 Median:38861.32 stddev:133058.82 (342.4%) stddev-sans-first:133349.59 16:50:47 INFO - PROCESS | 1637 | Values: 446.7 460.9 462.8 501.7 517.5 36167.0 37502.8 39635.4 39825.2 39993.8 39735.0 40387.6 40582.0 40832.8 40841.8 9189.4 9223.6 9272.7 9382.1 9391.6 9320.9 9364.4 9398.8 9415.2 9429.9 9395.0 9433.2 9445.7 9449.6 9454.5 9162.6 9323.7 9334.5 9346.6 9431.8 9092.3 9215.3 9245.7 9262.6 9287.0 38487.6 38766.0 38855.4 38867.2 38932.8 3223.2 3330.2 3332.9 3467.7 3877.7 29294.0 29385.0 29834.1 29925.6 30692.5 3041.0 3055.7 3092.5 3192.4 3231.4 438469.0 442479.0 443357.0 444069.0 444831.6 46974.5 53156.5 55053.0 55804.7 55810.0 57943.4 58107.0 58363.1 58391.7 58573.7 15078.3 15284.6 15494.5 15564.9 15621.0 11979.4 11982.9 12095.8 12122.5 12167.7 14541.8 15636.8 15683.3 15688.7 15849.6 3079.2 5790.8 6019.8 6040.0 6045.2 730.1 858.8 906.8 1018.1 1045.0 22495.3 22512.9 28593.7 28681.6 29059.1 966.8 1017.6 1025.6 1049.7 1055.5 14430.6 14722.5 14836.3 15169.0 15189.5 467657.7 522525.0 541942.0 546096.0 548010.0 44641.9 52178.7 55510.0 55563.9 55630.7 1285.7 1290.9 1312.9 1315.1 1316.8 22156.2 27622.6 28700.1 28871.9 30290.4 47208.3 47872.5 47881.3 47951.5 48106.8 522377.0 523622.4 525038.0 526299.0 526324.0 25320.2 25334.7 25441.6 25797.6 25822.6 43851.3 44517.3 44602.1 44800.8 44837.3 36555.6 37238.4 38172.8 38762.0 40091.6 29546.0 36551.9 36998.7 37113.8 37708.0 35818.8 36232.0 36914.4 37024.6 37259.3 29635.0 30060.8 32171.7 33604.8 33698.7 21210.9 23582.0 24876.8 24932.6 25340.5 16:50:47 INFO - PROCESS | 1637 | 16:50:47 INFO - PROCESS | 1637 | [#2] jquery.html Cycles:180 Average:30307.19 Median:4221.10 stddev:83621.47 (1981.0%) stddev-sans-first:83825.83 16:50:47 INFO - PROCESS | 1637 | Values: 365.1 367.8 374.2 378.3 395.3 18771.4 19102.0 19498.4 19808.1 20077.2 11252.3 11655.0 11683.1 11703.5 11706.7 4945.1 5254.7 5308.3 5334.9 5341.0 3840.6 4053.5 5225.0 5284.0 6272.4 6501.9 6592.0 6599.0 6604.2 6609.4 6317.2 6474.9 6535.1 6543.0 6548.8 5523.7 5593.8 5736.5 5744.0 5766.9 9093.5 9348.2 10294.4 12702.6 12735.7 2041.3 2146.2 2159.8 2205.4 2683.0 9343.5 9821.6 9841.1 10214.1 10246.5 1779.2 1795.5 1835.9 1851.3 1857.2 94908.5 103553.5 117997.0 122918.0 125716.4 8699.0 10751.5 10939.9 11181.7 11344.8 12002.0 13061.2 13900.7 13918.0 13941.7 7902.2 8184.7 8207.4 8277.8 8281.6 8045.5 8069.4 8091.0 8201.1 8274.8 4388.7 4402.4 4425.0 4441.0 4502.5 1561.8 1687.5 1688.7 1871.3 1927.0 834.8 865.5 866.7 868.2 900.5 19846.7 19922.7 20418.4 20499.2 20563.4 927.6 928.2 932.3 974.0 1041.7 10697.9 10856.5 10904.8 10912.3 10948.7 492192.0 498710.0 498878.0 500172.0 500591.0 93164.5 103016.0 105566.5 114884.9 115284.4 948.0 971.8 993.4 997.2 1001.2 13712.2 16688.8 17705.5 18015.0 18300.5 38291.6 52064.0 52402.2 52477.5 53160.0 73848.3 74861.6 75171.0 75770.6 76135.2 6955.3 7058.2 7085.6 7143.0 7259.0 7830.4 8889.2 12330.6 12455.8 12563.4 10516.7 10567.5 10622.7 10667.8 10963.7 10337.6 10349.6 10368.4 10899.0 10974.1 8886.1 9398.5 10919.9 11200.6 11303.0 5518.0 6475.4 7059.6 7199.0 7229.7 8782.3 9313.8 10771.6 10826.2 10938.5 16:50:47 INFO - PROCESS | 1637 | 16:50:47 INFO - PROCESS | 1637 | [#3] mootools.html Cycles:180 Average:5293.10 Median:5209.48 stddev:2861.01 (54.9%) stddev-sans-first:2854.63 16:50:47 INFO - PROCESS | 1637 | Values: 1473.2 1507.7 1682.6 1690.2 1717.9 6403.8 7375.2 7672.4 8280.9 8321.0 7808.2 7994.3 8114.1 8186.8 8210.9 1881.9 2272.2 2304.9 2315.1 2321.2 2288.3 2313.0 2326.0 2341.1 2342.6 2292.8 2293.5 2302.2 2306.7 2311.8 2267.0 2269.4 2274.7 2294.6 2298.4 2284.9 2312.8 2316.2 2320.3 2320.6 7714.2 7779.5 7815.0 7843.9 7847.7 4979.7 5075.1 5130.5 5138.7 5158.8 3250.3 3327.0 3594.6 3599.5 3615.7 1029.0 1034.4 1064.2 1081.9 1092.3 10393.5 10610.6 10637.7 10666.8 10684.6 3867.1 4217.0 4313.0 4426.8 4440.7 8745.8 9127.3 9206.5 9207.2 9231.5 4604.6 5077.2 5120.4 5122.3 5124.9 4184.5 4402.2 4667.8 4668.8 4678.7 3693.8 3721.5 3777.9 3827.0 3827.8 1250.6 1253.7 1260.5 1271.5 1276.9 840.2 850.6 871.7 875.2 893.7 5146.1 5260.2 5497.0 5567.7 5601.9 896.2 937.7 943.5 961.7 981.4 3736.0 3740.4 3750.7 3845.4 3862.0 9276.9 9286.0 9580.8 9618.9 9907.2 9726.7 9728.9 9752.8 9757.4 9766.3 7363.3 7683.3 7727.7 7737.8 7740.0 5449.1 5485.8 5534.2 5653.5 5664.2 8865.3 8990.9 9022.0 9091.3 9131.3 9138.3 9147.5 9165.6 9167.2 9346.5 6520.9 6710.3 6838.7 7023.2 7178.6 7666.0 7800.5 7857.9 7868.1 7873.6 6939.8 6974.9 7002.5 7025.7 7187.0 5794.6 6930.5 6972.9 7020.8 7033.9 6361.6 6437.7 6497.7 6512.3 6514.7 4568.9 4634.6 4640.9 4658.8 4687.6 4293.6 4624.1 4676.7 4719.4 4770.5 16:50:47 INFO - PROCESS | 1637 | 16:50:47 INFO - PROCESS | 1637 | [#4] prototype.html Cycles:180 Average:4624.60 Median:5136.19 stddev:1441.32 (28.1%) stddev-sans-first:1408.38 16:50:47 INFO - PROCESS | 1637 | Values: 302.3 306.3 307.5 310.2 312.7 4476.4 4942.8 5452.7 5561.2 5590.8 4747.4 5471.2 5505.3 5516.4 5558.8 3233.9 3971.4 4012.2 4094.4 4109.6 3609.9 4154.0 4154.6 4162.3 4199.8 3911.3 3976.6 4121.0 4166.5 4176.5 3794.9 3844.7 3855.7 4089.9 4152.6 3589.2 3596.0 3608.2 3612.0 3655.4 4413.6 4878.4 5331.4 5340.7 5437.9 4620.3 4807.7 4817.5 4824.5 4871.6 5710.9 5833.6 5838.3 5862.4 5877.7 4017.3 4036.4 4051.9 4054.3 4060.0 5633.5 5733.9 5915.9 6024.9 6160.3 3897.7 3903.2 3913.8 3940.3 3942.0 3425.9 3584.3 3973.2 3982.2 3993.4 4666.0 4705.9 4721.5 4745.7 4749.7 4364.6 5091.8 5547.4 5888.7 5945.4 1345.9 1368.5 1372.5 1380.0 1383.4 741.8 798.7 859.0 881.7 922.6 4826.9 4927.8 5353.9 5500.7 5516.0 5687.7 5773.0 5968.5 6044.7 6064.8 4782.8 4922.5 5121.8 5464.7 5514.5 2690.4 2818.4 2853.6 2861.1 3005.3 5287.8 5598.9 6049.8 6058.0 6069.5 4990.5 5180.5 5253.7 5486.1 5933.0 5171.6 5734.6 5766.5 5807.9 6065.8 5185.3 5339.0 5617.6 5766.4 5792.4 4718.7 6001.2 6054.6 6056.1 6065.1 5014.5 5214.9 5533.2 6205.6 6414.2 3426.7 3531.6 3854.4 3902.8 4000.3 5260.0 6037.3 6074.0 6085.3 6126.5 5096.6 5878.4 5910.3 5952.4 5961.3 4639.6 5503.9 5833.6 5846.8 5887.4 4811.9 5813.7 5828.7 5846.6 5856.8 5113.3 5150.6 5611.5 5707.5 5883.5 4479.1 4830.8 5698.8 5714.8 5733.3 16:50:47 INFO - PROCESS | 1637 | 16:50:47 INFO - PROCESS | 1637 | [#5] yui.html Cycles:180 Average:20424.08 Median:4438.23 stddev:30319.10 (683.1%) stddev-sans-first:30367.94 16:50:47 INFO - PROCESS | 1637 | Values: 687.9 708.5 728.4 735.5 744.8 13879.4 14780.7 14805.3 14810.6 14829.5 13283.5 13294.8 13344.2 13391.2 13436.8 629.0 655.6 699.2 777.0 799.8 673.4 799.9 809.8 811.2 812.1 862.8 863.5 863.6 864.0 865.5 651.5 743.0 831.5 843.4 862.1 638.6 638.8 638.9 639.0 640.0 11070.3 13040.7 13365.5 13388.5 13515.5 12689.2 13292.2 13339.6 13427.1 13452.3 10741.8 10807.4 10811.3 10829.3 11014.1 2937.8 3621.1 3647.8 3653.4 3705.3 61221.7 90246.0 91841.5 93346.0 94534.5 10583.0 10600.0 10640.2 10666.1 10732.5 20317.9 20552.5 20568.6 20576.5 20605.9 7334.8 7445.1 7526.4 7546.9 7703.7 3940.4 4015.4 4033.3 4034.7 4105.5 3244.9 3337.0 3338.3 3351.7 3358.0 592.9 613.9 625.7 625.8 627.5 623.7 631.0 631.4 635.8 641.2 7070.4 8703.4 8772.7 9105.3 9202.4 508.5 560.2 577.4 615.1 621.3 4227.4 4276.6 4389.3 4487.1 4528.7 89755.6 101217.0 115165.0 119319.4 121059.4 77888.0 79397.6 104807.0 105239.5 106267.5 91720.6 92270.5 93819.0 94180.5 94420.0 8626.4 8630.7 8655.0 8664.5 8793.2 50199.7 52447.5 54501.6 60073.7 61398.7 56919.1 61559.4 66802.3 72123.0 76873.2 10854.6 12266.9 12450.1 12525.9 12987.4 13895.9 14125.2 14390.3 14481.2 14731.3 13037.8 13135.2 13461.7 13498.8 13665.0 13453.0 13471.8 13483.9 13488.4 13873.1 12545.0 12622.4 12827.9 13068.3 13234.0 9861.4 9966.7 9976.6 10124.1 10158.4 8565.8 10189.5 10251.3 11402.8 11498.6 16:50:47 INFO - PROCESS | 1637 | -------- Summary: end -------- 16:50:47 INFO - PROCESS | 1637 | 16:50:47 INFO - TEST-INFO | 1637: exit 0 16:50:47 INFO - TEST-OK | dromaeo_css | took 1115848ms 16:50:47 INFO - TEST-START | kraken 16:50:47 INFO - operating with platform_type : mac_ 16:50:47 INFO - Initialising browser for kraken test... 16:50:47 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/tmpROJ7UC/profile http://localhost:49249/getInfo.html) 16:50:50 INFO - PROCESS | 1649 | 2016-11-16 16:50:50.559 plugin-container[1650:10068] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 16:50:50 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:50:50 INFO - PROCESS | 1649 | 2016-11-16 16:50:50.562 plugin-container[1650:10068] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 16:50:50 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:50:50 INFO - PROCESS | 1649 | 2016-11-16 16:50:50.562 plugin-container[1650:10068] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:50:50 INFO - PROCESS | 1649 | __metrics Screen width/height:1600/1200 16:50:50 INFO - PROCESS | 1649 | colorDepth:24 16:50:50 INFO - PROCESS | 1649 | Browser inner width/height: 1024/689 16:50:50 INFO - PROCESS | 1649 | __metrics 16:50:50 ERROR - PROCESS | 1649 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3179: TypeError: this.tabs is undefined 16:50:51 INFO - TEST-INFO | 1649: exit 0 16:50:51 INFO - Browser initialized. 16:50:51 INFO - Running cycle 1/1 for kraken test... 16:50:51 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/tmpROJ7UC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:50:53 INFO - PROCESS | 1651 | 2016-11-16 16:50:53.725 plugin-container[1652:10185] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873b, name = 'com.apple.tsm.portname' 16:50:53 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:50:53 INFO - PROCESS | 1651 | 2016-11-16 16:50:53.726 plugin-container[1652:10185] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b23, name = 'com.apple.CFPasteboardClient' 16:50:53 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:50:53 INFO - PROCESS | 1651 | 2016-11-16 16:50:53.726 plugin-container[1652:10185] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:51:03 INFO - PROCESS | 1651 | RSS: Main: 237940736 16:51:03 INFO - PROCESS | 1651 | 16:51:06 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-beat-detection) 16:51:07 INFO - PROCESS | 1651 | RSS: Main: 250359808 16:51:07 INFO - PROCESS | 1651 | 16:51:09 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-dft) 16:51:10 INFO - PROCESS | 1651 | RSS: Main: 258056192 16:51:10 INFO - PROCESS | 1651 | 16:51:13 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-fft) 16:51:14 INFO - PROCESS | 1651 | RSS: Main: 260751360 16:51:14 INFO - PROCESS | 1651 | 16:51:16 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-oscillator) 16:51:16 INFO - PROCESS | 1651 | RSS: Main: 261976064 16:51:16 INFO - PROCESS | 1651 | 16:51:18 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:51:19 INFO - PROCESS | 1651 | RSS: Main: 262266880 16:51:19 INFO - PROCESS | 1651 | 16:51:22 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-darkroom) 16:51:22 INFO - PROCESS | 1651 | RSS: Main: 268881920 16:51:22 INFO - PROCESS | 1651 | 16:51:25 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-desaturate) 16:51:26 INFO - PROCESS | 1651 | RSS: Main: 269004800 16:51:26 INFO - PROCESS | 1651 | 16:51:29 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49249/tests/kraken/driver.html?testName=json-parse-financial) 16:51:30 INFO - PROCESS | 1651 | RSS: Main: 268537856 16:51:30 INFO - PROCESS | 1651 | 16:51:31 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49249/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:51:32 INFO - PROCESS | 1651 | RSS: Main: 267567104 16:51:32 INFO - PROCESS | 1651 | 16:51:34 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:51:35 INFO - PROCESS | 1651 | RSS: Main: 267608064 16:51:35 INFO - PROCESS | 1651 | 16:51:37 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:51:37 INFO - PROCESS | 1651 | RSS: Main: 267616256 16:51:37 INFO - PROCESS | 1651 | 16:51:40 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:51:41 INFO - PROCESS | 1651 | RSS: Main: 267583488 16:51:41 INFO - PROCESS | 1651 | 16:51:44 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:51:45 INFO - PROCESS | 1651 | RSS: Main: 267898880 16:51:45 INFO - PROCESS | 1651 | 16:51:46 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49249/tests/kraken/driver.html?testName=ai-astar) 16:51:47 INFO - PROCESS | 1651 | RSS: Main: 269594624 16:51:47 INFO - PROCESS | 1651 | 16:51:47 INFO - PROCESS | 1651 | __start_tp_report 16:51:47 INFO - PROCESS | 1651 | _x_x_mozilla_page_load 16:51:47 INFO - PROCESS | 1651 | _x_x_mozilla_page_load_details 16:51:47 INFO - PROCESS | 1651 | |i|pagename|runs| 16:51:47 INFO - PROCESS | 1651 | |0;ai-astar;121;159;116;117;120;117;118;119;116;116 16:51:47 INFO - PROCESS | 1651 | |1;audio-beat-detection;149;113;114;149;119;123;116;116;143;137 16:51:47 INFO - PROCESS | 1651 | |2;audio-dft;198;193;201;218;208;195;195;194;197;195 16:51:47 INFO - PROCESS | 1651 | |3;audio-fft;84;58;59;83;60;59;62;84;81;60 16:51:47 INFO - PROCESS | 1651 | |4;audio-oscillator;86;78;70;93;97;60;115;90;91;123 16:51:47 INFO - PROCESS | 1651 | |5;imaging-gaussian-blur;91;87;88;85;89;86;89;88;100;86 16:51:47 INFO - PROCESS | 1651 | |6;imaging-darkroom;94;94;119;153;111;95;100;103;161;112 16:51:47 INFO - PROCESS | 1651 | |7;imaging-desaturate;119;145;149;94;93;92;95;91;92;95 16:51:47 INFO - PROCESS | 1651 | |8;json-parse-financial;56;57;57;58;57;57;65;57;58;58 16:51:47 INFO - PROCESS | 1651 | |9;json-stringify-tinderbox;69;69;79;78;74;70;59;102;109;105 16:51:47 INFO - PROCESS | 1651 | |10;stanford-crypto-aes;91;91;87;88;86;92;89;95;105;92 16:51:47 INFO - PROCESS | 1651 | |11;stanford-crypto-ccm;151;154;145;190;150;148;148;152;196;153 16:51:47 INFO - PROCESS | 1651 | |12;stanford-crypto-pbkdf2;178;177;174;174;176;179;176;177;175;174 16:51:47 INFO - PROCESS | 1651 | |13;stanford-crypto-sha256-iterative;69;70;67;67;77;95;89;90;91;87 16:51:47 INFO - PROCESS | 1651 | __end_tp_report 16:51:47 INFO - PROCESS | 1651 | __start_cc_report 16:51:47 INFO - PROCESS | 1651 | _x_x_mozilla_cycle_collect,965 16:51:47 INFO - PROCESS | 1651 | __end_cc_report 16:51:47 INFO - PROCESS | 1651 | __startTimestamp1479343907240__endTimestamp 16:51:47 INFO - PROCESS | 1651 | 16:51:47 INFO - PROCESS | 1651 | ------- Summary: start ------- 16:51:47 INFO - PROCESS | 1651 | Number of tests: 14 16:51:47 INFO - PROCESS | 1651 | 16:51:47 INFO - PROCESS | 1651 | [#0] ai-astar Cycles:10 Average:121.90 Median:118.50 stddev:13.15 (11.1%) stddev-sans-first:13.95 16:51:47 INFO - PROCESS | 1651 | Values: 121.0 159.0 116.0 117.0 120.0 117.0 118.0 119.0 116.0 116.0 16:51:47 INFO - PROCESS | 1651 | 16:51:47 INFO - PROCESS | 1651 | [#1] audio-beat-detection Cycles:10 Average:127.90 Median:130.00 stddev:14.92 (11.5%) stddev-sans-first:13.73 16:51:47 INFO - PROCESS | 1651 | Values: 149.0 113.0 114.0 149.0 119.0 123.0 116.0 116.0 143.0 137.0 16:51:47 INFO - PROCESS | 1651 | 16:51:47 INFO - PROCESS | 1651 | [#2] audio-dft Cycles:10 Average:199.40 Median:197.50 stddev:7.88 (4.0%) stddev-sans-first:8.34 16:51:47 INFO - PROCESS | 1651 | Values: 198.0 193.0 201.0 218.0 208.0 195.0 195.0 194.0 197.0 195.0 16:51:47 INFO - PROCESS | 1651 | 16:51:47 INFO - PROCESS | 1651 | [#3] audio-fft Cycles:10 Average:69.00 Median:71.50 stddev:12.12 (17.0%) stddev-sans-first:11.58 16:51:47 INFO - PROCESS | 1651 | Values: 84.0 58.0 59.0 83.0 60.0 59.0 62.0 84.0 81.0 60.0 16:51:47 INFO - PROCESS | 1651 | 16:51:47 INFO - PROCESS | 1651 | [#4] audio-oscillator Cycles:10 Average:90.30 Median:88.00 stddev:18.95 (21.5%) stddev-sans-first:20.04 16:51:47 INFO - PROCESS | 1651 | Values: 86.0 78.0 70.0 93.0 97.0 60.0 115.0 90.0 91.0 123.0 16:51:47 INFO - PROCESS | 1651 | 16:51:47 INFO - PROCESS | 1651 | [#5] imaging-gaussian-blur Cycles:10 Average:88.90 Median:88.00 stddev:4.28 (4.9%) stddev-sans-first:4.47 16:51:47 INFO - PROCESS | 1651 | Values: 91.0 87.0 88.0 85.0 89.0 86.0 89.0 88.0 100.0 86.0 16:51:47 INFO - PROCESS | 1651 | 16:51:47 INFO - PROCESS | 1651 | [#6] imaging-darkroom Cycles:10 Average:114.20 Median:157.00 stddev:24.14 (15.4%) stddev-sans-first:24.48 16:51:47 INFO - PROCESS | 1651 | Values: 94.0 94.0 119.0 153.0 111.0 95.0 100.0 103.0 161.0 112.0 16:51:47 INFO - PROCESS | 1651 | 16:51:47 INFO - PROCESS | 1651 | [#7] imaging-desaturate Cycles:10 Average:106.50 Median:92.50 stddev:22.87 (24.7%) stddev-sans-first:23.81 16:51:47 INFO - PROCESS | 1651 | Values: 119.0 145.0 149.0 94.0 93.0 92.0 95.0 91.0 92.0 95.0 16:51:47 INFO - PROCESS | 1651 | 16:51:47 INFO - PROCESS | 1651 | [#8] json-parse-financial Cycles:10 Average:58.00 Median:57.50 stddev:2.54 (4.4%) stddev-sans-first:2.59 16:51:47 INFO - PROCESS | 1651 | Values: 56.0 57.0 57.0 58.0 57.0 57.0 65.0 57.0 58.0 58.0 16:51:47 INFO - PROCESS | 1651 | 16:51:47 INFO - PROCESS | 1651 | [#9] json-stringify-tinderbox Cycles:10 Average:81.40 Median:69.50 stddev:17.49 (25.2%) stddev-sans-first:17.97 16:51:47 INFO - PROCESS | 1651 | Values: 69.0 69.0 79.0 78.0 74.0 70.0 59.0 102.0 109.0 105.0 16:51:47 INFO - PROCESS | 1651 | 16:51:47 INFO - PROCESS | 1651 | [#10] stanford-crypto-aes Cycles:10 Average:91.60 Median:91.00 stddev:5.42 (6.0%) stddev-sans-first:5.74 16:51:47 INFO - PROCESS | 1651 | Values: 91.0 91.0 87.0 88.0 86.0 92.0 89.0 95.0 105.0 92.0 16:51:47 INFO - PROCESS | 1651 | 16:51:47 INFO - PROCESS | 1651 | [#11] stanford-crypto-ccm Cycles:10 Average:158.70 Median:152.50 stddev:18.32 (12.0%) stddev-sans-first:19.22 16:51:47 INFO - PROCESS | 1651 | Values: 151.0 154.0 145.0 190.0 150.0 148.0 148.0 152.0 196.0 153.0 16:51:47 INFO - PROCESS | 1651 | 16:51:47 INFO - PROCESS | 1651 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.00 Median:176.50 stddev:1.76 (1.0%) stddev-sans-first:1.72 16:51:47 INFO - PROCESS | 1651 | Values: 178.0 177.0 174.0 174.0 176.0 179.0 176.0 177.0 175.0 174.0 16:51:47 INFO - PROCESS | 1651 | 16:51:47 INFO - PROCESS | 1651 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:80.20 Median:88.00 stddev:11.27 (12.8%) stddev-sans-first:11.20 16:51:47 INFO - PROCESS | 1651 | Values: 69.0 70.0 67.0 67.0 77.0 95.0 89.0 90.0 91.0 87.0 16:51:47 INFO - PROCESS | 1651 | -------- Summary: end -------- 16:51:47 INFO - PROCESS | 1651 | 16:51:48 INFO - TEST-INFO | 1651: exit 0 16:51:48 INFO - TEST-OK | kraken | took 60098ms 16:51:48 INFO - SUITE-END | took 1175s 16:51:48 INFO - Completed test suite (00:19:36) 16:51:48 INFO - javascript benchmark 16:51:48 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6993.950267193958, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2847.4874522585937, 3266.7223286020685, 3309.8841540546077, 3403.2682973160768, 3407.2504049109193, 5160.3033590465275, 5607.495325420711, 5675.5459563234945, 5679.574031947605, 5750.511206547438, 5546.946080244761, 5744.942550574503, 5754.223179870716, 5780.450857168573, 5793.594448388604, 5045.545900868921, 5134.204198349262, 5144.639875208737, 5262.131748261551, 5274.05067087924, 5057.797688092491, 5268.578513718912, 5275.657082289658, 5289.841304760864, 5300.761465734032, 5208.010478009166, 5265.842024739245, 5276.7889284428575, 5288.60335474839, 5293.15309550472, 4859.58693511052, 5235.895282094356, 5283.577313814885, 5317.335333083364, 5382.327209098863, 5141.74291285438, 5219.086659834514, 5230.476952304777, 5239.947600524022, 5287.576993840484, 5387.865303367408, 5716.885662286731, 5758.078707406795, 5860.915730589854, 5865.941340586583, 5850.3564607893095, 6126.111713801519, 6127.586602260224, 6206.069089636546, 6235.844103897439, 6178.691588785046, 6219.471344935686, 6221.87556248877, 6224.626522408625, 6234.719437625273, 5668.914966275509, 5756.194132821408, 5778.595498315161, 5778.624389414675, 5794.565407594448, 5077.0, 5244.239585260116, 6172.876542469216, 6201.162843016225, 6233.2208473940755, 4895.926561101586, 6118.816435506942, 6123.663198524035, 6128.816135515853, 6193.752249910043, 6183.752649894044, 6204.37956204385, 6214.0, 6224.875502490016, 6246.906296405557, 6077.118817771487, 6092.634441933498, 6130.01919692847, 6152.938470615237, 6155.599886007485, 5485.424030476823, 6218.098375735493, 6219.564630475914, 6244.937550624436, 6246.187995560639, 4291.570842915684, 4551.748269225989, 4642.9535704643195, 4720.716756994591, 4984.77568509418, 3391.491276308524, 3996.741026576619, 4340.587644173798, 4348.67384946124, 4373.190959672471, 5919.348871624118, 5926.970365148233, 5928.584999050111, 5937.881242375129, 5938.198343223609, 5842.415758424124, 5961.642301461926, 6130.264368275835, 6153.384661533901, 6228.844278893064, 5667.688277144796, 5713.542916566665, 5713.685747283939, 5771.422857714279, 5803.970980145073, 5312.654677445954, 5325.174597937326, 5332.0, 5340.973295133499, 5344.545713614309, 6170.7223174958135, 6281.560290779602, 6286.025666021774, 6300.464460520907, 6320.841978950563, 6150.0, 6170.753169873155, 6172.166757487861, 6197.4732147767945, 6223.439890409885, 6388.712507937061, 6395.968020159869, 6435.163428754232, 6436.066770318184, 6437.935620643733, 6153.446189842935, 6202.658853763086, 6211.844703882439, 6221.502279817694, 6224.844378890382, 6096.786612468542, 6110.6195130195665, 6110.938890611037, 6139.833431647972, 6215.285242197205, 6109.572329937039, 6114.652213009299, 6137.140800288053, 6141.486007784186, 6179.0, 5054.342935418372, 6124.908126378197, 6125.111858780541, 6126.6017708848785, 6134.0, 5170.939957308818, 5359.651622644516, 5879.970600146972, 5915.733791979457, 5943.465088142088, 6005.429484198994, 6107.735039974651, 6118.131909153847, 6118.449339559461, 6121.755129794937, 5019.67372120811, 5934.703264836828, 6050.2437195350585, 6056.909756243742, 6088.117223002729], "value": 5626.234407272602, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [446.7096387348223, 460.92144382145773, 462.8105768175785, 501.6588719670624, 517.5419753518137, 36167.0, 37502.81248593757, 39635.405468917954, 39825.2034959301, 39993.800030999875, 39734.99999999993, 40387.59612403875, 40582.0, 40832.79583602078, 40841.795791021075, 9189.448633082036, 9223.631054757801, 9272.67545635903, 9382.061793820598, 9391.624335026625, 9320.906790932071, 9364.391314564531, 9398.765030874214, 9415.199708024791, 9429.905700943005, 9395.0, 9433.198178154866, 9445.669401570947, 9449.622015119421, 9454.4800035998, 9162.587683554257, 9323.72028839136, 9334.533273336307, 9346.579403926806, 9431.764205894908, 9092.27261819053, 9215.262778977665, 9245.676401325922, 9262.610608408726, 9286.95356523213, 38487.615123848685, 38766.0, 38855.417168742206, 38867.22265554673, 38932.832015039596, 3223.2264256578487, 3330.200751819546, 3332.900012999614, 3467.6705712957228, 3877.689784817208, 29293.974710885017, 29385.0, 29834.10497685051, 29925.551116733266, 30692.53961190584, 3041.0268714011304, 3055.663876973531, 3092.5361195820583, 3192.4037981009496, 3231.402190594724, 438469.0, 442479.0, 443357.0, 444069.0, 444831.55168448226, 46974.53025469701, 53156.468435315925, 55053.0, 55804.720976394856, 55810.0, 57943.4205657938, 58107.0, 58363.12455313174, 58391.70804145952, 58573.70713146407, 15078.321475533627, 15284.617884552976, 15494.5351639451, 15564.92217538905, 15621.0, 11979.401029948467, 11982.940085299517, 12095.75808483843, 12122.454489547992, 12167.695807604881, 14541.78187327191, 15636.843631563766, 15683.294251758698, 15688.686226275411, 15849.603759905864, 3079.153232860981, 5790.841831633606, 6019.819405417845, 6039.973204555236, 6045.21412216409, 730.0764532865929, 858.7853036740814, 906.759708677201, 1018.0888105145852, 1044.9791004180026, 22495.32514024582, 22512.887435562716, 28593.714062859523, 28681.56977645337, 29059.12822615325, 966.8453047512364, 1017.6234793126442, 1025.600015993763, 1049.6850944716707, 1055.488088277185, 14430.639234019234, 14722.484713034992, 14836.3323650436, 15169.0, 15189.468368607046, 467657.66171169607, 522525.0, 541942.0, 546096.0, 548010.0, 44641.88395290144, 52178.73910630422, 55510.0, 55563.88872222533, 55630.72184639051, 1285.710715089116, 1290.8709129087204, 1312.9409176587267, 1315.0925861155963, 1316.795896636023, 22156.224532141943, 27622.5856612155, 28700.13899583016, 28871.85564072166, 30290.394192116473, 47208.29187562121, 47872.521274788196, 47881.281780774014, 47951.5204847947, 48106.75946620244, 522377.0, 523622.38188808813, 525038.0, 526299.0, 526324.0, 25320.240392788244, 25334.746652533977, 25441.618375724007, 25797.613035804854, 25822.61266081048, 43851.342229867216, 44517.33224001578, 44602.10795784131, 44800.77599611981, 44837.32744008908, 36555.634443655224, 37238.44142337813, 38172.80913595414, 38762.0, 40091.59908400878, 29545.965891193704, 36551.90344289676, 36998.70504532329, 37113.814430927676, 37708.0, 35818.82090589531, 36232.0, 36914.44628330631, 37024.62975370212, 37259.254814904096, 29635.0, 30060.849695752255, 32171.67828321687, 33604.83197583997, 33698.66301336955, 21210.893945530792, 23581.998914877735, 24876.751232487444, 24932.626010609492, 25340.493190135727], "value": 18649.743038027427, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [365.12006065382434, 367.7793324005596, 374.2440270653281, 378.33790865984525, 395.30031843636766, 18771.4368568943, 19102.0, 19498.415047548595, 19808.108635111417, 20077.196912123498, 11252.324860508355, 11655.0, 11683.06535477162, 11703.473343699532, 11706.707332316697, 4945.109880221558, 5254.658447200939, 5308.283381743473, 5334.866628334304, 5340.958513089949, 3840.577536470986, 4053.493313335833, 5225.033368826779, 5283.973580132113, 6272.40412160844, 6501.869962600746, 6592.04415359775, 6599.0, 6604.207495100594, 6609.372109649576, 6317.241930968267, 6474.870502589969, 6535.052417399498, 6543.0, 6548.803535893907, 5523.696196709219, 5593.776248950037, 5736.4550367715, 5743.971280143573, 5766.88466230673, 9093.45439273639, 9348.205402540792, 10294.374934391757, 12702.555410560586, 12735.681607959876, 2041.2957529652247, 2146.162996431393, 2159.75162856272, 2205.4376134085865, 2682.9865850670817, 9343.532823358873, 9821.558029888673, 9841.08673601303, 10214.080732734015, 10246.53890574926, 1779.1904683369037, 1795.4523870219577, 1835.8714889957707, 1851.333519932823, 1857.2478146350616, 94908.52545737296, 103553.48223258836, 117997.0, 122918.0, 125716.37141814324, 8698.956505217433, 10751.51618177184, 10939.890601094046, 11181.664550063511, 11344.829827552594, 12001.979831714401, 13061.21632702041, 13900.721985560232, 13917.956153288342, 13941.721165576835, 7902.24928631779, 8184.713535026245, 8207.384446166441, 8277.793055173668, 8281.585920703941, 8045.517268963881, 8069.435139540175, 8090.959545202236, 8201.13888041747, 8274.793130171673, 4388.714733542373, 4402.361657559636, 4424.977875110668, 4440.955590444119, 4502.482214545369, 1561.773542836305, 1687.5021868548808, 1688.729803231477, 1871.2514994002509, 1927.0172212171613, 834.8383309203615, 865.523961820996, 866.6663334616218, 868.1708967935606, 900.5092224737534, 19846.70270918488, 19922.701159482618, 20418.387448376572, 20499.18003279882, 20563.383098507067, 927.5918595817818, 928.2295694573488, 932.2635118256501, 974.0211087856659, 1041.7499800047933, 10697.946510267398, 10856.511456984455, 10904.781904362027, 10912.34525928431, 10948.726281843017, 492192.0, 498710.0, 498878.0, 500172.0, 500591.0, 93164.53417732868, 103016.0, 105566.47216763867, 114884.85115148909, 115284.42357788325, 947.9668211612423, 971.7910649210312, 993.3741742702196, 997.2470784557613, 1001.2040427859869, 13712.17726936387, 16688.83311166873, 17705.468835934942, 18015.0, 18300.542486437946, 38291.555244545074, 52064.0, 52402.213966789765, 52477.47522524726, 53160.0, 73848.26151738413, 74861.62569187119, 75171.0, 75770.62114689391, 76135.23864761282, 6955.269696681776, 7058.153021637231, 7085.610291434021, 7143.0, 7259.0, 7830.3735701144915, 8889.244414224864, 12330.581983072061, 12455.813162802746, 12563.371831408576, 10516.68449946503, 10567.524461399102, 10622.73443163927, 10667.786644267226, 10963.725906852393, 10337.586496540054, 10349.586016559253, 10368.429736364573, 10899.019088282093, 10974.122070234522, 8886.066962968795, 9398.483083430478, 10919.945400273265, 11200.607978720705, 11303.03924166455, 5517.972410137923, 6475.381154711322, 7059.5764254144915, 7198.964005179941, 7229.671840443133, 8782.29741620656, 9313.813723725352, 10771.553103006963, 10826.242163048826, 10938.45307734626], "value": 8714.68148601658, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1473.175021987687, 1507.6532397548563, 1682.6045879218375, 1690.1802625726527, 1717.9348803741675, 6403.775867844626, 7375.225601311866, 7672.424568157397, 8280.875786863195, 8320.958395208023, 7808.219178082203, 7994.280514753671, 8114.107448180695, 8186.836263274731, 8210.876836847452, 1881.9435416937513, 2272.2388000020014, 2304.8617082975074, 2315.131825565413, 2321.199186280739, 2288.302067869299, 2313.0, 2326.0463210083867, 2341.075275266269, 2342.590046741822, 2292.793648571628, 2293.529826385587, 2302.2057390200353, 2306.6540018997202, 2311.8150547956207, 2266.954660906781, 2269.353234328225, 2274.715660542432, 2294.598445272079, 2298.4139044543617, 2284.8971796269207, 2312.7571604981404, 2316.2240649382306, 2320.3039088273617, 2320.5938960681983, 7714.228577142297, 7779.533228006338, 7815.0, 7843.9215607844335, 7847.725329613436, 4979.651424400294, 5075.111855425323, 5130.53825155734, 5138.743062846843, 5158.793648254065, 3250.2686895448574, 3327.0, 3594.5686517618055, 3599.5320608321013, 3615.7468977171607, 1028.9742756431074, 1034.4362322534237, 1064.2018486135398, 1081.9026287634151, 1092.322759888874, 10393.480325983746, 10610.628627997983, 10637.734056648645, 10666.786664266749, 10684.626038088629, 3867.0719027433215, 4217.0, 4313.029568347097, 4426.800793964215, 4440.68915175941, 8745.781355466164, 9127.269818414643, 9206.493642849708, 9207.171354577986, 9231.492267925329, 4604.608608268268, 5077.1876499759865, 5120.359955005624, 5122.308488354099, 5124.948750512522, 4184.518780340239, 4402.185595664812, 4667.813287468532, 4668.766561671903, 4678.742669153229, 3693.759905606127, 3721.4789929409903, 3777.8866634001024, 3827.024108852225, 3827.8086095695103, 1250.64929875734, 1253.717913469462, 1260.4580030586753, 1271.5358894003807, 1276.9361531923366, 840.218596705058, 850.566211232276, 871.6992637540038, 875.2079368171687, 893.6872094766779, 5146.0702236661255, 5260.158374660036, 5497.038018346773, 5567.66594004361, 5601.8599535011135, 896.1934259166671, 937.6952490440634, 943.528235882059, 961.7307153996892, 981.4258658684606, 3735.9626403736156, 3740.4389341598976, 3750.718696097749, 3845.3655146900446, 3862.0, 9276.860847087299, 9286.024967378327, 9580.808383832424, 9618.855717164108, 9907.157891579152, 9726.659566915125, 9728.902710972941, 9752.804943901225, 9757.36577122499, 9766.316357855025, 7363.263673632701, 7683.270089341504, 7727.65225564851, 7737.833406646036, 7739.955106060723, 5449.128139497661, 5485.835424937259, 5534.1975413565615, 5653.519450846724, 5664.207011018379, 8865.335099867665, 8990.910090899008, 9022.0, 9091.31815113856, 9131.269498440242, 9138.31462640318, 9147.451152930846, 9165.633374664938, 9167.220786233245, 9346.485943273185, 6520.934790652223, 6710.295418981132, 6838.692258848463, 7023.157221133497, 7178.5692858428665, 7666.0, 7800.45396822217, 7857.9214207857185, 7868.134505204309, 7873.645685944261, 6939.757108501179, 6974.930250697428, 7002.474814388839, 7025.718971241093, 7187.0, 5794.62334948227, 6930.5148639594745, 6972.930270697431, 7020.7542736006035, 7033.894491582528, 6361.61830290184, 6437.678116094083, 6497.707603157774, 6512.283648798533, 6514.674266286762, 4568.862934111982, 4634.609617114919, 4640.93038604428, 4658.813647454064, 4687.554682305175, 4293.592108749664, 4624.1445332613575, 4676.672632915664, 4719.362886010482, 4770.546798054179], "value": 4323.70781665262, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [302.3212887068531, 306.2573259844877, 307.5141276782684, 310.1950438611802, 312.69512225580047, 4476.350929115284, 4942.802287908484, 5452.672839629625, 5561.165825126233, 5590.763694522193, 4747.382840230765, 5471.206675032118, 5505.3393592768825, 5516.393196748364, 5558.77764889405, 3233.9353212935725, 3971.3844354125085, 4012.2376748417705, 4094.406311084891, 4109.589041095882, 3609.9458508122266, 4153.958460415387, 4154.646855017327, 4162.250794856925, 4199.832006719727, 3911.3155197840406, 3976.6222208890254, 4120.958790412087, 4166.541680415152, 4176.477940257468, 3794.9184482422515, 3844.7308688391686, 3855.74937629056, 4089.9386509202386, 4152.6055024772595, 3589.228315912065, 3596.0332725501567, 3608.1881576645305, 3611.9638803611892, 3655.3603119454256, 4413.558644135593, 4878.365812444395, 5331.360236771573, 5340.652857564246, 5437.945620543784, 4620.283856002325, 4807.663463557552, 4817.494902650602, 4824.493428190025, 4871.634627402959, 5710.917283617584, 5833.620814647077, 5838.299404071496, 5862.413758624146, 5877.707693999344, 4017.296973029708, 4036.3945408188715, 4051.9392209116886, 4054.2904991626347, 4060.0, 5633.549316054747, 5733.913991290134, 5915.881682366372, 6024.855277497305, 6160.2915664698685, 3897.6881849451975, 3903.219356128729, 3913.845415602404, 3940.271049855786, 3941.964687190847, 3425.9486107708403, 3584.283143371284, 3973.2252210818615, 3982.2234664240773, 3993.4409182714444, 4665.9501612137, 4705.929411058837, 4721.527847215251, 4745.667803253739, 4749.715017098985, 4364.585364390377, 5091.821786237538, 5547.417521160301, 5888.676122813201, 5945.435183657545, 1345.8627459796455, 1368.473137841938, 1372.457879137735, 1379.9931000344934, 1383.3843939447052, 741.7848823841133, 798.6525861250375, 859.0421679827073, 881.6649673124172, 922.589447695769, 4826.855194344175, 4927.827526036572, 5353.894741050181, 5500.697461639648, 5516.0, 5687.7156142192725, 5772.971135144381, 5968.522518198533, 6044.667543285162, 6064.787732429344, 4782.808687652525, 4922.507749225048, 5121.769520371592, 5464.672119672832, 5514.531264842453, 2690.367763575597, 2818.42222344423, 2853.615996241821, 2861.0987538925174, 3005.3238021445, 5287.769784172647, 5598.888022239533, 6049.78825741097, 6058.0, 6069.453749162597, 4990.451050384455, 5180.481951804864, 5253.684778913277, 5486.052451379703, 5932.970335148297, 5171.560417364566, 5734.627249228954, 5766.52953496864, 5807.912881306868, 6065.848353791191, 5185.27406163145, 5339.0, 5617.640590951892, 5766.394528574439, 5792.4497172768515, 4718.693284936468, 6001.219841420588, 6054.576179667381, 6056.091586262096, 6065.120557519223, 5014.456134744194, 5214.895702085862, 5533.197686335539, 6205.627662340094, 6414.166158399565, 3426.674465925733, 3531.5585551806025, 3854.383298672199, 3902.765834049966, 4000.3399439093387, 5260.0, 6037.27552693662, 6073.969630151821, 6085.300190478151, 6126.540509461897, 5096.643234973516, 5878.441548053099, 5910.261217347832, 5952.404759524013, 5961.254843144607, 4639.559241872017, 5503.917441238465, 5833.620814647034, 5846.795362162304, 5887.381824908323, 4811.903761924812, 5813.709314534171, 5828.6502809831545, 5846.619969701956, 5856.765729370948, 5113.335266415491, 5150.6137039721425, 5611.512949068582, 5707.4577915098, 5883.529317654492, 4479.14115642018, 4830.81644996978, 5698.8290351289525, 5714.799982000776, 5733.327338219338], "value": 4161.278611484422, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [687.9036934829124, 708.4863473981364, 728.3699599846133, 735.5182355557112, 744.7542311037358, 13879.375428105734, 14780.704385912302, 14805.259737013146, 14810.629734256636, 14829.480968166117, 13283.535076272332, 13294.800577991313, 13344.199348039125, 13391.196528208315, 13436.798448023288, 629.0375725140519, 655.5640499068138, 699.1679900917892, 776.9689212431496, 799.8280369720524, 673.387217631955, 799.8520273749375, 809.8299357135008, 811.2495941254346, 812.1229072601606, 862.7670528957209, 863.4560227056945, 863.5941107679412, 864.023653271802, 865.4893612768466, 651.5016012750202, 742.9925700743031, 831.501099340397, 843.3759018326425, 862.1378621378622, 638.5530128909735, 638.7987783848073, 638.9233292004989, 639.0350570638323, 640.0271587187449, 11070.335779853154, 13040.739185216242, 13365.532206372827, 13388.531401400904, 13515.526956556474, 12689.175203611736, 13292.202467851863, 13339.599812005656, 13427.12723672958, 13452.327383630876, 10741.785164296749, 10807.4055926924, 10811.297265677706, 10829.29609575375, 11014.148945183457, 2937.8384188869604, 3621.149029977953, 3647.7628954118163, 3653.3789255826573, 3705.314516814398, 61221.69389153026, 90246.0, 91841.54079229628, 93346.0, 94534.52732736361, 10583.0, 10600.0, 10640.201984851166, 10666.093382062612, 10732.463376831127, 20317.898410507853, 20552.486187845425, 20568.588628227353, 20576.485587860123, 20605.89697051535, 7334.779956601311, 7445.106587209461, 7526.360259378014, 7546.886796698054, 7703.653335599912, 3940.408938659224, 4015.404006436566, 4033.3344998075577, 4034.6570541503716, 4105.487390075954, 3244.8864289749745, 3336.966630333714, 3338.2822693121097, 3351.6648335166774, 3358.009387230773, 592.8784599157162, 613.9017757158833, 625.7027911741886, 625.8122563231012, 627.4885967936117, 623.6694551887507, 631.0271499925801, 631.4474834519796, 635.8124353315691, 641.197431240009, 7070.39901608369, 8703.390762646553, 8772.736817895473, 9105.271578273721, 9202.401843880127, 508.49404842182264, 560.1905246918245, 577.4341145677259, 615.0528186592686, 621.3134486392562, 4227.427669804151, 4276.550962148992, 4389.31965545341, 4487.147441986012, 4528.682992190581, 89755.55122224348, 101216.98783012223, 115165.0, 119319.40340298417, 121059.39470302768, 77888.0, 79397.60301198457, 104807.0, 105239.4738026305, 106267.46866265619, 91720.62419063719, 92270.53864730634, 93819.0, 94180.52909735407, 94420.0, 8626.439281446937, 8630.741077767678, 8655.0479447262, 8664.523451210243, 8793.164649358301, 50199.74900125476, 52447.475525244256, 54501.63745906384, 60073.699631501564, 61398.69300653468, 56919.14621280767, 61559.38440615537, 66802.3319766796, 72123.0, 76873.23126768884, 10854.565817367533, 12266.877331226573, 12450.128491005542, 12525.874741252472, 12987.415566299727, 13895.930520347334, 14125.223112728898, 14390.280485975869, 14481.203533805741, 14731.337089831197, 13037.82659560644, 13135.21188728641, 13461.730765384833, 13498.797518037434, 13665.0, 13453.0, 13471.849531033584, 13483.932580337036, 13488.393022314216, 13873.125102402246, 12545.018904203815, 12622.368881556069, 12827.935860320638, 13068.346582671018, 13234.0, 9861.408315501092, 9966.700998970042, 9976.60093596248, 10124.088832005154, 10158.39049657023, 8565.77882973282, 10189.54147063369, 10251.28241023151, 11402.82895756581, 11498.597549085742], "value": 7113.221328837691, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1564.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 159.0, 116.0, 117.0, 120.0, 117.0, 118.0, 119.0, 116.0, 116.0], "value": 121.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 113.0, 114.0, 149.0, 119.0, 123.0, 116.0, 116.0, 143.0, 137.0], "value": 127.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 193.0, 201.0, 218.0, 208.0, 195.0, 195.0, 194.0, 197.0, 195.0], "value": 199.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 58.0, 59.0, 83.0, 60.0, 59.0, 62.0, 84.0, 81.0, 60.0], "value": 69.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 78.0, 70.0, 93.0, 97.0, 60.0, 115.0, 90.0, 91.0, 123.0], "value": 90.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 87.0, 88.0, 85.0, 89.0, 86.0, 89.0, 88.0, 100.0, 86.0], "value": 88.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 119.0, 153.0, 111.0, 95.0, 100.0, 103.0, 161.0, 112.0], "value": 114.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 145.0, 149.0, 94.0, 93.0, 92.0, 95.0, 91.0, 92.0, 95.0], "value": 106.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 57.0, 57.0, 58.0, 57.0, 57.0, 65.0, 57.0, 58.0, 58.0], "value": 58.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 79.0, 78.0, 74.0, 70.0, 59.0, 102.0, 109.0, 105.0], "value": 81.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 91.0, 87.0, 88.0, 86.0, 92.0, 89.0, 95.0, 105.0, 92.0], "value": 91.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 154.0, 145.0, 190.0, 150.0, 148.0, 148.0, 152.0, 196.0, 153.0], "value": 158.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 177.0, 174.0, 174.0, 176.0, 179.0, 176.0, 177.0, 175.0, 174.0], "value": 176.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 70.0, 67.0, 67.0, 77.0, 95.0, 89.0, 90.0, 91.0, 87.0], "value": 80.2, "unit": "score"}]}]} 16:51:48 INFO - Return code: 0 16:51:48 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:51:48 ERROR - # TBPL SUCCESS # 16:51:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:51:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:51:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:51:48 INFO - 2016-11-16 16:51:48.552 screenresolution[1656:10559] starting screenresolution argv=screenresolution get 16:51:48 INFO - 2016-11-16 16:51:48.555 screenresolution[1656:10559] Display 0: 1600x1200x32@60 16:51:48 INFO - 2016-11-16 16:51:48.561 screenresolution[1657:10562] starting screenresolution argv=screenresolution list 16:51:48 INFO - Available Modes on Display 0 16:51:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:51:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:51:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:51:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:51:48 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:51:48 INFO - Intel Iris: 16:51:48 INFO - Chipset Model: Intel Iris 16:51:48 INFO - Type: GPU 16:51:48 INFO - Bus: Built-In 16:51:48 INFO - VRAM (Dynamic, Max): 1536 MB 16:51:48 INFO - Vendor: Intel (0x8086) 16:51:48 INFO - Device ID: 0x0a2e 16:51:48 INFO - Revision ID: 0x0009 16:51:48 INFO - Displays: 16:51:48 INFO - Display: 16:51:48 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:51:48 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:51:48 INFO - Display Serial Number: 1600x1200 60 16:51:48 INFO - Main Display: Yes 16:51:48 INFO - Mirror: Off 16:51:48 INFO - Online: Yes 16:51:48 INFO - Rotation: Supported 16:51:48 INFO - Return code: 0 16:51:48 INFO - Running post-action listener: _resource_record_post_action 16:51:48 INFO - [mozharness: 2016-11-17 00:51:48.795765Z] Finished run-tests step (success) 16:51:48 INFO - Running post-run listener: _resource_record_post_run 16:51:48 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 38595072; Write bytes: 703455232; Read time: 397; Write time: 2007 16:51:48 INFO - TinderboxPrint: CPU usage
54.4% 16:51:48 INFO - TinderboxPrint: I/O read bytes / time
38,595,072 / 397 16:51:48 INFO - TinderboxPrint: I/O write bytes / time
703,455,232 / 2,007 16:51:48 INFO - TinderboxPrint: CPU idle
2,210.1 (45.6%) 16:51:48 INFO - TinderboxPrint: CPU system
980.4 (20.2%) 16:51:48 INFO - TinderboxPrint: CPU user
1,651.2 (34.1%) 16:51:48 INFO - TinderboxPrint: Swap in / out
437,731,328 / 0 16:51:48 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 381686272; Write bytes: 376804864; Read time: 28148; Write time: 768 16:51:48 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 37669376; Write bytes: 300878336; Read time: 384; Write time: 1184 16:51:49 INFO - Running post-run listener: _upload_blobber_files 16:51:49 INFO - Blob upload gear active. 16:51:49 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:51:49 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 16:51:49 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', 'Graphics', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:51:49 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 Graphics -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:51:49 INFO - (blobuploader) - INFO - Open directory for files ... 16:51:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:51:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:51:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:51:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:51:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:51:50 INFO - (blobuploader) - INFO - Done attempting. 16:51:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:51:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:51:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:51:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:51:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:51:51 INFO - (blobuploader) - INFO - Done attempting. 16:51:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:51:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:51:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:51:54 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:51:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:51:54 INFO - (blobuploader) - INFO - Done attempting. 16:51:54 INFO - (blobuploader) - INFO - Iteration through files over. 16:51:54 INFO - Return code: 0 16:51:54 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:51:54 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:51:54 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/7e56a2c8b882eabac9b95147671450c0001ca0c33bf030e2b0f66b0146702e0f85c8ec2223a1a6ddbac8d904aaeeed648058e1f5b896ea06326351c0e6060fd4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/d36893771ef00e40ef97ba2c41865b1ebf80b2a9e5e0f2c7ef4c4b2e06272c5484d6eb5c4fadd6764eaee3f2e0a0c5d9015f84b37bf01b5ae44dbf7800ec2162", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/4c2aefb6c7706f02e9d117fb8863134d542f128e32ffe52e63623ac776c881db506bb9ae59cf2d50376ac7ea5409d1f08b4357d540565edf5af07be5eff964b3"} 16:51:54 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:51:54 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:51:54 INFO - Contents: 16:51:54 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/7e56a2c8b882eabac9b95147671450c0001ca0c33bf030e2b0f66b0146702e0f85c8ec2223a1a6ddbac8d904aaeeed648058e1f5b896ea06326351c0e6060fd4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/d36893771ef00e40ef97ba2c41865b1ebf80b2a9e5e0f2c7ef4c4b2e06272c5484d6eb5c4fadd6764eaee3f2e0a0c5d9015f84b37bf01b5ae44dbf7800ec2162", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/4c2aefb6c7706f02e9d117fb8863134d542f128e32ffe52e63623ac776c881db506bb9ae59cf2d50376ac7ea5409d1f08b4357d540565edf5af07be5eff964b3"} 16:51:54 INFO - Running post-run listener: copy_logs_to_upload_dir 16:51:54 INFO - Copying logs to upload dir... 16:51:54 INFO - mkdir: /builds/slave/test/build/upload/logs 16:51:54 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.929216 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-11-16 16:51:54.219998) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-16 16:51:54.223270) ========= 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.wO3xnhE7Wi/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.P8kpWEIE99/Listeners TMPDIR=/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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/Graphics/sha512/7e56a2c8b882eabac9b95147671450c0001ca0c33bf030e2b0f66b0146702e0f85c8ec2223a1a6ddbac8d904aaeeed648058e1f5b896ea06326351c0e6060fd4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/d36893771ef00e40ef97ba2c41865b1ebf80b2a9e5e0f2c7ef4c4b2e06272c5484d6eb5c4fadd6764eaee3f2e0a0c5d9015f84b37bf01b5ae44dbf7800ec2162", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/4c2aefb6c7706f02e9d117fb8863134d542f128e32ffe52e63623ac776c881db506bb9ae59cf2d50376ac7ea5409d1f08b4357d540565edf5af07be5eff964b3"} build_url:https://queue.taskcluster.net/v1/task/DUesqtwSSKipEzq01bzPEw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011038 build_url: 'https://queue.taskcluster.net/v1/task/DUesqtwSSKipEzq01bzPEw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/7e56a2c8b882eabac9b95147671450c0001ca0c33bf030e2b0f66b0146702e0f85c8ec2223a1a6ddbac8d904aaeeed648058e1f5b896ea06326351c0e6060fd4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/d36893771ef00e40ef97ba2c41865b1ebf80b2a9e5e0f2c7ef4c4b2e06272c5484d6eb5c4fadd6764eaee3f2e0a0c5d9015f84b37bf01b5ae44dbf7800ec2162", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/4c2aefb6c7706f02e9d117fb8863134d542f128e32ffe52e63623ac776c881db506bb9ae59cf2d50376ac7ea5409d1f08b4357d540565edf5af07be5eff964b3"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-16 16:51:54.256701) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-16 16:51:54.257096) ========= 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.wO3xnhE7Wi/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.P8kpWEIE99/Listeners TMPDIR=/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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.005164 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-16 16:51:54.305884) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-16 16:51:54.306237) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-16 16:51:54.306587) ========= ========= Total master_lag: 0.17 =========