builder: autoland_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0296 starttime: 1476831252.51 results: success (0) buildid: 20161018132202 builduid: 7e79fb9d326844dfb7750166419998a3 revision: 3ce14ff1acd5816d957b93135174b86d1cb63afa ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-18 15:54:12.514210) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-18 15:54:12.514692) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-18 15:54:12.514964) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-18 15:54:12.542157) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 15:54:12.542547) ========= 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.CVkHpipyhk/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.aXTUrc26FD/Listeners TMPDIR=/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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-10-18 15:54:12-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 46.8M=0s 2016-10-18 15:54:12 (46.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.095823 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 15:54:12.660654) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 15:54:12.661054) ========= 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.CVkHpipyhk/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.aXTUrc26FD/Listeners TMPDIR=/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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.024611 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 15:54:12.709155) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 15:54:12.709523) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 3ce14ff1acd5816d957b93135174b86d1cb63afa --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 3ce14ff1acd5816d957b93135174b86d1cb63afa --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.CVkHpipyhk/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.aXTUrc26FD/Listeners TMPDIR=/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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-10-18 15:54:12,794 truncating revision to first 12 chars 2016-10-18 15:54:12,794 Setting DEBUG logging. 2016-10-18 15:54:12,794 attempt 1/10 2016-10-18 15:54:12,794 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/3ce14ff1acd5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-18 15:54:13,174 unpacking tar archive at: autoland-3ce14ff1acd5/testing/mozharness/ program finished with exit code 0 elapsedTime=0.647262 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 15:54:13.379295) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-18 15:54:13.379634) ========= script_repo_revision: 3ce14ff1acd5816d957b93135174b86d1cb63afa ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-18 15:54:13.380140) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-18 15:54:13.380460) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-18 15:54:13.399128) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-10-18 15:54:13.399814) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 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', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.CVkHpipyhk/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.aXTUrc26FD/Listeners TMPDIR=/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 15:54:13 INFO - MultiFileLogger online at 20161018 15:54:13 in /builds/slave/test 15:54:13 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 15:54:13 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:54:13 INFO - {'append_to_log': False, 15:54:13 INFO - 'base_work_dir': '/builds/slave/test', 15:54:13 INFO - 'blob_upload_branch': 'Autoland', 15:54:13 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:54:13 INFO - 'branch': 'Autoland', 15:54:13 INFO - 'buildbot_json_path': 'buildprops.json', 15:54:13 INFO - 'config_files': ('talos/mac_config.py',), 15:54:13 INFO - 'default_actions': ('clobber', 15:54:13 INFO - 'read-buildbot-config', 15:54:13 INFO - 'download-and-extract', 15:54:13 INFO - 'populate-webroot', 15:54:13 INFO - 'create-virtualenv', 15:54:13 INFO - 'install', 15:54:13 INFO - 'run-tests'), 15:54:13 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:54:13 INFO - 'download_minidump_stackwalk': True, 15:54:13 INFO - 'download_symbols': 'ondemand', 15:54:13 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:54:13 INFO - 'tooltool.py': '/tools/tooltool.py', 15:54:13 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:54:13 INFO - '/tools/misc-python/virtualenv.py')}, 15:54:13 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:54:13 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:54:13 INFO - 'installer_path': 'installer.exe', 15:54:13 INFO - 'log_level': 'info', 15:54:13 INFO - 'log_name': 'talos', 15:54:13 INFO - 'log_to_console': True, 15:54:13 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:54:13 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:54:13 INFO - 'opt_config_files': (), 15:54:13 INFO - 'pip_index': False, 15:54:13 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:54:13 INFO - 'cmd': ('bash', 15:54:13 INFO - '-c', 15:54:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:54:13 INFO - 'enabled': True, 15:54:13 INFO - 'halt_on_failure': False, 15:54:13 INFO - 'name': 'check_screen_resolution'},), 15:54:13 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:54:13 INFO - 'cmd': ('bash', 15:54:13 INFO - '-c', 15:54:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:54:13 INFO - 'enabled': True, 15:54:13 INFO - 'halt_on_failure': False, 15:54:13 INFO - 'name': 'check_screen_resolution'},), 15:54:13 INFO - 'run_cmd_checks_enabled': True, 15:54:13 INFO - 'sps_profile': False, 15:54:13 INFO - 'sps_profile_interval': 0, 15:54:13 INFO - 'suite': 'dromaeojs-e10s', 15:54:13 INFO - 'system_bits': '32', 15:54:13 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:54:13 INFO - 'title': 't-yosemite-r7-0296', 15:54:13 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:54:13 INFO - 'use_talos_json': True, 15:54:13 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:54:13 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:54:13 INFO - 'work_dir': 'build'} 15:54:13 INFO - [mozharness: 2016-10-18 22:54:13.605472Z] Running clobber step. 15:54:13 INFO - Running pre-action listener: _resource_record_pre_action 15:54:13 INFO - Running main action method: clobber 15:54:13 INFO - rmtree: /builds/slave/test/build 15:54:13 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:54:15 INFO - Running post-action listener: _resource_record_post_action 15:54:15 INFO - [mozharness: 2016-10-18 22:54:15.601641Z] Finished clobber step (success) 15:54:15 INFO - [mozharness: 2016-10-18 22:54:15.601761Z] Running read-buildbot-config step. 15:54:15 INFO - Running pre-action listener: _resource_record_pre_action 15:54:15 INFO - Running main action method: read_buildbot_config 15:54:15 INFO - Using buildbot properties: 15:54:15 INFO - { 15:54:15 INFO - "project": "", 15:54:15 INFO - "product": "firefox", 15:54:15 INFO - "script_repo_revision": "production", 15:54:15 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs-e10s", 15:54:15 INFO - "repository": "", 15:54:15 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs-e10s", 15:54:15 INFO - "buildid": "20161018132202", 15:54:15 INFO - "pgo_build": "False", 15:54:15 INFO - "basedir": "/builds/slave/test", 15:54:15 INFO - "buildnumber": 203, 15:54:15 INFO - "slavename": "t-yosemite-r7-0296", 15:54:15 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 15:54:15 INFO - "platform": "yosemite_r7", 15:54:15 INFO - "scheduler": "tests-autoland-macosx64-talos-8-3600", 15:54:15 INFO - "branch": "autoland", 15:54:15 INFO - "repo_path": "integration/autoland", 15:54:15 INFO - "revision": "3ce14ff1acd5816d957b93135174b86d1cb63afa", 15:54:15 INFO - "stage_platform": "macosx64", 15:54:15 INFO - "builduid": "7e79fb9d326844dfb7750166419998a3", 15:54:15 INFO - "slavebuilddir": "test" 15:54:15 INFO - } 15:54:15 INFO - Found installer url https://queue.taskcluster.net/v1/task/WbqCzpGrTmWbmO2oCt2o7g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 15:54:15 INFO - Running post-action listener: _resource_record_post_action 15:54:15 INFO - [mozharness: 2016-10-18 22:54:15.605197Z] Finished read-buildbot-config step (success) 15:54:15 INFO - [mozharness: 2016-10-18 22:54:15.605315Z] Running download-and-extract step. 15:54:15 INFO - Running pre-action listener: _resource_record_pre_action 15:54:15 INFO - Running main action method: download_and_extract 15:54:15 INFO - mkdir: /builds/slave/test/build/tests 15:54:15 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')]} 15:54:15 INFO - trying https://queue.taskcluster.net/v1/task/WbqCzpGrTmWbmO2oCt2o7g/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 15:54:15 INFO - Downloading https://queue.taskcluster.net/v1/task/WbqCzpGrTmWbmO2oCt2o7g/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 15:54:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WbqCzpGrTmWbmO2oCt2o7g/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 15:54:16 INFO - Downloaded 1199 bytes. 15:54:16 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 15:54:16 INFO - Using the following test package requirements: 15:54:16 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 15:54:16 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:54:16 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 15:54:16 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:54:16 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 15:54:16 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:54:16 INFO - u'jsshell-mac.zip'], 15:54:16 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:54:16 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 15:54:16 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 15:54:16 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:54:16 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 15:54:16 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:54:16 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 15:54:16 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:54:16 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 15:54:16 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:54:16 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 15:54:16 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 15:54:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WbqCzpGrTmWbmO2oCt2o7g/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 15:54:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WbqCzpGrTmWbmO2oCt2o7g/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:54:16 INFO - Fetch https://queue.taskcluster.net/v1/task/WbqCzpGrTmWbmO2oCt2o7g/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 15:54:17 INFO - Http code: 200 15:54:17 INFO - via: 1.1 d2bd759914e30b1d5aee2929535c55f9.cloudfront.net (CloudFront) 15:54:17 INFO - x-amz-cf-id: CSfSUX8Ae1QxsuZKsyFI01UoVU08in3IYyUoZVIM1PAMm-RbxUhB1g== 15:54:17 INFO - x-amz-version-id: oCfrlpVc5U1G_WJAZCckGbTaL_rLGqlQ 15:54:17 INFO - Expected file size: 16483717 15:54:17 INFO - Obtained file size: 16483717 15:54:24 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 15:54:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WbqCzpGrTmWbmO2oCt2o7g/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 15:54:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WbqCzpGrTmWbmO2oCt2o7g/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:54:24 INFO - Fetch https://queue.taskcluster.net/v1/task/WbqCzpGrTmWbmO2oCt2o7g/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 15:54:24 INFO - Http code: 200 15:54:24 INFO - via: 1.1 441811a054e8d055b893175754efd0c3.cloudfront.net (CloudFront) 15:54:24 INFO - x-amz-cf-id: 7bfNKfbKYZGv-4YUPTZJIk2N2lr0pBk7SIhPCbatDTIpLYsl-qTOhA== 15:54:24 INFO - x-amz-version-id: oCfrlpVc5U1G_WJAZCckGbTaL_rLGqlQ 15:54:25 INFO - Expected file size: 16483717 15:54:25 INFO - Obtained file size: 16483717 15:54:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WbqCzpGrTmWbmO2oCt2o7g/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 15:54:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WbqCzpGrTmWbmO2oCt2o7g/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 15:54:31 INFO - Fetch https://queue.taskcluster.net/v1/task/WbqCzpGrTmWbmO2oCt2o7g/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 15:54:32 INFO - Http code: 200 15:54:32 INFO - via: 1.1 9552af4ceb2e0dd55964a80d1e549fd5.cloudfront.net (CloudFront) 15:54:32 INFO - x-amz-cf-id: z6Gl3Rcq7R1ZFFYPD7a0WEZTJgj1SYxyrao5OZdxws7bN6UNS_8f6w== 15:54:32 INFO - x-amz-version-id: asstljzpMobM0wJ5DYbZ4Y0diB6uX_bc 15:54:32 INFO - Expected file size: 14409028 15:54:32 INFO - Obtained file size: 14409028 15:54:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:54:32 INFO - trying https://queue.taskcluster.net/v1/task/WbqCzpGrTmWbmO2oCt2o7g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 15:54:32 INFO - Downloading https://queue.taskcluster.net/v1/task/WbqCzpGrTmWbmO2oCt2o7g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 15:54:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WbqCzpGrTmWbmO2oCt2o7g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 15:54:35 INFO - Downloaded 111627436 bytes. 15:54:35 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/WbqCzpGrTmWbmO2oCt2o7g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 15:54:35 INFO - mkdir: /builds/slave/test/properties 15:54:35 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:54:35 INFO - Writing to file /builds/slave/test/properties/build_url 15:54:35 INFO - Contents: 15:54:35 INFO - build_url:https://queue.taskcluster.net/v1/task/WbqCzpGrTmWbmO2oCt2o7g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 15:54:36 INFO - Running post-action listener: _resource_record_post_action 15:54:36 INFO - Running post-action listener: set_extra_try_arguments 15:54:36 INFO - [mozharness: 2016-10-18 22:54:36.538699Z] Finished download-and-extract step (success) 15:54:36 INFO - [mozharness: 2016-10-18 22:54:36.538825Z] Running populate-webroot step. 15:54:36 INFO - Running pre-action listener: _resource_record_pre_action 15:54:36 INFO - Running main action method: populate_webroot 15:54:36 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:54:36 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:54:36 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:54:36 INFO - '--tppagecycles', 15:54:36 INFO - '7'], 15:54:36 INFO - 'tests': ['tsvgm']}}, 15:54:36 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:54:36 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:54:36 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:54:36 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:54:36 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:54:36 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:54:36 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:54:36 INFO - 'talos_options': ['--disable-e10s'], 15:54:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:54:36 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:54:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:54:36 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:54:36 INFO - 'talos_options': ['--disable-e10s'], 15:54:36 INFO - 'tests': ['damp', 'tps']}, 15:54:36 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:54:36 INFO - 'tests': ['damp', 'tps']}, 15:54:36 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:54:36 INFO - 'tests': ['dromaeo_dom']}, 15:54:36 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:54:36 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:54:36 INFO - 'tests': ['basic_compositor_video']}, 15:54:36 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:54:36 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:54:36 INFO - 'tests': ['a11yr', 15:54:36 INFO - 'ts_paint', 15:54:36 INFO - 'tpaint', 15:54:36 INFO - 'sessionrestore', 15:54:36 INFO - 'sessionrestore_no_auto_restore', 15:54:36 INFO - 'tabpaint']}, 15:54:36 INFO - 'other-e10s': {'tests': ['a11yr', 15:54:36 INFO - 'ts_paint', 15:54:36 INFO - 'tpaint', 15:54:36 INFO - 'sessionrestore', 15:54:36 INFO - 'sessionrestore_no_auto_restore', 15:54:36 INFO - 'tabpaint']}, 15:54:36 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:54:36 INFO - 'ts_paint', 15:54:36 INFO - 'tpaint', 15:54:36 INFO - 'sessionrestore', 15:54:36 INFO - 'sessionrestore_no_auto_restore', 15:54:36 INFO - 'tabpaint']}, 15:54:36 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:54:36 INFO - 'ts_paint', 15:54:36 INFO - 'tpaint', 15:54:36 INFO - 'sessionrestore', 15:54:36 INFO - 'sessionrestore_no_auto_restore', 15:54:36 INFO - 'tabpaint']}, 15:54:36 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:54:36 INFO - 'tests': ['a11yr', 15:54:36 INFO - 'ts_paint', 15:54:36 INFO - 'tpaint', 15:54:36 INFO - 'sessionrestore', 15:54:36 INFO - 'sessionrestore_no_auto_restore', 15:54:36 INFO - 'tabpaint']}, 15:54:36 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:54:36 INFO - 'tests': ['a11yr', 15:54:36 INFO - 'ts_paint', 15:54:36 INFO - 'tpaint', 15:54:36 INFO - 'sessionrestore', 15:54:36 INFO - 'sessionrestore_no_auto_restore', 15:54:36 INFO - 'tabpaint']}, 15:54:36 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:54:36 INFO - 'tests': ['tsvgx', 15:54:36 INFO - 'tsvgr_opacity', 15:54:36 INFO - 'tart', 15:54:36 INFO - 'tscrollx', 15:54:36 INFO - 'cart']}, 15:54:36 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:54:36 INFO - 'tsvgr_opacity', 15:54:36 INFO - 'tart', 15:54:36 INFO - 'tscrollx', 15:54:36 INFO - 'cart']}, 15:54:36 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:54:36 INFO - 'talos_options': ['--disable-e10s'], 15:54:36 INFO - 'tests': ['tp5o']}, 15:54:36 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:54:36 INFO - 'tests': ['tp5o']}, 15:54:36 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:54:36 INFO - 'talos_options': ['--disable-e10s', 15:54:36 INFO - '--xperf_path', 15:54:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:54:36 INFO - 'tests': ['tp5n']}, 15:54:36 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:54:36 INFO - 'talos_options': ['--xperf_path', 15:54:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:54:36 INFO - 'tests': ['tp5n']}}, 15:54:36 INFO - 'talos.zip': {'path': '', 15:54:36 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:54:36 INFO - Running post-action listener: _resource_record_post_action 15:54:36 INFO - [mozharness: 2016-10-18 22:54:36.557278Z] Finished populate-webroot step (success) 15:54:36 INFO - [mozharness: 2016-10-18 22:54:36.557409Z] Running create-virtualenv step. 15:54:36 INFO - Running pre-action listener: _resource_record_pre_action 15:54:36 INFO - Running main action method: create_virtualenv 15:54:36 INFO - Creating virtualenv /builds/slave/test/build/venv 15:54:36 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 15:54:36 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:54:36 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:54:36 INFO - Using real prefix '/tools/python27' 15:54:36 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:54:37 INFO - Installing distribute.............................................................................................................................................................................................done. 15:54:40 INFO - Installing pip.................done. 15:54:40 INFO - Return code: 0 15:54:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:54:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:54:40 INFO - Reading from file tmpfile_stdout 15:54:40 INFO - Output received: 15:54:40 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) 15:54:40 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:54:40 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')]} 15:54:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:54:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:40 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')]} 15:54:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:54:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:40 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 0x10f27f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f1f5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7b2d6ae50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f093d30>, '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 0x10eeb9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f08bb20>, '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.CVkHpipyhk/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.aXTUrc26FD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 15:54:40 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 15:54:40 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 15:54:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CVkHpipyhk/Render', 15:54:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:54:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:54:40 INFO - 'HOME': '/Users/cltbld', 15:54:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:54:40 INFO - 'LOGNAME': 'cltbld', 15:54:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:54:40 INFO - 'MOZ_NO_REMOTE': '1', 15:54:40 INFO - 'NO_EM_RESTART': '1', 15:54:40 INFO - 'PAGER': '/bin/cat', 15:54:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:54:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:54:40 INFO - 'PWD': '/builds/slave/test', 15:54:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:54:40 INFO - 'SHELL': '/bin/bash', 15:54:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aXTUrc26FD/Listeners', 15:54:40 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 15:54:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:54:40 INFO - 'USER': 'cltbld', 15:54:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:54:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:54:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:54:40 INFO - 'XPC_FLAGS': '0x0', 15:54:40 INFO - 'XPC_SERVICE_NAME': '0', 15:54:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:54:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:54:40 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 15:54:40 INFO - Cleaning up... 15:54:40 INFO - Return code: 0 15:54:40 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:54:40 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')]} 15:54:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:54:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:40 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')]} 15:54:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:54:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:40 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 0x10f27f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f1f5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7b2d6ae50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f093d30>, '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 0x10eeb9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f08bb20>, '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.CVkHpipyhk/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.aXTUrc26FD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 15:54:40 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 15:54:40 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 15:54:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CVkHpipyhk/Render', 15:54:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:54:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:54:40 INFO - 'HOME': '/Users/cltbld', 15:54:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:54:40 INFO - 'LOGNAME': 'cltbld', 15:54:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:54:40 INFO - 'MOZ_NO_REMOTE': '1', 15:54:40 INFO - 'NO_EM_RESTART': '1', 15:54:40 INFO - 'PAGER': '/bin/cat', 15:54:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:54:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:54:40 INFO - 'PWD': '/builds/slave/test', 15:54:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:54:40 INFO - 'SHELL': '/bin/bash', 15:54:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aXTUrc26FD/Listeners', 15:54:40 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 15:54:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:54:40 INFO - 'USER': 'cltbld', 15:54:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:54:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:54:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:54:40 INFO - 'XPC_FLAGS': '0x0', 15:54:40 INFO - 'XPC_SERVICE_NAME': '0', 15:54:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:54:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:54:41 INFO - Downloading/unpacking psutil>=3.1.1 15:54:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:54:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:54:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:54:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:54:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:54:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:54:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:54:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:54:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:54:44 INFO - Installing collected packages: psutil 15:54:44 INFO - Running setup.py install for psutil 15:54:44 INFO - building 'psutil._psutil_osx' extension 15:54:44 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 15:54:45 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 15:54:45 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 15:54:45 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 15:54:45 INFO - building 'psutil._psutil_posix' extension 15:54:45 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 15:54:45 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:54:45 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:54:45 INFO - ^ 15:54:45 INFO - 1 warning generated. 15:54:45 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 15:54:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:54:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:54:45 INFO - Successfully installed psutil 15:54:45 INFO - Cleaning up... 15:54:45 INFO - Return code: 0 15:54:45 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:54:45 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')]} 15:54:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:54:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:45 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')]} 15:54:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:54:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:45 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 0x10f27f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f1f5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7b2d6ae50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f093d30>, '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 0x10eeb9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f08bb20>, '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.CVkHpipyhk/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.aXTUrc26FD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 15:54:45 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 15:54:45 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 15:54:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CVkHpipyhk/Render', 15:54:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:54:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:54:45 INFO - 'HOME': '/Users/cltbld', 15:54:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:54:45 INFO - 'LOGNAME': 'cltbld', 15:54:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:54:45 INFO - 'MOZ_NO_REMOTE': '1', 15:54:45 INFO - 'NO_EM_RESTART': '1', 15:54:45 INFO - 'PAGER': '/bin/cat', 15:54:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:54:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:54:45 INFO - 'PWD': '/builds/slave/test', 15:54:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:54:45 INFO - 'SHELL': '/bin/bash', 15:54:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aXTUrc26FD/Listeners', 15:54:45 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 15:54:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:54:45 INFO - 'USER': 'cltbld', 15:54:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:54:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:54:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:54:45 INFO - 'XPC_FLAGS': '0x0', 15:54:45 INFO - 'XPC_SERVICE_NAME': '0', 15:54:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:54:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:54:45 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:54:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:54:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:54:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:54:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:54:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:54:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:54:48 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:54:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:54:48 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:54:48 INFO - Installing collected packages: mozsystemmonitor 15:54:48 INFO - Running setup.py install for mozsystemmonitor 15:54:48 INFO - Successfully installed mozsystemmonitor 15:54:48 INFO - Cleaning up... 15:54:48 INFO - Return code: 0 15:54:48 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:54:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:54:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:54:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:54:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:54:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:48 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 0x10f27f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f1f5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7b2d6ae50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f093d30>, '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 0x10eeb9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f08bb20>, '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.CVkHpipyhk/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.aXTUrc26FD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 15:54:48 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 15:54:48 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 15:54:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CVkHpipyhk/Render', 15:54:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:54:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:54:48 INFO - 'HOME': '/Users/cltbld', 15:54:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:54:48 INFO - 'LOGNAME': 'cltbld', 15:54:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:54:48 INFO - 'MOZ_NO_REMOTE': '1', 15:54:48 INFO - 'NO_EM_RESTART': '1', 15:54:48 INFO - 'PAGER': '/bin/cat', 15:54:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:54:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:54:48 INFO - 'PWD': '/builds/slave/test', 15:54:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:54:48 INFO - 'SHELL': '/bin/bash', 15:54:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aXTUrc26FD/Listeners', 15:54:48 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 15:54:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:54:48 INFO - 'USER': 'cltbld', 15:54:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:54:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:54:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:54:48 INFO - 'XPC_FLAGS': '0x0', 15:54:48 INFO - 'XPC_SERVICE_NAME': '0', 15:54:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:54:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:54:49 INFO - Downloading/unpacking jsonschema==2.5.1 15:54:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:54:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:54:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:54:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:54:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:54:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:54:51 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:54:51 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:54:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:54:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:54:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:54:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:54:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:54:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:54:51 INFO - Downloading functools32-3.2.3-2.tar.gz 15:54:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:54:52 INFO - warning: no files found matching '*.txt' 15:54:52 INFO - no previously-included directories found matching 'build' 15:54:52 INFO - no previously-included directories found matching 'dist' 15:54:52 INFO - no previously-included directories found matching '.git*' 15:54:52 INFO - Installing collected packages: jsonschema, functools32 15:54:52 INFO - Running setup.py install for functools32 15:54:52 INFO - warning: no files found matching '*.txt' 15:54:52 INFO - no previously-included directories found matching 'build' 15:54:52 INFO - no previously-included directories found matching 'dist' 15:54:52 INFO - no previously-included directories found matching '.git*' 15:54:52 INFO - Successfully installed jsonschema functools32 15:54:52 INFO - Cleaning up... 15:54:52 INFO - Return code: 0 15:54:52 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:54:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:54:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:54:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:54:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:54:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:52 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 0x10f27f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f1f5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7b2d6ae50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f093d30>, '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 0x10eeb9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f08bb20>, '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.CVkHpipyhk/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.aXTUrc26FD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 15:54:52 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 15:54:52 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 15:54:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CVkHpipyhk/Render', 15:54:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:54:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:54:52 INFO - 'HOME': '/Users/cltbld', 15:54:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:54:52 INFO - 'LOGNAME': 'cltbld', 15:54:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:54:52 INFO - 'MOZ_NO_REMOTE': '1', 15:54:52 INFO - 'NO_EM_RESTART': '1', 15:54:52 INFO - 'PAGER': '/bin/cat', 15:54:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:54:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:54:52 INFO - 'PWD': '/builds/slave/test', 15:54:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:54:52 INFO - 'SHELL': '/bin/bash', 15:54:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aXTUrc26FD/Listeners', 15:54:52 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 15:54:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:54:52 INFO - 'USER': 'cltbld', 15:54:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:54:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:54:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:54:52 INFO - 'XPC_FLAGS': '0x0', 15:54:52 INFO - 'XPC_SERVICE_NAME': '0', 15:54:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:54:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:54:52 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:54:52 INFO - Cleaning up... 15:54:52 INFO - Return code: 0 15:54:52 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:54:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:54:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:54:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:54:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:54:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:52 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 0x10f27f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f1f5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7b2d6ae50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f093d30>, '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 0x10eeb9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f08bb20>, '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.CVkHpipyhk/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.aXTUrc26FD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 15:54:52 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 15:54:52 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 15:54:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CVkHpipyhk/Render', 15:54:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:54:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:54:52 INFO - 'HOME': '/Users/cltbld', 15:54:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:54:52 INFO - 'LOGNAME': 'cltbld', 15:54:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:54:52 INFO - 'MOZ_NO_REMOTE': '1', 15:54:52 INFO - 'NO_EM_RESTART': '1', 15:54:52 INFO - 'PAGER': '/bin/cat', 15:54:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:54:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:54:52 INFO - 'PWD': '/builds/slave/test', 15:54:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:54:52 INFO - 'SHELL': '/bin/bash', 15:54:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aXTUrc26FD/Listeners', 15:54:52 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 15:54:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:54:52 INFO - 'USER': 'cltbld', 15:54:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:54:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:54:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:54:52 INFO - 'XPC_FLAGS': '0x0', 15:54:52 INFO - 'XPC_SERVICE_NAME': '0', 15:54:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:54:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:54:52 INFO - Downloading/unpacking blobuploader==1.2.4 15:54:52 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 15:54:52 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 15:54:52 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 15:54:52 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 15:54:52 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 15:54:52 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 15:54:55 INFO - Downloading blobuploader-1.2.4.tar.gz 15:54:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:54:55 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:54:55 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 15:54:55 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 15:54:55 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 15:54:55 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 15:54:55 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 15:54:55 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 15:54:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:54:56 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:54:56 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 15:54:56 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 15:54:56 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 15:54:56 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 15:54:56 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 15:54:56 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 15:54:56 INFO - Downloading docopt-0.6.1.tar.gz 15:54:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:54:56 INFO - Installing collected packages: blobuploader, requests, docopt 15:54:56 INFO - Running setup.py install for blobuploader 15:54:56 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:54:56 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:54:56 INFO - Running setup.py install for requests 15:54:57 INFO - Running setup.py install for docopt 15:54:57 INFO - Successfully installed blobuploader requests docopt 15:54:57 INFO - Cleaning up... 15:54:57 INFO - Return code: 0 15:54:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:54:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:54:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:54:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:54:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:54:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:54:57 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 0x10f27f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f1f5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7b2d6ae50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f093d30>, '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 0x10eeb9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f08bb20>, '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.CVkHpipyhk/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.aXTUrc26FD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 15:54:57 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 15:54:57 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 15:54:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CVkHpipyhk/Render', 15:54:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:54:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:54:57 INFO - 'HOME': '/Users/cltbld', 15:54:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:54:57 INFO - 'LOGNAME': 'cltbld', 15:54:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:54:57 INFO - 'MOZ_NO_REMOTE': '1', 15:54:57 INFO - 'NO_EM_RESTART': '1', 15:54:57 INFO - 'PAGER': '/bin/cat', 15:54:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:54:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:54:57 INFO - 'PWD': '/builds/slave/test', 15:54:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:54:57 INFO - 'SHELL': '/bin/bash', 15:54:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aXTUrc26FD/Listeners', 15:54:57 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 15:54:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:54:57 INFO - 'USER': 'cltbld', 15:54:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:54:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:54:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:54:57 INFO - 'XPC_FLAGS': '0x0', 15:54:57 INFO - 'XPC_SERVICE_NAME': '0', 15:54:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:54:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:54:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:54:57 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-tIwBLL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:54:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:54:57 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-m5k9EP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:54:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:54:58 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-DBljvb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:54:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:54:58 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-bHzcvu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:54:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:54:58 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-dw5j63-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:54:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:54:58 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-BJFyNn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:54:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:54:58 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-dOic8A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:54:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:54:58 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-d9SdbA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:54:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:54:58 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-kFkUOS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:54:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:54:58 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-CDfdYx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:54:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:54:58 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-UXZCDQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:54:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:54:58 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-1XWCI7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:54:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:54:59 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-NJquNb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:54:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:54:59 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-jax6VX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:54:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:54:59 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-pdzq_b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:54:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:54:59 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-jX_BhX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:54:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:54:59 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-dmkdOA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:54:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:54:59 INFO - Running setup.py install for manifestparser 15:54:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:54:59 INFO - Running setup.py install for mozcrash 15:54:59 INFO - Running setup.py install for mozdebug 15:54:59 INFO - Running setup.py install for mozdevice 15:55:00 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:55:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:55:00 INFO - Running setup.py install for mozfile 15:55:00 INFO - Running setup.py install for mozhttpd 15:55:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:55:00 INFO - Running setup.py install for mozinfo 15:55:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:55:00 INFO - Running setup.py install for mozInstall 15:55:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:55:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:55:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:55:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:55:00 INFO - Running setup.py install for mozleak 15:55:00 INFO - Running setup.py install for mozlog 15:55:01 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:55:01 INFO - Running setup.py install for moznetwork 15:55:01 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:55:01 INFO - Running setup.py install for mozprocess 15:55:01 INFO - Running setup.py install for mozprofile 15:55:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:55:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:55:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:55:01 INFO - Running setup.py install for mozrunner 15:55:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:55:01 INFO - Running setup.py install for mozscreenshot 15:55:01 INFO - Running setup.py install for moztest 15:55:02 INFO - Running setup.py install for mozversion 15:55:02 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:55:02 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:55:02 INFO - Cleaning up... 15:55:02 INFO - Return code: 0 15:55:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:55:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:55:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:55:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:55:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:55:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55:02 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 0x10f27f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f1f5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7b2d6ae50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f093d30>, '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 0x10eeb9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f08bb20>, '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.CVkHpipyhk/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.aXTUrc26FD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 15:55:02 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 15:55:02 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 15:55:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CVkHpipyhk/Render', 15:55:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:55:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:55:02 INFO - 'HOME': '/Users/cltbld', 15:55:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:55:02 INFO - 'LOGNAME': 'cltbld', 15:55:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:55:02 INFO - 'MOZ_NO_REMOTE': '1', 15:55:02 INFO - 'NO_EM_RESTART': '1', 15:55:02 INFO - 'PAGER': '/bin/cat', 15:55:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:55:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:55:02 INFO - 'PWD': '/builds/slave/test', 15:55:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:55:02 INFO - 'SHELL': '/bin/bash', 15:55:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aXTUrc26FD/Listeners', 15:55:02 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 15:55:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:55:02 INFO - 'USER': 'cltbld', 15:55:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:55:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:55:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:55:02 INFO - 'XPC_FLAGS': '0x0', 15:55:02 INFO - 'XPC_SERVICE_NAME': '0', 15:55:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:55:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:55:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:55:02 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-vcDN5s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:55:02 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)) 15:55:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:55:02 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-ApmBDa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:55:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 15:55:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:55:02 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-gB31El-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:55:02 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)) 15:55:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:55:02 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-8f6mtU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:55:02 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)) 15:55:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:55:02 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-QyNNW7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:55:03 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)) 15:55:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:55:03 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-wslpFR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:55:03 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)) 15:55:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:55:03 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-0ZTEnu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:55:03 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)) 15:55:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:55:03 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-aAbYzo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:55:03 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)) 15:55:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:55:03 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-ulAR0a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:55:03 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)) 15:55:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:55:03 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-N5SG2m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:55:03 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)) 15:55:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:55:03 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-38JVu_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:55:03 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)) 15:55:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:55:03 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-w5l2oF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:55:03 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)) 15:55:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:55:03 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-BJhxzp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:55:03 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)) 15:55:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:55:03 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-XskCWe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:55:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 15:55:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:55:03 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-BujmiO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:55:04 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)) 15:55:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:55:04 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-3VnPO4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:55:04 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)) 15:55:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:55:04 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-aqQ28G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:55:04 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)) 15:55:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:55:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:55:04 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)) 15:55:04 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)) 15:55:04 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)) 15:55:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:55:04 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 15:55:04 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 15:55:04 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 15:55:04 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 15:55:04 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 15:55:04 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 15:55:06 INFO - Downloading blessings-1.6.tar.gz 15:55:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:55:06 INFO - Installing collected packages: blessings 15:55:06 INFO - Running setup.py install for blessings 15:55:07 INFO - Successfully installed blessings 15:55:07 INFO - Cleaning up... 15:55:07 INFO - Return code: 0 15:55:07 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:55:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:55:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:55:07 INFO - Reading from file tmpfile_stdout 15:55:07 INFO - Current package versions: 15:55:07 INFO - blessings == 1.6 15:55:07 INFO - blobuploader == 1.2.4 15:55:07 INFO - docopt == 0.6.1 15:55:07 INFO - functools32 == 3.2.3-2 15:55:07 INFO - jsonschema == 2.5.1 15:55:07 INFO - manifestparser == 1.1 15:55:07 INFO - mozInstall == 1.12 15:55:07 INFO - mozcrash == 0.17 15:55:07 INFO - mozdebug == 0.1 15:55:07 INFO - mozdevice == 0.48 15:55:07 INFO - mozfile == 1.2 15:55:07 INFO - mozhttpd == 0.7 15:55:07 INFO - mozinfo == 0.9 15:55:07 INFO - mozleak == 0.1 15:55:07 INFO - mozlog == 3.4 15:55:07 INFO - moznetwork == 0.27 15:55:07 INFO - mozprocess == 0.23 15:55:07 INFO - mozprofile == 0.28 15:55:07 INFO - mozrunner == 6.12 15:55:07 INFO - mozscreenshot == 0.1 15:55:07 INFO - mozsystemmonitor == 0.3 15:55:07 INFO - moztest == 0.7 15:55:07 INFO - mozversion == 1.4 15:55:07 INFO - psutil == 3.1.1 15:55:07 INFO - requests == 1.2.3 15:55:07 INFO - wsgiref == 0.1.2 15:55:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:55:07 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')]} 15:55:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:55:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55:07 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')]} 15:55:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:55:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55:07 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 0x10f27f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f1f5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7b2d6ae50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f093d30>, '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 0x10eeb9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f08bb20>, '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.CVkHpipyhk/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.aXTUrc26FD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 15:55:07 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 15:55:07 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 15:55:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CVkHpipyhk/Render', 15:55:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:55:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:55:07 INFO - 'HOME': '/Users/cltbld', 15:55:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:55:07 INFO - 'LOGNAME': 'cltbld', 15:55:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:55:07 INFO - 'MOZ_NO_REMOTE': '1', 15:55:07 INFO - 'NO_EM_RESTART': '1', 15:55:07 INFO - 'PAGER': '/bin/cat', 15:55:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:55:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:55:07 INFO - 'PWD': '/builds/slave/test', 15:55:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:55:07 INFO - 'SHELL': '/bin/bash', 15:55:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aXTUrc26FD/Listeners', 15:55:07 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 15:55:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:55:07 INFO - 'USER': 'cltbld', 15:55:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:55:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:55:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:55:07 INFO - 'XPC_FLAGS': '0x0', 15:55:07 INFO - 'XPC_SERVICE_NAME': '0', 15:55:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:55:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:55:07 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)) 15:55:07 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)) 15:55:07 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)) 15:55:07 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)) 15:55:07 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)) 15:55:07 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)) 15:55:07 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)) 15:55:07 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)) 15:55:07 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)) 15:55:07 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)) 15:55:07 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)) 15:55:07 INFO - Cleaning up... 15:55:07 INFO - Return code: 0 15:55:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:55:07 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')]} 15:55:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:55:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55:07 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')]} 15:55:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:55:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55:07 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 0x10f27f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f1f5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7b2d6ae50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f093d30>, '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 0x10eeb9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f08bb20>, '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.CVkHpipyhk/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.aXTUrc26FD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 15:55:07 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 15:55:07 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 15:55:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CVkHpipyhk/Render', 15:55:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:55:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:55:07 INFO - 'HOME': '/Users/cltbld', 15:55:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:55:07 INFO - 'LOGNAME': 'cltbld', 15:55:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:55:07 INFO - 'MOZ_NO_REMOTE': '1', 15:55:07 INFO - 'NO_EM_RESTART': '1', 15:55:07 INFO - 'PAGER': '/bin/cat', 15:55:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:55:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:55:07 INFO - 'PWD': '/builds/slave/test', 15:55:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:55:07 INFO - 'SHELL': '/bin/bash', 15:55:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aXTUrc26FD/Listeners', 15:55:07 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 15:55:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:55:07 INFO - 'USER': 'cltbld', 15:55:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:55:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:55:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:55:07 INFO - 'XPC_FLAGS': '0x0', 15:55:07 INFO - 'XPC_SERVICE_NAME': '0', 15:55:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:55:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:55:08 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:55:08 INFO - Cleaning up... 15:55:08 INFO - Return code: 0 15:55:08 INFO - Running post-action listener: _resource_record_post_action 15:55:08 INFO - Running post-action listener: _start_resource_monitoring 15:55:08 INFO - Starting resource monitoring. 15:55:08 INFO - [mozharness: 2016-10-18 22:55:08.082281Z] Finished create-virtualenv step (success) 15:55:08 INFO - [mozharness: 2016-10-18 22:55:08.083278Z] Running install step. 15:55:08 INFO - Running pre-action listener: _resource_record_pre_action 15:55:08 INFO - Running main action method: install 15:55:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:55:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:55:08 INFO - Reading from file tmpfile_stdout 15:55:08 INFO - Detecting whether we're running mozinstall >=1.0... 15:55:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:55:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:55:08 INFO - Reading from file tmpfile_stdout 15:55:08 INFO - Output received: 15:55:08 INFO - Usage: mozinstall [options] installer 15:55:08 INFO - Options: 15:55:08 INFO - -h, --help show this help message and exit 15:55:08 INFO - -d DEST, --destination=DEST 15:55:08 INFO - Directory to install application into. [default: 15:55:08 INFO - "/builds/slave/test"] 15:55:08 INFO - --app=APP Application being installed. [default: firefox] 15:55:08 INFO - mkdir: /builds/slave/test/build/application 15:55:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 15:55:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 15:55:43 INFO - Reading from file tmpfile_stdout 15:55:43 INFO - Output received: 15:55:43 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 15:55:43 INFO - Running post-action listener: _resource_record_post_action 15:55:43 INFO - [mozharness: 2016-10-18 22:55:43.394454Z] Finished install step (success) 15:55:43 INFO - [mozharness: 2016-10-18 22:55:43.394623Z] Running run-tests step. 15:55:43 INFO - Running pre-action listener: _resource_record_pre_action 15:55:43 INFO - Running main action method: run_tests 15:55:43 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:55:43 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:55:43 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:55:43 INFO - 2016-10-18 15:55:43.409 screenresolution[1641:7485] starting screenresolution argv=screenresolution get 15:55:43 INFO - 2016-10-18 15:55:43.412 screenresolution[1641:7485] Display 0: 1600x1200x32@60 15:55:43 INFO - 2016-10-18 15:55:43.417 screenresolution[1642:7487] starting screenresolution argv=screenresolution list 15:55:43 INFO - Available Modes on Display 0 15:55:43 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:55:43 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:55:43 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:55:43 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:55:43 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:55:43 INFO - Intel Iris: 15:55:43 INFO - Chipset Model: Intel Iris 15:55:43 INFO - Type: GPU 15:55:43 INFO - Bus: Built-In 15:55:43 INFO - VRAM (Dynamic, Max): 1536 MB 15:55:43 INFO - Vendor: Intel (0x8086) 15:55:43 INFO - Device ID: 0x0a2e 15:55:43 INFO - Revision ID: 0x0009 15:55:43 INFO - Displays: 15:55:43 INFO - Display: 15:55:43 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:55:43 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:55:43 INFO - Display Serial Number: 1600x1200 60 15:55:43 INFO - Main Display: Yes 15:55:43 INFO - Mirror: Off 15:55:43 INFO - Online: Yes 15:55:43 INFO - Rotation: Supported 15:55:43 INFO - Return code: 0 15:55:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:55:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:55:43 INFO - Python 2.7.3 15:55:43 INFO - Return code: 0 15:55:43 INFO - grabbing minidump binary from tooltool 15:55:43 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')]} 15:55:43 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 0x10f093d30>, '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 0x10eeb9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f08bb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:55:43 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 15:55:43 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 15:55:43 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 15:55:43 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:55:43 INFO - Return code: 0 15:55:43 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:55:43 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:55:43 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:55:43 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:55:43 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:55:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WbqCzpGrTmWbmO2oCt2o7g/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0296', '--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 15:55:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/WbqCzpGrTmWbmO2oCt2o7g/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0296 --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 15:55:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CVkHpipyhk/Render', 15:55:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:55:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:55:43 INFO - 'HOME': '/Users/cltbld', 15:55:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:55:43 INFO - 'LOGNAME': 'cltbld', 15:55:43 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:55:43 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:55:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:55:43 INFO - 'MOZ_NO_REMOTE': '1', 15:55:43 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:55:43 INFO - 'NO_EM_RESTART': '1', 15:55:43 INFO - 'PAGER': '/bin/cat', 15:55:43 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:55:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:55:43 INFO - 'PWD': '/builds/slave/test', 15:55:43 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:55:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:55:43 INFO - 'SHELL': '/bin/bash', 15:55:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aXTUrc26FD/Listeners', 15:55:43 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 15:55:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:55:43 INFO - 'USER': 'cltbld', 15:55:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:55:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:55:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:55:43 INFO - 'XPC_FLAGS': '0x0', 15:55:43 INFO - 'XPC_SERVICE_NAME': '0', 15:55:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:55:43 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WbqCzpGrTmWbmO2oCt2o7g/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0296', '--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 15:55:43 INFO - mozversion application_buildid: 20161018132202 15:55:43 INFO - mozversion application_changeset: 3ce14ff1acd5816d957b93135174b86d1cb63afa 15:55:43 INFO - mozversion application_display_name: Nightly 15:55:43 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:55:43 INFO - mozversion application_name: Firefox 15:55:43 INFO - mozversion application_remotingname: firefox 15:55:43 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 15:55:43 INFO - mozversion application_vendor: Mozilla 15:55:43 INFO - mozversion application_version: 52.0a1 15:55:43 INFO - mozversion platform_buildid: 20161018132202 15:55:43 INFO - mozversion platform_changeset: 3ce14ff1acd5816d957b93135174b86d1cb63afa 15:55:43 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 15:55:43 INFO - mozversion platform_version: 52.0a1 15:55:43 INFO - using testdate: 1476831343 15:55:43 INFO - actual date: 1476831343 15:55:43 INFO - starting webserver on 'localhost:49281' 15:55:44 INFO - SUITE-START | Running 2 tests 15:55:44 INFO - TEST-START | dromaeo_css 15:55:44 INFO - operating with platform_type : mac_ 15:55:44 INFO - Initialising browser for dromaeo_css test... 15:55:44 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/tmpQ4FDGz/profile http://localhost:49281/getInfo.html) 15:55:46 INFO - PROCESS | 1652 | 2016-10-18 15:55:46.943 plugin-container[1654:7666] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c3f, name = 'com.apple.tsm.portname' 15:55:46 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:55:46 INFO - PROCESS | 1652 | 2016-10-18 15:55:46.946 plugin-container[1654:7666] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x920b, name = 'com.apple.CFPasteboardClient' 15:55:46 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:55:46 INFO - PROCESS | 1652 | 2016-10-18 15:55:46.946 plugin-container[1654:7666] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:55:47 INFO - PROCESS | 1652 | __metrics Screen width/height:1600/1200 15:55:47 INFO - PROCESS | 1652 | colorDepth:24 15:55:47 INFO - PROCESS | 1652 | Browser inner width/height: 1024/689 15:55:47 INFO - PROCESS | 1652 | __metrics 15:55:47 ERROR - PROCESS | 1652 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3087: TypeError: this.tabs is undefined 15:55:47 INFO - TEST-INFO | 1652: exit 0 15:55:47 INFO - Browser initialized. 15:55:47 INFO - Running cycle 1/1 for dromaeo_css test... 15:55:47 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/tmpQ4FDGz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:55:50 INFO - PROCESS | 1655 | 2016-10-18 15:55:50.157 plugin-container[1656:7791] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 15:55:50 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:55:50 INFO - PROCESS | 1655 | 2016-10-18 15:55:50.158 plugin-container[1656:7791] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0b, name = 'com.apple.CFPasteboardClient' 15:55:50 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:55:50 INFO - PROCESS | 1655 | 2016-10-18 15:55:50.158 plugin-container[1656:7791] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:55:59 INFO - PROCESS | 1655 | RSS: Main: 237543424 15:55:59 INFO - PROCESS | 1655 | 15:56:49 INFO - PROCESS | 1655 | 1476831409515 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] 15:59:03 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49281/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49281/tests/dromaeo/cssquery-ext.html) 15:59:05 INFO - PROCESS | 1655 | RSS: Main: 249737216 15:59:05 INFO - PROCESS | 1655 | 16:02:07 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49281/tests/dromaeo/cssquery-ext.html (next: http://localhost:49281/tests/dromaeo/cssquery-jquery.html) 16:02:08 INFO - PROCESS | 1655 | RSS: Main: 263778304 16:02:08 INFO - PROCESS | 1655 | 16:05:10 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49281/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49281/tests/dromaeo/cssquery-mootools.html) 16:05:11 INFO - PROCESS | 1655 | RSS: Main: 268492800 16:05:11 INFO - PROCESS | 1655 | 16:08:13 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49281/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49281/tests/dromaeo/cssquery-prototype.html) 16:08:13 INFO - PROCESS | 1655 | RSS: Main: 268242944 16:08:13 INFO - PROCESS | 1655 | 16:11:16 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49281/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49281/tests/dromaeo/cssquery-yui.html) 16:11:17 INFO - PROCESS | 1655 | RSS: Main: 273592320 16:11:17 INFO - PROCESS | 1655 | 16:14:20 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49281/tests/dromaeo/cssquery-yui.html (next: http://localhost:49281/tests/dromaeo/cssquery-dojo.html) 16:14:20 INFO - PROCESS | 1655 | RSS: Main: 276221952 16:14:20 INFO - PROCESS | 1655 | 16:14:20 INFO - PROCESS | 1655 | __start_tp_report 16:14:20 INFO - PROCESS | 1655 | _x_x_mozilla_page_load 16:14:20 INFO - PROCESS | 1655 | _x_x_mozilla_page_load_details 16:14:20 INFO - PROCESS | 1655 | |i|pagename|runs| 16:14:20 INFO - PROCESS | 1655 | |0;dojo.html;3357.7649564530493;3382.424987752082;3401.6938475537213;3407.846646900889;3409.2329225924163;5574.777008919648;5676.943230567693;5692.971535142324;5756.683382413966;5779.595428320014;5440.755166017529;5598.524125449331;5667.744951477183;5694.174344720023;5811.27359080115;4349.304111342186;5045.672031317971;5167.741612919367;5220.138677118271;5295.7881684732565;5193.246979188016;5298.496642818926;5309.707966061865;5323.653962492445;5343.679379237239;5112.386513618352;5157.123289040872;5256.710880901549;5262.789488420459;5262.973685131588;5178.818741344073;5209.166533354645;5213.191955246943;5227.738613069331;5234.895302093975;5136.5890728741615;5140.948590514085;5149.665271757362;5158.509941555546;5179.326687530597;5432.429594892519;5450.400455949843;5702.686352250625;5714.114312281603;5720.141978703186;5176.456472070454;6099.975683468243;6158.230221222348;6238.407351301619;6290.811275661692;6270.028145637433;6274.937250627527;6288.056791481269;6319.052142178664;6332.905006424908;5757.1652110444165;5860.1181162983485;5862.501687356537;5877.206577112121;5923.526117910556;6214.658193799384;6217.595856269328;6268.341824108494;6280.528960327993;6294.748210071546;6137.683438940172;6150.077488376779;6155.230596175478;6157.538184636081;6178.382161783786;6254.74981000764;6259.342769009279;6267.71795269214;6267.843303917439;6275.278342990615;5420.268263784417;6089.5737298388685;6103.034636161462;6116.914073580098;6126.632402055801;6139.737467782837;6146.323904370515;6163.322034576193;6186.81439556814;6270.435660790551;4070;4951.554360107608;4955.950440495621;4958.8760280993265;4965.404151501771;4406.228909940812;4411.250087485135;4421.9336709949375;4425.712328698689;4487.035287413222;5605.467480589337;5766.452187042224;5843.065109582446;5892.793752218652;5926.525877929756;6233.96883015582;6234.563580549318;6252.656103914237;6253.74985000604;6268.686565671698;5866.560007999332;5877.441643043904;5901.675407852524;5907.497862681635;5972.25346831646;5190.766415511311;5224.451432599522;5302.390225124083;5408.864778380494;5440.727963601804;6319.241690996926;6339.270983836917;6365.681715914278;6384.042393640805;6389.8722025560155;6252.624842509464;6255.749770009149;6260.968695156495;6275.843103922439;6281.654509002049;6173.753049877954;6203.255609326729;6256.812295631138;6352.333005034405;6363.5863668861375;6122.663253521099;6198.690065496798;6209.192804935329;6224.470919971853;6236.158118653923;6213.471854892385;6250.281217659845;6282.057691346335;6312.684365781784;6320.4311611955145;5675.886482270249;5877.265341832271;6002.159697642421;6122.173506576555;6126.540509461897;5488.204210389391;6029.246344206974;6030.396960303934;6094;6138;4884.413870335582;5806.66446717249;5997.820065398045;6009.128676341993;6013;5890.175375447355;5978.551608629283;6010.278766548042;6030.849228769316;6129.295131059986;5853.94146058551;5854.502367298657;5862.765489380377;5871.383504732113;5874.882502350015 16:14:20 INFO - PROCESS | 1655 | |1;ext.html;489.93385892904445;497.8506448065584;515.5334422347776;520.7396301849074;540.970246636435;38793.61206387935;40303;41931.79034104829;42188.15623687524;42199.156016879686;30197.79208831644;34213.97358079262;43162.7841860791;43223.78388108063;43281.567184328145;9486.667966621153;9690.90309096907;9691.127798498133;9707.320487565874;9712.514374281292;9460.8580871287;9562.330636855422;9573.946865844751;9575;9594.664186753465;8198.38512111591;9440;9523.333366664336;9582.520873956275;9630.951845240765;8429.747107586782;9369.063093690611;9556.61773529058;9611.375260608038;9644.324897257195;9463.763405914839;9574.377665451793;9575.760605984906;9583;9585.472798996052;41082.79458602718;41232.79383603063;41493;41504.58495415037;41798.37302440466;3451.11996440906;3452.5166476693284;3622.166901612644;3834.501514803058;4113.938290925638;24834.5033099337;29609.851950740325;30411.783528658827;30527;31754.047378578678;3233.805971641709;3234.8544315505624;3237.3848968695875;3291.6504233264486;3332.683395077464;402919;494221.0577894211;502740;504744;510358;53269.40191794252;57231.71384143052;62942.37057629365;63688.68155659285;63690.36309636937;54388.72805635946;66017.6699116511;66912.33087669159;67154;67283.66358168177;15922.522324330288;16025.038497690175;16237.269322880498;16252.674946501003;16253.837461625233;12364.938175309066;12492.937535312265;12511.49954001848;12544.498220071277;12573.1198816082;19274.903964861845;19363.934983575757;19397.059551507296;19503.414897553095;19574.902125489283;6802.727890884309;7847.332976697045;7957.204279572113;7991.24083212094;7996.60059489587;836.5566249887569;915.6383228624655;917.9449233046039;919.4253591505309;972.7033254857337;29703.702962970525;30352.54471182934;30492.84753576218;30650.54024189639;30657.386852263277;926.5598840550684;997.8652881860855;1049.611643691839;1062.6971313175864;1120.6581992492206;14358.210298433687;15007.624809379853;15676.68646627061;15809.477559798426;15940.681186376207;615648;622000;622225;624558;626103;57883.71058144682;58100.70949645225;58223;58240.70879645574;58796.706016470496;1332.5003123828565;1339.2901762066122;1374.0656353679394;1377.091119860907;1386.7989141574415;24172.274831755076;27328.86335568389;29459.705402945696;29996.400071998003;30260.394792103594;41203.7939810299;50249.49750502448;50774.49225507698;50987;51086.74456627693;529177.354113227;537450;540814;541219;543008;26703.065392711163;26738.866305668347;26952.056678016175;27224.183274501796;27301.590476142475;47387.289190661475;48265;48852;49262.75368623134;49429.50570494393;38200.426993595676;40065.59934400619;40144.59855401408;40566.18867622572;41442.58557414508;30076;33044;34928.65071349254;39539.80230098831;40123.796286111465;37630.247395052495;38156.427653584666;38855;38887.6111238884;38972.80513597414;32789.344213116085;34485.655143448246;34578;34817;35012.649873501956;21651.02570384368;23046.539069218186;25923.481530369663;26465.94136234529;26534.867325663246 16:14:20 INFO - PROCESS | 1655 | |2;jquery.html;336.5624687905722;374.67029014467283;378.5362930410248;386.81046287319185;399.1159581526919;19414.805851941477;19532.121054552543;19581.70627440586;20155.29456469022;20396.490087747778;9783.902160978389;11859.229150105237;12070.577529786458;12160.695982600439;12212.81680774787;5257.553107985826;5457.890842183154;5545.306836645419;5546.278983732109;5549.417311182328;3897.1036661567855;4122.402251673506;5366.275552800361;5490.615656904019;5812.767489300423;6059.969700151515;6084.634921904678;6177.567570270083;6178.598391104586;6183.412575805291;5535.640183388067;5870.73581688825;5888.352281249059;5956.404359564052;6135.417135372132;5585.218069470277;5746.827595172152;5750.568707346965;5752.07966725322;5764.452377024176;12678.858902698708;13121.47514099435;19929.701054484194;24186.39534011646;24556.526608403554;1875.2311552263507;2126.851120421571;2136.6474531702406;2152.074607918594;2162.243214874797;9428.481433521154;10011.49942502872;10285.074343309137;10516.106130978877;10555;1873.4473330367575;1932.4299331697182;1947.5325921778813;1955.4915721912405;1957.784643689193;111500;114396.42801786105;114406;114967;115518;11304.265222760494;11454.198206125491;11541.769164616828;11628.360440175702;11821.940890295493;11886.276489908914;14399.712005759826;14465.349059292357;14502;14517.346719397652;8045.477043992122;8259.42184047123;8290.46112002718;8293.875591866126;8363.62363693623;6961.26906674802;7956.801079973047;8007.839843203104;8117.350611951029;8169.5098294102545;3401.6018706811637;3747.9250414991548;4055.7160998729805;4456.264716321771;4492.460904691458;1570.175657779652;1572.842715728418;1687.4684474390529;1968.39963811039;1971.2706298669584;728.4281838756657;811.7564730580802;837.6942416018231;884.6151923913119;933.7478880702172;16905.7464138038;21174.364769056952;21275;21363.893180533996;21366.465838353855;813.6338647608482;922.3589605224305;939.9013103624158;940.2759874896428;949.7245798718433;9044.005159432458;10778.568857245778;10832.241743078066;11402.771944561062;11549.13381496391;237399;239450.80274598516;239455;239505;239595;117926;123685;127472.36263818621;131920;133127;948.7770373962113;962.6438217859297;1002.1832206751474;1006.4716024087413;1010.8433192855119;19023.04884755729;19200.71198932045;19218.807811922263;19243.711344329568;19467.221311147394;52454.73772631113;53460.198097027795;55261;55741;56149;80393.19606804091;81350;81424.5928770376;81914;82470;6934.16789985205;7358;7907.096825444055;8094.635741391769;8269.669213231404;8386.874196887173;11921.225120367495;12710.936445317715;13062.150960187557;13564.186148831102;9133.954330228305;9334.673286434941;10704.250702450754;11046.558137674725;11335.603253886404;9178.495182765013;11237.55049797999;11293.661190164308;11326;11424.771504569695;11383;11507;11638.41807909618;11697.649070527897;11785.05546584804;6301.8109456716375;6606.141201643755;7004.4046256068805;7595.354394876497;8489.4481858679;7400.777976660709;9142.771430714285;9874.75313117149;11483.081353491867;11562.843715628369 16:14:20 INFO - PROCESS | 1655 | |3;mootools.html;1461.7588097963835;1564.2804310017389;1777.893326400416;1852.527605460609;1886.9150888210029;8002.719904803333;8321.375896807733;8475.110113438093;8957.328200384973;9040.367174297802;7693.692252309915;8528.275096616779;8828.337874659393;8877.979032411275;8965.775855603613;2353.6587194856734;2373.964390534143;2403.2549909528016;2407.253751337091;2408.5664580375524;2362.2913126062112;2364.7044119485063;2377.9762202378015;2405.0860672944254;2407.831451798375;2355.5642206191824;2393.533261014103;2400.8199385046105;2411.2043025801445;2411.493586346869;2359.7050368703913;2362.9054837806466;2364.3498038039506;2372.833901626887;2376.310869847742;2308.56137333906;2335.602947498929;2344.5076533927895;2348.8120950323932;2349.612313968193;8938.46369217849;8987.820243595157;9011.549422528848;9019.278457723367;9068.093190680946;5277.182036784305;5469.316335458067;5474.72626368684;5477.287952566181;5477.75350109246;3608.116011577154;3627.546556680415;3661.2677464506937;3698.1124530112847;3701.1857391373856;1061.541663775762;1074.7796701676139;1079.8434227037114;1084.4415126210008;1092.814221582333;11547.884521154765;11665.766684666343;11706.590269340617;11738.882611173864;11748;4578.702384345007;4748.264019077049;4817.325574419584;4852.199387101159;4870.659053866266;9983.950080249553;9989.250806189564;10097.444640544694;10105.9494702526;10107.848382274271;5378.757955891994;5420.593455490854;5432.320959880015;5432.945670543244;5437.4562543745305;4459.576340247671;4593.885152871205;4786.210275304606;4920.040592084581;4966.279889416087;3930.449737036817;3999.3601023836045;4054.71616986814;4096.221717873595;4156.709030367845;1309.4696647857634;1319.7954317080869;1324.43711422645;1333.120140707148;1334.1061488802331;889.7597648634962;922.5018490015451;924.4083786376724;934.0892629686138;941.1623654947102;4983.053219888209;5646.830595082154;5671.376148623648;5781.508571771446;5842.123681447817;815.1970309245388;923.9399439036441;946.668665966906;969.9030096990244;1018.0277834667875;3356.6139893912023;3720.6465385788306;3865.8840234793;3898.7075969302414;3977.8209980550946;10521.684349469528;10773.299735517166;10900.018998290192;10902.07332376741;10917.727056823644;10811.83782243267;10854.29447085937;10855.185861060294;10859.34843909368;10925.836112458319;8185.222403871623;8238.752837414888;8306;8324.62539185738;8327.20891515305;5577.44225577439;5874.765009399576;5922.644641321534;6001.909971350346;6031.336552979256;9592.328537002342;9754.609815607297;10021.248406369406;10091.596336146471;10125.291229613857;10256.538455769658;10258;10258.281920265808;10265.486725663837;10271.383716977005;6945.930540694529;7164;7321.890171647536;7539.811504712426;7716.498427602412;8338.082810890675;8552.957235213784;8603.39776215684;8608.225259726654;8648;7406.925930740624;7446.73936412223;7578.734744283924;7636;7685.346745526693;6164.260288765377;7425.925740742739;7438.628068596657;7495.1005879294835;7633.6564854580565;7018.789436316919;7023.403010743939;7093.1488221413765;7109;7116.50184487081;4941.703497790144;5017.42299635532;5032.421271553671;5039.370078740158;5091.541761241506;4872.6589138761365;4901.681390709735;4970.030843985388;5135.409427915688;5185.714785686823 16:14:20 INFO - PROCESS | 1655 | |4;prototype.html;294.3362717073;294.41117764471045;308.8779931926889;314.6428803308244;315.75371210455836;5238.611767881514;5246.2917506136755;5420;5518.586106042043;5536.501714845663;4923.433805112404;5393.4336894626085;5468.26178465907;5474.616776825624;5504.532114770239;3502.964970350302;3754.3805272130066;3963.0686788604653;4163.646090082354;4214.873553793391;3857.4599556062035;4122.546519882811;4215.557366476522;4222.15556888622;4255.851045213418;4081.8775436736796;4088.9182216355657;4093.7543747375253;4113.732607380525;4124.8143833527565;4102.487189101363;4104.8152833122585;4108.548059713429;4133.91732165358;4153.525498448038;3571.8928432147077;3572.8392222350058;3587.7488575799703;3598.6581274778855;3599.280143971216;5213.478652134794;5337.690631808265;5352.57179425645;5378.596605254622;5393.595480338951;3959.4060890866313;3969.3252147134767;4737.38414006177;4742.059922847857;4753.027493590156;5448.447192550144;5622.240997465331;5799.710014499258;5811.6803575803315;5831.795887143972;2960.2007457986438;3082.6763189864964;3416.367971925177;3420.521127042191;3790.2419516096893;4813.663043586916;5087.821926232564;5574.972125139429;5636.900804343155;6158.0147176452;3404.7446441516986;3675.280292583469;3756.511653485084;3966.7421617594764;3994.8801535953967;3613.5663720353728;3823.2735780201674;4021.5174179098112;4051.736637118578;4064.288749468831;3943.684505239574;4068.7558746475306;4678.450244616692;4728.267118596623;4796.952030479651;5859.882802343929;5880.676562789087;5941.197938278364;5948.940510594839;5968.895754285384;1181.6809461445362;1286.0740267007739;1328.6678330417396;1351.722622122105;1364.860341614743;749.3143773447343;848.6859861851154;853.6926706385697;899.0335389456361;905.5653286422554;4407.955920440819;4816.494268101869;5388.488093631099;5415.593830462731;5433.320834895638;5504.972475137599;5674.914876276859;5889.941100589025;5915.160722853055;5933.881322373615;4566.634669226454;5158.97420512895;5377.650452720561;5422.749558933077;5507.504324610804;2649.5893136563864;2649.7281304973335;2650.6819181697933;2659.9069032583766;2675.952896034535;4553.954460455419;4945.480724523945;5127.3083379966365;5220.243064755589;5280.6303558750515;4524.615407690383;4559.726416415026;4661.743604101807;5167.6641018333685;5169.948300517022;4524.457065152203;5119.59043276537;5223.2948551945765;5229.790808367623;5271.8385461960415;4030.858919937788;4096.692748043849;4441.578050085237;4484.578327703004;5127.846164614918;3986.0234242610745;4331.675124365622;4653.906921861611;4945.876353091202;5036.395632524121;4295.806688699078;4483.798229079634;4600.976995115003;4948.62885283598;5005.873678422471;3005.8196508209576;3024.75801935849;3409.573803274591;3426.3147370525494;3482.2513159670793;4860.732659703609;4914.631402644744;4993;5007.824726134568;5239.528442440199;5347.7593508293;5417.241586178017;5826.76692932278;5831.35855055952;5870.442307980735;5225.372955245243;5432.375276843213;5435.266239057834;5889.411058894077;5907.3501914790195;5156.097682905402;5520.282363292747;5713.800016999385;5766.826995190151;5789.073748200268;5188.948110518846;5382.434844341287;5583.74873130702;5845.649261044351;5879.559033072451;4886.971906658697;4887.804487820447;5123.925644613202;5696.430356964271;5698.521143302677 16:14:20 INFO - PROCESS | 1655 | |5;yui.html;613.7667686279215;689.5483458334793;705.4427002667893;785.1794874356301;804.3484777330362;14799.33402996865;14956;15662.21688915554;15944.441944531944;15993.600159996005;14479.855201447983;14775.113493190416;14775.48285809997;14796.408143674267;14809.407623695039;701.8210356359147;856.678745470447;881.2597418168737;881.7663319220412;882.995585022074;716.0905649824746;855.7646647172015;875.6366108065146;882.280941033056;887.7114937645259;934.6495064350869;936.9344145909755;938.8310104181245;940.2994768897181;943.5848226780229;914.2183433164648;934.3879758758025;936.3632729743772;936.8782058332441;940.6096469964956;675.4360109308684;691.2707094015811;698.5005720909573;698.7135274537416;700.2857085772534;14958.551243462605;14983.850161498463;14988.625284367869;15002.774958375632;15032.947693661448;14295.857041429557;14425.639359016004;14492.565223043326;14533.34599943005;14723.852761472355;11846.940765296205;11911.10666700001;11974.22167559106;12002.839488891996;12064.457099430547;3768.623137686237;3825.0437390652337;3849.499565056553;3961.564227934925;3963.2667956427863;114972;117264;118276;118417.40791295988;121120;11109;11178.608748693756;11193.832092518618;11215.66353009411;11413.97274245322;22694.97872595737;22940.655890161663;22962.31113066611;22964.081436742676;23000.65499017516;7722.189170137167;7824.334931530883;7875.960620196863;8024.83950321002;8030.996125484315;4147.502299723992;4173.874783756492;4231.153769246163;4253.404523366731;4374.321980093091;3589.7666651668083;3650;3688.944665830015;3702.407614781622;3713.867270482529;601.2064075420491;623.9500839932795;624.8531595075153;635.841039740065;644.3620815392821;628.355935166449;644.2301449767576;655.0531612251999;662.1259936883267;669.6149713914518;8014.470294538163;9048.41185322952;9176.632934682671;9258.305627077996;9383.436993780395;529.6795438759495;536.9207892136833;622.2377587455421;654.8493846415352;693.639307560066;4206.3690446432665;4373.606375426163;4485.116251174514;4671.556202160789;4939.827106051271;85843.84936903806;133571;142586;147028;147615;96600;99851.50074249727;102988;128952.35523822322;131122;86250;101316;101778.49110754547;101975;104194;8789.296856251613;9347.672831450867;9682.644429779977;9922.107010369102;9926.205903527845;52118.69703257449;55912.44087559235;59824;61420.77158456895;62594.37405625886;65628.34371656414;84352.15647843688;86128.56935715281;88049;89286;12984.285864277555;13155.210687358789;13159.00926262718;13261.867381326449;13652.249126297751;11881.287122772661;14681.19253441071;14904.701905962036;15113.244337782848;15219.619509512351;10678.73303167427;11607.76784464323;11958;13183.736325273632;13782;13592.79610805819;13595.660108497366;13883.167009979434;14040.876729861382;14392;12874.291913944822;13179.341032948507;13385.86614133885;13389.062765606313;14118.788218176942;9464;10029.448380339152;10548.841767373342;11655.708607284885;11827.763444731228;9274.397164184307;10562.78874422492;11438.42807859587;11501.48243329069;11620.477078531318 16:14:20 INFO - PROCESS | 1655 | __end_tp_report 16:14:20 INFO - PROCESS | 1655 | __start_cc_report 16:14:20 INFO - PROCESS | 1655 | _x_x_mozilla_cycle_collect,366 16:14:20 INFO - PROCESS | 1655 | __end_cc_report 16:14:20 INFO - PROCESS | 1655 | __startTimestamp1476832460477__endTimestamp 16:14:20 INFO - PROCESS | 1655 | 16:14:20 INFO - PROCESS | 1655 | ------- Summary: start ------- 16:14:20 INFO - PROCESS | 1655 | Number of tests: 6 16:14:20 INFO - PROCESS | 1655 | 16:14:20 INFO - PROCESS | 1655 | [#0] dojo.html Cycles:165 Average:5714.51 Median:5892.79 stddev:655.35 (11.1%) stddev-sans-first:630.74 16:14:20 INFO - PROCESS | 1655 | Values: 3357.8 3382.4 3401.7 3407.8 3409.2 5574.8 5676.9 5693.0 5756.7 5779.6 5440.8 5598.5 5667.7 5694.2 5811.3 4349.3 5045.7 5167.7 5220.1 5295.8 5193.2 5298.5 5309.7 5323.7 5343.7 5112.4 5157.1 5256.7 5262.8 5263.0 5178.8 5209.2 5213.2 5227.7 5234.9 5136.6 5140.9 5149.7 5158.5 5179.3 5432.4 5450.4 5702.7 5714.1 5720.1 5176.5 6100.0 6158.2 6238.4 6290.8 6270.0 6274.9 6288.1 6319.1 6332.9 5757.2 5860.1 5862.5 5877.2 5923.5 6214.7 6217.6 6268.3 6280.5 6294.7 6137.7 6150.1 6155.2 6157.5 6178.4 6254.7 6259.3 6267.7 6267.8 6275.3 5420.3 6089.6 6103.0 6116.9 6126.6 6139.7 6146.3 6163.3 6186.8 6270.4 4070.0 4951.6 4956.0 4958.9 4965.4 4406.2 4411.3 4421.9 4425.7 4487.0 5605.5 5766.5 5843.1 5892.8 5926.5 6234.0 6234.6 6252.7 6253.7 6268.7 5866.6 5877.4 5901.7 5907.5 5972.3 5190.8 5224.5 5302.4 5408.9 5440.7 6319.2 6339.3 6365.7 6384.0 6389.9 6252.6 6255.7 6261.0 6275.8 6281.7 6173.8 6203.3 6256.8 6352.3 6363.6 6122.7 6198.7 6209.2 6224.5 6236.2 6213.5 6250.3 6282.1 6312.7 6320.4 5675.9 5877.3 6002.2 6122.2 6126.5 5488.2 6029.2 6030.4 6094.0 6138.0 4884.4 5806.7 5997.8 6009.1 6013.0 5890.2 5978.6 6010.3 6030.8 6129.3 5853.9 5854.5 5862.8 5871.4 5874.9 16:14:20 INFO - PROCESS | 1655 | 16:14:20 INFO - PROCESS | 1655 | [#1] ext.html Cycles:180 Average:68403.26 Median:41467.79 stddev:147160.51 (354.9%) stddev-sans-first:147484.99 16:14:20 INFO - PROCESS | 1655 | Values: 489.9 497.9 515.5 520.7 541.0 38793.6 40303.0 41931.8 42188.2 42199.2 30197.8 34214.0 43162.8 43223.8 43281.6 9486.7 9690.9 9691.1 9707.3 9712.5 9460.9 9562.3 9573.9 9575.0 9594.7 8198.4 9440.0 9523.3 9582.5 9631.0 8429.7 9369.1 9556.6 9611.4 9644.3 9463.8 9574.4 9575.8 9583.0 9585.5 41082.8 41232.8 41493.0 41504.6 41798.4 3451.1 3452.5 3622.2 3834.5 4113.9 24834.5 29609.9 30411.8 30527.0 31754.0 3233.8 3234.9 3237.4 3291.7 3332.7 402919.0 494221.1 502740.0 504744.0 510358.0 53269.4 57231.7 62942.4 63688.7 63690.4 54388.7 66017.7 66912.3 67154.0 67283.7 15922.5 16025.0 16237.3 16252.7 16253.8 12364.9 12492.9 12511.5 12544.5 12573.1 19274.9 19363.9 19397.1 19503.4 19574.9 6802.7 7847.3 7957.2 7991.2 7996.6 836.6 915.6 917.9 919.4 972.7 29703.7 30352.5 30492.8 30650.5 30657.4 926.6 997.9 1049.6 1062.7 1120.7 14358.2 15007.6 15676.7 15809.5 15940.7 615648.0 622000.0 622225.0 624558.0 626103.0 57883.7 58100.7 58223.0 58240.7 58796.7 1332.5 1339.3 1374.1 1377.1 1386.8 24172.3 27328.9 29459.7 29996.4 30260.4 41203.8 50249.5 50774.5 50987.0 51086.7 529177.4 537450.0 540814.0 541219.0 543008.0 26703.1 26738.9 26952.1 27224.2 27301.6 47387.3 48265.0 48852.0 49262.8 49429.5 38200.4 40065.6 40144.6 40566.2 41442.6 30076.0 33044.0 34928.7 39539.8 40123.8 37630.2 38156.4 38855.0 38887.6 38972.8 32789.3 34485.7 34578.0 34817.0 35012.6 21651.0 23046.5 25923.5 26465.9 26534.9 16:14:20 INFO - PROCESS | 1655 | 16:14:20 INFO - PROCESS | 1655 | [#2] jquery.html Cycles:180 Average:24423.53 Median:131890.70 stddev:46603.82 (35.3%) stddev-sans-first:46699.47 16:14:20 INFO - PROCESS | 1655 | Values: 336.6 374.7 378.5 386.8 399.1 19414.8 19532.1 19581.7 20155.3 20396.5 9783.9 11859.2 12070.6 12160.7 12212.8 5257.6 5457.9 5545.3 5546.3 5549.4 3897.1 4122.4 5366.3 5490.6 5812.8 6060.0 6084.6 6177.6 6178.6 6183.4 5535.6 5870.7 5888.4 5956.4 6135.4 5585.2 5746.8 5750.6 5752.1 5764.5 12678.9 13121.5 19929.7 24186.4 24556.5 1875.2 2126.9 2136.6 2152.1 2162.2 9428.5 10011.5 10285.1 10516.1 10555.0 1873.4 1932.4 1947.5 1955.5 1957.8 111500.0 114396.4 114406.0 114967.0 115518.0 11304.3 11454.2 11541.8 11628.4 11821.9 11886.3 14399.7 14465.3 14502.0 14517.3 8045.5 8259.4 8290.5 8293.9 8363.6 6961.3 7956.8 8007.8 8117.4 8169.5 3401.6 3747.9 4055.7 4456.3 4492.5 1570.2 1572.8 1687.5 1968.4 1971.3 728.4 811.8 837.7 884.6 933.7 16905.7 21174.4 21275.0 21363.9 21366.5 813.6 922.4 939.9 940.3 949.7 9044.0 10778.6 10832.2 11402.8 11549.1 237399.0 239450.8 239455.0 239505.0 239595.0 117926.0 123685.0 127472.4 131920.0 133127.0 948.8 962.6 1002.2 1006.5 1010.8 19023.0 19200.7 19218.8 19243.7 19467.2 52454.7 53460.2 55261.0 55741.0 56149.0 80393.2 81350.0 81424.6 81914.0 82470.0 6934.2 7358.0 7907.1 8094.6 8269.7 8386.9 11921.2 12710.9 13062.2 13564.2 9134.0 9334.7 10704.3 11046.6 11335.6 9178.5 11237.6 11293.7 11326.0 11424.8 11383.0 11507.0 11638.4 11697.6 11785.1 6301.8 6606.1 7004.4 7595.4 8489.4 7400.8 9142.8 9874.8 11483.1 11562.8 16:14:20 INFO - PROCESS | 1655 | 16:14:20 INFO - PROCESS | 1655 | [#3] mootools.html Cycles:180 Average:5748.15 Median:4887.17 stddev:3216.42 (65.8%) stddev-sans-first:3209.31 16:14:20 INFO - PROCESS | 1655 | Values: 1461.8 1564.3 1777.9 1852.5 1886.9 8002.7 8321.4 8475.1 8957.3 9040.4 7693.7 8528.3 8828.3 8878.0 8965.8 2353.7 2374.0 2403.3 2407.3 2408.6 2362.3 2364.7 2378.0 2405.1 2407.8 2355.6 2393.5 2400.8 2411.2 2411.5 2359.7 2362.9 2364.3 2372.8 2376.3 2308.6 2335.6 2344.5 2348.8 2349.6 8938.5 8987.8 9011.5 9019.3 9068.1 5277.2 5469.3 5474.7 5477.3 5477.8 3608.1 3627.5 3661.3 3698.1 3701.2 1061.5 1074.8 1079.8 1084.4 1092.8 11547.9 11665.8 11706.6 11738.9 11748.0 4578.7 4748.3 4817.3 4852.2 4870.7 9984.0 9989.3 10097.4 10105.9 10107.8 5378.8 5420.6 5432.3 5432.9 5437.5 4459.6 4593.9 4786.2 4920.0 4966.3 3930.4 3999.4 4054.7 4096.2 4156.7 1309.5 1319.8 1324.4 1333.1 1334.1 889.8 922.5 924.4 934.1 941.2 4983.1 5646.8 5671.4 5781.5 5842.1 815.2 923.9 946.7 969.9 1018.0 3356.6 3720.6 3865.9 3898.7 3977.8 10521.7 10773.3 10900.0 10902.1 10917.7 10811.8 10854.3 10855.2 10859.3 10925.8 8185.2 8238.8 8306.0 8324.6 8327.2 5577.4 5874.8 5922.6 6001.9 6031.3 9592.3 9754.6 10021.2 10091.6 10125.3 10256.5 10258.0 10258.3 10265.5 10271.4 6945.9 7164.0 7321.9 7539.8 7716.5 8338.1 8553.0 8603.4 8608.2 8648.0 7406.9 7446.7 7578.7 7636.0 7685.3 6164.3 7425.9 7438.6 7495.1 7633.7 7018.8 7023.4 7093.1 7109.0 7116.5 4941.7 5017.4 5032.4 5039.4 5091.5 4872.7 4901.7 4970.0 5135.4 5185.7 16:14:20 INFO - PROCESS | 1655 | 16:14:20 INFO - PROCESS | 1655 | [#4] prototype.html Cycles:180 Average:4406.83 Median:4887.39 stddev:1371.38 (28.1%) stddev-sans-first:1340.04 16:14:20 INFO - PROCESS | 1655 | Values: 294.3 294.4 308.9 314.6 315.8 5238.6 5246.3 5420.0 5518.6 5536.5 4923.4 5393.4 5468.3 5474.6 5504.5 3503.0 3754.4 3963.1 4163.6 4214.9 3857.5 4122.5 4215.6 4222.2 4255.9 4081.9 4088.9 4093.8 4113.7 4124.8 4102.5 4104.8 4108.5 4133.9 4153.5 3571.9 3572.8 3587.7 3598.7 3599.3 5213.5 5337.7 5352.6 5378.6 5393.6 3959.4 3969.3 4737.4 4742.1 4753.0 5448.4 5622.2 5799.7 5811.7 5831.8 2960.2 3082.7 3416.4 3420.5 3790.2 4813.7 5087.8 5575.0 5636.9 6158.0 3404.7 3675.3 3756.5 3966.7 3994.9 3613.6 3823.3 4021.5 4051.7 4064.3 3943.7 4068.8 4678.5 4728.3 4797.0 5859.9 5880.7 5941.2 5948.9 5968.9 1181.7 1286.1 1328.7 1351.7 1364.9 749.3 848.7 853.7 899.0 905.6 4408.0 4816.5 5388.5 5415.6 5433.3 5505.0 5674.9 5889.9 5915.2 5933.9 4566.6 5159.0 5377.7 5422.7 5507.5 2649.6 2649.7 2650.7 2659.9 2676.0 4554.0 4945.5 5127.3 5220.2 5280.6 4524.6 4559.7 4661.7 5167.7 5169.9 4524.5 5119.6 5223.3 5229.8 5271.8 4030.9 4096.7 4441.6 4484.6 5127.8 3986.0 4331.7 4653.9 4945.9 5036.4 4295.8 4483.8 4601.0 4948.6 5005.9 3005.8 3024.8 3409.6 3426.3 3482.3 4860.7 4914.6 4993.0 5007.8 5239.5 5347.8 5417.2 5826.8 5831.4 5870.4 5225.4 5432.4 5435.3 5889.4 5907.4 5156.1 5520.3 5713.8 5766.8 5789.1 5188.9 5382.4 5583.7 5845.6 5879.6 4887.0 4887.8 5123.9 5696.4 5698.5 16:14:20 INFO - PROCESS | 1655 | 16:14:20 INFO - PROCESS | 1655 | [#5] yui.html Cycles:180 Average:23485.56 Median:3837.27 stddev:36594.65 (953.7%) stddev-sans-first:36657.01 16:14:20 INFO - PROCESS | 1655 | Values: 613.8 689.5 705.4 785.2 804.3 14799.3 14956.0 15662.2 15944.4 15993.6 14479.9 14775.1 14775.5 14796.4 14809.4 701.8 856.7 881.3 881.8 883.0 716.1 855.8 875.6 882.3 887.7 934.6 936.9 938.8 940.3 943.6 914.2 934.4 936.4 936.9 940.6 675.4 691.3 698.5 698.7 700.3 14958.6 14983.9 14988.6 15002.8 15032.9 14295.9 14425.6 14492.6 14533.3 14723.9 11846.9 11911.1 11974.2 12002.8 12064.5 3768.6 3825.0 3849.5 3961.6 3963.3 114972.0 117264.0 118276.0 118417.4 121120.0 11109.0 11178.6 11193.8 11215.7 11414.0 22695.0 22940.7 22962.3 22964.1 23000.7 7722.2 7824.3 7876.0 8024.8 8031.0 4147.5 4173.9 4231.2 4253.4 4374.3 3589.8 3650.0 3688.9 3702.4 3713.9 601.2 624.0 624.9 635.8 644.4 628.4 644.2 655.1 662.1 669.6 8014.5 9048.4 9176.6 9258.3 9383.4 529.7 536.9 622.2 654.8 693.6 4206.4 4373.6 4485.1 4671.6 4939.8 85843.8 133571.0 142586.0 147028.0 147615.0 96600.0 99851.5 102988.0 128952.4 131122.0 86250.0 101316.0 101778.5 101975.0 104194.0 8789.3 9347.7 9682.6 9922.1 9926.2 52118.7 55912.4 59824.0 61420.8 62594.4 65628.3 84352.2 86128.6 88049.0 89286.0 12984.3 13155.2 13159.0 13261.9 13652.2 11881.3 14681.2 14904.7 15113.2 15219.6 10678.7 11607.8 11958.0 13183.7 13782.0 13592.8 13595.7 13883.2 14040.9 14392.0 12874.3 13179.3 13385.9 13389.1 14118.8 9464.0 10029.4 10548.8 11655.7 11827.8 9274.4 10562.8 11438.4 11501.5 11620.5 16:14:20 INFO - PROCESS | 1655 | -------- Summary: end -------- 16:14:20 INFO - PROCESS | 1655 | 16:14:21 INFO - TEST-INFO | 1655: exit 0 16:14:21 INFO - TEST-OK | dromaeo_css | took 1117096ms 16:14:21 INFO - TEST-START | kraken 16:14:21 INFO - operating with platform_type : mac_ 16:14:21 INFO - Initialising browser for kraken test... 16:14:21 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/tmpcTrg_Z/profile http://localhost:49281/getInfo.html) 16:14:24 INFO - PROCESS | 1666 | 2016-10-18 16:14:24.115 plugin-container[1667:9941] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 16:14:24 INFO - PROCESS | 1666 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:14:24 INFO - PROCESS | 1666 | 2016-10-18 16:14:24.118 plugin-container[1667:9941] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x471f, name = 'com.apple.CFPasteboardClient' 16:14:24 INFO - PROCESS | 1666 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:14:24 INFO - PROCESS | 1666 | 2016-10-18 16:14:24.118 plugin-container[1667:9941] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:14:24 INFO - PROCESS | 1666 | __metrics Screen width/height:1600/1200 16:14:24 INFO - PROCESS | 1666 | colorDepth:24 16:14:24 INFO - PROCESS | 1666 | Browser inner width/height: 1024/689 16:14:24 INFO - PROCESS | 1666 | __metrics 16:14:24 ERROR - PROCESS | 1666 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3087: TypeError: this.tabs is undefined 16:14:25 INFO - TEST-INFO | 1666: exit 0 16:14:25 INFO - Browser initialized. 16:14:25 INFO - Running cycle 1/1 for kraken test... 16:14:25 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/tmpcTrg_Z/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:14:27 INFO - PROCESS | 1668 | 2016-10-18 16:14:27.260 plugin-container[1669:10051] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x863f, name = 'com.apple.tsm.portname' 16:14:27 INFO - PROCESS | 1668 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:14:27 INFO - PROCESS | 1668 | 2016-10-18 16:14:27.260 plugin-container[1669:10051] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8847, name = 'com.apple.CFPasteboardClient' 16:14:27 INFO - PROCESS | 1668 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:14:27 INFO - PROCESS | 1668 | 2016-10-18 16:14:27.260 plugin-container[1669:10051] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:14:36 INFO - PROCESS | 1668 | RSS: Main: 237064192 16:14:36 INFO - PROCESS | 1668 | 16:14:40 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49281/tests/kraken/driver.html?testName=audio-beat-detection) 16:14:41 INFO - PROCESS | 1668 | RSS: Main: 249761792 16:14:41 INFO - PROCESS | 1668 | 16:14:43 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49281/tests/kraken/driver.html?testName=audio-dft) 16:14:44 INFO - PROCESS | 1668 | RSS: Main: 257376256 16:14:44 INFO - PROCESS | 1668 | 16:14:47 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49281/tests/kraken/driver.html?testName=audio-fft) 16:14:48 INFO - PROCESS | 1668 | RSS: Main: 260366336 16:14:48 INFO - PROCESS | 1668 | 16:14:50 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49281/tests/kraken/driver.html?testName=audio-oscillator) 16:14:50 INFO - PROCESS | 1668 | RSS: Main: 262119424 16:14:50 INFO - PROCESS | 1668 | 16:14:52 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49281/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:14:53 INFO - PROCESS | 1668 | RSS: Main: 262701056 16:14:53 INFO - PROCESS | 1668 | 16:14:56 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49281/tests/kraken/driver.html?testName=imaging-darkroom) 16:14:56 INFO - PROCESS | 1668 | RSS: Main: 263393280 16:14:56 INFO - PROCESS | 1668 | 16:14:59 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49281/tests/kraken/driver.html?testName=imaging-desaturate) 16:15:00 INFO - PROCESS | 1668 | RSS: Main: 263335936 16:15:00 INFO - PROCESS | 1668 | 16:15:03 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49281/tests/kraken/driver.html?testName=json-parse-financial) 16:15:03 INFO - PROCESS | 1668 | RSS: Main: 263884800 16:15:03 INFO - PROCESS | 1668 | 16:15:05 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49281/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:15:06 INFO - PROCESS | 1668 | RSS: Main: 263413760 16:15:06 INFO - PROCESS | 1668 | 16:15:08 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:15:08 INFO - PROCESS | 1668 | RSS: Main: 263614464 16:15:08 INFO - PROCESS | 1668 | 16:15:11 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:15:11 INFO - PROCESS | 1668 | RSS: Main: 263397376 16:15:11 INFO - PROCESS | 1668 | 16:15:14 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:15:15 INFO - PROCESS | 1668 | RSS: Main: 263938048 16:15:15 INFO - PROCESS | 1668 | 16:15:18 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:15:19 INFO - PROCESS | 1668 | RSS: Main: 263598080 16:15:19 INFO - PROCESS | 1668 | 16:15:21 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49281/tests/kraken/driver.html?testName=ai-astar) 16:15:21 INFO - PROCESS | 1668 | RSS: Main: 264949760 16:15:21 INFO - PROCESS | 1668 | 16:15:21 INFO - PROCESS | 1668 | __start_tp_report 16:15:21 INFO - PROCESS | 1668 | _x_x_mozilla_page_load 16:15:21 INFO - PROCESS | 1668 | _x_x_mozilla_page_load_details 16:15:21 INFO - PROCESS | 1668 | |i|pagename|runs| 16:15:21 INFO - PROCESS | 1668 | |0;ai-astar;107;118;118;116;116;118;119;98;173;174 16:15:21 INFO - PROCESS | 1668 | |1;audio-beat-detection;149;102;153;181;143;122;118;116;145;116 16:15:21 INFO - PROCESS | 1668 | |2;audio-dft;190;209;269;280;192;194;197;196;197;198 16:15:21 INFO - PROCESS | 1668 | |3;audio-fft;87;58;60;84;59;60;60;59;79;59 16:15:21 INFO - PROCESS | 1668 | |4;audio-oscillator;83;70;87;77;70;72;88;70;71;87 16:15:21 INFO - PROCESS | 1668 | |5;imaging-gaussian-blur;92;89;92;89;93;88;93;90;91;88 16:15:21 INFO - PROCESS | 1668 | |6;imaging-darkroom;97;97;97;96;99;96;97;98;98;96 16:15:21 INFO - PROCESS | 1668 | |7;imaging-desaturate;90;74;136;135;89;88;91;121;82;139 16:15:21 INFO - PROCESS | 1668 | |8;json-parse-financial;55;58;57;56;59;71;92;86;76;59 16:15:21 INFO - PROCESS | 1668 | |9;json-stringify-tinderbox;68;70;78;81;74;72;72;73;70;72 16:15:21 INFO - PROCESS | 1668 | |10;stanford-crypto-aes;89;95;108;114;110;112;90;88;89;91 16:15:21 INFO - PROCESS | 1668 | |11;stanford-crypto-ccm;154;165;144;159;161;183;165;154;187;150 16:15:21 INFO - PROCESS | 1668 | |12;stanford-crypto-pbkdf2;232;204;189;240;183;162;178;171;224;239 16:15:21 INFO - PROCESS | 1668 | |13;stanford-crypto-sha256-iterative;69;79;89;91;89;81;71;76;86;64 16:15:21 INFO - PROCESS | 1668 | __end_tp_report 16:15:21 INFO - PROCESS | 1668 | __start_cc_report 16:15:21 INFO - PROCESS | 1668 | _x_x_mozilla_cycle_collect,950 16:15:21 INFO - PROCESS | 1668 | __end_cc_report 16:15:21 INFO - PROCESS | 1668 | __startTimestamp1476832521421__endTimestamp 16:15:21 INFO - PROCESS | 1668 | 16:15:21 INFO - PROCESS | 1668 | ------- Summary: start ------- 16:15:21 INFO - PROCESS | 1668 | Number of tests: 14 16:15:21 INFO - PROCESS | 1668 | 16:15:21 INFO - PROCESS | 1668 | [#0] ai-astar Cycles:10 Average:125.70 Median:118.50 stddev:26.03 (22.0%) stddev-sans-first:26.72 16:15:21 INFO - PROCESS | 1668 | Values: 107.0 118.0 118.0 116.0 116.0 118.0 119.0 98.0 173.0 174.0 16:15:21 INFO - PROCESS | 1668 | 16:15:21 INFO - PROCESS | 1668 | [#1] audio-beat-detection Cycles:10 Average:134.50 Median:144.00 stddev:23.73 (16.5%) stddev-sans-first:24.58 16:15:21 INFO - PROCESS | 1668 | Values: 149.0 102.0 153.0 181.0 143.0 122.0 118.0 116.0 145.0 116.0 16:15:21 INFO - PROCESS | 1668 | 16:15:21 INFO - PROCESS | 1668 | [#2] audio-dft Cycles:10 Average:212.20 Median:197.50 stddev:33.32 (16.9%) stddev-sans-first:34.36 16:15:21 INFO - PROCESS | 1668 | Values: 190.0 209.0 269.0 280.0 192.0 194.0 197.0 196.0 197.0 198.0 16:15:21 INFO - PROCESS | 1668 | 16:15:21 INFO - PROCESS | 1668 | [#3] audio-fft Cycles:10 Average:66.50 Median:60.00 stddev:11.79 (19.6%) stddev-sans-first:9.90 16:15:21 INFO - PROCESS | 1668 | Values: 87.0 58.0 60.0 84.0 59.0 60.0 60.0 59.0 79.0 59.0 16:15:21 INFO - PROCESS | 1668 | 16:15:21 INFO - PROCESS | 1668 | [#4] audio-oscillator Cycles:10 Average:77.50 Median:80.00 stddev:7.91 (9.9%) stddev-sans-first:8.13 16:15:21 INFO - PROCESS | 1668 | Values: 83.0 70.0 87.0 77.0 70.0 72.0 88.0 70.0 71.0 87.0 16:15:21 INFO - PROCESS | 1668 | 16:15:21 INFO - PROCESS | 1668 | [#5] imaging-gaussian-blur Cycles:10 Average:90.50 Median:91.50 stddev:1.96 (2.1%) stddev-sans-first:2.00 16:15:21 INFO - PROCESS | 1668 | Values: 92.0 89.0 92.0 89.0 93.0 88.0 93.0 90.0 91.0 88.0 16:15:21 INFO - PROCESS | 1668 | 16:15:21 INFO - PROCESS | 1668 | [#6] imaging-darkroom Cycles:10 Average:97.10 Median:97.00 stddev:0.99 (1.0%) stddev-sans-first:1.05 16:15:21 INFO - PROCESS | 1668 | Values: 97.0 97.0 97.0 96.0 99.0 96.0 97.0 98.0 98.0 96.0 16:15:21 INFO - PROCESS | 1668 | 16:15:21 INFO - PROCESS | 1668 | [#7] imaging-desaturate Cycles:10 Average:104.50 Median:85.00 stddev:25.22 (29.7%) stddev-sans-first:26.20 16:15:21 INFO - PROCESS | 1668 | Values: 90.0 74.0 136.0 135.0 89.0 88.0 91.0 121.0 82.0 139.0 16:15:21 INFO - PROCESS | 1668 | 16:15:21 INFO - PROCESS | 1668 | [#8] json-parse-financial Cycles:10 Average:66.90 Median:65.00 stddev:13.57 (20.9%) stddev-sans-first:13.69 16:15:21 INFO - PROCESS | 1668 | Values: 55.0 58.0 57.0 56.0 59.0 71.0 92.0 86.0 76.0 59.0 16:15:21 INFO - PROCESS | 1668 | 16:15:21 INFO - PROCESS | 1668 | [#9] json-stringify-tinderbox Cycles:10 Average:73.00 Median:72.50 stddev:3.89 (5.4%) stddev-sans-first:3.68 16:15:21 INFO - PROCESS | 1668 | Values: 68.0 70.0 78.0 81.0 74.0 72.0 72.0 73.0 70.0 72.0 16:15:21 INFO - PROCESS | 1668 | 16:15:21 INFO - PROCESS | 1668 | [#10] stanford-crypto-aes Cycles:10 Average:98.60 Median:89.00 stddev:10.94 (12.3%) stddev-sans-first:11.03 16:15:21 INFO - PROCESS | 1668 | Values: 89.0 95.0 108.0 114.0 110.0 112.0 90.0 88.0 89.0 91.0 16:15:21 INFO - PROCESS | 1668 | 16:15:21 INFO - PROCESS | 1668 | [#11] stanford-crypto-ccm Cycles:10 Average:162.20 Median:163.00 stddev:13.70 (8.4%) stddev-sans-first:14.21 16:15:21 INFO - PROCESS | 1668 | Values: 154.0 165.0 144.0 159.0 161.0 183.0 165.0 154.0 187.0 150.0 16:15:21 INFO - PROCESS | 1668 | 16:15:21 INFO - PROCESS | 1668 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:202.20 Median:214.00 stddev:29.57 (13.8%) stddev-sans-first:29.33 16:15:21 INFO - PROCESS | 1668 | Values: 232.0 204.0 189.0 240.0 183.0 162.0 178.0 171.0 224.0 239.0 16:15:21 INFO - PROCESS | 1668 | 16:15:21 INFO - PROCESS | 1668 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:79.50 Median:83.50 stddev:9.38 (11.2%) stddev-sans-first:9.15 16:15:21 INFO - PROCESS | 1668 | Values: 69.0 79.0 89.0 91.0 89.0 81.0 71.0 76.0 86.0 64.0 16:15:21 INFO - PROCESS | 1668 | -------- Summary: end -------- 16:15:21 INFO - PROCESS | 1668 | 16:15:22 INFO - TEST-INFO | 1668: exit 0 16:15:22 INFO - TEST-OK | kraken | took 61391ms 16:15:22 INFO - SUITE-END | took 1178s 16:15:22 INFO - Completed test suite (00:19:38) 16:15:22 INFO - javascript benchmark 16:15:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7216.57159057156, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3357.7649564530493, 3382.424987752082, 3401.6938475537213, 3407.846646900889, 3409.2329225924163, 5574.777008919648, 5676.943230567693, 5692.971535142324, 5756.683382413966, 5779.595428320014, 5440.755166017529, 5598.524125449331, 5667.744951477183, 5694.174344720023, 5811.27359080115, 4349.304111342186, 5045.672031317971, 5167.741612919367, 5220.138677118271, 5295.7881684732565, 5193.246979188016, 5298.496642818926, 5309.707966061865, 5323.653962492445, 5343.679379237239, 5112.386513618352, 5157.123289040872, 5256.710880901549, 5262.789488420459, 5262.973685131588, 5178.818741344073, 5209.166533354645, 5213.191955246943, 5227.738613069331, 5234.895302093975, 5136.5890728741615, 5140.948590514085, 5149.665271757362, 5158.509941555546, 5179.326687530597, 5432.429594892519, 5450.400455949843, 5702.686352250625, 5714.114312281603, 5720.141978703186, 5176.456472070454, 6099.975683468243, 6158.230221222348, 6238.407351301619, 6290.811275661692, 6270.028145637433, 6274.937250627527, 6288.056791481269, 6319.052142178664, 6332.905006424908, 5757.1652110444165, 5860.1181162983485, 5862.501687356537, 5877.206577112121, 5923.526117910556, 6214.658193799384, 6217.595856269328, 6268.341824108494, 6280.528960327993, 6294.748210071546, 6137.683438940172, 6150.077488376779, 6155.230596175478, 6157.538184636081, 6178.382161783786, 6254.74981000764, 6259.342769009279, 6267.71795269214, 6267.843303917439, 6275.278342990615, 5420.268263784417, 6089.5737298388685, 6103.034636161462, 6116.914073580098, 6126.632402055801, 6139.737467782837, 6146.323904370515, 6163.322034576193, 6186.81439556814, 6270.435660790551, 4070.0, 4951.554360107608, 4955.950440495621, 4958.8760280993265, 4965.404151501771, 4406.228909940812, 4411.250087485135, 4421.9336709949375, 4425.712328698689, 4487.035287413222, 5605.467480589337, 5766.452187042224, 5843.065109582446, 5892.793752218652, 5926.525877929756, 6233.96883015582, 6234.563580549318, 6252.656103914237, 6253.74985000604, 6268.686565671698, 5866.560007999332, 5877.441643043904, 5901.675407852524, 5907.497862681635, 5972.25346831646, 5190.766415511311, 5224.451432599522, 5302.390225124083, 5408.864778380494, 5440.727963601804, 6319.241690996926, 6339.270983836917, 6365.681715914278, 6384.042393640805, 6389.8722025560155, 6252.624842509464, 6255.749770009149, 6260.968695156495, 6275.843103922439, 6281.654509002049, 6173.753049877954, 6203.255609326729, 6256.812295631138, 6352.333005034405, 6363.5863668861375, 6122.663253521099, 6198.690065496798, 6209.192804935329, 6224.470919971853, 6236.158118653923, 6213.471854892385, 6250.281217659845, 6282.057691346335, 6312.684365781784, 6320.4311611955145, 5675.886482270249, 5877.265341832271, 6002.159697642421, 6122.173506576555, 6126.540509461897, 5488.204210389391, 6029.246344206974, 6030.396960303934, 6094.0, 6138.0, 4884.413870335582, 5806.66446717249, 5997.820065398045, 6009.128676341993, 6013.0, 5890.175375447355, 5978.551608629283, 6010.278766548042, 6030.849228769316, 6129.295131059986, 5853.94146058551, 5854.502367298657, 5862.765489380377, 5871.383504732113, 5874.882502350015], "value": 5673.106819756447, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [489.93385892904445, 497.8506448065584, 515.5334422347776, 520.7396301849074, 540.970246636435, 38793.61206387935, 40303.0, 41931.79034104829, 42188.15623687524, 42199.156016879686, 30197.79208831644, 34213.97358079262, 43162.7841860791, 43223.78388108063, 43281.567184328145, 9486.667966621153, 9690.90309096907, 9691.127798498133, 9707.320487565874, 9712.514374281292, 9460.8580871287, 9562.330636855422, 9573.946865844751, 9575.0, 9594.664186753465, 8198.38512111591, 9440.0, 9523.333366664336, 9582.520873956275, 9630.951845240765, 8429.747107586782, 9369.063093690611, 9556.61773529058, 9611.375260608038, 9644.324897257195, 9463.763405914839, 9574.377665451793, 9575.760605984906, 9583.0, 9585.472798996052, 41082.79458602718, 41232.79383603063, 41493.0, 41504.58495415037, 41798.37302440466, 3451.11996440906, 3452.5166476693284, 3622.166901612644, 3834.501514803058, 4113.938290925638, 24834.5033099337, 29609.851950740325, 30411.783528658827, 30527.0, 31754.047378578678, 3233.805971641709, 3234.8544315505624, 3237.3848968695875, 3291.6504233264486, 3332.683395077464, 402919.0, 494221.0577894211, 502740.0, 504744.0, 510358.0, 53269.40191794252, 57231.71384143052, 62942.37057629365, 63688.68155659285, 63690.36309636937, 54388.72805635946, 66017.6699116511, 66912.33087669159, 67154.0, 67283.66358168177, 15922.522324330288, 16025.038497690175, 16237.269322880498, 16252.674946501003, 16253.837461625233, 12364.938175309066, 12492.937535312265, 12511.49954001848, 12544.498220071277, 12573.1198816082, 19274.903964861845, 19363.934983575757, 19397.059551507296, 19503.414897553095, 19574.902125489283, 6802.727890884309, 7847.332976697045, 7957.204279572113, 7991.24083212094, 7996.60059489587, 836.5566249887569, 915.6383228624655, 917.9449233046039, 919.4253591505309, 972.7033254857337, 29703.702962970525, 30352.54471182934, 30492.84753576218, 30650.54024189639, 30657.386852263277, 926.5598840550684, 997.8652881860855, 1049.611643691839, 1062.6971313175864, 1120.6581992492206, 14358.210298433687, 15007.624809379853, 15676.68646627061, 15809.477559798426, 15940.681186376207, 615648.0, 622000.0, 622225.0, 624558.0, 626103.0, 57883.71058144682, 58100.70949645225, 58223.0, 58240.70879645574, 58796.706016470496, 1332.5003123828565, 1339.2901762066122, 1374.0656353679394, 1377.091119860907, 1386.7989141574415, 24172.274831755076, 27328.86335568389, 29459.705402945696, 29996.400071998003, 30260.394792103594, 41203.7939810299, 50249.49750502448, 50774.49225507698, 50987.0, 51086.74456627693, 529177.354113227, 537450.0, 540814.0, 541219.0, 543008.0, 26703.065392711163, 26738.866305668347, 26952.056678016175, 27224.183274501796, 27301.590476142475, 47387.289190661475, 48265.0, 48852.0, 49262.75368623134, 49429.50570494393, 38200.426993595676, 40065.59934400619, 40144.59855401408, 40566.18867622572, 41442.58557414508, 30076.0, 33044.0, 34928.65071349254, 39539.80230098831, 40123.796286111465, 37630.247395052495, 38156.427653584666, 38855.0, 38887.6111238884, 38972.80513597414, 32789.344213116085, 34485.655143448246, 34578.0, 34817.0, 35012.649873501956, 21651.02570384368, 23046.539069218186, 25923.481530369663, 26465.94136234529, 26534.867325663246], "value": 19838.05348444353, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [336.5624687905722, 374.67029014467283, 378.5362930410248, 386.81046287319185, 399.1159581526919, 19414.805851941477, 19532.121054552543, 19581.70627440586, 20155.29456469022, 20396.490087747778, 9783.902160978389, 11859.229150105237, 12070.577529786458, 12160.695982600439, 12212.81680774787, 5257.553107985826, 5457.890842183154, 5545.306836645419, 5546.278983732109, 5549.417311182328, 3897.1036661567855, 4122.402251673506, 5366.275552800361, 5490.615656904019, 5812.767489300423, 6059.969700151515, 6084.634921904678, 6177.567570270083, 6178.598391104586, 6183.412575805291, 5535.640183388067, 5870.73581688825, 5888.352281249059, 5956.404359564052, 6135.417135372132, 5585.218069470277, 5746.827595172152, 5750.568707346965, 5752.07966725322, 5764.452377024176, 12678.858902698708, 13121.47514099435, 19929.701054484194, 24186.39534011646, 24556.526608403554, 1875.2311552263507, 2126.851120421571, 2136.6474531702406, 2152.074607918594, 2162.243214874797, 9428.481433521154, 10011.49942502872, 10285.074343309137, 10516.106130978877, 10555.0, 1873.4473330367575, 1932.4299331697182, 1947.5325921778813, 1955.4915721912405, 1957.784643689193, 111500.0, 114396.42801786105, 114406.0, 114967.0, 115518.0, 11304.265222760494, 11454.198206125491, 11541.769164616828, 11628.360440175702, 11821.940890295493, 11886.276489908914, 14399.712005759826, 14465.349059292357, 14502.0, 14517.346719397652, 8045.477043992122, 8259.42184047123, 8290.46112002718, 8293.875591866126, 8363.62363693623, 6961.26906674802, 7956.801079973047, 8007.839843203104, 8117.350611951029, 8169.5098294102545, 3401.6018706811637, 3747.9250414991548, 4055.7160998729805, 4456.264716321771, 4492.460904691458, 1570.175657779652, 1572.842715728418, 1687.4684474390529, 1968.39963811039, 1971.2706298669584, 728.4281838756657, 811.7564730580802, 837.6942416018231, 884.6151923913119, 933.7478880702172, 16905.7464138038, 21174.364769056952, 21275.0, 21363.893180533996, 21366.465838353855, 813.6338647608482, 922.3589605224305, 939.9013103624158, 940.2759874896428, 949.7245798718433, 9044.005159432458, 10778.568857245778, 10832.241743078066, 11402.771944561062, 11549.13381496391, 237399.0, 239450.80274598516, 239455.0, 239505.0, 239595.0, 117926.0, 123685.0, 127472.36263818621, 131920.0, 133127.0, 948.7770373962113, 962.6438217859297, 1002.1832206751474, 1006.4716024087413, 1010.8433192855119, 19023.04884755729, 19200.71198932045, 19218.807811922263, 19243.711344329568, 19467.221311147394, 52454.73772631113, 53460.198097027795, 55261.0, 55741.0, 56149.0, 80393.19606804091, 81350.0, 81424.5928770376, 81914.0, 82470.0, 6934.16789985205, 7358.0, 7907.096825444055, 8094.635741391769, 8269.669213231404, 8386.874196887173, 11921.225120367495, 12710.936445317715, 13062.150960187557, 13564.186148831102, 9133.954330228305, 9334.673286434941, 10704.250702450754, 11046.558137674725, 11335.603253886404, 9178.495182765013, 11237.55049797999, 11293.661190164308, 11326.0, 11424.771504569695, 11383.0, 11507.0, 11638.41807909618, 11697.649070527897, 11785.05546584804, 6301.8109456716375, 6606.141201643755, 7004.4046256068805, 7595.354394876497, 8489.4481858679, 7400.777976660709, 9142.771430714285, 9874.75313117149, 11483.081353491867, 11562.843715628369], "value": 8835.549388241488, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1461.7588097963835, 1564.2804310017389, 1777.893326400416, 1852.527605460609, 1886.9150888210029, 8002.719904803333, 8321.375896807733, 8475.110113438093, 8957.328200384973, 9040.367174297802, 7693.692252309915, 8528.275096616779, 8828.337874659393, 8877.979032411275, 8965.775855603613, 2353.6587194856734, 2373.964390534143, 2403.2549909528016, 2407.253751337091, 2408.5664580375524, 2362.2913126062112, 2364.7044119485063, 2377.9762202378015, 2405.0860672944254, 2407.831451798375, 2355.5642206191824, 2393.533261014103, 2400.8199385046105, 2411.2043025801445, 2411.493586346869, 2359.7050368703913, 2362.9054837806466, 2364.3498038039506, 2372.833901626887, 2376.310869847742, 2308.56137333906, 2335.602947498929, 2344.5076533927895, 2348.8120950323932, 2349.612313968193, 8938.46369217849, 8987.820243595157, 9011.549422528848, 9019.278457723367, 9068.093190680946, 5277.182036784305, 5469.316335458067, 5474.72626368684, 5477.287952566181, 5477.75350109246, 3608.116011577154, 3627.546556680415, 3661.2677464506937, 3698.1124530112847, 3701.1857391373856, 1061.541663775762, 1074.7796701676139, 1079.8434227037114, 1084.4415126210008, 1092.814221582333, 11547.884521154765, 11665.766684666343, 11706.590269340617, 11738.882611173864, 11748.0, 4578.702384345007, 4748.264019077049, 4817.325574419584, 4852.199387101159, 4870.659053866266, 9983.950080249553, 9989.250806189564, 10097.444640544694, 10105.9494702526, 10107.848382274271, 5378.757955891994, 5420.593455490854, 5432.320959880015, 5432.945670543244, 5437.4562543745305, 4459.576340247671, 4593.885152871205, 4786.210275304606, 4920.040592084581, 4966.279889416087, 3930.449737036817, 3999.3601023836045, 4054.71616986814, 4096.221717873595, 4156.709030367845, 1309.4696647857634, 1319.7954317080869, 1324.43711422645, 1333.120140707148, 1334.1061488802331, 889.7597648634962, 922.5018490015451, 924.4083786376724, 934.0892629686138, 941.1623654947102, 4983.053219888209, 5646.830595082154, 5671.376148623648, 5781.508571771446, 5842.123681447817, 815.1970309245388, 923.9399439036441, 946.668665966906, 969.9030096990244, 1018.0277834667875, 3356.6139893912023, 3720.6465385788306, 3865.8840234793, 3898.7075969302414, 3977.8209980550946, 10521.684349469528, 10773.299735517166, 10900.018998290192, 10902.07332376741, 10917.727056823644, 10811.83782243267, 10854.29447085937, 10855.185861060294, 10859.34843909368, 10925.836112458319, 8185.222403871623, 8238.752837414888, 8306.0, 8324.62539185738, 8327.20891515305, 5577.44225577439, 5874.765009399576, 5922.644641321534, 6001.909971350346, 6031.336552979256, 9592.328537002342, 9754.609815607297, 10021.248406369406, 10091.596336146471, 10125.291229613857, 10256.538455769658, 10258.0, 10258.281920265808, 10265.486725663837, 10271.383716977005, 6945.930540694529, 7164.0, 7321.890171647536, 7539.811504712426, 7716.498427602412, 8338.082810890675, 8552.957235213784, 8603.39776215684, 8608.225259726654, 8648.0, 7406.925930740624, 7446.73936412223, 7578.734744283924, 7636.0, 7685.346745526693, 6164.260288765377, 7425.925740742739, 7438.628068596657, 7495.1005879294835, 7633.6564854580565, 7018.789436316919, 7023.403010743939, 7093.1488221413765, 7109.0, 7116.50184487081, 4941.703497790144, 5017.42299635532, 5032.421271553671, 5039.370078740158, 5091.541761241506, 4872.6589138761365, 4901.681390709735, 4970.030843985388, 5135.409427915688, 5185.714785686823], "value": 4629.662731022207, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [294.3362717073, 294.41117764471045, 308.8779931926889, 314.6428803308244, 315.75371210455836, 5238.611767881514, 5246.2917506136755, 5420.0, 5518.586106042043, 5536.501714845663, 4923.433805112404, 5393.4336894626085, 5468.26178465907, 5474.616776825624, 5504.532114770239, 3502.964970350302, 3754.3805272130066, 3963.0686788604653, 4163.646090082354, 4214.873553793391, 3857.4599556062035, 4122.546519882811, 4215.557366476522, 4222.15556888622, 4255.851045213418, 4081.8775436736796, 4088.9182216355657, 4093.7543747375253, 4113.732607380525, 4124.8143833527565, 4102.487189101363, 4104.8152833122585, 4108.548059713429, 4133.91732165358, 4153.525498448038, 3571.8928432147077, 3572.8392222350058, 3587.7488575799703, 3598.6581274778855, 3599.280143971216, 5213.478652134794, 5337.690631808265, 5352.57179425645, 5378.596605254622, 5393.595480338951, 3959.4060890866313, 3969.3252147134767, 4737.38414006177, 4742.059922847857, 4753.027493590156, 5448.447192550144, 5622.240997465331, 5799.710014499258, 5811.6803575803315, 5831.795887143972, 2960.2007457986438, 3082.6763189864964, 3416.367971925177, 3420.521127042191, 3790.2419516096893, 4813.663043586916, 5087.821926232564, 5574.972125139429, 5636.900804343155, 6158.0147176452, 3404.7446441516986, 3675.280292583469, 3756.511653485084, 3966.7421617594764, 3994.8801535953967, 3613.5663720353728, 3823.2735780201674, 4021.5174179098112, 4051.736637118578, 4064.288749468831, 3943.684505239574, 4068.7558746475306, 4678.450244616692, 4728.267118596623, 4796.952030479651, 5859.882802343929, 5880.676562789087, 5941.197938278364, 5948.940510594839, 5968.895754285384, 1181.6809461445362, 1286.0740267007739, 1328.6678330417396, 1351.722622122105, 1364.860341614743, 749.3143773447343, 848.6859861851154, 853.6926706385697, 899.0335389456361, 905.5653286422554, 4407.955920440819, 4816.494268101869, 5388.488093631099, 5415.593830462731, 5433.320834895638, 5504.972475137599, 5674.914876276859, 5889.941100589025, 5915.160722853055, 5933.881322373615, 4566.634669226454, 5158.97420512895, 5377.650452720561, 5422.749558933077, 5507.504324610804, 2649.5893136563864, 2649.7281304973335, 2650.6819181697933, 2659.9069032583766, 2675.952896034535, 4553.954460455419, 4945.480724523945, 5127.3083379966365, 5220.243064755589, 5280.6303558750515, 4524.615407690383, 4559.726416415026, 4661.743604101807, 5167.6641018333685, 5169.948300517022, 4524.457065152203, 5119.59043276537, 5223.2948551945765, 5229.790808367623, 5271.8385461960415, 4030.858919937788, 4096.692748043849, 4441.578050085237, 4484.578327703004, 5127.846164614918, 3986.0234242610745, 4331.675124365622, 4653.906921861611, 4945.876353091202, 5036.395632524121, 4295.806688699078, 4483.798229079634, 4600.976995115003, 4948.62885283598, 5005.873678422471, 3005.8196508209576, 3024.75801935849, 3409.573803274591, 3426.3147370525494, 3482.2513159670793, 4860.732659703609, 4914.631402644744, 4993.0, 5007.824726134568, 5239.528442440199, 5347.7593508293, 5417.241586178017, 5826.76692932278, 5831.35855055952, 5870.442307980735, 5225.372955245243, 5432.375276843213, 5435.266239057834, 5889.411058894077, 5907.3501914790195, 5156.097682905402, 5520.282363292747, 5713.800016999385, 5766.826995190151, 5789.073748200268, 5188.948110518846, 5382.434844341287, 5583.74873130702, 5845.649261044351, 5879.559033072451, 4886.971906658697, 4887.804487820447, 5123.925644613202, 5696.430356964271, 5698.521143302677], "value": 3972.48858387672, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [613.7667686279215, 689.5483458334793, 705.4427002667893, 785.1794874356301, 804.3484777330362, 14799.33402996865, 14956.0, 15662.21688915554, 15944.441944531944, 15993.600159996005, 14479.855201447983, 14775.113493190416, 14775.48285809997, 14796.408143674267, 14809.407623695039, 701.8210356359147, 856.678745470447, 881.2597418168737, 881.7663319220412, 882.995585022074, 716.0905649824746, 855.7646647172015, 875.6366108065146, 882.280941033056, 887.7114937645259, 934.6495064350869, 936.9344145909755, 938.8310104181245, 940.2994768897181, 943.5848226780229, 914.2183433164648, 934.3879758758025, 936.3632729743772, 936.8782058332441, 940.6096469964956, 675.4360109308684, 691.2707094015811, 698.5005720909573, 698.7135274537416, 700.2857085772534, 14958.551243462605, 14983.850161498463, 14988.625284367869, 15002.774958375632, 15032.947693661448, 14295.857041429557, 14425.639359016004, 14492.565223043326, 14533.34599943005, 14723.852761472355, 11846.940765296205, 11911.10666700001, 11974.22167559106, 12002.839488891996, 12064.457099430547, 3768.623137686237, 3825.0437390652337, 3849.499565056553, 3961.564227934925, 3963.2667956427863, 114972.0, 117264.0, 118276.0, 118417.40791295988, 121120.0, 11109.0, 11178.608748693756, 11193.832092518618, 11215.66353009411, 11413.97274245322, 22694.97872595737, 22940.655890161663, 22962.31113066611, 22964.081436742676, 23000.65499017516, 7722.189170137167, 7824.334931530883, 7875.960620196863, 8024.83950321002, 8030.996125484315, 4147.502299723992, 4173.874783756492, 4231.153769246163, 4253.404523366731, 4374.321980093091, 3589.7666651668083, 3650.0, 3688.944665830015, 3702.407614781622, 3713.867270482529, 601.2064075420491, 623.9500839932795, 624.8531595075153, 635.841039740065, 644.3620815392821, 628.355935166449, 644.2301449767576, 655.0531612251999, 662.1259936883267, 669.6149713914518, 8014.470294538163, 9048.41185322952, 9176.632934682671, 9258.305627077996, 9383.436993780395, 529.6795438759495, 536.9207892136833, 622.2377587455421, 654.8493846415352, 693.639307560066, 4206.3690446432665, 4373.606375426163, 4485.116251174514, 4671.556202160789, 4939.827106051271, 85843.84936903806, 133571.0, 142586.0, 147028.0, 147615.0, 96600.0, 99851.50074249727, 102988.0, 128952.35523822322, 131122.0, 86250.0, 101316.0, 101778.49110754547, 101975.0, 104194.0, 8789.296856251613, 9347.672831450867, 9682.644429779977, 9922.107010369102, 9926.205903527845, 52118.69703257449, 55912.44087559235, 59824.0, 61420.77158456895, 62594.37405625886, 65628.34371656414, 84352.15647843688, 86128.56935715281, 88049.0, 89286.0, 12984.285864277555, 13155.210687358789, 13159.00926262718, 13261.867381326449, 13652.249126297751, 11881.287122772661, 14681.19253441071, 14904.701905962036, 15113.244337782848, 15219.619509512351, 10678.73303167427, 11607.76784464323, 11958.0, 13183.736325273632, 13782.0, 13592.79610805819, 13595.660108497366, 13883.167009979434, 14040.876729861382, 14392.0, 12874.291913944822, 13179.341032948507, 13385.86614133885, 13389.062765606313, 14118.788218176942, 9464.0, 10029.448380339152, 10548.841767373342, 11655.708607284885, 11827.763444731228, 9274.397164184307, 10562.78874422492, 11438.42807859587, 11501.48243329069, 11620.477078531318], "value": 7722.787496905613, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1590.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 118.0, 118.0, 116.0, 116.0, 118.0, 119.0, 98.0, 173.0, 174.0], "value": 125.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 102.0, 153.0, 181.0, 143.0, 122.0, 118.0, 116.0, 145.0, 116.0], "value": 134.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [190.0, 209.0, 269.0, 280.0, 192.0, 194.0, 197.0, 196.0, 197.0, 198.0], "value": 212.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 58.0, 60.0, 84.0, 59.0, 60.0, 60.0, 59.0, 79.0, 59.0], "value": 66.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 70.0, 87.0, 77.0, 70.0, 72.0, 88.0, 70.0, 71.0, 87.0], "value": 77.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 89.0, 92.0, 89.0, 93.0, 88.0, 93.0, 90.0, 91.0, 88.0], "value": 90.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 97.0, 97.0, 96.0, 99.0, 96.0, 97.0, 98.0, 98.0, 96.0], "value": 97.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 74.0, 136.0, 135.0, 89.0, 88.0, 91.0, 121.0, 82.0, 139.0], "value": 104.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 58.0, 57.0, 56.0, 59.0, 71.0, 92.0, 86.0, 76.0, 59.0], "value": 66.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 70.0, 78.0, 81.0, 74.0, 72.0, 72.0, 73.0, 70.0, 72.0], "value": 73.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 95.0, 108.0, 114.0, 110.0, 112.0, 90.0, 88.0, 89.0, 91.0], "value": 98.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 165.0, 144.0, 159.0, 161.0, 183.0, 165.0, 154.0, 187.0, 150.0], "value": 162.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [232.0, 204.0, 189.0, 240.0, 183.0, 162.0, 178.0, 171.0, 224.0, 239.0], "value": 202.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 79.0, 89.0, 91.0, 89.0, 81.0, 71.0, 76.0, 86.0, 64.0], "value": 79.5, "unit": "score"}]}]} 16:15:22 INFO - Return code: 0 16:15:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:15:22 ERROR - # TBPL SUCCESS # 16:15:22 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:15:22 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:15:22 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:15:22 INFO - 2016-10-18 16:15:22.785 screenresolution[1673:10429] starting screenresolution argv=screenresolution get 16:15:22 INFO - 2016-10-18 16:15:22.788 screenresolution[1673:10429] Display 0: 1600x1200x32@60 16:15:22 INFO - 2016-10-18 16:15:22.794 screenresolution[1674:10432] starting screenresolution argv=screenresolution list 16:15:22 INFO - Available Modes on Display 0 16:15:22 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:15:22 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:15:22 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:15:22 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:15:23 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:15:23 INFO - Intel Iris: 16:15:23 INFO - Chipset Model: Intel Iris 16:15:23 INFO - Type: GPU 16:15:23 INFO - Bus: Built-In 16:15:23 INFO - VRAM (Dynamic, Max): 1536 MB 16:15:23 INFO - Vendor: Intel (0x8086) 16:15:23 INFO - Device ID: 0x0a2e 16:15:23 INFO - Revision ID: 0x0009 16:15:23 INFO - Displays: 16:15:23 INFO - Display: 16:15:23 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:15:23 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:15:23 INFO - Display Serial Number: 1600x1200 60 16:15:23 INFO - Main Display: Yes 16:15:23 INFO - Mirror: Off 16:15:23 INFO - Online: Yes 16:15:23 INFO - Rotation: Supported 16:15:23 INFO - Return code: 0 16:15:23 INFO - Running post-action listener: _resource_record_post_action 16:15:23 INFO - [mozharness: 2016-10-18 23:15:23.040053Z] Finished run-tests step (success) 16:15:23 INFO - Running post-run listener: _resource_record_post_run 16:15:23 INFO - Total resource usage - Wall time: 1215s; CPU: 55.0%; Read bytes: 38246912; Write bytes: 709658624; Read time: 249; Write time: 2001 16:15:23 INFO - TinderboxPrint: CPU usage
54.7% 16:15:23 INFO - TinderboxPrint: I/O read bytes / time
38,246,912 / 249 16:15:23 INFO - TinderboxPrint: I/O write bytes / time
709,658,624 / 2,001 16:15:23 INFO - TinderboxPrint: CPU idle
2,199.7 (45.3%) 16:15:23 INFO - TinderboxPrint: CPU system
984.7 (20.3%) 16:15:23 INFO - TinderboxPrint: CPU user
1,673.4 (34.4%) 16:15:23 INFO - TinderboxPrint: Swap in / out
445,018,112 / 0 16:15:23 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 401783296; Write bytes: 396011008; Read time: 30862; Write time: 814 16:15:23 INFO - run-tests - Wall time: 1180s; CPU: 55.0%; Read bytes: 36141568; Write bytes: 299401728; Read time: 229; Write time: 1140 16:15:23 INFO - Running post-run listener: _upload_blobber_files 16:15:23 INFO - Blob upload gear active. 16:15:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:15:23 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:15:23 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', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:15:23 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 Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:15:23 INFO - (blobuploader) - INFO - Open directory for files ... 16:15:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:15:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:15:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:15:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:15:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:15:24 INFO - (blobuploader) - INFO - Done attempting. 16:15:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:15:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:15:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:15:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:15:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:15:25 INFO - (blobuploader) - INFO - Done attempting. 16:15:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:15:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:15:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:15:28 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:15:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:15:28 INFO - (blobuploader) - INFO - Done attempting. 16:15:28 INFO - (blobuploader) - INFO - Iteration through files over. 16:15:28 INFO - Return code: 0 16:15:28 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:15:28 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:15:28 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/81eba48faefff6d307be3b353ee7f562f267755340c06cee054426f867d58acb479f2178a16e43af8e03370fd537dc668bfbfe3bd25c6ab895e9cc27719e3d0f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/dc11f3b6a3626c2ca599db03746d254f2686f7e18ecad2cd08df4d08828e318e1ccf52a194c5c6e7f075c0a08aff8ec0619d04a38d16628d3f7651dda6705856", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/78633d819a82ca2d57101e6e16bb33a12b90bdaadcf828c61eff2820644d2e762a76841c7a4932f602be761cf1b483bb0a54ba5141af93f36fd3aec5fb159233"} 16:15:28 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:15:28 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:15:28 INFO - Contents: 16:15:28 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/81eba48faefff6d307be3b353ee7f562f267755340c06cee054426f867d58acb479f2178a16e43af8e03370fd537dc668bfbfe3bd25c6ab895e9cc27719e3d0f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/dc11f3b6a3626c2ca599db03746d254f2686f7e18ecad2cd08df4d08828e318e1ccf52a194c5c6e7f075c0a08aff8ec0619d04a38d16628d3f7651dda6705856", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/78633d819a82ca2d57101e6e16bb33a12b90bdaadcf828c61eff2820644d2e762a76841c7a4932f602be761cf1b483bb0a54ba5141af93f36fd3aec5fb159233"} 16:15:28 INFO - Running post-run listener: copy_logs_to_upload_dir 16:15:28 INFO - Copying logs to upload dir... 16:15:28 INFO - mkdir: /builds/slave/test/build/upload/logs 16:15:28 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.212255 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-10-18 16:15:28.660316) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-18 16:15:28.663482) ========= 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.CVkHpipyhk/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.aXTUrc26FD/Listeners TMPDIR=/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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/Autoland/sha512/81eba48faefff6d307be3b353ee7f562f267755340c06cee054426f867d58acb479f2178a16e43af8e03370fd537dc668bfbfe3bd25c6ab895e9cc27719e3d0f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/dc11f3b6a3626c2ca599db03746d254f2686f7e18ecad2cd08df4d08828e318e1ccf52a194c5c6e7f075c0a08aff8ec0619d04a38d16628d3f7651dda6705856", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/78633d819a82ca2d57101e6e16bb33a12b90bdaadcf828c61eff2820644d2e762a76841c7a4932f602be761cf1b483bb0a54ba5141af93f36fd3aec5fb159233"} build_url:https://queue.taskcluster.net/v1/task/WbqCzpGrTmWbmO2oCt2o7g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011104 build_url: 'https://queue.taskcluster.net/v1/task/WbqCzpGrTmWbmO2oCt2o7g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/81eba48faefff6d307be3b353ee7f562f267755340c06cee054426f867d58acb479f2178a16e43af8e03370fd537dc668bfbfe3bd25c6ab895e9cc27719e3d0f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/dc11f3b6a3626c2ca599db03746d254f2686f7e18ecad2cd08df4d08828e318e1ccf52a194c5c6e7f075c0a08aff8ec0619d04a38d16628d3f7651dda6705856", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/78633d819a82ca2d57101e6e16bb33a12b90bdaadcf828c61eff2820644d2e762a76841c7a4932f602be761cf1b483bb0a54ba5141af93f36fd3aec5fb159233"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-18 16:15:28.696437) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 16:15:28.696735) ========= 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.CVkHpipyhk/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.aXTUrc26FD/Listeners TMPDIR=/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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.004541 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 16:15:28.744917) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-18 16:15:28.745369) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-18 16:15:28.745694) ========= ========= Total master_lag: 0.18 =========