builder: graphics_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0068 starttime: 1479853692.41 results: success (0) buildid: 20161122113650 builduid: f73688ebcd404696896c5ac2efeb46d0 revision: 4cf8741bb86f96dcb62dc98594f109e7ed2733a1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-22 14:28:12.413056) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-22 14:28:12.413514) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-22 14:28:12.413801) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-22 14:28:12.438809) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 14:28:12.439170) ========= 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.vZtWipJufU/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.UFgG2ZkXKF/Listeners TMPDIR=/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-11-22 14:28: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% 149M=0s 2016-11-22 14:28:12 (149 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.115765 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 14:28:12.577298) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 14:28:12.577664) ========= 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.vZtWipJufU/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.UFgG2ZkXKF/Listeners TMPDIR=/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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.019640 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 14:28:12.626550) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 14:28:12.626903) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev 4cf8741bb86f96dcb62dc98594f109e7ed2733a1 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/graphics --rev 4cf8741bb86f96dcb62dc98594f109e7ed2733a1 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.vZtWipJufU/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.UFgG2ZkXKF/Listeners TMPDIR=/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-11-22 14:28:12,695 truncating revision to first 12 chars 2016-11-22 14:28:12,695 Setting DEBUG logging. 2016-11-22 14:28:12,695 attempt 1/10 2016-11-22 14:28:12,695 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/4cf8741bb86f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-22 14:28:13,400 unpacking tar archive at: graphics-4cf8741bb86f/testing/mozharness/ program finished with exit code 0 elapsedTime=0.959233 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 14:28:13.609152) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-22 14:28:13.609466) ========= script_repo_revision: 4cf8741bb86f96dcb62dc98594f109e7ed2733a1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-22 14:28:13.609908) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-22 14:28:13.610168) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-22 14:28:13.630616) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-11-22 14:28:13.631129) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Graphics --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Graphics', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Graphics'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.vZtWipJufU/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.UFgG2ZkXKF/Listeners TMPDIR=/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 14:28:13 INFO - MultiFileLogger online at 20161122 14:28:13 in /builds/slave/test 14:28:13 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Graphics --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 14:28:13 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:28:13 INFO - {'append_to_log': False, 14:28:13 INFO - 'base_work_dir': '/builds/slave/test', 14:28:13 INFO - 'blob_upload_branch': 'Graphics', 14:28:13 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:28:13 INFO - 'branch': 'Graphics', 14:28:13 INFO - 'buildbot_json_path': 'buildprops.json', 14:28:13 INFO - 'config_files': ('talos/mac_config.py',), 14:28:13 INFO - 'default_actions': ('clobber', 14:28:13 INFO - 'read-buildbot-config', 14:28:13 INFO - 'download-and-extract', 14:28:13 INFO - 'populate-webroot', 14:28:13 INFO - 'create-virtualenv', 14:28:13 INFO - 'install', 14:28:13 INFO - 'run-tests'), 14:28:13 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:28:13 INFO - 'download_minidump_stackwalk': True, 14:28:13 INFO - 'download_symbols': 'ondemand', 14:28:13 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:28:13 INFO - 'tooltool.py': '/tools/tooltool.py', 14:28:13 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:28:13 INFO - '/tools/misc-python/virtualenv.py')}, 14:28:13 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:28:13 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:28:13 INFO - 'installer_path': 'installer.exe', 14:28:13 INFO - 'log_level': 'info', 14:28:13 INFO - 'log_name': 'talos', 14:28:13 INFO - 'log_to_console': True, 14:28:13 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 14:28:13 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 14:28:13 INFO - 'opt_config_files': (), 14:28:13 INFO - 'pip_index': False, 14:28:13 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:28:13 INFO - 'cmd': ('bash', 14:28:13 INFO - '-c', 14:28:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:28:13 INFO - 'enabled': True, 14:28:13 INFO - 'halt_on_failure': False, 14:28:13 INFO - 'name': 'check_screen_resolution'},), 14:28:13 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:28:13 INFO - 'cmd': ('bash', 14:28:13 INFO - '-c', 14:28:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:28:13 INFO - 'enabled': True, 14:28:13 INFO - 'halt_on_failure': False, 14:28:13 INFO - 'name': 'check_screen_resolution'},), 14:28:13 INFO - 'run_cmd_checks_enabled': True, 14:28:13 INFO - 'sps_profile': False, 14:28:13 INFO - 'sps_profile_interval': 0, 14:28:13 INFO - 'suite': 'dromaeojs-e10s', 14:28:13 INFO - 'system_bits': '32', 14:28:13 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:28:13 INFO - 'title': 't-yosemite-r7-0068', 14:28:13 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:28:13 INFO - 'use_talos_json': True, 14:28:13 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:28:13 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:28:13 INFO - 'work_dir': 'build'} 14:28:13 INFO - [mozharness: 2016-11-22 22:28:13.825128Z] Running clobber step. 14:28:13 INFO - Running pre-action listener: _resource_record_pre_action 14:28:13 INFO - Running main action method: clobber 14:28:13 INFO - rmtree: /builds/slave/test/build 14:28:13 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:28:15 INFO - Running post-action listener: _resource_record_post_action 14:28:15 INFO - [mozharness: 2016-11-22 22:28:15.337912Z] Finished clobber step (success) 14:28:15 INFO - [mozharness: 2016-11-22 22:28:15.338105Z] Running read-buildbot-config step. 14:28:15 INFO - Running pre-action listener: _resource_record_pre_action 14:28:15 INFO - Running main action method: read_buildbot_config 14:28:15 INFO - Using buildbot properties: 14:28:15 INFO - { 14:28:15 INFO - "project": "", 14:28:15 INFO - "product": "firefox", 14:28:15 INFO - "script_repo_revision": "default", 14:28:15 INFO - "builddir": "graphics_yosemite_r7_test-dromaeojs-e10s", 14:28:15 INFO - "repository": "", 14:28:15 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 graphics talos dromaeojs-e10s", 14:28:15 INFO - "buildid": "20161122113650", 14:28:15 INFO - "pgo_build": "False", 14:28:15 INFO - "basedir": "/builds/slave/test", 14:28:15 INFO - "buildnumber": 3, 14:28:15 INFO - "slavename": "t-yosemite-r7-0068", 14:28:15 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 14:28:15 INFO - "platform": "yosemite_r7", 14:28:15 INFO - "scheduler": "tests-graphics-macosx64-talos", 14:28:15 INFO - "branch": "graphics", 14:28:15 INFO - "repo_path": "projects/graphics", 14:28:15 INFO - "revision": "4cf8741bb86f96dcb62dc98594f109e7ed2733a1", 14:28:15 INFO - "stage_platform": "macosx64", 14:28:15 INFO - "builduid": "f73688ebcd404696896c5ac2efeb46d0", 14:28:15 INFO - "slavebuilddir": "test" 14:28:15 INFO - } 14:28:15 INFO - Found installer url https://queue.taskcluster.net/v1/task/Dn6vgelbTQmaLPB-CzmrBw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 14:28:15 INFO - Running post-action listener: _resource_record_post_action 14:28:15 INFO - [mozharness: 2016-11-22 22:28:15.342033Z] Finished read-buildbot-config step (success) 14:28:15 INFO - [mozharness: 2016-11-22 22:28:15.342155Z] Running download-and-extract step. 14:28:15 INFO - Running pre-action listener: _resource_record_pre_action 14:28:15 INFO - Running main action method: download_and_extract 14:28:15 INFO - mkdir: /builds/slave/test/build/tests 14:28: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')]} 14:28:15 INFO - trying https://queue.taskcluster.net/v1/task/Dn6vgelbTQmaLPB-CzmrBw/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 14:28:15 INFO - Downloading https://queue.taskcluster.net/v1/task/Dn6vgelbTQmaLPB-CzmrBw/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 14:28:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Dn6vgelbTQmaLPB-CzmrBw/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 14:28:16 INFO - Downloaded 1199 bytes. 14:28:16 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 14:28:16 INFO - Using the following test package requirements: 14:28:16 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 14:28:16 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 14:28:16 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 14:28:16 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 14:28:16 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 14:28:16 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 14:28:16 INFO - u'jsshell-mac.zip'], 14:28:16 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 14:28:16 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 14:28:16 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 14:28:16 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 14:28:16 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 14:28:16 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 14:28:16 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 14:28:16 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 14:28:16 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 14:28:16 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 14:28:16 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 14:28:16 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 14:28:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Dn6vgelbTQmaLPB-CzmrBw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 14:28:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Dn6vgelbTQmaLPB-CzmrBw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:28:16 INFO - Fetch https://queue.taskcluster.net/v1/task/Dn6vgelbTQmaLPB-CzmrBw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 14:28:17 INFO - Http code: 200 14:28:17 INFO - via: 1.1 f1a40337a32137e1c23ceffead6a50d5.cloudfront.net (CloudFront) 14:28:17 INFO - x-amz-cf-id: zIxYqEwlXsyu3bN48WP8dhd3JPIi_bVkHHhcqZbVbPVFwNo8r68ugQ== 14:28:17 INFO - x-amz-version-id: 9QdWe88bhJonvVB2X6_EwShBvWC5MU97 14:28:17 INFO - Expected file size: 16248451 14:28:17 INFO - Obtained file size: 16248451 14:28:24 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 14:28:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Dn6vgelbTQmaLPB-CzmrBw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 14:28:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Dn6vgelbTQmaLPB-CzmrBw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:28:24 INFO - Fetch https://queue.taskcluster.net/v1/task/Dn6vgelbTQmaLPB-CzmrBw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 14:28:24 INFO - Http code: 200 14:28:24 INFO - via: 1.1 5ed093c37d643652e278a198e0556a40.cloudfront.net (CloudFront) 14:28:24 INFO - x-amz-cf-id: yHWi0eir_Zhf4Nrl2UCaSz6aHEpY16hf8XW6grDzZceotvWL8c9wFw== 14:28:24 INFO - x-amz-version-id: 9QdWe88bhJonvVB2X6_EwShBvWC5MU97 14:28:25 INFO - Expected file size: 16248451 14:28:25 INFO - Obtained file size: 16248451 14:28:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Dn6vgelbTQmaLPB-CzmrBw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 14:28:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Dn6vgelbTQmaLPB-CzmrBw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 14:28:31 INFO - Fetch https://queue.taskcluster.net/v1/task/Dn6vgelbTQmaLPB-CzmrBw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 14:28:32 INFO - Http code: 200 14:28:32 INFO - via: 1.1 e2af8a85927835558866752f53562ecd.cloudfront.net (CloudFront) 14:28:32 INFO - x-amz-cf-id: jQg4bDA3vi85Oggor-MEx0qo5ab_vxV4oGSXo3rXq5Wo6KOHX32RdQ== 14:28:32 INFO - x-amz-version-id: nTbaFWNf4sgO.XB3fY4MrSznt4zQCqvd 14:28:32 INFO - Expected file size: 14409392 14:28:32 INFO - Obtained file size: 14409392 14:28:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:28:33 INFO - trying https://queue.taskcluster.net/v1/task/Dn6vgelbTQmaLPB-CzmrBw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 14:28:33 INFO - Downloading https://queue.taskcluster.net/v1/task/Dn6vgelbTQmaLPB-CzmrBw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 14:28:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Dn6vgelbTQmaLPB-CzmrBw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 14:28:35 INFO - Downloaded 107618658 bytes. 14:28:35 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Dn6vgelbTQmaLPB-CzmrBw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 14:28:35 INFO - mkdir: /builds/slave/test/properties 14:28:35 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:28:35 INFO - Writing to file /builds/slave/test/properties/build_url 14:28:35 INFO - Contents: 14:28:35 INFO - build_url:https://queue.taskcluster.net/v1/task/Dn6vgelbTQmaLPB-CzmrBw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 14:28:36 INFO - Running post-action listener: _resource_record_post_action 14:28:36 INFO - Running post-action listener: set_extra_try_arguments 14:28:36 INFO - [mozharness: 2016-11-22 22:28:36.393666Z] Finished download-and-extract step (success) 14:28:36 INFO - [mozharness: 2016-11-22 22:28:36.393814Z] Running populate-webroot step. 14:28:36 INFO - Running pre-action listener: _resource_record_pre_action 14:28:36 INFO - Running main action method: populate_webroot 14:28:36 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:28:36 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:28:36 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:28:36 INFO - '--tppagecycles', 14:28:36 INFO - '7'], 14:28:36 INFO - 'tests': ['tsvgm']}}, 14:28:36 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:28:36 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:28:36 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:28:36 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:28:36 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:28:36 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:28:36 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 14:28:36 INFO - 'talos_options': ['--disable-e10s'], 14:28:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:28:36 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 14:28:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:28:36 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 14:28:36 INFO - 'talos_options': ['--disable-e10s'], 14:28:36 INFO - 'tests': ['damp', 'tps']}, 14:28:36 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 14:28:36 INFO - 'tests': ['damp', 'tps']}, 14:28:36 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:28:36 INFO - 'tests': ['dromaeo_dom']}, 14:28:36 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:28:36 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:28:36 INFO - 'tests': ['basic_compositor_video']}, 14:28:36 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:28:36 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:28:36 INFO - 'tests': ['a11yr', 14:28:36 INFO - 'ts_paint', 14:28:36 INFO - 'tpaint', 14:28:36 INFO - 'sessionrestore', 14:28:36 INFO - 'sessionrestore_no_auto_restore', 14:28:36 INFO - 'tabpaint']}, 14:28:36 INFO - 'other-e10s': {'tests': ['a11yr', 14:28:36 INFO - 'ts_paint', 14:28:36 INFO - 'tpaint', 14:28:36 INFO - 'sessionrestore', 14:28:36 INFO - 'sessionrestore_no_auto_restore', 14:28:36 INFO - 'tabpaint']}, 14:28:36 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:28:36 INFO - 'ts_paint', 14:28:36 INFO - 'tpaint', 14:28:36 INFO - 'sessionrestore', 14:28:36 INFO - 'sessionrestore_no_auto_restore', 14:28:36 INFO - 'tabpaint']}, 14:28:36 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:28:36 INFO - 'ts_paint', 14:28:36 INFO - 'tpaint', 14:28:36 INFO - 'sessionrestore', 14:28:36 INFO - 'sessionrestore_no_auto_restore', 14:28:36 INFO - 'tabpaint']}, 14:28:36 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:28:36 INFO - 'tests': ['a11yr', 14:28:36 INFO - 'ts_paint', 14:28:36 INFO - 'tpaint', 14:28:36 INFO - 'sessionrestore', 14:28:36 INFO - 'sessionrestore_no_auto_restore', 14:28:36 INFO - 'tabpaint']}, 14:28:36 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:28:36 INFO - 'tests': ['a11yr', 14:28:36 INFO - 'ts_paint', 14:28:36 INFO - 'tpaint', 14:28:36 INFO - 'sessionrestore', 14:28:36 INFO - 'sessionrestore_no_auto_restore', 14:28:36 INFO - 'tabpaint']}, 14:28:36 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:28:36 INFO - 'tests': ['tsvgx', 14:28:36 INFO - 'tsvgr_opacity', 14:28:36 INFO - 'tart', 14:28:36 INFO - 'tscrollx', 14:28:36 INFO - 'cart']}, 14:28:36 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:28:36 INFO - 'tsvgr_opacity', 14:28:36 INFO - 'tart', 14:28:36 INFO - 'tscrollx', 14:28:36 INFO - 'cart']}, 14:28:36 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 14:28:36 INFO - 'talos_options': ['--disable-e10s'], 14:28:36 INFO - 'tests': ['tp5o']}, 14:28:36 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 14:28:36 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 14:28:36 INFO - 'talos_options': ['--disable-e10s', 14:28:36 INFO - '--xperf_path', 14:28:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:28:36 INFO - 'tests': ['tp5n']}, 14:28:36 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 14:28:36 INFO - 'talos_options': ['--xperf_path', 14:28:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:28:36 INFO - 'tests': ['tp5n']}}, 14:28:36 INFO - 'talos.zip': {'path': '', 14:28:36 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:28:36 INFO - Running post-action listener: _resource_record_post_action 14:28:36 INFO - [mozharness: 2016-11-22 22:28:36.412227Z] Finished populate-webroot step (success) 14:28:36 INFO - [mozharness: 2016-11-22 22:28:36.412331Z] Running create-virtualenv step. 14:28:36 INFO - Running pre-action listener: _resource_record_pre_action 14:28:36 INFO - Running main action method: create_virtualenv 14:28:36 INFO - Creating virtualenv /builds/slave/test/build/venv 14:28: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 14:28:36 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:28:36 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:28:36 INFO - Using real prefix '/tools/python27' 14:28:36 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:28:37 INFO - Installing distribute.............................................................................................................................................................................................done. 14:28:40 INFO - Installing pip.................done. 14:28:40 INFO - Return code: 0 14:28:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 14:28:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 14:28:40 INFO - Reading from file tmpfile_stdout 14:28:40 INFO - Output received: 14:28: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) 14:28:40 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:28: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')]} 14:28:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:28:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:28: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')]} 14:28:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:28:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:28: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 0x10cc853f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbfb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa242d47440>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca9bd30>, '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 0x10c902db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca93b20>, '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.vZtWipJufU/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.UFgG2ZkXKF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 14:28: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 14:28: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 14:28:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vZtWipJufU/Render', 14:28:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:28:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:28:40 INFO - 'HOME': '/Users/cltbld', 14:28:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:28:40 INFO - 'LOGNAME': 'cltbld', 14:28:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:28:40 INFO - 'MOZ_NO_REMOTE': '1', 14:28:40 INFO - 'NO_EM_RESTART': '1', 14:28:40 INFO - 'PAGER': '/bin/cat', 14:28:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:28:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:28:40 INFO - 'PWD': '/builds/slave/test', 14:28:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:28:40 INFO - 'SHELL': '/bin/bash', 14:28:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UFgG2ZkXKF/Listeners', 14:28:40 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 14:28:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:28:40 INFO - 'USER': 'cltbld', 14:28:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:28:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:28:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:28:40 INFO - 'XPC_FLAGS': '0x0', 14:28:40 INFO - 'XPC_SERVICE_NAME': '0', 14:28:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:28:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:28: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 14:28:40 INFO - Cleaning up... 14:28:40 INFO - Return code: 0 14:28:40 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:28: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')]} 14:28:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:28:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:28: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')]} 14:28:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:28:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:28: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 0x10cc853f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbfb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa242d47440>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca9bd30>, '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 0x10c902db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca93b20>, '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.vZtWipJufU/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.UFgG2ZkXKF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 14:28: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 14:28: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 14:28:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vZtWipJufU/Render', 14:28:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:28:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:28:40 INFO - 'HOME': '/Users/cltbld', 14:28:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:28:40 INFO - 'LOGNAME': 'cltbld', 14:28:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:28:40 INFO - 'MOZ_NO_REMOTE': '1', 14:28:40 INFO - 'NO_EM_RESTART': '1', 14:28:40 INFO - 'PAGER': '/bin/cat', 14:28:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:28:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:28:40 INFO - 'PWD': '/builds/slave/test', 14:28:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:28:40 INFO - 'SHELL': '/bin/bash', 14:28:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UFgG2ZkXKF/Listeners', 14:28:40 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 14:28:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:28:40 INFO - 'USER': 'cltbld', 14:28:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:28:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:28:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:28:40 INFO - 'XPC_FLAGS': '0x0', 14:28:40 INFO - 'XPC_SERVICE_NAME': '0', 14:28:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:28:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:28:40 INFO - Downloading/unpacking psutil>=3.1.1 14:28:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:28:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:28:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:28:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:28:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:28:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:28:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:28:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:28:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:28:44 INFO - Installing collected packages: psutil 14:28:44 INFO - Running setup.py install for psutil 14:28:44 INFO - building 'psutil._psutil_osx' extension 14:28: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 14:28: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_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 14:28: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/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 14:28:44 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 14:28:44 INFO - building 'psutil._psutil_posix' extension 14:28:44 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 14:28:44 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 14:28:44 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 14:28:44 INFO - ^ 14:28:44 INFO - 1 warning generated. 14:28:44 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 14:28:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:28:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:28:45 INFO - Successfully installed psutil 14:28:45 INFO - Cleaning up... 14:28:45 INFO - Return code: 0 14:28:45 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:28: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')]} 14:28:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:28:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:28: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')]} 14:28:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:28:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:28: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 0x10cc853f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbfb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa242d47440>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca9bd30>, '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 0x10c902db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca93b20>, '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.vZtWipJufU/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.UFgG2ZkXKF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 14:28: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 14:28: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 14:28:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vZtWipJufU/Render', 14:28:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:28:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:28:45 INFO - 'HOME': '/Users/cltbld', 14:28:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:28:45 INFO - 'LOGNAME': 'cltbld', 14:28:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:28:45 INFO - 'MOZ_NO_REMOTE': '1', 14:28:45 INFO - 'NO_EM_RESTART': '1', 14:28:45 INFO - 'PAGER': '/bin/cat', 14:28:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:28:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:28:45 INFO - 'PWD': '/builds/slave/test', 14:28:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:28:45 INFO - 'SHELL': '/bin/bash', 14:28:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UFgG2ZkXKF/Listeners', 14:28:45 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 14:28:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:28:45 INFO - 'USER': 'cltbld', 14:28:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:28:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:28:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:28:45 INFO - 'XPC_FLAGS': '0x0', 14:28:45 INFO - 'XPC_SERVICE_NAME': '0', 14:28:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:28:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:28:45 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:28: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 14:28: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 14:28: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 14:28: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 14:28: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 14:28: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 14:28:48 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:28:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:28:48 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:28:48 INFO - Installing collected packages: mozsystemmonitor 14:28:48 INFO - Running setup.py install for mozsystemmonitor 14:28:48 INFO - Successfully installed mozsystemmonitor 14:28:48 INFO - Cleaning up... 14:28:48 INFO - Return code: 0 14:28:48 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:28: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')]} 14:28:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:28:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:28: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')]} 14:28:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:28:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:28: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 0x10cc853f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbfb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa242d47440>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca9bd30>, '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 0x10c902db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca93b20>, '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.vZtWipJufU/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.UFgG2ZkXKF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 14:28: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 14:28: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 14:28:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vZtWipJufU/Render', 14:28:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:28:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:28:48 INFO - 'HOME': '/Users/cltbld', 14:28:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:28:48 INFO - 'LOGNAME': 'cltbld', 14:28:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:28:48 INFO - 'MOZ_NO_REMOTE': '1', 14:28:48 INFO - 'NO_EM_RESTART': '1', 14:28:48 INFO - 'PAGER': '/bin/cat', 14:28:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:28:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:28:48 INFO - 'PWD': '/builds/slave/test', 14:28:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:28:48 INFO - 'SHELL': '/bin/bash', 14:28:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UFgG2ZkXKF/Listeners', 14:28:48 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 14:28:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:28:48 INFO - 'USER': 'cltbld', 14:28:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:28:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:28:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:28:48 INFO - 'XPC_FLAGS': '0x0', 14:28:48 INFO - 'XPC_SERVICE_NAME': '0', 14:28:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:28:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:28:48 INFO - Downloading/unpacking jsonschema==2.5.1 14:28:48 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 14:28:48 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 14:28:48 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 14:28:48 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 14:28:48 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 14:28:48 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 14:28:51 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:28:51 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:28: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 14:28: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 14:28: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 14:28: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 14:28: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 14:28: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 14:28:51 INFO - Downloading functools32-3.2.3-2.tar.gz 14:28:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:28:51 INFO - warning: no files found matching '*.txt' 14:28:51 INFO - no previously-included directories found matching 'build' 14:28:51 INFO - no previously-included directories found matching 'dist' 14:28:51 INFO - no previously-included directories found matching '.git*' 14:28:51 INFO - Installing collected packages: jsonschema, functools32 14:28:51 INFO - Running setup.py install for functools32 14:28:51 INFO - warning: no files found matching '*.txt' 14:28:51 INFO - no previously-included directories found matching 'build' 14:28:51 INFO - no previously-included directories found matching 'dist' 14:28:51 INFO - no previously-included directories found matching '.git*' 14:28:51 INFO - Successfully installed jsonschema functools32 14:28:51 INFO - Cleaning up... 14:28:51 INFO - Return code: 0 14:28:51 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:28:51 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')]} 14:28:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:28:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:28:51 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')]} 14:28:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:28:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:28:51 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 0x10cc853f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbfb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa242d47440>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca9bd30>, '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 0x10c902db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca93b20>, '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.vZtWipJufU/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.UFgG2ZkXKF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 14:28:51 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 14:28:51 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 14:28:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vZtWipJufU/Render', 14:28:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:28:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:28:51 INFO - 'HOME': '/Users/cltbld', 14:28:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:28:51 INFO - 'LOGNAME': 'cltbld', 14:28:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:28:51 INFO - 'MOZ_NO_REMOTE': '1', 14:28:51 INFO - 'NO_EM_RESTART': '1', 14:28:51 INFO - 'PAGER': '/bin/cat', 14:28:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:28:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:28:51 INFO - 'PWD': '/builds/slave/test', 14:28:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:28:51 INFO - 'SHELL': '/bin/bash', 14:28:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UFgG2ZkXKF/Listeners', 14:28:51 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 14:28:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:28:51 INFO - 'USER': 'cltbld', 14:28:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:28:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:28:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:28:51 INFO - 'XPC_FLAGS': '0x0', 14:28:51 INFO - 'XPC_SERVICE_NAME': '0', 14:28:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:28:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:28:52 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:28:52 INFO - Cleaning up... 14:28:52 INFO - Return code: 0 14:28:52 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:28: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')]} 14:28:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:28:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:28: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')]} 14:28:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:28:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:28: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 0x10cc853f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbfb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa242d47440>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca9bd30>, '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 0x10c902db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca93b20>, '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.vZtWipJufU/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.UFgG2ZkXKF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 14:28: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 14:28: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 14:28:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vZtWipJufU/Render', 14:28:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:28:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:28:52 INFO - 'HOME': '/Users/cltbld', 14:28:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:28:52 INFO - 'LOGNAME': 'cltbld', 14:28:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:28:52 INFO - 'MOZ_NO_REMOTE': '1', 14:28:52 INFO - 'NO_EM_RESTART': '1', 14:28:52 INFO - 'PAGER': '/bin/cat', 14:28:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:28:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:28:52 INFO - 'PWD': '/builds/slave/test', 14:28:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:28:52 INFO - 'SHELL': '/bin/bash', 14:28:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UFgG2ZkXKF/Listeners', 14:28:52 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 14:28:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:28:52 INFO - 'USER': 'cltbld', 14:28:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:28:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:28:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:28:52 INFO - 'XPC_FLAGS': '0x0', 14:28:52 INFO - 'XPC_SERVICE_NAME': '0', 14:28:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:28:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:28:52 INFO - Downloading/unpacking blobuploader==1.2.4 14:28: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 14:28: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 14:28: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 14:28: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 14:28: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 14:28: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 14:28:54 INFO - Downloading blobuploader-1.2.4.tar.gz 14:28:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:28:55 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:28: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 14:28: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 14:28: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 14:28: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 14:28: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 14:28: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 14:28:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:28:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:28: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 14:28: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 14:28: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 14:28: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 14:28: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 14:28: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 14:28:56 INFO - Downloading docopt-0.6.1.tar.gz 14:28:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:28:56 INFO - Installing collected packages: blobuploader, requests, docopt 14:28:56 INFO - Running setup.py install for blobuploader 14:28:56 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:28:56 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:28:56 INFO - Running setup.py install for requests 14:28:56 INFO - Running setup.py install for docopt 14:28:57 INFO - Successfully installed blobuploader requests docopt 14:28:57 INFO - Cleaning up... 14:28:57 INFO - Return code: 0 14:28:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:28: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')]} 14:28:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:28:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:28: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')]} 14:28:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:28:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:28: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 0x10cc853f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbfb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa242d47440>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca9bd30>, '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 0x10c902db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca93b20>, '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.vZtWipJufU/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.UFgG2ZkXKF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 14:28: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 14:28: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 14:28:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vZtWipJufU/Render', 14:28:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:28:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:28:57 INFO - 'HOME': '/Users/cltbld', 14:28:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:28:57 INFO - 'LOGNAME': 'cltbld', 14:28:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:28:57 INFO - 'MOZ_NO_REMOTE': '1', 14:28:57 INFO - 'NO_EM_RESTART': '1', 14:28:57 INFO - 'PAGER': '/bin/cat', 14:28:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:28:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:28:57 INFO - 'PWD': '/builds/slave/test', 14:28:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:28:57 INFO - 'SHELL': '/bin/bash', 14:28:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UFgG2ZkXKF/Listeners', 14:28:57 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 14:28:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:28:57 INFO - 'USER': 'cltbld', 14:28:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:28:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:28:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:28:57 INFO - 'XPC_FLAGS': '0x0', 14:28:57 INFO - 'XPC_SERVICE_NAME': '0', 14:28:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:28:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:28:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:28:57 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-PP9uYO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:28:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:28:57 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-xB8W_m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:28:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:28:57 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-sKoKaH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:28:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:28:57 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-NtaWbf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:28:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:28:57 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-WOnvKH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:28:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:28:57 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-QuNanD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:28:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:28:58 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-p_B2LJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:28:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:28:58 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-Ax2Lnh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:28:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:28:58 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-oTjAwk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:28:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:28:58 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-RtCZun-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:28:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:28:58 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-gjTrPb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:28:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:28:58 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-qbMroi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:28:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:28:58 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-3qqlfc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:28:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:28:58 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-TGNYzZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:28:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:28:58 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-fYRIBs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:28:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:28:59 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-TzFZJW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:28:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:28:59 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-6rxLIM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:28:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:28:59 INFO - Running setup.py install for manifestparser 14:28:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:28:59 INFO - Running setup.py install for mozcrash 14:28:59 INFO - Running setup.py install for mozdebug 14:28:59 INFO - Running setup.py install for mozdevice 14:28:59 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:28:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:28:59 INFO - Running setup.py install for mozfile 14:28:59 INFO - Running setup.py install for mozhttpd 14:29:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:29:00 INFO - Running setup.py install for mozinfo 14:29:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:29:00 INFO - Running setup.py install for mozInstall 14:29:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:29:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:29:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:29:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:29:00 INFO - Running setup.py install for mozleak 14:29:00 INFO - Running setup.py install for mozlog 14:29:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:29:00 INFO - Running setup.py install for moznetwork 14:29:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:29:00 INFO - Running setup.py install for mozprocess 14:29:01 INFO - Running setup.py install for mozprofile 14:29:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:29:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:29:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:29:01 INFO - Running setup.py install for mozrunner 14:29:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:29:01 INFO - Running setup.py install for mozscreenshot 14:29:01 INFO - Running setup.py install for moztest 14:29:01 INFO - Running setup.py install for mozversion 14:29:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:29:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:29:01 INFO - Cleaning up... 14:29:01 INFO - Return code: 0 14:29:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:29:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:29:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:29:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:29:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:29:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29:01 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 0x10cc853f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbfb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa242d47440>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca9bd30>, '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 0x10c902db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca93b20>, '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.vZtWipJufU/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.UFgG2ZkXKF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 14:29:01 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 14:29:01 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 14:29:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vZtWipJufU/Render', 14:29:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:29:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:29:01 INFO - 'HOME': '/Users/cltbld', 14:29:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:29:01 INFO - 'LOGNAME': 'cltbld', 14:29:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:29:01 INFO - 'MOZ_NO_REMOTE': '1', 14:29:01 INFO - 'NO_EM_RESTART': '1', 14:29:01 INFO - 'PAGER': '/bin/cat', 14:29:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:29:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:29:01 INFO - 'PWD': '/builds/slave/test', 14:29:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:29:01 INFO - 'SHELL': '/bin/bash', 14:29:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UFgG2ZkXKF/Listeners', 14:29:01 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 14:29:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:29:01 INFO - 'USER': 'cltbld', 14:29:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:29:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:29:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:29:01 INFO - 'XPC_FLAGS': '0x0', 14:29:01 INFO - 'XPC_SERVICE_NAME': '0', 14:29:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:29:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:29:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:29:02 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-IEHg1b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:29: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)) 14:29:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:29:02 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-FbS0Lt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:29:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:29:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:29:02 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-_mKrKM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:29: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)) 14:29:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:29:02 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-f0ye0j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:29:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:29:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:29:02 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-MllWq5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:29:02 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)) 14:29:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:29:02 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-Lt_Dqu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:29:02 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)) 14:29:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:29:02 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-00Moa8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:29:02 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)) 14:29:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:29:02 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-MhHa18-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:29:02 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)) 14:29:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:29:02 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-uySrHg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:29: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)) 14:29:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:29:03 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-7aVFWu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:29: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)) 14:29:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:29:03 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-N2IP8K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:29: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)) 14:29:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:29:03 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-D_MznF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:29: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)) 14:29:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:29:03 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-KpR6Nr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:29: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)) 14:29:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:29:03 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-WUDyH9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:29:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 14:29:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:29:03 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-lklKFn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:29:03 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)) 14:29:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:29:03 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-HmnXM5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:29:03 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)) 14:29:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:29:03 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-h5LIeo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:29:03 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)) 14:29:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:29:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:29:03 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)) 14:29:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:29:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:29:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:29:03 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 14:29:03 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 14:29:03 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 14:29:03 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 14:29:03 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 14:29:03 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 14:29:06 INFO - Downloading blessings-1.6.tar.gz 14:29:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:29:06 INFO - Installing collected packages: blessings 14:29:06 INFO - Running setup.py install for blessings 14:29:06 INFO - Successfully installed blessings 14:29:06 INFO - Cleaning up... 14:29:06 INFO - Return code: 0 14:29:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:29:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:29:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:29:07 INFO - Reading from file tmpfile_stdout 14:29:07 INFO - Current package versions: 14:29:07 INFO - blessings == 1.6 14:29:07 INFO - blobuploader == 1.2.4 14:29:07 INFO - docopt == 0.6.1 14:29:07 INFO - functools32 == 3.2.3-2 14:29:07 INFO - jsonschema == 2.5.1 14:29:07 INFO - manifestparser == 1.1 14:29:07 INFO - mozInstall == 1.12 14:29:07 INFO - mozcrash == 1.0 14:29:07 INFO - mozdebug == 0.1 14:29:07 INFO - mozdevice == 0.49 14:29:07 INFO - mozfile == 1.2 14:29:07 INFO - mozhttpd == 0.7 14:29:07 INFO - mozinfo == 0.9 14:29:07 INFO - mozleak == 0.1 14:29:07 INFO - mozlog == 3.4 14:29:07 INFO - moznetwork == 0.27 14:29:07 INFO - mozprocess == 0.23 14:29:07 INFO - mozprofile == 0.28 14:29:07 INFO - mozrunner == 6.13 14:29:07 INFO - mozscreenshot == 0.1 14:29:07 INFO - mozsystemmonitor == 0.3 14:29:07 INFO - moztest == 0.7 14:29:07 INFO - mozversion == 1.4 14:29:07 INFO - psutil == 3.1.1 14:29:07 INFO - requests == 1.2.3 14:29:07 INFO - wsgiref == 0.1.2 14:29:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:29: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')]} 14:29:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:29:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29: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')]} 14:29:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:29:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29: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 0x10cc853f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbfb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa242d47440>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca9bd30>, '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 0x10c902db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca93b20>, '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.vZtWipJufU/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.UFgG2ZkXKF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 14:29: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 14:29: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 14:29:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vZtWipJufU/Render', 14:29:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:29:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:29:07 INFO - 'HOME': '/Users/cltbld', 14:29:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:29:07 INFO - 'LOGNAME': 'cltbld', 14:29:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:29:07 INFO - 'MOZ_NO_REMOTE': '1', 14:29:07 INFO - 'NO_EM_RESTART': '1', 14:29:07 INFO - 'PAGER': '/bin/cat', 14:29:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:29:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:29:07 INFO - 'PWD': '/builds/slave/test', 14:29:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:29:07 INFO - 'SHELL': '/bin/bash', 14:29:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UFgG2ZkXKF/Listeners', 14:29:07 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 14:29:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:29:07 INFO - 'USER': 'cltbld', 14:29:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:29:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:29:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:29:07 INFO - 'XPC_FLAGS': '0x0', 14:29:07 INFO - 'XPC_SERVICE_NAME': '0', 14:29:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:29:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:29: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)) 14:29: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)) 14:29: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)) 14:29: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)) 14:29: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)) 14:29: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)) 14:29: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)) 14:29: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)) 14:29: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)) 14:29: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)) 14:29: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)) 14:29:07 INFO - Cleaning up... 14:29:07 INFO - Return code: 0 14:29:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:29: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')]} 14:29:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:29:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29: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')]} 14:29:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:29:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29: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 0x10cc853f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbfb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa242d47440>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca9bd30>, '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 0x10c902db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca93b20>, '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.vZtWipJufU/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.UFgG2ZkXKF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 14:29: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 14:29: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 14:29:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vZtWipJufU/Render', 14:29:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:29:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:29:07 INFO - 'HOME': '/Users/cltbld', 14:29:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:29:07 INFO - 'LOGNAME': 'cltbld', 14:29:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:29:07 INFO - 'MOZ_NO_REMOTE': '1', 14:29:07 INFO - 'NO_EM_RESTART': '1', 14:29:07 INFO - 'PAGER': '/bin/cat', 14:29:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:29:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:29:07 INFO - 'PWD': '/builds/slave/test', 14:29:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:29:07 INFO - 'SHELL': '/bin/bash', 14:29:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UFgG2ZkXKF/Listeners', 14:29:07 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 14:29:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:29:07 INFO - 'USER': 'cltbld', 14:29:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:29:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:29:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:29:07 INFO - 'XPC_FLAGS': '0x0', 14:29:07 INFO - 'XPC_SERVICE_NAME': '0', 14:29:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:29:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:29:07 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:29:07 INFO - Cleaning up... 14:29:07 INFO - Return code: 0 14:29:07 INFO - Running post-action listener: _resource_record_post_action 14:29:07 INFO - Running post-action listener: _start_resource_monitoring 14:29:07 INFO - Starting resource monitoring. 14:29:07 INFO - [mozharness: 2016-11-22 22:29:07.622241Z] Finished create-virtualenv step (success) 14:29:07 INFO - [mozharness: 2016-11-22 22:29:07.623335Z] Running install step. 14:29:07 INFO - Running pre-action listener: _resource_record_pre_action 14:29:07 INFO - Running main action method: install 14:29:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:29:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:29:07 INFO - Reading from file tmpfile_stdout 14:29:07 INFO - Detecting whether we're running mozinstall >=1.0... 14:29:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:29:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:29:07 INFO - Reading from file tmpfile_stdout 14:29:07 INFO - Output received: 14:29:07 INFO - Usage: mozinstall [options] installer 14:29:07 INFO - Options: 14:29:07 INFO - -h, --help show this help message and exit 14:29:07 INFO - -d DEST, --destination=DEST 14:29:07 INFO - Directory to install application into. [default: 14:29:07 INFO - "/builds/slave/test"] 14:29:07 INFO - --app=APP Application being installed. [default: firefox] 14:29:07 INFO - mkdir: /builds/slave/test/build/application 14:29:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 14:29:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 14:29:40 INFO - Reading from file tmpfile_stdout 14:29:40 INFO - Output received: 14:29:40 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 14:29:40 INFO - Running post-action listener: _resource_record_post_action 14:29:40 INFO - [mozharness: 2016-11-22 22:29:40.743491Z] Finished install step (success) 14:29:40 INFO - [mozharness: 2016-11-22 22:29:40.743664Z] Running run-tests step. 14:29:40 INFO - Running pre-action listener: _resource_record_pre_action 14:29:40 INFO - Running main action method: run_tests 14:29:40 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:29:40 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:29:40 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:29:40 INFO - 2016-11-22 14:29:40.758 screenresolution[1896:16323] starting screenresolution argv=screenresolution get 14:29:40 INFO - 2016-11-22 14:29:40.761 screenresolution[1896:16323] Display 0: 1600x1200x32@60 14:29:40 INFO - 2016-11-22 14:29:40.767 screenresolution[1897:16327] starting screenresolution argv=screenresolution list 14:29:40 INFO - Available Modes on Display 0 14:29:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:29:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:29:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:29:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:29:40 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:29:40 INFO - Intel Iris: 14:29:40 INFO - Chipset Model: Intel Iris 14:29:40 INFO - Type: GPU 14:29:40 INFO - Bus: Built-In 14:29:40 INFO - VRAM (Dynamic, Max): 1536 MB 14:29:40 INFO - Vendor: Intel (0x8086) 14:29:40 INFO - Device ID: 0x0a2e 14:29:40 INFO - Revision ID: 0x0009 14:29:40 INFO - Displays: 14:29:40 INFO - Display: 14:29:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:29:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:29:41 INFO - Display Serial Number: 1600x1200 60 14:29:41 INFO - Main Display: Yes 14:29:41 INFO - Mirror: Off 14:29:41 INFO - Online: Yes 14:29:41 INFO - Rotation: Supported 14:29:41 INFO - Return code: 0 14:29:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:29:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:29:41 INFO - Python 2.7.3 14:29:41 INFO - Return code: 0 14:29:41 INFO - grabbing minidump binary from tooltool 14:29:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:29:41 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 0x10ca9bd30>, '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 0x10c902db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca93b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:29:41 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 14:29:41 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 14:29:41 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 14:29:41 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:29:41 INFO - Return code: 0 14:29:41 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:29:41 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:29:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:29:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:29:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:29:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Dn6vgelbTQmaLPB-CzmrBw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0068', '--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 14:29:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Graphics --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Dn6vgelbTQmaLPB-CzmrBw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0068 --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 14:29:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vZtWipJufU/Render', 14:29:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:29:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:29:41 INFO - 'HOME': '/Users/cltbld', 14:29:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:29:41 INFO - 'LOGNAME': 'cltbld', 14:29:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:29:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:29:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:29:41 INFO - 'MOZ_NO_REMOTE': '1', 14:29:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:29:41 INFO - 'NO_EM_RESTART': '1', 14:29:41 INFO - 'PAGER': '/bin/cat', 14:29:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:29:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:29:41 INFO - 'PWD': '/builds/slave/test', 14:29:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:29:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:29:41 INFO - 'SHELL': '/bin/bash', 14:29:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UFgG2ZkXKF/Listeners', 14:29:41 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 14:29:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:29:41 INFO - 'USER': 'cltbld', 14:29:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:29:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:29:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:29:41 INFO - 'XPC_FLAGS': '0x0', 14:29:41 INFO - 'XPC_SERVICE_NAME': '0', 14:29:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:29:41 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Dn6vgelbTQmaLPB-CzmrBw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0068', '--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 14:29:41 INFO - mozversion application_buildid: 20161122113650 14:29:41 INFO - mozversion application_changeset: 4cf8741bb86f96dcb62dc98594f109e7ed2733a1 14:29:41 INFO - mozversion application_display_name: Nightly 14:29:41 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:29:41 INFO - mozversion application_name: Firefox 14:29:41 INFO - mozversion application_remotingname: firefox 14:29:41 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 14:29:41 INFO - mozversion application_vendor: Mozilla 14:29:41 INFO - mozversion application_version: 53.0a1 14:29:41 INFO - mozversion platform_buildid: 20161122113650 14:29:41 INFO - mozversion platform_changeset: 4cf8741bb86f96dcb62dc98594f109e7ed2733a1 14:29:41 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 14:29:41 INFO - mozversion platform_version: 53.0a1 14:29:41 INFO - using testdate: 1479853781 14:29:41 INFO - actual date: 1479853781 14:29:41 INFO - starting webserver on 'localhost:53486' 14:29:41 INFO - SUITE-START | Running 2 tests 14:29:41 INFO - TEST-START | dromaeo_css 14:29:41 INFO - operating with platform_type : mac_ 14:29:41 INFO - Initialising browser for dromaeo_css test... 14:29:41 INFO - TEST-INFO | started process 1907 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/tmp7eLUOi/profile http://localhost:53486/getInfo.html) 14:29:44 INFO - PROCESS | 1907 | 2016-11-22 14:29:44.161 plugin-container[1909:16480] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x924f, name = 'com.apple.tsm.portname' 14:29:44 INFO - PROCESS | 1907 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:29:44 INFO - PROCESS | 1907 | 2016-11-22 14:29:44.163 plugin-container[1909:16480] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x960b, name = 'com.apple.CFPasteboardClient' 14:29:44 INFO - PROCESS | 1907 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:29:44 INFO - PROCESS | 1907 | 2016-11-22 14:29:44.163 plugin-container[1909:16480] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:29:44 INFO - PROCESS | 1907 | __metrics Screen width/height:1600/1200 14:29:44 INFO - PROCESS | 1907 | colorDepth:24 14:29:44 INFO - PROCESS | 1907 | Browser inner width/height: 1024/689 14:29:44 INFO - PROCESS | 1907 | __metrics 14:29:44 ERROR - PROCESS | 1907 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3198: TypeError: this.tabs is undefined 14:29:45 INFO - TEST-INFO | 1907: exit 0 14:29:45 INFO - Browser initialized. 14:29:45 INFO - Running cycle 1/1 for dromaeo_css test... 14:29:45 INFO - TEST-INFO | started process 1910 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/tmp7eLUOi/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:29:47 INFO - PROCESS | 1910 | 2016-11-22 14:29:47.911 plugin-container[1911:16600] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 14:29:47 INFO - PROCESS | 1910 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:29:47 INFO - PROCESS | 1910 | 2016-11-22 14:29:47.911 plugin-container[1911:16600] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0f, name = 'com.apple.CFPasteboardClient' 14:29:47 INFO - PROCESS | 1910 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:29:47 INFO - PROCESS | 1910 | 2016-11-22 14:29:47.911 plugin-container[1911:16600] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:29:57 INFO - PROCESS | 1910 | RSS: Main: 241483776 14:29:57 INFO - PROCESS | 1910 | 14:30:47 INFO - PROCESS | 1910 | 1479853847198 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] 14:33:00 INFO - PROCESS | 1910 | Cycle 1(1): loaded http://localhost:53486/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53486/tests/dromaeo/cssquery-ext.html) 14:33:02 INFO - PROCESS | 1910 | RSS: Main: 246951936 14:33:02 INFO - PROCESS | 1910 | 14:36:04 INFO - PROCESS | 1910 | Cycle 1(1): loaded http://localhost:53486/tests/dromaeo/cssquery-ext.html (next: http://localhost:53486/tests/dromaeo/cssquery-jquery.html) 14:36:05 INFO - PROCESS | 1910 | RSS: Main: 261431296 14:36:05 INFO - PROCESS | 1910 | 14:39:06 INFO - PROCESS | 1910 | Cycle 1(1): loaded http://localhost:53486/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53486/tests/dromaeo/cssquery-mootools.html) 14:39:07 INFO - PROCESS | 1910 | RSS: Main: 265818112 14:39:07 INFO - PROCESS | 1910 | 14:42:09 INFO - PROCESS | 1910 | Cycle 1(1): loaded http://localhost:53486/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53486/tests/dromaeo/cssquery-prototype.html) 14:42:10 INFO - PROCESS | 1910 | RSS: Main: 264687616 14:42:10 INFO - PROCESS | 1910 | 14:45:12 INFO - PROCESS | 1910 | Cycle 1(1): loaded http://localhost:53486/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53486/tests/dromaeo/cssquery-yui.html) 14:45:13 INFO - PROCESS | 1910 | RSS: Main: 270082048 14:45:13 INFO - PROCESS | 1910 | 14:48:16 INFO - PROCESS | 1910 | Cycle 1(1): loaded http://localhost:53486/tests/dromaeo/cssquery-yui.html (next: http://localhost:53486/tests/dromaeo/cssquery-dojo.html) 14:48:16 INFO - PROCESS | 1910 | RSS: Main: 272592896 14:48:16 INFO - PROCESS | 1910 | 14:48:16 INFO - PROCESS | 1910 | __start_tp_report 14:48:16 INFO - PROCESS | 1910 | _x_x_mozilla_page_load 14:48:16 INFO - PROCESS | 1910 | _x_x_mozilla_page_load_details 14:48:16 INFO - PROCESS | 1910 | |i|pagename|runs| 14:48:16 INFO - PROCESS | 1910 | |0;dojo.html;2893.623828902243;3405.5743032120986;3405.8978230653074;3412.385770561298;3444.810535420552;4700.247960326348;5764.827055188346;5778.3643799182055;5798.072308430652;5812.593118481708;4549.590536851682;4860.489648586891;5817.389174136718;5830.125481177825;5869.031609784381;5106.66806657566;5247.055530004598;5320.441353657869;5339.599530035243;5345.812896548621;4374.562543745616;5302.655327403706;5370.275012873251;5422.024035673578;5460.781568737266;5195.1687729963405;5325.733713314357;5348.251244825688;5385.703786291752;5391.272178255925;5177.249298851683;5232.7122008289525;5253.238280449352;5276.076686579871;5283.418823929364;5194.37667479901;5199.532042116228;5282.947170528284;5289.867753306198;5319.308489896327;4887.340209071801;5049.2173713074535;5294.973525132388;5865.530757539386;5882.264716910387;5063.8480845574695;6209.410106039919;6258.34287399821;6268.216472940882;6282.340354262782;6218.378162183791;6275.372462753734;6287.528435367366;6289.433950944392;6290.339514351019;5753.424657534213;5838.620489668158;5960.433758792908;5962.1951036609935;5988.071848863389;6289.528285378615;6291.339409362043;6302.8424289392215;6314.55798094129;6370.458511026522;6154.230721159855;6246.781362652286;6251.374862513712;6262.060690896402;6269.655168965751;6282.277538083179;6285.2457705075685;6308.779192728232;6309.747610095636;6314.55798094129;6118.9846826261555;6164.137020817089;6185.103160041859;6198.380161983856;6201.782937597252;6198.822223777558;6249.562530622813;6270.592411493239;6278.717457714414;6290.811275661737;4962.776675049559;4994.925076123861;5000.149974504343;5001.274815151783;5029.7736601853;3581.516495273157;4500.189965806121;4508.45898492183;4534.77326133692;4578.313253012075;4604.240300350405;5549.306336707911;5892.204552385459;5988.670623115683;6081.483073938764;6209.5032397408095;6250.9062364064575;6255.78104766331;6258.3428739982555;6294.402031806971;5993.9810232260625;6008.609440386448;6027.859856479545;6033.3664965178905;6074;5292.814751483679;5375.9462405376225;5404.000259951921;5418.566514678816;5447.509724124848;6311.053341998737;6331.525135614847;6340.714667839959;6358.523110766803;6385.808425747235;6247.96876015617;6255.843603909757;6268.811935641938;6279.686015699288;6297.905531416941;5327.147656374961;5931.9110213345975;6235.407636274546;6286.4342209201395;6492.837679058061;5026.120428924851;5986.161937328865;6079.939200608114;6088.360722124113;6257.874842503215;6145.201123853871;6161.907571386343;6177.722002509808;6196.411340922605;6265.27949285838;5200.97399513;6119.204503414528;6158.32258451561;6190.783322583688;6202.81391558254;5096.311997880239;6000.619857432902;6202.56582039271;6221.813345599639;6226.750929962751;5155.793768249228;5639.661620302795;5947.3457919627945;5979.910301345396;6033.396660333931;5253.947460525345;6137.407629318811;6160.44555989963;6197.256329240339;6202.131701561875;4860.9513904861915;6004.279486461653;6026.969865150646;6039.849003774941;6053.969730151497 14:48:16 INFO - PROCESS | 1910 | |1;ext.html;497.52734901843235;511.65718968291253;512.0373697448796;528.9444608316127;560.6103757888264;36261.637383626155;36889.26221475572;38100.856974290815;38159.04602384942;38183.618163818355;38856.251468683906;39630.801845990805;39646.40530392047;39720.20559588807;39807.20385592287;9024.684136055239;9346.672866449675;9348.532573371338;9350.111739384782;9369.48467834269;9272;9339.206167475773;9388;9388.061193880625;9392.389494682859;9335.719928402159;9399.154076133147;9403.95298023509;9404;9435.575399107056;9307.999999999965;9375.953120234424;9386.155246027856;9411.57647905839;9417.540281256348;9278.907210927871;9358.4384936904;9360.672376466859;9368.34421590489;9416.717498474989;37576.62423375786;37958.24083518342;37960.24079518422;38013.61986380129;38131.8093409534;3046.177532066352;3222.452183128874;3646.2342907989355;3833.3291673956946;3869.535655721303;26482;27816.026439074536;29705.702942970725;29864.55203171954;29953;3064.8007879487764;3201.2637093468634;3243.9351212975844;3312.0560640217664;3316.950245746316;473595;474804;477645;479258;479529;55171.44828551663;55799.72100139473;56062;56220.43779562234;56224.71887640617;57346.7132664334;57561;57624.71187644035;57674.71162644244;57901;14951.40194392234;14974.625634359229;14977.925110374379;15150.393984240507;15162.545123646309;11773.234739741889;11792.764144717057;11804.350760708072;11806.704832379088;11821.468033938494;12309.692257693629;15542.290348061704;15542.9222853885;15575.688486230212;15762.842371576138;5712.400197979236;5746.655200687972;6354.3010268870385;6637.502187335969;6752.189737231563;787.9881801772977;914.881065461499;955.025873608915;992.4839083676447;1040.245821779219;25629.487410252063;27178.456430871272;27893.72106278952;28676.569851452245;28916.855415723207;971.8104969531015;1005.3967619428431;1023.6673081248624;1036.1141224253156;1087.537796436527;14507.202103884387;14875.702485950436;15052.924735376471;15181;15359.539213823604;626356;627581;627871;630361;632797;54826;55113.44886551164;55231.72384138054;55524;55538;1303.9348032598332;1345.0920628575868;1363.5909227231791;1374.0519041861085;1376.2843321472778;23523.059077637387;25625.61561576615;26537.469250614493;29235.415291694473;29842.850785745934;47186.82032949066;47409.52590474051;47712.2843157346;47764.7611761939;48069.5193048079;521994;524989;525277;526017;526033;24370.87814560916;24513.142040028513;24903.50192996166;24936;24956;42765.35851962161;43313.566864330955;43583.56416435795;43902.34146487869;44375;28212.71787282101;34424.82787586046;35537.1115722109;36157.0960725984;37125.6287437133;30456.84771576128;34221.486677700355;36095.63904361028;36991.260174796895;37092.81453592715;31273.530897037017;31378.529322060644;31585.368292634477;33145.00564983054;35954.28091438209;29615.407691845612;37434;39710.60289397069;39987;40515.594844051186;29637;30752.69247307498;30755.538666919565;30996.53505197469;31467.685323146474 14:48:16 INFO - PROCESS | 1910 | |2;jquery.html;349.64511021313365;371.4261466034974;376.9849206031758;382.2622338885949;398.62673091200804;18404.44786656399;18915;19346.322878699248;19593.51016224595;19821.405357839252;11273;12418.130730848821;19001.904990475065;19447.027648617546;19648.312309069148;5097.439281679021;5349.491798279158;5355.866103347408;5356.143017117262;5365.382980957182;3733.813309334536;5227.817026404076;5877.941220587803;5966.105084237356;6032.638041717511;5728.713564321767;5955.731992060368;5995.490383317423;6022.849428764294;6023.578349515536;4927.827526036572;5728.713564321788;5907.497862681677;5957.404259574051;5977.073553599177;5531.197976293455;5617.33760978387;5742.454466825645;5764.423557644244;5775.624584402;12549.623511294676;12653.557125500656;19521.414357569294;24257.878710606336;24365.756342436525;2127.1278775702044;2142.9785702142935;2194.473326401668;2206.5035367042447;2828;9384.812303753886;9691.70924872255;10140.340877842917;10199.38803671782;14821.851781482263;1719.7617715245128;1825.12394050855;1847.094923487495;1873.953151171218;1952.336205690058;96008.0399196013;111372.4431377846;121725.39137304346;121883;122957.99999999911;7313.963430182922;9227.215686666708;9309.72070837876;9918.504074796232;11222.989930906258;11589.304641721355;12207;12207.75584488323;12208;12231.816522752166;7621.69513219465;7838.608069596611;7873.685052597832;7905.01187351581;7975.441719079608;8141.104478507359;8142.877856832152;8182.7545173644885;8284.875726864102;8333.541655209021;3159.573457583243;3764.0778009387327;3821.121142137324;3874.4188371744463;3888.066863952631;1555.9766603500957;1636.6563021765323;1647.8846480746472;1933.980660193408;1955.3645065353533;744.9384626906744;883.3551507399634;889.9777505562283;936.6721647423348;1015.4161357219629;19133.521661958563;20593.691094633592;20700.171993120108;20871.791282086986;20885.060172292284;708.4934271995445;891.2023738753779;891.3359547137425;908.8409528332515;911.794846159622;10426.113780328757;10830.566777328977;10850.294730842628;10869.619563315246;10907.29102608328;578118;582885;587219;588786;597489;71422.64288678524;133105.33447332896;138445.3077734605;141536.87694684588;144203;948.2129832239225;995.3778888194878;999.3254553176722;1001.7195185348114;1007.1539906478594;14774.852251477778;17151.7427238589;17478;18443.907780461548;19901.60196796085;51190.72023199301;55947;56279.718601406734;56475.71762141163;56914;78320;79446.26091130087;81388.59305703433;82469.58765206135;84099;6311.968440157953;7815.452918295665;7875.803104922423;7939.325157361458;8059.194080591894;10607.628732994308;12145.45345459474;12305.56930507428;12553;12569.1201615886;10247.231457640557;10458.163346932379;10830.67507974762;10940.671779846618;11242.156838237;9061;9640.650308956612;10303.948480257552;10819.350838949687;11045.061169800658;10995.670129896116;11072.335659860435;11269.154813388865;11415.486303116213;11424.657260282205;7962.283394494523;8012.399070069651;8025.197480251928;8094.150114238156;8286.751397457845;10124.088832005154;10320.741981450525;10858.185636077484;11101.222914395914;11379.886201137882 14:48:16 INFO - PROCESS | 1910 | |3;mootools.html;1416.3130881522463;1430.0847457627117;1591.7294060009797;1730.2992288123298;1760.0495732304548;7467.551946883191;7713.1515533291295;7970.76087717368;8406.83186336273;8407.453515521494;7871.8425631487335;8158.143394943534;8169.6732130714845;8273.669053237878;8286.378521610874;1966;2285.7828506291917;2339.7426283108844;2346.9790641071104;2352.882355882208;2318.2002209237785;2350.7061617297836;2363.6881530750425;2365.550545396369;2370.9644355334685;2218.1016688241207;2251.763564825694;2276.4764104256033;2337.1118974789642;2341.519988402373;2273.193016479151;2273.26119011322;2290.3701482092392;2294.8852557372147;2304.3547806614174;2321.9071237150492;2338.988305058464;2340.1224540797202;2350.5298940211856;2352.2120089769946;8199.71301004462;8305.46014509055;8328.042275138316;8337.374696897696;8418.074011858691;5066.113430149746;5219.138842091051;5233.371995360543;5237.319148510707;5243.030039442715;3163.193385686658;3280.6555311692405;3524;3533.770304930197;3535.063208249816;1025.5077562769839;1052.5894900988621;1055.8944105589455;1074.4949873559415;1090.6291860767299;10671;10720.249582529226;10767.892321076768;10782.191335649857;10796.67609971702;4440.555944405598;4546.181687296253;4623.630109591225;4640.628749700083;4754.02542478791;9269.90730092704;9350.485723285285;9363.578638961262;9377.765555861022;9423.623055077856;5187.351581052369;5189.714565698923;5191.532762051358;5196;5202;4226.915461690749;4543.909121817545;4661.650376221797;4771.809127634925;4782.115308667908;3709.1654377765326;3757.4176002719623;3784.6026167252594;3808.3716186829415;3890.630390112935;1273.949042038308;1282.160185078775;1284.8650891656428;1286.298967062953;1295.8704129586965;759.2407592407592;861.1990848510819;890.1517033092649;902.6434558349547;932.2029664636774;5462.235287059815;5480.177973303957;5666.263385759825;5728.914066289009;5734.369219385864;787.4605894961969;996.4917891875195;1008.5240733998018;1011.4234886114991;1035.3011717091083;3601.5858176309534;3793.2223894101644;3840.1935593525113;3863.285292220948;3907.27715372657;9833.655822046194;10133.493325333851;10246.282760206863;10253.974602539687;10259.076683098558;9982.201423886218;10052.447115408577;10080.294379393372;10186.643467478603;10212.846807297896;7621.733239336597;7655.57894315818;7856.350166464992;7860.842783144305;7898.44710870245;4264.552222016768;4661.860144195544;5348.78604855817;5456.890862182813;5468.91796623059;9056.592453339746;9336.439813611205;9351.345405821527;9391.906080939103;9407.52962351866;7439.256074392518;9301.953490232776;9303.255739540686;9538.046195380406;9549.570269337757;6467.385598368148;6677.532572719863;6899;6899.793006209822;7105.893411598933;7678.155402905788;7844;7844.176361481962;7862.371010319046;7865.842683146419;6855.828604284733;7003;7061.187963384071;7072.752453664097;7131.0729605151;6942.722291108501;6972.58164510131;6975.8604827902145;7001.8949715755325;7052.047973523714;6601.603903765789;6658.533902626769;6661.900071498834;6721;6739.382548188301;4544.7045942013665;4607.101615185007;4645.09420662981;4650.790714417792;4751.192297309535;4010.318245898146;4832.420109586731;4841.733704646278;4883.975580122076;4934.777934992862 14:48:16 INFO - PROCESS | 1910 | |4;prototype.html;263.30224904004393;304.8003557669724;310.8119587649472;312.2132226788493;316.2504863473566;4643.373144625479;5582.413846546115;5613.80351687691;5627.577931655122;5646.8588285292935;5218.686878787275;5517.5585953123755;5559.11054231322;5625.299856069079;5636.928983493143;3470.31689630529;3991.880243592697;4053.6351728344443;4178.373244013407;4181.937270940938;3610.151614370621;3977.1051513409398;4064.8577299794515;4198.6850986176005;4206.179794939988;4181.310083836179;4187.60217779312;4199.937000944973;4218.11419601884;4237.936430953539;3895.4935858338376;3977.0825736649376;4145.341863254722;4155.812988415529;4180.414741936132;2745.752882240588;3364.4280472319765;3550.609432962372;3555.5022296878456;3602.5316708827686;5075.390953085654;5355.651882627617;5395.21769343443;5397.379301380352;5432.663954499563;4013.9197216055513;4516.503184649686;4719;4781.8087276508895;4788.438185570703;5040.873978150539;5815.738291776838;5822.505087067604;5831.475167234969;5872.794452194153;3261.771675982682;3471.5486986691567;3524.753267271292;3737.897320290521;3831.616838316174;5181.766820493087;5422.566194704415;5827.941720582782;6062.272527296708;6187.535934804908;3646.4894914711963;3980.402939559089;4007.919841603152;4022.456968309299;4024.396340548941;3567.340042092221;3794.1273507093524;4064.532578753422;4074.307367747431;4086.38587757834;3782.470454136423;4031.339316051392;4635.58279754817;4801.351817504592;4814.061258054646;4870.537298956593;5736.856578585485;5866.266716660418;5891.675957822275;5944;1254.2913254011455;1288.5747703257903;1307.1046333261745;1312.7308901675133;1313.5747713730518;829.531314807132;883.3110174064241;885.4731434796286;891.7012800711834;894.6376717429451;4858.927116093261;5264.6838290427395;5282.392524859691;5336.546393556514;5359.124306492697;5155.880692181251;5516.4143306850065;5761.740721667535;6041.40219705522;6052.334243233241;4669.976650116728;5230.895382092413;5303.8442126934415;5427.83716488506;5478;2720.8231464954715;2990.880364785428;3037;3051.6643169251365;3060.449119158573;5743.128435782109;6027.397260273938;6040.758369665252;6053.82062457515;6129.96935015331;5393.768044996347;5710.151821461123;5723.971380143073;5912.674802885796;5962.493188078975;5679.744411501492;5961.582689211714;5993.400659933972;6038.822429626269;6125.202374527821;4881.87638377307;5062.670926389773;5239.135542635422;5853.297604287342;5892.646441213541;5199.610029247897;5453.672779633234;5688.601797874109;5713.600047996601;5729.71351432435;5189.76646050921;5552.416996215501;5599.748011339418;5691.345495268097;6038.3357830637715;3523.4714792781288;3620.7103431725927;3775.244951009864;3799.9670176455465;3802.16352402471;4959.429665588359;5005.5745261653165;5466.699331536644;5800.4489573490455;5804.564657650609;5167.1474206755465;5408.349908544863;5511.944880551143;5576.498115169654;5622.88754224905;4354.382846128458;4760.714357138583;5030.270610761492;5121.103806833864;5643.871225754948;5040.49595040493;5398.379186393615;5423.810166644149;5424.430434804446;5454.400015998158;5586.357568879631;5723.914141287967;5741.483266506035;5888.440598143169;5888.852778680396;4433.578810013044;4725.267583524559;5126.512981266774;5479.342478902558;5748.48263656273 14:48:16 INFO - PROCESS | 1910 | |5;yui.html;586.841552780749;746.5520687587451;754.7924320811777;774.4888373673376;775.7207405334083;13861.930690346548;14220.217888016155;14657.267136643166;14672.559823205294;14674.972751907357;13182.538611148611;13424.194548327107;13438.5296514622;13447.260400677958;13484;816.7713040348712;834.4367551902471;834.6035633074301;834.8789425533294;837.6146972392706;651.6741629185407;668.8394785251553;789.3369569561568;804.74650485097;811.8741595052746;858.159004175908;863.5078005536847;863.641588740675;864.5029108262743;868.700298397052;855.7261676263598;856.5888373580653;857.1557016338901;857.6998050682242;859.0464584311409;637.1303171171318;641.3041849593197;641.7497176101324;642.8489305013318;662.8508585568256;10902.345859248471;13218.60344189676;13230.933845330808;13251.801222981663;13335.06654534183;13165.275909824955;13248.86751132486;13357.198568085849;13386.531471398454;13409.396577153953;11109.83335249972;11197.760447910368;11232.10143188543;11286.717832054183;11531.192816502848;3583.4803953426867;3621.94567081494;3641.745077844552;3749.7750134991716;3780.9054773630605;85515.57242213811;87209.56395218047;88246;88386;89687.55156224243;10311.74220644475;10343.534540945675;10438.164946804238;10509.962985348973;10679.199060070527;19960.800391996185;20397.694034589495;20557;20583.176672933216;20608.58782824335;6479.838004049937;7461.403087752967;7481.8877716834295;7555.924440755632;7617.428692848059;4151.813168407429;4231.42875711775;4235.534091250021;4242.7242229255;4248.171606536757;2666.8665817028073;3064.402441523926;3321.186309354175;3371.460566309379;3376.40912840252;589.4930359890491;589.8672798620362;634.8825467288566;639.3861892583079;660.0132801461846;570.0081857567885;604.9969750151221;609.871926895357;621.9657918814418;624.6938999890112;8576.742697719079;8643.956780216184;8893.510856902933;8940.910590894138;9211.217046550984;545.38916413617;560.0982418306523;627.9340968214614;670.7551743613643;696.2202333386639;4141.503019637663;4184.702742149943;4209.957900421018;4410.669199810027;4414.139242847678;83190;99425;118408.40795796138;119104;123342;52335;69160.96258556125;83335.16664833395;93430;106315;43782;56014.71992640011;59344;75663.24336756673;78454.60772696215;8537.615807288812;8858.778530536789;9360.625574976924;9397.85903211466;9639.855402169112;50124.74937625288;54371.72814135904;56114;57675.711621441624;58329;44527.777361114284;68515.65742171257;75023;75474.86787698299;77079;11818.113641477095;12093.697657558632;12232.143749937419;12246.571370002008;12597.370131493572;11389.316641001566;13681.657958551115;13969.301534923416;13992.930035349758;14502.927485362507;12556.811647825472;12766.361681916052;12767.872321276669;12780.61658150257;12794.74410511766;12401.441935112762;12638.747225055264;12739.108262421541;12792.232466052066;12995.480180792661;12246.265224086583;12477.625671229878;12613.68465788326;12620.305883176514;12736.426860791102;10788.244822862323;10969.451527423756;10980.1215902726;11247.606333778605;11389.658310250707;9849.458279794393;10242.334248273839;11091.44542772842;11201.383923884265;11214.66356009321 14:48:16 INFO - PROCESS | 1910 | __end_tp_report 14:48:16 INFO - PROCESS | 1910 | __start_cc_report 14:48:16 INFO - PROCESS | 1910 | _x_x_mozilla_cycle_collect,372 14:48:16 INFO - PROCESS | 1910 | __end_cc_report 14:48:16 INFO - PROCESS | 1910 | __startTimestamp1479854896728__endTimestamp 14:48:16 INFO - PROCESS | 1910 | 14:48:16 INFO - PROCESS | 1910 | ------- Summary: start ------- 14:48:16 INFO - PROCESS | 1910 | Number of tests: 6 14:48:16 INFO - PROCESS | 1910 | 14:48:16 INFO - PROCESS | 1910 | [#0] dojo.html Cycles:165 Average:5705.01 Median:5993.98 stddev:696.47 (11.6%) stddev-sans-first:662.77 14:48:16 INFO - PROCESS | 1910 | Values: 2893.6 3405.6 3405.9 3412.4 3444.8 4700.2 5764.8 5778.4 5798.1 5812.6 4549.6 4860.5 5817.4 5830.1 5869.0 5106.7 5247.1 5320.4 5339.6 5345.8 4374.6 5302.7 5370.3 5422.0 5460.8 5195.2 5325.7 5348.3 5385.7 5391.3 5177.2 5232.7 5253.2 5276.1 5283.4 5194.4 5199.5 5282.9 5289.9 5319.3 4887.3 5049.2 5295.0 5865.5 5882.3 5063.8 6209.4 6258.3 6268.2 6282.3 6218.4 6275.4 6287.5 6289.4 6290.3 5753.4 5838.6 5960.4 5962.2 5988.1 6289.5 6291.3 6302.8 6314.6 6370.5 6154.2 6246.8 6251.4 6262.1 6269.7 6282.3 6285.2 6308.8 6309.7 6314.6 6119.0 6164.1 6185.1 6198.4 6201.8 6198.8 6249.6 6270.6 6278.7 6290.8 4962.8 4994.9 5000.1 5001.3 5029.8 3581.5 4500.2 4508.5 4534.8 4578.3 4604.2 5549.3 5892.2 5988.7 6081.5 6209.5 6250.9 6255.8 6258.3 6294.4 5994.0 6008.6 6027.9 6033.4 6074.0 5292.8 5375.9 5404.0 5418.6 5447.5 6311.1 6331.5 6340.7 6358.5 6385.8 6248.0 6255.8 6268.8 6279.7 6297.9 5327.1 5931.9 6235.4 6286.4 6492.8 5026.1 5986.2 6079.9 6088.4 6257.9 6145.2 6161.9 6177.7 6196.4 6265.3 5201.0 6119.2 6158.3 6190.8 6202.8 5096.3 6000.6 6202.6 6221.8 6226.8 5155.8 5639.7 5947.3 5979.9 6033.4 5253.9 6137.4 6160.4 6197.3 6202.1 4861.0 6004.3 6027.0 6039.8 6054.0 14:48:16 INFO - PROCESS | 1910 | 14:48:16 INFO - PROCESS | 1910 | [#1] ext.html Cycles:180 Average:66788.57 Median:3851.43 stddev:146296.95 (3798.5%) stddev-sans-first:146622.68 14:48:16 INFO - PROCESS | 1910 | Values: 497.5 511.7 512.0 528.9 560.6 36261.6 36889.3 38100.9 38159.0 38183.6 38856.3 39630.8 39646.4 39720.2 39807.2 9024.7 9346.7 9348.5 9350.1 9369.5 9272.0 9339.2 9388.0 9388.1 9392.4 9335.7 9399.2 9404.0 9404.0 9435.6 9308.0 9376.0 9386.2 9411.6 9417.5 9278.9 9358.4 9360.7 9368.3 9416.7 37576.6 37958.2 37960.2 38013.6 38131.8 3046.2 3222.5 3646.2 3833.3 3869.5 26482.0 27816.0 29705.7 29864.6 29953.0 3064.8 3201.3 3243.9 3312.1 3317.0 473595.0 474804.0 477645.0 479258.0 479529.0 55171.4 55799.7 56062.0 56220.4 56224.7 57346.7 57561.0 57624.7 57674.7 57901.0 14951.4 14974.6 14977.9 15150.4 15162.5 11773.2 11792.8 11804.4 11806.7 11821.5 12309.7 15542.3 15542.9 15575.7 15762.8 5712.4 5746.7 6354.3 6637.5 6752.2 788.0 914.9 955.0 992.5 1040.2 25629.5 27178.5 27893.7 28676.6 28916.9 971.8 1005.4 1023.7 1036.1 1087.5 14507.2 14875.7 15052.9 15181.0 15359.5 626356.0 627581.0 627871.0 630361.0 632797.0 54826.0 55113.4 55231.7 55524.0 55538.0 1303.9 1345.1 1363.6 1374.1 1376.3 23523.1 25625.6 26537.5 29235.4 29842.9 47186.8 47409.5 47712.3 47764.8 48069.5 521994.0 524989.0 525277.0 526017.0 526033.0 24370.9 24513.1 24903.5 24936.0 24956.0 42765.4 43313.6 43583.6 43902.3 44375.0 28212.7 34424.8 35537.1 36157.1 37125.6 30456.8 34221.5 36095.6 36991.3 37092.8 31273.5 31378.5 31585.4 33145.0 35954.3 29615.4 37434.0 39710.6 39987.0 40515.6 29637.0 30752.7 30755.5 30996.5 31467.7 14:48:16 INFO - PROCESS | 1910 | 14:48:16 INFO - PROCESS | 1910 | [#2] jquery.html Cycles:180 Average:34074.43 Median:2052.62 stddev:98234.90 (4785.8%) stddev-sans-first:98477.83 14:48:16 INFO - PROCESS | 1910 | Values: 349.6 371.4 377.0 382.3 398.6 18404.4 18915.0 19346.3 19593.5 19821.4 11273.0 12418.1 19001.9 19447.0 19648.3 5097.4 5349.5 5355.9 5356.1 5365.4 3733.8 5227.8 5877.9 5966.1 6032.6 5728.7 5955.7 5995.5 6022.8 6023.6 4927.8 5728.7 5907.5 5957.4 5977.1 5531.2 5617.3 5742.5 5764.4 5775.6 12549.6 12653.6 19521.4 24257.9 24365.8 2127.1 2143.0 2194.5 2206.5 2828.0 9384.8 9691.7 10140.3 10199.4 14821.9 1719.8 1825.1 1847.1 1874.0 1952.3 96008.0 111372.4 121725.4 121883.0 122958.0 7314.0 9227.2 9309.7 9918.5 11223.0 11589.3 12207.0 12207.8 12208.0 12231.8 7621.7 7838.6 7873.7 7905.0 7975.4 8141.1 8142.9 8182.8 8284.9 8333.5 3159.6 3764.1 3821.1 3874.4 3888.1 1556.0 1636.7 1647.9 1934.0 1955.4 744.9 883.4 890.0 936.7 1015.4 19133.5 20593.7 20700.2 20871.8 20885.1 708.5 891.2 891.3 908.8 911.8 10426.1 10830.6 10850.3 10869.6 10907.3 578118.0 582885.0 587219.0 588786.0 597489.0 71422.6 133105.3 138445.3 141536.9 144203.0 948.2 995.4 999.3 1001.7 1007.2 14774.9 17151.7 17478.0 18443.9 19901.6 51190.7 55947.0 56279.7 56475.7 56914.0 78320.0 79446.3 81388.6 82469.6 84099.0 6312.0 7815.5 7875.8 7939.3 8059.2 10607.6 12145.5 12305.6 12553.0 12569.1 10247.2 10458.2 10830.7 10940.7 11242.2 9061.0 9640.7 10303.9 10819.4 11045.1 10995.7 11072.3 11269.2 11415.5 11424.7 7962.3 8012.4 8025.2 8094.2 8286.8 10124.1 10320.7 10858.2 11101.2 11379.9 14:48:16 INFO - PROCESS | 1910 | 14:48:16 INFO - PROCESS | 1910 | [#3] mootools.html Cycles:180 Average:5392.38 Median:4862.85 stddev:2952.22 (60.7%) stddev-sans-first:2945.38 14:48:16 INFO - PROCESS | 1910 | Values: 1416.3 1430.1 1591.7 1730.3 1760.0 7467.6 7713.2 7970.8 8406.8 8407.5 7871.8 8158.1 8169.7 8273.7 8286.4 1966.0 2285.8 2339.7 2347.0 2352.9 2318.2 2350.7 2363.7 2365.6 2371.0 2218.1 2251.8 2276.5 2337.1 2341.5 2273.2 2273.3 2290.4 2294.9 2304.4 2321.9 2339.0 2340.1 2350.5 2352.2 8199.7 8305.5 8328.0 8337.4 8418.1 5066.1 5219.1 5233.4 5237.3 5243.0 3163.2 3280.7 3524.0 3533.8 3535.1 1025.5 1052.6 1055.9 1074.5 1090.6 10671.0 10720.2 10767.9 10782.2 10796.7 4440.6 4546.2 4623.6 4640.6 4754.0 9269.9 9350.5 9363.6 9377.8 9423.6 5187.4 5189.7 5191.5 5196.0 5202.0 4226.9 4543.9 4661.7 4771.8 4782.1 3709.2 3757.4 3784.6 3808.4 3890.6 1273.9 1282.2 1284.9 1286.3 1295.9 759.2 861.2 890.2 902.6 932.2 5462.2 5480.2 5666.3 5728.9 5734.4 787.5 996.5 1008.5 1011.4 1035.3 3601.6 3793.2 3840.2 3863.3 3907.3 9833.7 10133.5 10246.3 10254.0 10259.1 9982.2 10052.4 10080.3 10186.6 10212.8 7621.7 7655.6 7856.4 7860.8 7898.4 4264.6 4661.9 5348.8 5456.9 5468.9 9056.6 9336.4 9351.3 9391.9 9407.5 7439.3 9302.0 9303.3 9538.0 9549.6 6467.4 6677.5 6899.0 6899.8 7105.9 7678.2 7844.0 7844.2 7862.4 7865.8 6855.8 7003.0 7061.2 7072.8 7131.1 6942.7 6972.6 6975.9 7001.9 7052.0 6601.6 6658.5 6661.9 6721.0 6739.4 4544.7 4607.1 4645.1 4650.8 4751.2 4010.3 4832.4 4841.7 4884.0 4934.8 14:48:16 INFO - PROCESS | 1910 | 14:48:16 INFO - PROCESS | 1910 | [#4] prototype.html Cycles:180 Average:4582.81 Median:5185.77 stddev:1426.48 (27.5%) stddev-sans-first:1393.15 14:48:16 INFO - PROCESS | 1910 | Values: 263.3 304.8 310.8 312.2 316.3 4643.4 5582.4 5613.8 5627.6 5646.9 5218.7 5517.6 5559.1 5625.3 5636.9 3470.3 3991.9 4053.6 4178.4 4181.9 3610.2 3977.1 4064.9 4198.7 4206.2 4181.3 4187.6 4199.9 4218.1 4237.9 3895.5 3977.1 4145.3 4155.8 4180.4 2745.8 3364.4 3550.6 3555.5 3602.5 5075.4 5355.7 5395.2 5397.4 5432.7 4013.9 4516.5 4719.0 4781.8 4788.4 5040.9 5815.7 5822.5 5831.5 5872.8 3261.8 3471.5 3524.8 3737.9 3831.6 5181.8 5422.6 5827.9 6062.3 6187.5 3646.5 3980.4 4007.9 4022.5 4024.4 3567.3 3794.1 4064.5 4074.3 4086.4 3782.5 4031.3 4635.6 4801.4 4814.1 4870.5 5736.9 5866.3 5891.7 5944.0 1254.3 1288.6 1307.1 1312.7 1313.6 829.5 883.3 885.5 891.7 894.6 4858.9 5264.7 5282.4 5336.5 5359.1 5155.9 5516.4 5761.7 6041.4 6052.3 4670.0 5230.9 5303.8 5427.8 5478.0 2720.8 2990.9 3037.0 3051.7 3060.4 5743.1 6027.4 6040.8 6053.8 6130.0 5393.8 5710.2 5724.0 5912.7 5962.5 5679.7 5961.6 5993.4 6038.8 6125.2 4881.9 5062.7 5239.1 5853.3 5892.6 5199.6 5453.7 5688.6 5713.6 5729.7 5189.8 5552.4 5599.7 5691.3 6038.3 3523.5 3620.7 3775.2 3800.0 3802.2 4959.4 5005.6 5466.7 5800.4 5804.6 5167.1 5408.3 5511.9 5576.5 5622.9 4354.4 4760.7 5030.3 5121.1 5643.9 5040.5 5398.4 5423.8 5424.4 5454.4 5586.4 5723.9 5741.5 5888.4 5888.9 4433.6 4725.3 5126.5 5479.3 5748.5 14:48:16 INFO - PROCESS | 1910 | 14:48:16 INFO - PROCESS | 1910 | [#5] yui.html Cycles:180 Average:19229.07 Median:24015.09 stddev:27971.43 (116.5%) stddev-sans-first:28014.87 14:48:16 INFO - PROCESS | 1910 | Values: 586.8 746.6 754.8 774.5 775.7 13861.9 14220.2 14657.3 14672.6 14675.0 13182.5 13424.2 13438.5 13447.3 13484.0 816.8 834.4 834.6 834.9 837.6 651.7 668.8 789.3 804.7 811.9 858.2 863.5 863.6 864.5 868.7 855.7 856.6 857.2 857.7 859.0 637.1 641.3 641.7 642.8 662.9 10902.3 13218.6 13230.9 13251.8 13335.1 13165.3 13248.9 13357.2 13386.5 13409.4 11109.8 11197.8 11232.1 11286.7 11531.2 3583.5 3621.9 3641.7 3749.8 3780.9 85515.6 87209.6 88246.0 88386.0 89687.6 10311.7 10343.5 10438.2 10510.0 10679.2 19960.8 20397.7 20557.0 20583.2 20608.6 6479.8 7461.4 7481.9 7555.9 7617.4 4151.8 4231.4 4235.5 4242.7 4248.2 2666.9 3064.4 3321.2 3371.5 3376.4 589.5 589.9 634.9 639.4 660.0 570.0 605.0 609.9 622.0 624.7 8576.7 8644.0 8893.5 8940.9 9211.2 545.4 560.1 627.9 670.8 696.2 4141.5 4184.7 4210.0 4410.7 4414.1 83190.0 99425.0 118408.4 119104.0 123342.0 52335.0 69161.0 83335.2 93430.0 106315.0 43782.0 56014.7 59344.0 75663.2 78454.6 8537.6 8858.8 9360.6 9397.9 9639.9 50124.7 54371.7 56114.0 57675.7 58329.0 44527.8 68515.7 75023.0 75474.9 77079.0 11818.1 12093.7 12232.1 12246.6 12597.4 11389.3 13681.7 13969.3 13992.9 14502.9 12556.8 12766.4 12767.9 12780.6 12794.7 12401.4 12638.7 12739.1 12792.2 12995.5 12246.3 12477.6 12613.7 12620.3 12736.4 10788.2 10969.5 10980.1 11247.6 11389.7 9849.5 10242.3 11091.4 11201.4 11214.7 14:48:16 INFO - PROCESS | 1910 | -------- Summary: end -------- 14:48:16 INFO - PROCESS | 1910 | 14:48:17 INFO - TEST-INFO | 1910: exit 0 14:48:17 INFO - TEST-OK | dromaeo_css | took 1116063ms 14:48:17 INFO - TEST-START | kraken 14:48:17 INFO - operating with platform_type : mac_ 14:48:17 INFO - Initialising browser for kraken test... 14:48:17 INFO - TEST-INFO | started process 1916 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/tmpTuIxbH/profile http://localhost:53486/getInfo.html) 14:48:20 INFO - PROCESS | 1916 | 2016-11-22 14:48:20.412 plugin-container[1917:18745] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a3f, name = 'com.apple.tsm.portname' 14:48:20 INFO - PROCESS | 1916 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:48:20 INFO - PROCESS | 1916 | 2016-11-22 14:48:20.415 plugin-container[1917:18745] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 14:48:20 INFO - PROCESS | 1916 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:48:20 INFO - PROCESS | 1916 | 2016-11-22 14:48:20.415 plugin-container[1917:18745] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:48:20 INFO - PROCESS | 1916 | __metrics Screen width/height:1600/1200 14:48:20 INFO - PROCESS | 1916 | colorDepth:24 14:48:20 INFO - PROCESS | 1916 | Browser inner width/height: 1024/689 14:48:20 INFO - PROCESS | 1916 | __metrics 14:48:20 ERROR - PROCESS | 1916 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3198: TypeError: this.tabs is undefined 14:48:21 INFO - TEST-INFO | 1916: exit 0 14:48:21 INFO - Browser initialized. 14:48:21 INFO - Running cycle 1/1 for kraken test... 14:48:21 INFO - TEST-INFO | started process 1918 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/tmpTuIxbH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:48:24 INFO - PROCESS | 1918 | 2016-11-22 14:48:24.165 plugin-container[1919:18858] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x454b, name = 'com.apple.tsm.portname' 14:48:24 INFO - PROCESS | 1918 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:48:24 INFO - PROCESS | 1918 | 2016-11-22 14:48:24.166 plugin-container[1919:18858] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x910b, name = 'com.apple.CFPasteboardClient' 14:48:24 INFO - PROCESS | 1918 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:48:24 INFO - PROCESS | 1918 | 2016-11-22 14:48:24.166 plugin-container[1919:18858] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:48:33 INFO - PROCESS | 1918 | RSS: Main: 242548736 14:48:33 INFO - PROCESS | 1918 | 14:48:36 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:53486/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53486/tests/kraken/driver.html?testName=audio-beat-detection) 14:48:37 INFO - PROCESS | 1918 | RSS: Main: 253915136 14:48:37 INFO - PROCESS | 1918 | 14:48:40 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:53486/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53486/tests/kraken/driver.html?testName=audio-dft) 14:48:41 INFO - PROCESS | 1918 | RSS: Main: 261517312 14:48:41 INFO - PROCESS | 1918 | 14:48:44 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:53486/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53486/tests/kraken/driver.html?testName=audio-fft) 14:48:45 INFO - PROCESS | 1918 | RSS: Main: 264478720 14:48:45 INFO - PROCESS | 1918 | 14:48:47 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:53486/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53486/tests/kraken/driver.html?testName=audio-oscillator) 14:48:48 INFO - PROCESS | 1918 | RSS: Main: 265523200 14:48:48 INFO - PROCESS | 1918 | 14:48:49 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:53486/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53486/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:48:50 INFO - PROCESS | 1918 | RSS: Main: 267116544 14:48:50 INFO - PROCESS | 1918 | 14:48:53 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:53486/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53486/tests/kraken/driver.html?testName=imaging-darkroom) 14:48:54 INFO - PROCESS | 1918 | RSS: Main: 272519168 14:48:54 INFO - PROCESS | 1918 | 14:48:57 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:53486/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53486/tests/kraken/driver.html?testName=imaging-desaturate) 14:48:57 INFO - PROCESS | 1918 | RSS: Main: 272195584 14:48:57 INFO - PROCESS | 1918 | 14:49:00 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:53486/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53486/tests/kraken/driver.html?testName=json-parse-financial) 14:49:01 INFO - PROCESS | 1918 | RSS: Main: 272154624 14:49:01 INFO - PROCESS | 1918 | 14:49:02 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:53486/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53486/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:49:03 INFO - PROCESS | 1918 | RSS: Main: 271282176 14:49:03 INFO - PROCESS | 1918 | 14:49:05 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:53486/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53486/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:49:06 INFO - PROCESS | 1918 | RSS: Main: 271351808 14:49:06 INFO - PROCESS | 1918 | 14:49:08 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:53486/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53486/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:49:08 INFO - PROCESS | 1918 | RSS: Main: 271290368 14:49:08 INFO - PROCESS | 1918 | 14:49:12 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:53486/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53486/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:49:12 INFO - PROCESS | 1918 | RSS: Main: 271613952 14:49:12 INFO - PROCESS | 1918 | 14:49:15 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:53486/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53486/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:49:16 INFO - PROCESS | 1918 | RSS: Main: 271458304 14:49:16 INFO - PROCESS | 1918 | 14:49:18 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:53486/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53486/tests/kraken/driver.html?testName=ai-astar) 14:49:18 INFO - PROCESS | 1918 | RSS: Main: 273289216 14:49:18 INFO - PROCESS | 1918 | 14:49:18 INFO - PROCESS | 1918 | __start_tp_report 14:49:18 INFO - PROCESS | 1918 | _x_x_mozilla_page_load 14:49:18 INFO - PROCESS | 1918 | _x_x_mozilla_page_load_details 14:49:18 INFO - PROCESS | 1918 | |i|pagename|runs| 14:49:18 INFO - PROCESS | 1918 | |0;ai-astar;111;178;123;117;119;118;116;118;119;120 14:49:18 INFO - PROCESS | 1918 | |1;audio-beat-detection;173;112;120;157;119;115;120;121;146;117 14:49:18 INFO - PROCESS | 1918 | |2;audio-dft;221;212;162;268;275;190;277;283;193;202 14:49:18 INFO - PROCESS | 1918 | |3;audio-fft;97;60;59;84;62;62;82;83;89;51 14:49:18 INFO - PROCESS | 1918 | |4;audio-oscillator;84;71;88;73;71;71;85;72;69;106 14:49:18 INFO - PROCESS | 1918 | |5;imaging-gaussian-blur;91;90;90;88;90;86;91;89;90;88 14:49:18 INFO - PROCESS | 1918 | |6;imaging-darkroom;147;96;95;96;95;97;108;152;98;155 14:49:18 INFO - PROCESS | 1918 | |7;imaging-desaturate;92;92;92;118;114;93;94;93;91;93 14:49:18 INFO - PROCESS | 1918 | |8;json-parse-financial;57;57;56;58;57;59;57;58;58;58 14:49:18 INFO - PROCESS | 1918 | |9;json-stringify-tinderbox;70;72;75;82;81;76;71;71;73;73 14:49:18 INFO - PROCESS | 1918 | |10;stanford-crypto-aes;102;104;111;87;89;90;90;91;88;91 14:49:18 INFO - PROCESS | 1918 | |11;stanford-crypto-ccm;141;146;152;154;173;160;170;165;206;204 14:49:18 INFO - PROCESS | 1918 | |12;stanford-crypto-pbkdf2;183;179;174;177;211;177;175;172;177;222 14:49:18 INFO - PROCESS | 1918 | |13;stanford-crypto-sha256-iterative;70;67;69;66;69;67;68;69;69;69 14:49:18 INFO - PROCESS | 1918 | __end_tp_report 14:49:18 INFO - PROCESS | 1918 | __start_cc_report 14:49:18 INFO - PROCESS | 1918 | _x_x_mozilla_cycle_collect,947 14:49:18 INFO - PROCESS | 1918 | __end_cc_report 14:49:18 INFO - PROCESS | 1918 | __startTimestamp1479854958399__endTimestamp 14:49:18 INFO - PROCESS | 1918 | 14:49:18 INFO - PROCESS | 1918 | ------- Summary: start ------- 14:49:18 INFO - PROCESS | 1918 | Number of tests: 14 14:49:18 INFO - PROCESS | 1918 | 14:49:18 INFO - PROCESS | 1918 | [#0] ai-astar Cycles:10 Average:123.90 Median:119.00 stddev:19.26 (16.2%) stddev-sans-first:19.85 14:49:18 INFO - PROCESS | 1918 | Values: 111.0 178.0 123.0 117.0 119.0 118.0 116.0 118.0 119.0 120.0 14:49:18 INFO - PROCESS | 1918 | 14:49:18 INFO - PROCESS | 1918 | [#1] audio-beat-detection Cycles:10 Average:130.00 Median:120.50 stddev:20.96 (17.4%) stddev-sans-first:15.41 14:49:18 INFO - PROCESS | 1918 | Values: 173.0 112.0 120.0 157.0 119.0 115.0 120.0 121.0 146.0 117.0 14:49:18 INFO - PROCESS | 1918 | 14:49:18 INFO - PROCESS | 1918 | [#2] audio-dft Cycles:10 Average:228.30 Median:244.50 stddev:43.77 (17.9%) stddev-sans-first:46.34 14:49:18 INFO - PROCESS | 1918 | Values: 221.0 212.0 162.0 268.0 275.0 190.0 277.0 283.0 193.0 202.0 14:49:18 INFO - PROCESS | 1918 | 14:49:18 INFO - PROCESS | 1918 | [#3] audio-fft Cycles:10 Average:72.90 Median:82.50 stddev:15.72 (19.1%) stddev-sans-first:14.05 14:49:18 INFO - PROCESS | 1918 | Values: 97.0 60.0 59.0 84.0 62.0 62.0 82.0 83.0 89.0 51.0 14:49:18 INFO - PROCESS | 1918 | 14:49:18 INFO - PROCESS | 1918 | [#4] audio-oscillator Cycles:10 Average:79.00 Median:72.50 stddev:11.78 (16.2%) stddev-sans-first:12.35 14:49:18 INFO - PROCESS | 1918 | Values: 84.0 71.0 88.0 73.0 71.0 71.0 85.0 72.0 69.0 106.0 14:49:18 INFO - PROCESS | 1918 | 14:49:18 INFO - PROCESS | 1918 | [#5] imaging-gaussian-blur Cycles:10 Average:89.30 Median:90.00 stddev:1.57 (1.7%) stddev-sans-first:1.54 14:49:18 INFO - PROCESS | 1918 | Values: 91.0 90.0 90.0 88.0 90.0 86.0 91.0 89.0 90.0 88.0 14:49:18 INFO - PROCESS | 1918 | 14:49:18 INFO - PROCESS | 1918 | [#6] imaging-darkroom Cycles:10 Average:113.90 Median:95.50 stddev:26.17 (27.4%) stddev-sans-first:24.87 14:49:18 INFO - PROCESS | 1918 | Values: 147.0 96.0 95.0 96.0 95.0 97.0 108.0 152.0 98.0 155.0 14:49:18 INFO - PROCESS | 1918 | 14:49:18 INFO - PROCESS | 1918 | [#7] imaging-desaturate Cycles:10 Average:97.20 Median:92.50 stddev:9.99 (10.8%) stddev-sans-first:10.41 14:49:18 INFO - PROCESS | 1918 | Values: 92.0 92.0 92.0 118.0 114.0 93.0 94.0 93.0 91.0 93.0 14:49:18 INFO - PROCESS | 1918 | 14:49:18 INFO - PROCESS | 1918 | [#8] json-parse-financial Cycles:10 Average:57.50 Median:58.00 stddev:0.85 (1.5%) stddev-sans-first:0.88 14:49:18 INFO - PROCESS | 1918 | Values: 57.0 57.0 56.0 58.0 57.0 59.0 57.0 58.0 58.0 58.0 14:49:18 INFO - PROCESS | 1918 | 14:49:18 INFO - PROCESS | 1918 | [#9] json-stringify-tinderbox Cycles:10 Average:74.40 Median:74.00 stddev:4.17 (5.6%) stddev-sans-first:4.11 14:49:18 INFO - PROCESS | 1918 | Values: 70.0 72.0 75.0 82.0 81.0 76.0 71.0 71.0 73.0 73.0 14:49:18 INFO - PROCESS | 1918 | 14:49:18 INFO - PROCESS | 1918 | [#10] stanford-crypto-aes Cycles:10 Average:94.30 Median:89.50 stddev:8.25 (9.2%) stddev-sans-first:8.26 14:49:18 INFO - PROCESS | 1918 | Values: 102.0 104.0 111.0 87.0 89.0 90.0 90.0 91.0 88.0 91.0 14:49:18 INFO - PROCESS | 1918 | 14:49:18 INFO - PROCESS | 1918 | [#11] stanford-crypto-ccm Cycles:10 Average:167.10 Median:167.50 stddev:22.36 (13.3%) stddev-sans-first:21.63 14:49:18 INFO - PROCESS | 1918 | Values: 141.0 146.0 152.0 154.0 173.0 160.0 170.0 165.0 206.0 204.0 14:49:18 INFO - PROCESS | 1918 | 14:49:18 INFO - PROCESS | 1918 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:184.70 Median:178.00 stddev:17.21 (9.7%) stddev-sans-first:18.24 14:49:18 INFO - PROCESS | 1918 | Values: 183.0 179.0 174.0 177.0 211.0 177.0 175.0 172.0 177.0 222.0 14:49:18 INFO - PROCESS | 1918 | 14:49:18 INFO - PROCESS | 1918 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.30 Median:69.00 stddev:1.25 (1.8%) stddev-sans-first:1.17 14:49:18 INFO - PROCESS | 1918 | Values: 70.0 67.0 69.0 66.0 69.0 67.0 68.0 69.0 69.0 69.0 14:49:18 INFO - PROCESS | 1918 | -------- Summary: end -------- 14:49:18 INFO - PROCESS | 1918 | 14:49:19 INFO - TEST-INFO | 1918: exit 0 14:49:19 INFO - TEST-OK | kraken | took 62066ms 14:49:19 INFO - SUITE-END | took 1178s 14:49:19 INFO - Completed test suite (00:19:38) 14:49:19 INFO - javascript benchmark 14:49:19 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7066.735664084946, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2893.623828902243, 3405.5743032120986, 3405.8978230653074, 3412.385770561298, 3444.810535420552, 4700.247960326348, 5764.827055188346, 5778.3643799182055, 5798.072308430652, 5812.593118481708, 4549.590536851682, 4860.489648586891, 5817.389174136718, 5830.125481177825, 5869.031609784381, 5106.66806657566, 5247.055530004598, 5320.441353657869, 5339.599530035243, 5345.812896548621, 4374.562543745616, 5302.655327403706, 5370.275012873251, 5422.024035673578, 5460.781568737266, 5195.1687729963405, 5325.733713314357, 5348.251244825688, 5385.703786291752, 5391.272178255925, 5177.249298851683, 5232.7122008289525, 5253.238280449352, 5276.076686579871, 5283.418823929364, 5194.37667479901, 5199.532042116228, 5282.947170528284, 5289.867753306198, 5319.308489896327, 4887.340209071801, 5049.2173713074535, 5294.973525132388, 5865.530757539386, 5882.264716910387, 5063.8480845574695, 6209.410106039919, 6258.34287399821, 6268.216472940882, 6282.340354262782, 6218.378162183791, 6275.372462753734, 6287.528435367366, 6289.433950944392, 6290.339514351019, 5753.424657534213, 5838.620489668158, 5960.433758792908, 5962.1951036609935, 5988.071848863389, 6289.528285378615, 6291.339409362043, 6302.8424289392215, 6314.55798094129, 6370.458511026522, 6154.230721159855, 6246.781362652286, 6251.374862513712, 6262.060690896402, 6269.655168965751, 6282.277538083179, 6285.2457705075685, 6308.779192728232, 6309.747610095636, 6314.55798094129, 6118.9846826261555, 6164.137020817089, 6185.103160041859, 6198.380161983856, 6201.782937597252, 6198.822223777558, 6249.562530622813, 6270.592411493239, 6278.717457714414, 6290.811275661737, 4962.776675049559, 4994.925076123861, 5000.149974504343, 5001.274815151783, 5029.7736601853, 3581.516495273157, 4500.189965806121, 4508.45898492183, 4534.77326133692, 4578.313253012075, 4604.240300350405, 5549.306336707911, 5892.204552385459, 5988.670623115683, 6081.483073938764, 6209.5032397408095, 6250.9062364064575, 6255.78104766331, 6258.3428739982555, 6294.402031806971, 5993.9810232260625, 6008.609440386448, 6027.859856479545, 6033.3664965178905, 6074.0, 5292.814751483679, 5375.9462405376225, 5404.000259951921, 5418.566514678816, 5447.509724124848, 6311.053341998737, 6331.525135614847, 6340.714667839959, 6358.523110766803, 6385.808425747235, 6247.96876015617, 6255.843603909757, 6268.811935641938, 6279.686015699288, 6297.905531416941, 5327.147656374961, 5931.9110213345975, 6235.407636274546, 6286.4342209201395, 6492.837679058061, 5026.120428924851, 5986.161937328865, 6079.939200608114, 6088.360722124113, 6257.874842503215, 6145.201123853871, 6161.907571386343, 6177.722002509808, 6196.411340922605, 6265.27949285838, 5200.97399513, 6119.204503414528, 6158.32258451561, 6190.783322583688, 6202.81391558254, 5096.311997880239, 6000.619857432902, 6202.56582039271, 6221.813345599639, 6226.750929962751, 5155.793768249228, 5639.661620302795, 5947.3457919627945, 5979.910301345396, 6033.396660333931, 5253.947460525345, 6137.407629318811, 6160.44555989963, 6197.256329240339, 6202.131701561875, 4860.9513904861915, 6004.279486461653, 6026.969865150646, 6039.849003774941, 6053.969730151497], "value": 5663.240041681968, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [497.52734901843235, 511.65718968291253, 512.0373697448796, 528.9444608316127, 560.6103757888264, 36261.637383626155, 36889.26221475572, 38100.856974290815, 38159.04602384942, 38183.618163818355, 38856.251468683906, 39630.801845990805, 39646.40530392047, 39720.20559588807, 39807.20385592287, 9024.684136055239, 9346.672866449675, 9348.532573371338, 9350.111739384782, 9369.48467834269, 9272.0, 9339.206167475773, 9388.0, 9388.061193880625, 9392.389494682859, 9335.719928402159, 9399.154076133147, 9403.95298023509, 9404.0, 9435.575399107056, 9307.999999999965, 9375.953120234424, 9386.155246027856, 9411.57647905839, 9417.540281256348, 9278.907210927871, 9358.4384936904, 9360.672376466859, 9368.34421590489, 9416.717498474989, 37576.62423375786, 37958.24083518342, 37960.24079518422, 38013.61986380129, 38131.8093409534, 3046.177532066352, 3222.452183128874, 3646.2342907989355, 3833.3291673956946, 3869.535655721303, 26482.0, 27816.026439074536, 29705.702942970725, 29864.55203171954, 29953.0, 3064.8007879487764, 3201.2637093468634, 3243.9351212975844, 3312.0560640217664, 3316.950245746316, 473595.0, 474804.0, 477645.0, 479258.0, 479529.0, 55171.44828551663, 55799.72100139473, 56062.0, 56220.43779562234, 56224.71887640617, 57346.7132664334, 57561.0, 57624.71187644035, 57674.71162644244, 57901.0, 14951.40194392234, 14974.625634359229, 14977.925110374379, 15150.393984240507, 15162.545123646309, 11773.234739741889, 11792.764144717057, 11804.350760708072, 11806.704832379088, 11821.468033938494, 12309.692257693629, 15542.290348061704, 15542.9222853885, 15575.688486230212, 15762.842371576138, 5712.400197979236, 5746.655200687972, 6354.3010268870385, 6637.502187335969, 6752.189737231563, 787.9881801772977, 914.881065461499, 955.025873608915, 992.4839083676447, 1040.245821779219, 25629.487410252063, 27178.456430871272, 27893.72106278952, 28676.569851452245, 28916.855415723207, 971.8104969531015, 1005.3967619428431, 1023.6673081248624, 1036.1141224253156, 1087.537796436527, 14507.202103884387, 14875.702485950436, 15052.924735376471, 15181.0, 15359.539213823604, 626356.0, 627581.0, 627871.0, 630361.0, 632797.0, 54826.0, 55113.44886551164, 55231.72384138054, 55524.0, 55538.0, 1303.9348032598332, 1345.0920628575868, 1363.5909227231791, 1374.0519041861085, 1376.2843321472778, 23523.059077637387, 25625.61561576615, 26537.469250614493, 29235.415291694473, 29842.850785745934, 47186.82032949066, 47409.52590474051, 47712.2843157346, 47764.7611761939, 48069.5193048079, 521994.0, 524989.0, 525277.0, 526017.0, 526033.0, 24370.87814560916, 24513.142040028513, 24903.50192996166, 24936.0, 24956.0, 42765.35851962161, 43313.566864330955, 43583.56416435795, 43902.34146487869, 44375.0, 28212.71787282101, 34424.82787586046, 35537.1115722109, 36157.0960725984, 37125.6287437133, 30456.84771576128, 34221.486677700355, 36095.63904361028, 36991.260174796895, 37092.81453592715, 31273.530897037017, 31378.529322060644, 31585.368292634477, 33145.00564983054, 35954.28091438209, 29615.407691845612, 37434.0, 39710.60289397069, 39987.0, 40515.594844051186, 29637.0, 30752.69247307498, 30755.538666919565, 30996.53505197469, 31467.685323146474], "value": 18998.0252940264, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [349.64511021313365, 371.4261466034974, 376.9849206031758, 382.2622338885949, 398.62673091200804, 18404.44786656399, 18915.0, 19346.322878699248, 19593.51016224595, 19821.405357839252, 11273.0, 12418.130730848821, 19001.904990475065, 19447.027648617546, 19648.312309069148, 5097.439281679021, 5349.491798279158, 5355.866103347408, 5356.143017117262, 5365.382980957182, 3733.813309334536, 5227.817026404076, 5877.941220587803, 5966.105084237356, 6032.638041717511, 5728.713564321767, 5955.731992060368, 5995.490383317423, 6022.849428764294, 6023.578349515536, 4927.827526036572, 5728.713564321788, 5907.497862681677, 5957.404259574051, 5977.073553599177, 5531.197976293455, 5617.33760978387, 5742.454466825645, 5764.423557644244, 5775.624584402, 12549.623511294676, 12653.557125500656, 19521.414357569294, 24257.878710606336, 24365.756342436525, 2127.1278775702044, 2142.9785702142935, 2194.473326401668, 2206.5035367042447, 2828.0, 9384.812303753886, 9691.70924872255, 10140.340877842917, 10199.38803671782, 14821.851781482263, 1719.7617715245128, 1825.12394050855, 1847.094923487495, 1873.953151171218, 1952.336205690058, 96008.0399196013, 111372.4431377846, 121725.39137304346, 121883.0, 122957.99999999911, 7313.963430182922, 9227.215686666708, 9309.72070837876, 9918.504074796232, 11222.989930906258, 11589.304641721355, 12207.0, 12207.75584488323, 12208.0, 12231.816522752166, 7621.69513219465, 7838.608069596611, 7873.685052597832, 7905.01187351581, 7975.441719079608, 8141.104478507359, 8142.877856832152, 8182.7545173644885, 8284.875726864102, 8333.541655209021, 3159.573457583243, 3764.0778009387327, 3821.121142137324, 3874.4188371744463, 3888.066863952631, 1555.9766603500957, 1636.6563021765323, 1647.8846480746472, 1933.980660193408, 1955.3645065353533, 744.9384626906744, 883.3551507399634, 889.9777505562283, 936.6721647423348, 1015.4161357219629, 19133.521661958563, 20593.691094633592, 20700.171993120108, 20871.791282086986, 20885.060172292284, 708.4934271995445, 891.2023738753779, 891.3359547137425, 908.8409528332515, 911.794846159622, 10426.113780328757, 10830.566777328977, 10850.294730842628, 10869.619563315246, 10907.29102608328, 578118.0, 582885.0, 587219.0, 588786.0, 597489.0, 71422.64288678524, 133105.33447332896, 138445.3077734605, 141536.87694684588, 144203.0, 948.2129832239225, 995.3778888194878, 999.3254553176722, 1001.7195185348114, 1007.1539906478594, 14774.852251477778, 17151.7427238589, 17478.0, 18443.907780461548, 19901.60196796085, 51190.72023199301, 55947.0, 56279.718601406734, 56475.71762141163, 56914.0, 78320.0, 79446.26091130087, 81388.59305703433, 82469.58765206135, 84099.0, 6311.968440157953, 7815.452918295665, 7875.803104922423, 7939.325157361458, 8059.194080591894, 10607.628732994308, 12145.45345459474, 12305.56930507428, 12553.0, 12569.1201615886, 10247.231457640557, 10458.163346932379, 10830.67507974762, 10940.671779846618, 11242.156838237, 9061.0, 9640.650308956612, 10303.948480257552, 10819.350838949687, 11045.061169800658, 10995.670129896116, 11072.335659860435, 11269.154813388865, 11415.486303116213, 11424.657260282205, 7962.283394494523, 8012.399070069651, 8025.197480251928, 8094.150114238156, 8286.751397457845, 10124.088832005154, 10320.741981450525, 10858.185636077484, 11101.222914395914, 11379.886201137882], "value": 9063.794369749086, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1416.3130881522463, 1430.0847457627117, 1591.7294060009797, 1730.2992288123298, 1760.0495732304548, 7467.551946883191, 7713.1515533291295, 7970.76087717368, 8406.83186336273, 8407.453515521494, 7871.8425631487335, 8158.143394943534, 8169.6732130714845, 8273.669053237878, 8286.378521610874, 1966.0, 2285.7828506291917, 2339.7426283108844, 2346.9790641071104, 2352.882355882208, 2318.2002209237785, 2350.7061617297836, 2363.6881530750425, 2365.550545396369, 2370.9644355334685, 2218.1016688241207, 2251.763564825694, 2276.4764104256033, 2337.1118974789642, 2341.519988402373, 2273.193016479151, 2273.26119011322, 2290.3701482092392, 2294.8852557372147, 2304.3547806614174, 2321.9071237150492, 2338.988305058464, 2340.1224540797202, 2350.5298940211856, 2352.2120089769946, 8199.71301004462, 8305.46014509055, 8328.042275138316, 8337.374696897696, 8418.074011858691, 5066.113430149746, 5219.138842091051, 5233.371995360543, 5237.319148510707, 5243.030039442715, 3163.193385686658, 3280.6555311692405, 3524.0, 3533.770304930197, 3535.063208249816, 1025.5077562769839, 1052.5894900988621, 1055.8944105589455, 1074.4949873559415, 1090.6291860767299, 10671.0, 10720.249582529226, 10767.892321076768, 10782.191335649857, 10796.67609971702, 4440.555944405598, 4546.181687296253, 4623.630109591225, 4640.628749700083, 4754.02542478791, 9269.90730092704, 9350.485723285285, 9363.578638961262, 9377.765555861022, 9423.623055077856, 5187.351581052369, 5189.714565698923, 5191.532762051358, 5196.0, 5202.0, 4226.915461690749, 4543.909121817545, 4661.650376221797, 4771.809127634925, 4782.115308667908, 3709.1654377765326, 3757.4176002719623, 3784.6026167252594, 3808.3716186829415, 3890.630390112935, 1273.949042038308, 1282.160185078775, 1284.8650891656428, 1286.298967062953, 1295.8704129586965, 759.2407592407592, 861.1990848510819, 890.1517033092649, 902.6434558349547, 932.2029664636774, 5462.235287059815, 5480.177973303957, 5666.263385759825, 5728.914066289009, 5734.369219385864, 787.4605894961969, 996.4917891875195, 1008.5240733998018, 1011.4234886114991, 1035.3011717091083, 3601.5858176309534, 3793.2223894101644, 3840.1935593525113, 3863.285292220948, 3907.27715372657, 9833.655822046194, 10133.493325333851, 10246.282760206863, 10253.974602539687, 10259.076683098558, 9982.201423886218, 10052.447115408577, 10080.294379393372, 10186.643467478603, 10212.846807297896, 7621.733239336597, 7655.57894315818, 7856.350166464992, 7860.842783144305, 7898.44710870245, 4264.552222016768, 4661.860144195544, 5348.78604855817, 5456.890862182813, 5468.91796623059, 9056.592453339746, 9336.439813611205, 9351.345405821527, 9391.906080939103, 9407.52962351866, 7439.256074392518, 9301.953490232776, 9303.255739540686, 9538.046195380406, 9549.570269337757, 6467.385598368148, 6677.532572719863, 6899.0, 6899.793006209822, 7105.893411598933, 7678.155402905788, 7844.0, 7844.176361481962, 7862.371010319046, 7865.842683146419, 6855.828604284733, 7003.0, 7061.187963384071, 7072.752453664097, 7131.0729605151, 6942.722291108501, 6972.58164510131, 6975.8604827902145, 7001.8949715755325, 7052.047973523714, 6601.603903765789, 6658.533902626769, 6661.900071498834, 6721.0, 6739.382548188301, 4544.7045942013665, 4607.101615185007, 4645.09420662981, 4650.790714417792, 4751.192297309535, 4010.318245898146, 4832.420109586731, 4841.733704646278, 4883.975580122076, 4934.777934992862], "value": 4386.399384105866, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [263.30224904004393, 304.8003557669724, 310.8119587649472, 312.2132226788493, 316.2504863473566, 4643.373144625479, 5582.413846546115, 5613.80351687691, 5627.577931655122, 5646.8588285292935, 5218.686878787275, 5517.5585953123755, 5559.11054231322, 5625.299856069079, 5636.928983493143, 3470.31689630529, 3991.880243592697, 4053.6351728344443, 4178.373244013407, 4181.937270940938, 3610.151614370621, 3977.1051513409398, 4064.8577299794515, 4198.6850986176005, 4206.179794939988, 4181.310083836179, 4187.60217779312, 4199.937000944973, 4218.11419601884, 4237.936430953539, 3895.4935858338376, 3977.0825736649376, 4145.341863254722, 4155.812988415529, 4180.414741936132, 2745.752882240588, 3364.4280472319765, 3550.609432962372, 3555.5022296878456, 3602.5316708827686, 5075.390953085654, 5355.651882627617, 5395.21769343443, 5397.379301380352, 5432.663954499563, 4013.9197216055513, 4516.503184649686, 4719.0, 4781.8087276508895, 4788.438185570703, 5040.873978150539, 5815.738291776838, 5822.505087067604, 5831.475167234969, 5872.794452194153, 3261.771675982682, 3471.5486986691567, 3524.753267271292, 3737.897320290521, 3831.616838316174, 5181.766820493087, 5422.566194704415, 5827.941720582782, 6062.272527296708, 6187.535934804908, 3646.4894914711963, 3980.402939559089, 4007.919841603152, 4022.456968309299, 4024.396340548941, 3567.340042092221, 3794.1273507093524, 4064.532578753422, 4074.307367747431, 4086.38587757834, 3782.470454136423, 4031.339316051392, 4635.58279754817, 4801.351817504592, 4814.061258054646, 4870.537298956593, 5736.856578585485, 5866.266716660418, 5891.675957822275, 5944.0, 1254.2913254011455, 1288.5747703257903, 1307.1046333261745, 1312.7308901675133, 1313.5747713730518, 829.531314807132, 883.3110174064241, 885.4731434796286, 891.7012800711834, 894.6376717429451, 4858.927116093261, 5264.6838290427395, 5282.392524859691, 5336.546393556514, 5359.124306492697, 5155.880692181251, 5516.4143306850065, 5761.740721667535, 6041.40219705522, 6052.334243233241, 4669.976650116728, 5230.895382092413, 5303.8442126934415, 5427.83716488506, 5478.0, 2720.8231464954715, 2990.880364785428, 3037.0, 3051.6643169251365, 3060.449119158573, 5743.128435782109, 6027.397260273938, 6040.758369665252, 6053.82062457515, 6129.96935015331, 5393.768044996347, 5710.151821461123, 5723.971380143073, 5912.674802885796, 5962.493188078975, 5679.744411501492, 5961.582689211714, 5993.400659933972, 6038.822429626269, 6125.202374527821, 4881.87638377307, 5062.670926389773, 5239.135542635422, 5853.297604287342, 5892.646441213541, 5199.610029247897, 5453.672779633234, 5688.601797874109, 5713.600047996601, 5729.71351432435, 5189.76646050921, 5552.416996215501, 5599.748011339418, 5691.345495268097, 6038.3357830637715, 3523.4714792781288, 3620.7103431725927, 3775.244951009864, 3799.9670176455465, 3802.16352402471, 4959.429665588359, 5005.5745261653165, 5466.699331536644, 5800.4489573490455, 5804.564657650609, 5167.1474206755465, 5408.349908544863, 5511.944880551143, 5576.498115169654, 5622.88754224905, 4354.382846128458, 4760.714357138583, 5030.270610761492, 5121.103806833864, 5643.871225754948, 5040.49595040493, 5398.379186393615, 5423.810166644149, 5424.430434804446, 5454.400015998158, 5586.357568879631, 5723.914141287967, 5741.483266506035, 5888.440598143169, 5888.852778680396, 4433.578810013044, 4725.267583524559, 5126.512981266774, 5479.342478902558, 5748.48263656273], "value": 4122.774643136611, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [586.841552780749, 746.5520687587451, 754.7924320811777, 774.4888373673376, 775.7207405334083, 13861.930690346548, 14220.217888016155, 14657.267136643166, 14672.559823205294, 14674.972751907357, 13182.538611148611, 13424.194548327107, 13438.5296514622, 13447.260400677958, 13484.0, 816.7713040348712, 834.4367551902471, 834.6035633074301, 834.8789425533294, 837.6146972392706, 651.6741629185407, 668.8394785251553, 789.3369569561568, 804.74650485097, 811.8741595052746, 858.159004175908, 863.5078005536847, 863.641588740675, 864.5029108262743, 868.700298397052, 855.7261676263598, 856.5888373580653, 857.1557016338901, 857.6998050682242, 859.0464584311409, 637.1303171171318, 641.3041849593197, 641.7497176101324, 642.8489305013318, 662.8508585568256, 10902.345859248471, 13218.60344189676, 13230.933845330808, 13251.801222981663, 13335.06654534183, 13165.275909824955, 13248.86751132486, 13357.198568085849, 13386.531471398454, 13409.396577153953, 11109.83335249972, 11197.760447910368, 11232.10143188543, 11286.717832054183, 11531.192816502848, 3583.4803953426867, 3621.94567081494, 3641.745077844552, 3749.7750134991716, 3780.9054773630605, 85515.57242213811, 87209.56395218047, 88246.0, 88386.0, 89687.55156224243, 10311.74220644475, 10343.534540945675, 10438.164946804238, 10509.962985348973, 10679.199060070527, 19960.800391996185, 20397.694034589495, 20557.0, 20583.176672933216, 20608.58782824335, 6479.838004049937, 7461.403087752967, 7481.8877716834295, 7555.924440755632, 7617.428692848059, 4151.813168407429, 4231.42875711775, 4235.534091250021, 4242.7242229255, 4248.171606536757, 2666.8665817028073, 3064.402441523926, 3321.186309354175, 3371.460566309379, 3376.40912840252, 589.4930359890491, 589.8672798620362, 634.8825467288566, 639.3861892583079, 660.0132801461846, 570.0081857567885, 604.9969750151221, 609.871926895357, 621.9657918814418, 624.6938999890112, 8576.742697719079, 8643.956780216184, 8893.510856902933, 8940.910590894138, 9211.217046550984, 545.38916413617, 560.0982418306523, 627.9340968214614, 670.7551743613643, 696.2202333386639, 4141.503019637663, 4184.702742149943, 4209.957900421018, 4410.669199810027, 4414.139242847678, 83190.0, 99425.0, 118408.40795796138, 119104.0, 123342.0, 52335.0, 69160.96258556125, 83335.16664833395, 93430.0, 106315.0, 43782.0, 56014.71992640011, 59344.0, 75663.24336756673, 78454.60772696215, 8537.615807288812, 8858.778530536789, 9360.625574976924, 9397.85903211466, 9639.855402169112, 50124.74937625288, 54371.72814135904, 56114.0, 57675.711621441624, 58329.0, 44527.777361114284, 68515.65742171257, 75023.0, 75474.86787698299, 77079.0, 11818.113641477095, 12093.697657558632, 12232.143749937419, 12246.571370002008, 12597.370131493572, 11389.316641001566, 13681.657958551115, 13969.301534923416, 13992.930035349758, 14502.927485362507, 12556.811647825472, 12766.361681916052, 12767.872321276669, 12780.61658150257, 12794.74410511766, 12401.441935112762, 12638.747225055264, 12739.108262421541, 12792.232466052066, 12995.480180792661, 12246.265224086583, 12477.625671229878, 12613.68465788326, 12620.305883176514, 12736.426860791102, 10788.244822862323, 10969.451527423756, 10980.1215902726, 11247.606333778605, 11389.658310250707, 9849.458279794393, 10242.334248273839, 11091.44542772842, 11201.383923884265, 11214.66356009321], "value": 7061.342391044021, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1580.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 178.0, 123.0, 117.0, 119.0, 118.0, 116.0, 118.0, 119.0, 120.0], "value": 123.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 112.0, 120.0, 157.0, 119.0, 115.0, 120.0, 121.0, 146.0, 117.0], "value": 130.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [221.0, 212.0, 162.0, 268.0, 275.0, 190.0, 277.0, 283.0, 193.0, 202.0], "value": 228.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 60.0, 59.0, 84.0, 62.0, 62.0, 82.0, 83.0, 89.0, 51.0], "value": 72.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 71.0, 88.0, 73.0, 71.0, 71.0, 85.0, 72.0, 69.0, 106.0], "value": 79.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 90.0, 90.0, 88.0, 90.0, 86.0, 91.0, 89.0, 90.0, 88.0], "value": 89.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 96.0, 95.0, 96.0, 95.0, 97.0, 108.0, 152.0, 98.0, 155.0], "value": 113.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 92.0, 118.0, 114.0, 93.0, 94.0, 93.0, 91.0, 93.0], "value": 97.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 57.0, 56.0, 58.0, 57.0, 59.0, 57.0, 58.0, 58.0, 58.0], "value": 57.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 72.0, 75.0, 82.0, 81.0, 76.0, 71.0, 71.0, 73.0, 73.0], "value": 74.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 104.0, 111.0, 87.0, 89.0, 90.0, 90.0, 91.0, 88.0, 91.0], "value": 94.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 146.0, 152.0, 154.0, 173.0, 160.0, 170.0, 165.0, 206.0, 204.0], "value": 167.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 179.0, 174.0, 177.0, 211.0, 177.0, 175.0, 172.0, 177.0, 222.0], "value": 184.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 67.0, 69.0, 66.0, 69.0, 67.0, 68.0, 69.0, 69.0, 69.0], "value": 68.3, "unit": "score"}]}]} 14:49:19 INFO - Return code: 0 14:49:19 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:49:19 ERROR - # TBPL SUCCESS # 14:49:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:49:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:49:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:49:19 INFO - 2016-11-22 14:49:19.720 screenresolution[1923:19238] starting screenresolution argv=screenresolution get 14:49:19 INFO - 2016-11-22 14:49:19.724 screenresolution[1923:19238] Display 0: 1600x1200x32@60 14:49:19 INFO - 2016-11-22 14:49:19.730 screenresolution[1924:19241] starting screenresolution argv=screenresolution list 14:49:19 INFO - Available Modes on Display 0 14:49:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:49:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:49:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:49:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:49:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:49:19 INFO - Intel Iris: 14:49:19 INFO - Chipset Model: Intel Iris 14:49:19 INFO - Type: GPU 14:49:19 INFO - Bus: Built-In 14:49:19 INFO - VRAM (Dynamic, Max): 1536 MB 14:49:19 INFO - Vendor: Intel (0x8086) 14:49:19 INFO - Device ID: 0x0a2e 14:49:19 INFO - Revision ID: 0x0009 14:49:19 INFO - Displays: 14:49:19 INFO - Display: 14:49:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:49:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:49:19 INFO - Display Serial Number: 1600x1200 60 14:49:19 INFO - Main Display: Yes 14:49:19 INFO - Mirror: Off 14:49:19 INFO - Online: Yes 14:49:19 INFO - Rotation: Supported 14:49:19 INFO - Return code: 0 14:49:19 INFO - Running post-action listener: _resource_record_post_action 14:49:19 INFO - [mozharness: 2016-11-22 22:49:19.975459Z] Finished run-tests step (success) 14:49:19 INFO - Running post-run listener: _resource_record_post_run 14:49:20 INFO - Total resource usage - Wall time: 1211s; CPU: 55.0%; Read bytes: 1019904; Write bytes: 693691392; Read time: 13; Write time: 2025 14:49:20 INFO - TinderboxPrint: CPU usage
54.6% 14:49:20 INFO - TinderboxPrint: I/O read bytes / time
1,019,904 / 13 14:49:20 INFO - TinderboxPrint: I/O write bytes / time
693,691,392 / 2,025 14:49:20 INFO - TinderboxPrint: CPU idle
2,200.3 (45.4%) 14:49:20 INFO - TinderboxPrint: CPU system
982.1 (20.3%) 14:49:20 INFO - TinderboxPrint: CPU user
1,663.2 (34.3%) 14:49:20 INFO - TinderboxPrint: Swap in / out
384,286,720 / 0 14:49:20 INFO - install - Wall time: 33s; CPU: 55.0%; Read bytes: 383977984; Write bytes: 386779136; Read time: 28909; Write time: 731 14:49:20 INFO - run-tests - Wall time: 1179s; CPU: 55.0%; Read bytes: 937984; Write bytes: 305654784; Read time: 12; Write time: 1288 14:49:20 INFO - Running post-run listener: _upload_blobber_files 14:49:20 INFO - Blob upload gear active. 14:49:20 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:49:20 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 14:49:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Graphics', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:49:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Graphics -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:49:20 INFO - (blobuploader) - INFO - Open directory for files ... 14:49:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 14:49:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:49:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:49:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 14:49:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:49:21 INFO - (blobuploader) - INFO - Done attempting. 14:49:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 14:49:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:49:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:49:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 14:49:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:49:22 INFO - (blobuploader) - INFO - Done attempting. 14:49:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:49:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:49:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:49:25 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:49:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:49:25 INFO - (blobuploader) - INFO - Done attempting. 14:49:25 INFO - (blobuploader) - INFO - Iteration through files over. 14:49:25 INFO - Return code: 0 14:49:25 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:49:25 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:49:25 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/996947159a0a57bba0b7f82a4be3211c7602be6b18d3483d1a4c0be7d5e2620658c17b5df56cae9e7fd52e84d8e11b8a7f3c4360cffb6c6235ee6e7160f64bc7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/027902ff4c3068aad12056c53413971b66d3de86a1a9e616cedd7e967616311e3dfd9f49d0d1e677d6d39d15ea1f4fe5ea7428a968376762b0cfda483dc93bcb", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/27e9c7ec4e7824928c077a83f0430b89aca98d5803fca450de6b4b975a6309b58e6f97eceeb06f242750f7ce09a88e6d3d314e674004931e7de6c83be878ba69"} 14:49:25 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:49:25 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:49:25 INFO - Contents: 14:49:25 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/996947159a0a57bba0b7f82a4be3211c7602be6b18d3483d1a4c0be7d5e2620658c17b5df56cae9e7fd52e84d8e11b8a7f3c4360cffb6c6235ee6e7160f64bc7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/027902ff4c3068aad12056c53413971b66d3de86a1a9e616cedd7e967616311e3dfd9f49d0d1e677d6d39d15ea1f4fe5ea7428a968376762b0cfda483dc93bcb", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/27e9c7ec4e7824928c077a83f0430b89aca98d5803fca450de6b4b975a6309b58e6f97eceeb06f242750f7ce09a88e6d3d314e674004931e7de6c83be878ba69"} 14:49:25 INFO - Running post-run listener: copy_logs_to_upload_dir 14:49:25 INFO - Copying logs to upload dir... 14:49:25 INFO - mkdir: /builds/slave/test/build/upload/logs 14:49:25 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.725662 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-11-22 14:49:25.416365) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-22 14:49:25.421220) ========= 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.vZtWipJufU/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.UFgG2ZkXKF/Listeners TMPDIR=/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/996947159a0a57bba0b7f82a4be3211c7602be6b18d3483d1a4c0be7d5e2620658c17b5df56cae9e7fd52e84d8e11b8a7f3c4360cffb6c6235ee6e7160f64bc7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/027902ff4c3068aad12056c53413971b66d3de86a1a9e616cedd7e967616311e3dfd9f49d0d1e677d6d39d15ea1f4fe5ea7428a968376762b0cfda483dc93bcb", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/27e9c7ec4e7824928c077a83f0430b89aca98d5803fca450de6b4b975a6309b58e6f97eceeb06f242750f7ce09a88e6d3d314e674004931e7de6c83be878ba69"} build_url:https://queue.taskcluster.net/v1/task/Dn6vgelbTQmaLPB-CzmrBw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011399 build_url: 'https://queue.taskcluster.net/v1/task/Dn6vgelbTQmaLPB-CzmrBw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/996947159a0a57bba0b7f82a4be3211c7602be6b18d3483d1a4c0be7d5e2620658c17b5df56cae9e7fd52e84d8e11b8a7f3c4360cffb6c6235ee6e7160f64bc7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/027902ff4c3068aad12056c53413971b66d3de86a1a9e616cedd7e967616311e3dfd9f49d0d1e677d6d39d15ea1f4fe5ea7428a968376762b0cfda483dc93bcb", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/27e9c7ec4e7824928c077a83f0430b89aca98d5803fca450de6b4b975a6309b58e6f97eceeb06f242750f7ce09a88e6d3d314e674004931e7de6c83be878ba69"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-22 14:49:25.459637) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 14:49:25.460062) ========= 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.vZtWipJufU/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.UFgG2ZkXKF/Listeners TMPDIR=/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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.005325 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 14:49:25.509781) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-22 14:49:25.510091) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-22 14:49:25.512997) ========= ========= Total master_lag: 0.21 =========