builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0348 starttime: 1472642684.6 results: success (0) buildid: 20160831031421 builduid: c18158c29ccb4971bdaa43e3f0b342c4 revision: a4204b1030f2ff5d369aba3dc57ef190239e2f66 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-31 04:24:44.601522) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-31 04:24:44.601968) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-31 04:24:44.602242) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-31 04:24:44.624298) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 04:24:44.624568) ========= 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.jBPAMdFuo0/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.zTuK0r4L93/Listeners TMPDIR=/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/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-08-31 04:24:44-- 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: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 154M=0s 2016-08-31 04:24:44 (154 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.161372 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 04:24:44.807934) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 04:24:44.808223) ========= 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.jBPAMdFuo0/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.zTuK0r4L93/Listeners TMPDIR=/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/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.020618 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 04:24:44.857039) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-31 04:24:44.857404) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev a4204b1030f2ff5d369aba3dc57ef190239e2f66 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev a4204b1030f2ff5d369aba3dc57ef190239e2f66 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.jBPAMdFuo0/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.zTuK0r4L93/Listeners TMPDIR=/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/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-08-31 04:24:44,941 truncating revision to first 12 chars 2016-08-31 04:24:44,941 Setting DEBUG logging. 2016-08-31 04:24:44,941 attempt 1/10 2016-08-31 04:24:44,942 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/a4204b1030f2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-31 04:24:46,591 unpacking tar archive at: autoland-a4204b1030f2/testing/mozharness/ program finished with exit code 0 elapsedTime=1.908238 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-31 04:24:46.787840) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-31 04:24:46.788143) ========= script_repo_revision: a4204b1030f2ff5d369aba3dc57ef190239e2f66 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-31 04:24:46.788546) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-31 04:24:46.788809) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-31 04:24:46.807626) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-08-31 04:24:46.807914) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.jBPAMdFuo0/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.zTuK0r4L93/Listeners TMPDIR=/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/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 04:24:46 INFO - MultiFileLogger online at 20160831 04:24:46 in /builds/slave/test 04:24:46 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 04:24:46 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:24:46 INFO - {'append_to_log': False, 04:24:46 INFO - 'base_work_dir': '/builds/slave/test', 04:24:46 INFO - 'blob_upload_branch': 'Autoland', 04:24:46 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:24:46 INFO - 'branch': 'Autoland', 04:24:46 INFO - 'buildbot_json_path': 'buildprops.json', 04:24:46 INFO - 'config_files': ('talos/mac_config.py',), 04:24:46 INFO - 'default_actions': ('clobber', 04:24:46 INFO - 'read-buildbot-config', 04:24:46 INFO - 'download-and-extract', 04:24:46 INFO - 'populate-webroot', 04:24:46 INFO - 'create-virtualenv', 04:24:46 INFO - 'install', 04:24:46 INFO - 'run-tests'), 04:24:46 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:24:46 INFO - 'download_minidump_stackwalk': True, 04:24:47 INFO - 'download_symbols': 'ondemand', 04:24:47 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:24:47 INFO - 'tooltool.py': '/tools/tooltool.py', 04:24:47 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:24:47 INFO - '/tools/misc-python/virtualenv.py')}, 04:24:47 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:24:47 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:24:47 INFO - 'installer_path': 'installer.exe', 04:24:47 INFO - 'log_level': 'info', 04:24:47 INFO - 'log_name': 'talos', 04:24:47 INFO - 'log_to_console': True, 04:24:47 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:24:47 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:24:47 INFO - 'opt_config_files': (), 04:24:47 INFO - 'pip_index': False, 04:24:47 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:24:47 INFO - 'cmd': ('bash', 04:24:47 INFO - '-c', 04:24:47 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:24:47 INFO - 'enabled': True, 04:24:47 INFO - 'halt_on_failure': False, 04:24:47 INFO - 'name': 'check_screen_resolution'},), 04:24:47 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:24:47 INFO - 'cmd': ('bash', 04:24:47 INFO - '-c', 04:24:47 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:24:47 INFO - 'enabled': True, 04:24:47 INFO - 'halt_on_failure': False, 04:24:47 INFO - 'name': 'check_screen_resolution'},), 04:24:47 INFO - 'run_cmd_checks_enabled': True, 04:24:47 INFO - 'sps_profile': False, 04:24:47 INFO - 'sps_profile_interval': 0, 04:24:47 INFO - 'suite': 'dromaeojs', 04:24:47 INFO - 'system_bits': '32', 04:24:47 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:24:47 INFO - 'title': 't-yosemite-r7-0348', 04:24:47 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:24:47 INFO - 'use_talos_json': True, 04:24:47 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:24:47 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:24:47 INFO - 'work_dir': 'build'} 04:24:47 INFO - [mozharness: 2016-08-31 11:24:47.004376Z] Running clobber step. 04:24:47 INFO - Running pre-action listener: _resource_record_pre_action 04:24:47 INFO - Running main action method: clobber 04:24:47 INFO - rmtree: /builds/slave/test/build 04:24:47 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:24:48 INFO - Running post-action listener: _resource_record_post_action 04:24:48 INFO - [mozharness: 2016-08-31 11:24:48.506052Z] Finished clobber step (success) 04:24:48 INFO - [mozharness: 2016-08-31 11:24:48.506224Z] Running read-buildbot-config step. 04:24:48 INFO - Running pre-action listener: _resource_record_pre_action 04:24:48 INFO - Running main action method: read_buildbot_config 04:24:48 INFO - Using buildbot properties: 04:24:48 INFO - { 04:24:48 INFO - "project": "", 04:24:48 INFO - "product": "firefox", 04:24:48 INFO - "script_repo_revision": "production", 04:24:48 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 04:24:48 INFO - "repository": "", 04:24:48 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 04:24:48 INFO - "buildid": "20160831031421", 04:24:48 INFO - "pgo_build": "False", 04:24:48 INFO - "basedir": "/builds/slave/test", 04:24:48 INFO - "buildnumber": 207, 04:24:48 INFO - "slavename": "t-yosemite-r7-0348", 04:24:48 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 04:24:48 INFO - "platform": "yosemite_r7", 04:24:48 INFO - "scheduler": "tests-autoland-macosx64-talos", 04:24:48 INFO - "branch": "autoland", 04:24:48 INFO - "repo_path": "integration/autoland", 04:24:48 INFO - "revision": "a4204b1030f2ff5d369aba3dc57ef190239e2f66", 04:24:48 INFO - "stage_platform": "macosx64", 04:24:48 INFO - "builduid": "c18158c29ccb4971bdaa43e3f0b342c4", 04:24:48 INFO - "slavebuilddir": "test" 04:24:48 INFO - } 04:24:48 INFO - Found installer url https://queue.taskcluster.net/v1/task/cJHAre0_TE682_DlK7RbqQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 04:24:48 INFO - Running post-action listener: _resource_record_post_action 04:24:48 INFO - [mozharness: 2016-08-31 11:24:48.509637Z] Finished read-buildbot-config step (success) 04:24:48 INFO - [mozharness: 2016-08-31 11:24:48.509759Z] Running download-and-extract step. 04:24:48 INFO - Running pre-action listener: _resource_record_pre_action 04:24:48 INFO - Running main action method: download_and_extract 04:24:48 INFO - mkdir: /builds/slave/test/build/tests 04:24: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')]} 04:24:48 INFO - trying https://queue.taskcluster.net/v1/task/cJHAre0_TE682_DlK7RbqQ/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 04:24:48 INFO - Downloading https://queue.taskcluster.net/v1/task/cJHAre0_TE682_DlK7RbqQ/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 04:24:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cJHAre0_TE682_DlK7RbqQ/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 04:24:49 INFO - Downloaded 1199 bytes. 04:24:49 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 04:24:49 INFO - Using the following test package requirements: 04:24:49 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 04:24:49 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 04:24:49 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 04:24:49 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 04:24:49 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 04:24:49 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 04:24:49 INFO - u'jsshell-mac.zip'], 04:24:49 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 04:24:49 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 04:24:49 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 04:24:49 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 04:24:49 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 04:24:49 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 04:24:49 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 04:24:49 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 04:24:49 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 04:24:49 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 04:24:49 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 04:24:49 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:24:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:24:49 INFO - trying https://queue.taskcluster.net/v1/task/cJHAre0_TE682_DlK7RbqQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 04:24:49 INFO - Downloading https://queue.taskcluster.net/v1/task/cJHAre0_TE682_DlK7RbqQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 04:24:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cJHAre0_TE682_DlK7RbqQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:24:50 INFO - Downloaded 16391768 bytes. 04:24:50 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 04:24:57 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 04:24: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')]} 04:24:57 INFO - trying https://queue.taskcluster.net/v1/task/cJHAre0_TE682_DlK7RbqQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 04:24:57 INFO - Downloading https://queue.taskcluster.net/v1/task/cJHAre0_TE682_DlK7RbqQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 04:24:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cJHAre0_TE682_DlK7RbqQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:24:58 INFO - Downloaded 16391768 bytes. 04:24:58 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 04:25:04 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')]} 04:25:04 INFO - trying https://queue.taskcluster.net/v1/task/cJHAre0_TE682_DlK7RbqQ/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 04:25:04 INFO - Downloading https://queue.taskcluster.net/v1/task/cJHAre0_TE682_DlK7RbqQ/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip 04:25:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cJHAre0_TE682_DlK7RbqQ/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:25:06 INFO - Downloaded 14413998 bytes. 04:25:06 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 04:25:06 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')]} 04:25:06 INFO - trying https://queue.taskcluster.net/v1/task/cJHAre0_TE682_DlK7RbqQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 04:25:06 INFO - Downloading https://queue.taskcluster.net/v1/task/cJHAre0_TE682_DlK7RbqQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 04:25:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cJHAre0_TE682_DlK7RbqQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 04:25:09 INFO - Downloaded 111565726 bytes. 04:25:09 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/cJHAre0_TE682_DlK7RbqQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 04:25:09 INFO - mkdir: /builds/slave/test/properties 04:25:09 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:25:09 INFO - Writing to file /builds/slave/test/properties/build_url 04:25:09 INFO - Contents: 04:25:09 INFO - build_url:https://queue.taskcluster.net/v1/task/cJHAre0_TE682_DlK7RbqQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 04:25:10 INFO - Running post-action listener: _resource_record_post_action 04:25:10 INFO - Running post-action listener: set_extra_try_arguments 04:25:10 INFO - [mozharness: 2016-08-31 11:25:10.063231Z] Finished download-and-extract step (success) 04:25:10 INFO - [mozharness: 2016-08-31 11:25:10.063330Z] Running populate-webroot step. 04:25:10 INFO - Running pre-action listener: _resource_record_pre_action 04:25:10 INFO - Running main action method: populate_webroot 04:25:10 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:25:10 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:25:10 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:25:10 INFO - '--tppagecycles', 04:25:10 INFO - '7'], 04:25:10 INFO - 'tests': ['tsvgm']}}, 04:25:10 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:25:10 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:25:10 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:25:10 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:25:10 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:25:10 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:25:10 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:25:10 INFO - 'talos_options': ['--disable-e10s'], 04:25:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:25:10 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:25:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:25:10 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:25:10 INFO - 'talos_options': ['--disable-e10s'], 04:25:10 INFO - 'tests': ['damp', 'tps']}, 04:25:10 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:25:10 INFO - 'tests': ['damp', 'tps']}, 04:25:10 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:25:10 INFO - 'tests': ['dromaeo_dom']}, 04:25:10 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:25:10 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:25:10 INFO - 'tests': ['basic_compositor_video']}, 04:25:10 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:25:10 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:25:10 INFO - 'tests': ['a11yr', 04:25:10 INFO - 'ts_paint', 04:25:10 INFO - 'tpaint', 04:25:10 INFO - 'sessionrestore', 04:25:10 INFO - 'sessionrestore_no_auto_restore', 04:25:10 INFO - 'tabpaint']}, 04:25:10 INFO - 'other-e10s': {'tests': ['a11yr', 04:25:10 INFO - 'ts_paint', 04:25:10 INFO - 'tpaint', 04:25:10 INFO - 'sessionrestore', 04:25:10 INFO - 'sessionrestore_no_auto_restore', 04:25:10 INFO - 'tabpaint']}, 04:25:10 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:25:10 INFO - 'ts_paint', 04:25:10 INFO - 'tpaint', 04:25:10 INFO - 'sessionrestore', 04:25:10 INFO - 'sessionrestore_no_auto_restore', 04:25:10 INFO - 'tabpaint']}, 04:25:10 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:25:10 INFO - 'ts_paint', 04:25:10 INFO - 'tpaint', 04:25:10 INFO - 'sessionrestore', 04:25:10 INFO - 'sessionrestore_no_auto_restore', 04:25:10 INFO - 'tabpaint']}, 04:25:10 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:25:10 INFO - 'tests': ['a11yr', 04:25:10 INFO - 'ts_paint', 04:25:10 INFO - 'tpaint', 04:25:10 INFO - 'sessionrestore', 04:25:10 INFO - 'sessionrestore_no_auto_restore', 04:25:10 INFO - 'tabpaint']}, 04:25:10 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:25:10 INFO - 'tests': ['a11yr', 04:25:10 INFO - 'ts_paint', 04:25:10 INFO - 'tpaint', 04:25:10 INFO - 'sessionrestore', 04:25:10 INFO - 'sessionrestore_no_auto_restore', 04:25:10 INFO - 'tabpaint']}, 04:25:10 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:25:10 INFO - 'tests': ['tsvgx', 04:25:10 INFO - 'tsvgr_opacity', 04:25:10 INFO - 'tart', 04:25:10 INFO - 'tscrollx', 04:25:10 INFO - 'cart']}, 04:25:10 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:25:10 INFO - 'tsvgr_opacity', 04:25:10 INFO - 'tart', 04:25:10 INFO - 'tscrollx', 04:25:10 INFO - 'cart']}, 04:25:10 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:25:10 INFO - 'talos_options': ['--disable-e10s'], 04:25:10 INFO - 'tests': ['tp5o']}, 04:25:10 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:25:10 INFO - 'tests': ['tp5o']}, 04:25:10 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:25:10 INFO - 'talos_options': ['--disable-e10s', 04:25:10 INFO - '--xperf_path', 04:25:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:25:10 INFO - 'tests': ['tp5n']}, 04:25:10 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:25:10 INFO - 'talos_options': ['--xperf_path', 04:25:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:25:10 INFO - 'tests': ['tp5n']}}, 04:25:10 INFO - 'talos.zip': {'path': '', 04:25:10 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:25:10 INFO - Running post-action listener: _resource_record_post_action 04:25:10 INFO - [mozharness: 2016-08-31 11:25:10.082759Z] Finished populate-webroot step (success) 04:25:10 INFO - [mozharness: 2016-08-31 11:25:10.082879Z] Running create-virtualenv step. 04:25:10 INFO - Running pre-action listener: _resource_record_pre_action 04:25:10 INFO - Running main action method: create_virtualenv 04:25:10 INFO - Creating virtualenv /builds/slave/test/build/venv 04:25:10 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 04:25:10 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:25:10 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:25:10 INFO - Using real prefix '/tools/python27' 04:25:10 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:25:10 INFO - Installing distribute.............................................................................................................................................................................................done. 04:25:13 INFO - Installing pip.................done. 04:25:13 INFO - Return code: 0 04:25:13 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:25:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:25:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:25:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:25:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:25:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100d59a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100dee618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc64b47b190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cbdd30>, '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 0x100a22db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100cb5b20>, '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.jBPAMdFuo0/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.zTuK0r4L93/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/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 04:25:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:25:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:25:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jBPAMdFuo0/Render', 04:25:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:25:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:25:14 INFO - 'HOME': '/Users/cltbld', 04:25:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:25:14 INFO - 'LOGNAME': 'cltbld', 04:25:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:25:14 INFO - 'MOZ_NO_REMOTE': '1', 04:25:14 INFO - 'NO_EM_RESTART': '1', 04:25:14 INFO - 'PAGER': '/bin/cat', 04:25:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:25:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:25:14 INFO - 'PWD': '/builds/slave/test', 04:25:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:25:14 INFO - 'SHELL': '/bin/bash', 04:25:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zTuK0r4L93/Listeners', 04:25:14 INFO - 'TMPDIR': '/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/', 04:25:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:25:14 INFO - 'USER': 'cltbld', 04:25:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:25:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:25:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:25:14 INFO - 'XPC_FLAGS': '0x0', 04:25:14 INFO - 'XPC_SERVICE_NAME': '0', 04:25:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:25:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:25:14 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 04:25:14 INFO - Cleaning up... 04:25:14 INFO - Return code: 0 04:25:14 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:25:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:25:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:25:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:25:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:25:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100d59a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100dee618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc64b47b190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cbdd30>, '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 0x100a22db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100cb5b20>, '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.jBPAMdFuo0/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.zTuK0r4L93/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/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 04:25:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 04:25:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 04:25:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jBPAMdFuo0/Render', 04:25:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:25:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:25:14 INFO - 'HOME': '/Users/cltbld', 04:25:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:25:14 INFO - 'LOGNAME': 'cltbld', 04:25:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:25:14 INFO - 'MOZ_NO_REMOTE': '1', 04:25:14 INFO - 'NO_EM_RESTART': '1', 04:25:14 INFO - 'PAGER': '/bin/cat', 04:25:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:25:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:25:14 INFO - 'PWD': '/builds/slave/test', 04:25:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:25:14 INFO - 'SHELL': '/bin/bash', 04:25:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zTuK0r4L93/Listeners', 04:25:14 INFO - 'TMPDIR': '/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/', 04:25:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:25:14 INFO - 'USER': 'cltbld', 04:25:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:25:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:25:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:25:14 INFO - 'XPC_FLAGS': '0x0', 04:25:14 INFO - 'XPC_SERVICE_NAME': '0', 04:25:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:25:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:25:14 INFO - Downloading/unpacking psutil>=3.1.1 04:25:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:25:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:25:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:25:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:25:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:25:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:25:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:25:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:25:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:25:17 INFO - Installing collected packages: psutil 04:25:17 INFO - Running setup.py install for psutil 04:25:17 INFO - building 'psutil._psutil_osx' extension 04:25:17 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 04:25:18 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 04:25:18 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 04:25:18 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 04:25:18 INFO - building 'psutil._psutil_posix' extension 04:25:18 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 04:25:18 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:25:18 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:25:18 INFO - ^ 04:25:18 INFO - 1 warning generated. 04:25:18 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 04:25:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:25:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:25:18 INFO - Successfully installed psutil 04:25:18 INFO - Cleaning up... 04:25:18 INFO - Return code: 0 04:25:18 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:25:18 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')]} 04:25:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:25:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:18 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')]} 04:25:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:25:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:18 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 0x100d59a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100dee618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc64b47b190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cbdd30>, '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 0x100a22db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100cb5b20>, '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.jBPAMdFuo0/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.zTuK0r4L93/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/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 04:25:18 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 04:25:18 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 04:25:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jBPAMdFuo0/Render', 04:25:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:25:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:25:18 INFO - 'HOME': '/Users/cltbld', 04:25:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:25:18 INFO - 'LOGNAME': 'cltbld', 04:25:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:25:18 INFO - 'MOZ_NO_REMOTE': '1', 04:25:18 INFO - 'NO_EM_RESTART': '1', 04:25:18 INFO - 'PAGER': '/bin/cat', 04:25:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:25:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:25:18 INFO - 'PWD': '/builds/slave/test', 04:25:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:25:18 INFO - 'SHELL': '/bin/bash', 04:25:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zTuK0r4L93/Listeners', 04:25:18 INFO - 'TMPDIR': '/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/', 04:25:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:25:18 INFO - 'USER': 'cltbld', 04:25:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:25:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:25:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:25:18 INFO - 'XPC_FLAGS': '0x0', 04:25:18 INFO - 'XPC_SERVICE_NAME': '0', 04:25:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:25:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:25:19 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:25:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:25:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:25:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:25:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:25:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:25:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:25:21 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:25:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:25:21 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:25:21 INFO - Installing collected packages: mozsystemmonitor 04:25:21 INFO - Running setup.py install for mozsystemmonitor 04:25:21 INFO - Successfully installed mozsystemmonitor 04:25:21 INFO - Cleaning up... 04:25:21 INFO - Return code: 0 04:25:21 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:25:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:25:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:25:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:25:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:25:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x100d59a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100dee618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc64b47b190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cbdd30>, '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 0x100a22db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100cb5b20>, '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.jBPAMdFuo0/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.zTuK0r4L93/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/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 04:25:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 04:25:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 04:25:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jBPAMdFuo0/Render', 04:25:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:25:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:25:21 INFO - 'HOME': '/Users/cltbld', 04:25:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:25:21 INFO - 'LOGNAME': 'cltbld', 04:25:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:25:21 INFO - 'MOZ_NO_REMOTE': '1', 04:25:21 INFO - 'NO_EM_RESTART': '1', 04:25:21 INFO - 'PAGER': '/bin/cat', 04:25:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:25:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:25:21 INFO - 'PWD': '/builds/slave/test', 04:25:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:25:21 INFO - 'SHELL': '/bin/bash', 04:25:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zTuK0r4L93/Listeners', 04:25:21 INFO - 'TMPDIR': '/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/', 04:25:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:25:21 INFO - 'USER': 'cltbld', 04:25:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:25:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:25:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:25:21 INFO - 'XPC_FLAGS': '0x0', 04:25:21 INFO - 'XPC_SERVICE_NAME': '0', 04:25:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:25:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:25:21 INFO - Downloading/unpacking jsonschema==2.5.1 04:25:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:25:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:25:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:25:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:25:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:25:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:25:24 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:25:24 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:25:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:25:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:25:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:25:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:25:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:25:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:25:24 INFO - Downloading functools32-3.2.3-2.tar.gz 04:25:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:25:24 INFO - warning: no files found matching '*.txt' 04:25:24 INFO - no previously-included directories found matching 'build' 04:25:24 INFO - no previously-included directories found matching 'dist' 04:25:24 INFO - no previously-included directories found matching '.git*' 04:25:24 INFO - Installing collected packages: jsonschema, functools32 04:25:25 INFO - Running setup.py install for functools32 04:25:25 INFO - warning: no files found matching '*.txt' 04:25:25 INFO - no previously-included directories found matching 'build' 04:25:25 INFO - no previously-included directories found matching 'dist' 04:25:25 INFO - no previously-included directories found matching '.git*' 04:25:25 INFO - Successfully installed jsonschema functools32 04:25:25 INFO - Cleaning up... 04:25:25 INFO - Return code: 0 04:25:25 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:25:25 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')]} 04:25:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:25:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:25 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')]} 04:25:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:25:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:25 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 0x100d59a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100dee618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc64b47b190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cbdd30>, '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 0x100a22db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100cb5b20>, '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.jBPAMdFuo0/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.zTuK0r4L93/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/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 04:25:25 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 04:25:25 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 04:25:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jBPAMdFuo0/Render', 04:25:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:25:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:25:25 INFO - 'HOME': '/Users/cltbld', 04:25:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:25:25 INFO - 'LOGNAME': 'cltbld', 04:25:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:25:25 INFO - 'MOZ_NO_REMOTE': '1', 04:25:25 INFO - 'NO_EM_RESTART': '1', 04:25:25 INFO - 'PAGER': '/bin/cat', 04:25:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:25:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:25:25 INFO - 'PWD': '/builds/slave/test', 04:25:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:25:25 INFO - 'SHELL': '/bin/bash', 04:25:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zTuK0r4L93/Listeners', 04:25:25 INFO - 'TMPDIR': '/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/', 04:25:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:25:25 INFO - 'USER': 'cltbld', 04:25:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:25:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:25:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:25:25 INFO - 'XPC_FLAGS': '0x0', 04:25:25 INFO - 'XPC_SERVICE_NAME': '0', 04:25:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:25:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:25:25 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:25:25 INFO - Cleaning up... 04:25:25 INFO - Return code: 0 04:25:25 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:25:25 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')]} 04:25:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:25:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:25 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')]} 04:25:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:25:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:25 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 0x100d59a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100dee618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc64b47b190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cbdd30>, '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 0x100a22db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100cb5b20>, '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.jBPAMdFuo0/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.zTuK0r4L93/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/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 04:25:25 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 04:25:25 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 04:25:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jBPAMdFuo0/Render', 04:25:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:25:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:25:25 INFO - 'HOME': '/Users/cltbld', 04:25:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:25:25 INFO - 'LOGNAME': 'cltbld', 04:25:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:25:25 INFO - 'MOZ_NO_REMOTE': '1', 04:25:25 INFO - 'NO_EM_RESTART': '1', 04:25:25 INFO - 'PAGER': '/bin/cat', 04:25:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:25:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:25:25 INFO - 'PWD': '/builds/slave/test', 04:25:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:25:25 INFO - 'SHELL': '/bin/bash', 04:25:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zTuK0r4L93/Listeners', 04:25:25 INFO - 'TMPDIR': '/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/', 04:25:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:25:25 INFO - 'USER': 'cltbld', 04:25:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:25:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:25:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:25:25 INFO - 'XPC_FLAGS': '0x0', 04:25:25 INFO - 'XPC_SERVICE_NAME': '0', 04:25:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:25:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:25:25 INFO - Downloading/unpacking blobuploader==1.2.4 04:25:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:25:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:25:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:25:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:25:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:25:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:25:28 INFO - Downloading blobuploader-1.2.4.tar.gz 04:25:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:25:28 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:25:28 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 04:25:28 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 04:25:28 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 04:25:28 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 04:25:28 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 04:25:28 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 04:25:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:25:29 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:25:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:25:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:25:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:25:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:25:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:25:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:25:29 INFO - Downloading docopt-0.6.1.tar.gz 04:25:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:25:29 INFO - Installing collected packages: blobuploader, requests, docopt 04:25:29 INFO - Running setup.py install for blobuploader 04:25:29 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:25:29 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:25:29 INFO - Running setup.py install for requests 04:25:30 INFO - Running setup.py install for docopt 04:25:30 INFO - Successfully installed blobuploader requests docopt 04:25:30 INFO - Cleaning up... 04:25:30 INFO - Return code: 0 04:25:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:25:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:25:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:25:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:25:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:25:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:30 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 0x100d59a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100dee618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc64b47b190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cbdd30>, '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 0x100a22db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100cb5b20>, '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.jBPAMdFuo0/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.zTuK0r4L93/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/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 04:25:30 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 04:25:30 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 04:25:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jBPAMdFuo0/Render', 04:25:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:25:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:25:30 INFO - 'HOME': '/Users/cltbld', 04:25:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:25:30 INFO - 'LOGNAME': 'cltbld', 04:25:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:25:30 INFO - 'MOZ_NO_REMOTE': '1', 04:25:30 INFO - 'NO_EM_RESTART': '1', 04:25:30 INFO - 'PAGER': '/bin/cat', 04:25:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:25:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:25:30 INFO - 'PWD': '/builds/slave/test', 04:25:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:25:30 INFO - 'SHELL': '/bin/bash', 04:25:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zTuK0r4L93/Listeners', 04:25:30 INFO - 'TMPDIR': '/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/', 04:25:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:25:30 INFO - 'USER': 'cltbld', 04:25:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:25:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:25:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:25:30 INFO - 'XPC_FLAGS': '0x0', 04:25:30 INFO - 'XPC_SERVICE_NAME': '0', 04:25:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:25:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:25:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:25:30 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-x6xSS_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:25:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:25:30 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-vL9ZfA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:25:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:25:30 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-ZwCoJP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:25:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:25:30 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-l_Fliv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:25:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:25:31 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-d_o6S_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:25:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:25:31 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-pyKv9_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:25:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:25:31 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-uIKe8r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:25:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:25:31 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-FRBI1N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:25:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:25:31 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-MdgXeE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:25:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:25:31 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-cJcpry-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:25:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:25:31 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-PtGHjj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:25:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:25:31 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-HN4rvq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:25:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:25:31 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-RMhYBK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:25:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:25:31 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-wMkN_g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:25:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:25:32 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-fu9lUv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:25:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:25:32 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-5mPVZa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:25:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:25:32 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-8PQL5g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:25:32 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:25:32 INFO - Running setup.py install for manifestparser 04:25:32 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:25:32 INFO - Running setup.py install for mozcrash 04:25:32 INFO - Running setup.py install for mozdebug 04:25:32 INFO - Running setup.py install for mozdevice 04:25:32 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:25:32 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:25:32 INFO - Running setup.py install for mozfile 04:25:33 INFO - Running setup.py install for mozhttpd 04:25:33 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:25:33 INFO - Running setup.py install for mozinfo 04:25:33 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:25:33 INFO - Running setup.py install for mozInstall 04:25:33 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:25:33 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:25:33 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:25:33 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:25:33 INFO - Running setup.py install for mozleak 04:25:33 INFO - Running setup.py install for mozlog 04:25:33 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:25:33 INFO - Running setup.py install for moznetwork 04:25:34 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:25:34 INFO - Running setup.py install for mozprocess 04:25:34 INFO - Running setup.py install for mozprofile 04:25:34 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:25:34 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:25:34 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:25:34 INFO - Running setup.py install for mozrunner 04:25:34 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:25:34 INFO - Running setup.py install for mozscreenshot 04:25:34 INFO - Running setup.py install for moztest 04:25:34 INFO - Running setup.py install for mozversion 04:25:34 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:25:34 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:25:34 INFO - Cleaning up... 04:25:34 INFO - Return code: 0 04:25:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:25:34 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')]} 04:25:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:25:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:34 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')]} 04:25:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:25:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:34 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 0x100d59a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100dee618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc64b47b190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cbdd30>, '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 0x100a22db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100cb5b20>, '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.jBPAMdFuo0/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.zTuK0r4L93/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/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 04:25:34 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 04:25:34 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 04:25:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jBPAMdFuo0/Render', 04:25:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:25:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:25:34 INFO - 'HOME': '/Users/cltbld', 04:25:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:25:34 INFO - 'LOGNAME': 'cltbld', 04:25:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:25:34 INFO - 'MOZ_NO_REMOTE': '1', 04:25:34 INFO - 'NO_EM_RESTART': '1', 04:25:34 INFO - 'PAGER': '/bin/cat', 04:25:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:25:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:25:34 INFO - 'PWD': '/builds/slave/test', 04:25:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:25:34 INFO - 'SHELL': '/bin/bash', 04:25:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zTuK0r4L93/Listeners', 04:25:34 INFO - 'TMPDIR': '/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/', 04:25:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:25:34 INFO - 'USER': 'cltbld', 04:25:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:25:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:25:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:25:34 INFO - 'XPC_FLAGS': '0x0', 04:25:34 INFO - 'XPC_SERVICE_NAME': '0', 04:25:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:25:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:25:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:25:35 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-xbhmbS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:25:35 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)) 04:25:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:25:35 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-1qarxk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:25:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:25:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:25:35 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-pKYOgf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:25:35 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)) 04:25:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:25:35 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-pTMFUa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:25:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:25:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:25:35 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-1zF9bN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:25:35 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)) 04:25:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:25:35 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-gaLfWD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:25:35 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)) 04:25:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:25:35 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-rX00s6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:25:35 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)) 04:25:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:25:35 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-lLtzay-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:25:36 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)) 04:25:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:25:36 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-Eiq7hU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:25:36 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)) 04:25:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:25:36 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-wCg8pi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:25:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 04:25:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:25:36 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-2fa1y6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:25:36 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)) 04:25:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:25:36 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-yPpAZ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:25:36 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)) 04:25:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:25:36 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-YqZvFr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:25:36 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)) 04:25:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:25:36 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-xSCvKo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:25:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 04:25:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:25:36 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-vZq5Xz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:25:36 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)) 04:25:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:25:36 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-qE2wGD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:25:36 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)) 04:25:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:25:36 INFO - Running setup.py (path:/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/pip-PaBioF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:25:37 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)) 04:25:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:25:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:25:37 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)) 04:25:37 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:25:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:25:37 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:25:37 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 04:25:37 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 04:25:37 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 04:25:37 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 04:25:37 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 04:25:37 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 04:25:39 INFO - Downloading blessings-1.6.tar.gz 04:25:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:25:39 INFO - Installing collected packages: blessings 04:25:39 INFO - Running setup.py install for blessings 04:25:39 INFO - Successfully installed blessings 04:25:39 INFO - Cleaning up... 04:25:39 INFO - Return code: 0 04:25:39 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:25:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:25:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:25:40 INFO - Reading from file tmpfile_stdout 04:25:40 INFO - Current package versions: 04:25:40 INFO - blessings == 1.6 04:25:40 INFO - blobuploader == 1.2.4 04:25:40 INFO - docopt == 0.6.1 04:25:40 INFO - functools32 == 3.2.3-2 04:25:40 INFO - jsonschema == 2.5.1 04:25:40 INFO - manifestparser == 1.1 04:25:40 INFO - mozInstall == 1.12 04:25:40 INFO - mozcrash == 0.17 04:25:40 INFO - mozdebug == 0.1 04:25:40 INFO - mozdevice == 0.48 04:25:40 INFO - mozfile == 1.2 04:25:40 INFO - mozhttpd == 0.7 04:25:40 INFO - mozinfo == 0.9 04:25:40 INFO - mozleak == 0.1 04:25:40 INFO - mozlog == 3.3 04:25:40 INFO - moznetwork == 0.27 04:25:40 INFO - mozprocess == 0.23 04:25:40 INFO - mozprofile == 0.28 04:25:40 INFO - mozrunner == 6.12 04:25:40 INFO - mozscreenshot == 0.1 04:25:40 INFO - mozsystemmonitor == 0.3 04:25:40 INFO - moztest == 0.7 04:25:40 INFO - mozversion == 1.4 04:25:40 INFO - psutil == 3.1.1 04:25:40 INFO - requests == 1.2.3 04:25:40 INFO - wsgiref == 0.1.2 04:25:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:25: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')]} 04:25:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:25:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25: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')]} 04:25:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:25:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25:40 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 0x100d59a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100dee618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc64b47b190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cbdd30>, '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 0x100a22db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100cb5b20>, '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.jBPAMdFuo0/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.zTuK0r4L93/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/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 04:25:40 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 04:25:40 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 04:25:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jBPAMdFuo0/Render', 04:25:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:25:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:25:40 INFO - 'HOME': '/Users/cltbld', 04:25:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:25:40 INFO - 'LOGNAME': 'cltbld', 04:25:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:25:40 INFO - 'MOZ_NO_REMOTE': '1', 04:25:40 INFO - 'NO_EM_RESTART': '1', 04:25:40 INFO - 'PAGER': '/bin/cat', 04:25:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:25:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:25:40 INFO - 'PWD': '/builds/slave/test', 04:25:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:25:40 INFO - 'SHELL': '/bin/bash', 04:25:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zTuK0r4L93/Listeners', 04:25:40 INFO - 'TMPDIR': '/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/', 04:25:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:25:40 INFO - 'USER': 'cltbld', 04:25:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:25:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:25:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:25:40 INFO - 'XPC_FLAGS': '0x0', 04:25:40 INFO - 'XPC_SERVICE_NAME': '0', 04:25:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:25:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:25:40 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)) 04:25:40 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)) 04:25:40 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)) 04:25:40 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)) 04:25:40 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)) 04:25:40 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)) 04:25:40 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)) 04:25:40 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)) 04:25:40 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)) 04:25:40 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)) 04:25:40 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)) 04:25:40 INFO - Cleaning up... 04:25:40 INFO - Return code: 0 04:25:40 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:25: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')]} 04:25:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:25:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25: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')]} 04:25:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:25:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:25: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100d59a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100dee618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc64b47b190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cbdd30>, '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 0x100a22db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100cb5b20>, '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.jBPAMdFuo0/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.zTuK0r4L93/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/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 04:25: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', 'jsonschema'] in /builds/slave/test/build 04:25: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 jsonschema 04:25:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jBPAMdFuo0/Render', 04:25:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:25:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:25:40 INFO - 'HOME': '/Users/cltbld', 04:25:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:25:40 INFO - 'LOGNAME': 'cltbld', 04:25:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:25:40 INFO - 'MOZ_NO_REMOTE': '1', 04:25:40 INFO - 'NO_EM_RESTART': '1', 04:25:40 INFO - 'PAGER': '/bin/cat', 04:25:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:25:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:25:40 INFO - 'PWD': '/builds/slave/test', 04:25:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:25:40 INFO - 'SHELL': '/bin/bash', 04:25:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zTuK0r4L93/Listeners', 04:25:40 INFO - 'TMPDIR': '/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/', 04:25:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:25:40 INFO - 'USER': 'cltbld', 04:25:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:25:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:25:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:25:40 INFO - 'XPC_FLAGS': '0x0', 04:25:40 INFO - 'XPC_SERVICE_NAME': '0', 04:25:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:25:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:25:40 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:25:40 INFO - Cleaning up... 04:25:40 INFO - Return code: 0 04:25:40 INFO - Running post-action listener: _resource_record_post_action 04:25:40 INFO - Running post-action listener: _start_resource_monitoring 04:25:40 INFO - Starting resource monitoring. 04:25:40 INFO - [mozharness: 2016-08-31 11:25:40.698236Z] Finished create-virtualenv step (success) 04:25:40 INFO - [mozharness: 2016-08-31 11:25:40.699228Z] Running install step. 04:25:40 INFO - Running pre-action listener: _resource_record_pre_action 04:25:40 INFO - Running main action method: install 04:25:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:25:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:25:40 INFO - Reading from file tmpfile_stdout 04:25:40 INFO - Detecting whether we're running mozinstall >=1.0... 04:25:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:25:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:25:41 INFO - Reading from file tmpfile_stdout 04:25:41 INFO - Output received: 04:25:41 INFO - Usage: mozinstall [options] installer 04:25:41 INFO - Options: 04:25:41 INFO - -h, --help show this help message and exit 04:25:41 INFO - -d DEST, --destination=DEST 04:25:41 INFO - Directory to install application into. [default: 04:25:41 INFO - "/builds/slave/test"] 04:25:41 INFO - --app=APP Application being installed. [default: firefox] 04:25:41 INFO - mkdir: /builds/slave/test/build/application 04:25:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 04:25:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 04:26:15 INFO - Reading from file tmpfile_stdout 04:26:15 INFO - Output received: 04:26:15 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 04:26:15 INFO - Running post-action listener: _resource_record_post_action 04:26:15 INFO - [mozharness: 2016-08-31 11:26:15.645476Z] Finished install step (success) 04:26:15 INFO - [mozharness: 2016-08-31 11:26:15.645616Z] Running run-tests step. 04:26:15 INFO - Running pre-action listener: _resource_record_pre_action 04:26:15 INFO - Running main action method: run_tests 04:26:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:26:15 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:26:15 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:26:15 INFO - 2016-08-31 04:26:15.660 screenresolution[1945:15852] starting screenresolution argv=screenresolution get 04:26:15 INFO - 2016-08-31 04:26:15.662 screenresolution[1945:15852] Display 0: 1600x1200x32@60 04:26:15 INFO - 2016-08-31 04:26:15.668 screenresolution[1946:15854] starting screenresolution argv=screenresolution list 04:26:15 INFO - Available Modes on Display 0 04:26:15 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:26:15 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:26:15 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:26:15 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:26:15 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:26:15 INFO - Intel Iris: 04:26:15 INFO - Chipset Model: Intel Iris 04:26:15 INFO - Type: GPU 04:26:15 INFO - Bus: Built-In 04:26:15 INFO - VRAM (Dynamic, Max): 1536 MB 04:26:15 INFO - Vendor: Intel (0x8086) 04:26:15 INFO - Device ID: 0x0a2e 04:26:15 INFO - Revision ID: 0x0009 04:26:15 INFO - Displays: 04:26:15 INFO - Display: 04:26:15 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:26:15 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:26:15 INFO - Display Serial Number: 1600x1200 60 04:26:15 INFO - Main Display: Yes 04:26:15 INFO - Mirror: Off 04:26:15 INFO - Online: Yes 04:26:15 INFO - Rotation: Supported 04:26:15 INFO - Return code: 0 04:26:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:26:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:26:15 INFO - Python 2.7.3 04:26:15 INFO - Return code: 0 04:26:15 INFO - grabbing minidump binary from tooltool 04:26: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')]} 04:26:15 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 0x100cbdd30>, '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 0x100a22db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100cb5b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:26:15 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 04:26:15 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 04:26:15 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 04:26:15 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:26:16 INFO - Return code: 0 04:26:16 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:26:16 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:26:16 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:26:16 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:26:16 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:26:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cJHAre0_TE682_DlK7RbqQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0348', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 04:26:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/cJHAre0_TE682_DlK7RbqQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0348 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 04:26:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jBPAMdFuo0/Render', 04:26:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:26:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:26:16 INFO - 'HOME': '/Users/cltbld', 04:26:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:26:16 INFO - 'LOGNAME': 'cltbld', 04:26:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:26:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:26:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:26:16 INFO - 'MOZ_NO_REMOTE': '1', 04:26:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:26:16 INFO - 'NO_EM_RESTART': '1', 04:26:16 INFO - 'PAGER': '/bin/cat', 04:26:16 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:26:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:26:16 INFO - 'PWD': '/builds/slave/test', 04:26:16 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:26:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:26:16 INFO - 'SHELL': '/bin/bash', 04:26:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zTuK0r4L93/Listeners', 04:26:16 INFO - 'TMPDIR': '/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/', 04:26:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:26:16 INFO - 'USER': 'cltbld', 04:26:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:26:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:26:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:26:16 INFO - 'XPC_FLAGS': '0x0', 04:26:16 INFO - 'XPC_SERVICE_NAME': '0', 04:26:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:26:16 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cJHAre0_TE682_DlK7RbqQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0348', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 04:26:16 INFO - mozversion application_buildid: 20160831031421 04:26:16 INFO - mozversion application_changeset: a4204b1030f2ff5d369aba3dc57ef190239e2f66 04:26:16 INFO - mozversion application_display_name: Nightly 04:26:16 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:26:16 INFO - mozversion application_name: Firefox 04:26:16 INFO - mozversion application_remotingname: firefox 04:26:16 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 04:26:16 INFO - mozversion application_vendor: Mozilla 04:26:16 INFO - mozversion application_version: 51.0a1 04:26:16 INFO - mozversion platform_buildid: 20160831031421 04:26:16 INFO - mozversion platform_changeset: a4204b1030f2ff5d369aba3dc57ef190239e2f66 04:26:16 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 04:26:16 INFO - mozversion platform_version: 51.0a1 04:26:16 INFO - using testdate: 1472642776 04:26:16 INFO - actual date: 1472642776 04:26:16 INFO - starting webserver on 'localhost:50879' 04:26:16 INFO - SUITE-START | Running 2 tests 04:26:16 INFO - TEST-START | dromaeo_css 04:26:16 INFO - operating with platform_type : mac_ 04:26:16 INFO - Initialising browser for dromaeo_css test... 04:26:16 INFO - TEST-INFO | started process 1956 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/tmptS7Knk/profile http://localhost:50879/getInfo.html) 04:26:18 INFO - PROCESS | 1956 | __metrics Screen width/height:1600/1200 04:26:18 INFO - PROCESS | 1956 | colorDepth:24 04:26:18 INFO - PROCESS | 1956 | Browser inner width/height: 1024/689 04:26:18 INFO - PROCESS | 1956 | __metrics 04:26:18 INFO - PROCESS | 1956 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:26:18 INFO - PROCESS | 1956 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:26:19 INFO - TEST-INFO | 1956: exit 0 04:26:19 INFO - Browser initialized. 04:26:19 INFO - Running cycle 1/1 for dromaeo_css test... 04:26:19 INFO - TEST-INFO | started process 1958 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/tmptS7Knk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:26:31 INFO - PROCESS | 1958 | RSS: Main: 234553344 04:26:31 INFO - PROCESS | 1958 | 04:27:22 INFO - PROCESS | 1958 | 1472642842902 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] 04:29:34 INFO - PROCESS | 1958 | Cycle 1(1): loaded http://localhost:50879/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50879/tests/dromaeo/cssquery-ext.html) 04:29:35 INFO - PROCESS | 1958 | RSS: Main: 358121472 04:29:35 INFO - PROCESS | 1958 | 04:32:39 INFO - PROCESS | 1958 | Cycle 1(1): loaded http://localhost:50879/tests/dromaeo/cssquery-ext.html (next: http://localhost:50879/tests/dromaeo/cssquery-jquery.html) 04:32:39 INFO - PROCESS | 1958 | RSS: Main: 370184192 04:32:39 INFO - PROCESS | 1958 | 04:35:41 INFO - PROCESS | 1958 | Cycle 1(1): loaded http://localhost:50879/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50879/tests/dromaeo/cssquery-mootools.html) 04:35:42 INFO - PROCESS | 1958 | RSS: Main: 341995520 04:35:42 INFO - PROCESS | 1958 | 04:38:44 INFO - PROCESS | 1958 | Cycle 1(1): loaded http://localhost:50879/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50879/tests/dromaeo/cssquery-prototype.html) 04:38:45 INFO - PROCESS | 1958 | RSS: Main: 355864576 04:38:45 INFO - PROCESS | 1958 | 04:41:48 INFO - PROCESS | 1958 | Cycle 1(1): loaded http://localhost:50879/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50879/tests/dromaeo/cssquery-yui.html) 04:41:49 INFO - PROCESS | 1958 | RSS: Main: 332115968 04:41:49 INFO - PROCESS | 1958 | 04:44:52 INFO - PROCESS | 1958 | Cycle 1(1): loaded http://localhost:50879/tests/dromaeo/cssquery-yui.html (next: http://localhost:50879/tests/dromaeo/cssquery-dojo.html) 04:44:52 INFO - PROCESS | 1958 | RSS: Main: 417071104 04:44:52 INFO - PROCESS | 1958 | 04:44:52 INFO - PROCESS | 1958 | __start_tp_report 04:44:52 INFO - PROCESS | 1958 | _x_x_mozilla_page_load 04:44:52 INFO - PROCESS | 1958 | _x_x_mozilla_page_load_details 04:44:52 INFO - PROCESS | 1958 | |i|pagename|runs| 04:44:52 INFO - PROCESS | 1958 | |0;dojo.html;3301.4057469655468;3315.2043509557707;3318.13728430608;3366.2594229269553;3403.8127902965343;5526.198701188326;5645.379008309088;5645.491905728483;5744.080947048473;5744.2819647544;5660.2075709400715;5736.426357364272;5738;5784.3058832940005;5837.066069428893;5122.129238029525;5172.224166375055;5200.063988482072;5202.739863006854;5295.417504074549;5308;5313.309269794921;5321.680699158043;5332.92000619991;5355.571554275668;5100.719460429675;5131.7690703918415;5136.332276803992;5192.688438693691;5221.765020574083;5175.534201921845;5184.300119483897;5190.740462976836;5191.351081114861;5196.76614552346;5146.279520867081;5150.227465880111;5162.251473536354;5175.456577059392;5203.947960520384;5618.634788738719;5664.20701101846;5782.942170578324;5794.391588883191;5830.941690583082;6196.690165491753;6219;6252.155958945576;6271.027990661455;6272.247330320345;6066.493309820571;6280.057991301295;6333.524985626096;6347.39699728525;6347.777827776005;5763.337216220189;5769.423057694197;5816.534677225812;5816.796412125555;5824.359320474744;6279.152314437492;6310.1481300024825;6321.3678632137335;6321.557490975588;6322.968385158045;5982.4316689914385;5991.101334799815;6177.96911015451;6188.195534580475;6200.410960958792;6206.565540412218;6212.81361559154;6216.316205217422;6267.592606480564;6271.122042913996;6235.594686345373;6261.342559031327;6266.874662506724;6270.435660790551;6281.528885333527;6240.219972503437;6241.750329986749;6283.183186185858;6289.339619339903;6346.873062538723;4815.903681926342;4862.805487780449;4867.002264535761;4942.589613166903;4973;4399.978000109979;4405.911881762347;4422.0050488640445;4487.080148569536;4499.21263778844;5789.392113828072;5795.94204057954;5826.912596311059;5827.679477628686;5877.089051197071;6123.203983482119;6157.384261573897;6232.750689972441;6263.404976527223;6313.43179113882;5691.971540142273;5743.913841292384;5753.741081651336;5776.393478684678;5856.092305692625;4539.387182730356;4723.220668589644;5265.868353291198;5355.678659280456;5381.300430943952;6189.381061893775;6300.432961033529;6314.936850631527;6322.620642761449;6323.177986861679;6208.751649934133;6264.060390941395;6269.592476489013;6310.337414571404;6320.336364681826;6296.779612713533;6302.401271879165;6302.464290535356;6305.621662700252;6308.747650094128;6049.18336024631;6050.878982420415;6184.907226391518;6207.596506227081;6213.565050446426;5567.944320556905;5931.555133365101;6161.476274516716;6283.308836027942;6305.1172835803945;6072.848178795565;6077.48341390969;6088.299845517822;6113;6122.234720659918;5920.851978700394;6075.696215189311;6097.213687255483;6106.6946652668075;6142.600730952474;5575.804846830341;5856.150858125463;5884.882302354014;5949.25634295713;5979.302414718303;6108.5723999319625;6134.325224225242;6138.907916381348;6143.784967526115;6159.076138579249;5735.225744524436;5854.356020837619;5925.318588362221;5925.496332811759;5934.465898069195 04:44:52 INFO - PROCESS | 1958 | |1;ext.html;470.0317346266689;519.6102922807894;524.43885043004;524.9921251181233;525.2069375243384;30712.07863764088;39924.600753992454;41201.17597648045;41260.17479650405;42046;42337;42622.57377426225;42692;42694.78652606733;42804.571954280524;8761.868571971425;9517.619295228182;9679.419234845896;9679.612815487408;9680.515974201297;8771.868421973675;11954.760904781899;12192.268463892151;12224.388780560936;12400.62798116058;12129.090318226125;12137.15039947204;12206.023518118529;12229.266244025343;12233.204841685307;12045.879541204564;12135.393230338535;12136.757264854741;12139.332336721476;12143.817842732366;11857.407129643483;11926.344051077187;11949.581764638282;11960.34218118003;11993.16047876649;40209.39685904714;40481;40495.1900961982;40546.59453405458;40553.188936221406;3328.983355083233;3390.491426286052;3527.770694904849;3668.0025625363332;3955.248502784462;30925.22686932822;30963.845180774177;30983.845080774678;31486;32304.353912921844;2855.8286502809897;3211.550382946389;3218.855151518187;3242.91892702682;3352.0614228016193;386961;387362;387691;388252;398377.00811496045;56950;57048.42951570514;57218.141727874114;57226.7138664304;57235.71382143146;34557.65442345595;34979.4753078704;35011.824940875646;35851.64148358535;51477;16194.271257793427;16315.347386104422;16344.836551634331;16391.34434622602;16400.343986240416;11265.549378024787;11269.830952535718;11368.602098926498;11531.48108335127;12383;18767.87392756439;18789;18794.248230070643;18814.24743010264;18856.905715471334;7694.692212311556;7762.573058481725;7794.454388192886;7830.960845195737;7982.361411087099;857.5926434943352;977.6333874796951;980.6812785844486;983.3657291047377;1003.0220534978338;22695.319140425814;27023.594646080324;30098.54852177219;30170.09489715312;30872.691273087432;981.3228872078386;1035.6168217759327;1055.145332280855;1077.9622713205158;1086.5436516662862;13212.141210821266;14831.102930202649;15587.688246235011;15829.050256984387;16000.75998860018;655522;754519;761727.9753631992;763131;1009172;59234.40765592289;59677.40322596805;59814;62577.68711156415;62792.372076278654;1280.1423046558828;1312.8818406343476;1387.6947071644222;1414.7736362182002;1438.7913752505829;31198.6032993078;31530;32684;32784.83607581947;33079.83460082684;57843.42156578381;58631.70684146552;58690.706546466994;58705.82588348295;58732;633870;638765;639780;640251;640445;35504.64495355013;36378.45432318465;37078.44382334213;41891.7905410471;43889.34165987449;48769.51230487791;48792.53622391334;48930.021399571095;49328.75335623299;49532.75233623809;38107.809460952514;38376;40280.395794062526;41224.151863841966;41730.58269417267;38480.80759596184;38548.6145138545;38561;40046;40120.79939600283;38216.04459888414;38661;38891.416628751154;39066;39261.82214533569;27112.32219194536;32467;32995.34009319848;33702.662973370934;35039;23013.65479517775;25493.87253063723;25527.6170857441;25978.66895047618;26039.349016274744 04:44:52 INFO - PROCESS | 1958 | |2;jquery.html;374.46825507778954;374.9700023998082;386.3104358719686;395.5905637665017;405.8437501561898;19251;21208.36374908751;21768.782312176874;22049.338519844412;22139.33581992539;20407.693884591743;20792.68810967833;21118.366449006517;21314.57370852582;21383.35849924505;5880.206172166767;5952.9107063394085;6123.969380153093;6175.475084617813;6180.969095154518;4223.66210703143;4318.9784051079705;6073.7874174403905;6163.568550201488;6183.628982261056;5132.948670513303;6854.21176564694;6856.554323968926;6861.451083913275;6871.175458944934;4706.764661766915;6114.388561143895;6119.143319935212;6852.691628876688;6868.2788307227775;5873;5885.470307672287;5886.882262354772;5895.145203945404;5897.4102589741115;12619.432125554373;12817.551385701548;12823.102382833205;12828.102032857703;12836.780505852023;2162.76209616942;2172.2940044485604;2224.799768020886;2304.1129165271323;2406.975930240693;9067.727968160965;9549.47477888716;9868.407895526292;9942.055504727039;10036.347637403545;1477.578890016351;1671.5319710481085;1864.9533761655932;1900.0309841980552;1909.4080834941212;90439.54780226057;114708.85291147065;118571;123437;123849;9092.454452732858;10691.09125724322;10802.297850639683;11090.55637774496;11091.611793587186;13509.324533773468;13560.796588051187;13564.660883477794;13835.239061851493;14266.85733142676;8302.750917472486;8423.831523369498;8450.492970421672;8567.914320856711;8586.398952073296;8270.297024752963;8289.41974061822;8292.212239837207;8460.450070745383;8468.534230617375;4383.736975781463;4436.689431739812;4439.267520859086;4473.823585254506;4597.7241365518175;1647.250501022032;1818.5908170661496;1830.5240637434097;1906.2470324221854;1941.5340318323783;362.32788177929564;367.4579994508079;387.17338482340404;818.4352796570466;898.4609234459272;11114.49984750672;11139.665810025712;11452.427378631035;11468.559432170316;11602.88397116018;393.91136994176657;402.4767801857573;406.74578388507183;409.8094386110473;417.23229258164514;5738.139279108167;5788.9710551447815;5795.1017592273265;5934;5961.5230781537375;596760;607082;609186;617797;618664;112698;131763;132037;134860;135722;411.06892887609587;425.8871399079246;426.2306536701222;426.35833071226983;428.6892003297584;8721.56392180401;10785.02934735846;10870.891291086988;11154.274972126786;11219;52133.21800173076;52449;52475.73762131165;53270.20094698505;54081;71222.6438867823;74860;75451.62274188593;76479.61760191164;77416.83874741771;4450.465944086601;5219.5041471060185;6684.866302673822;7556.168821429748;8145.51126932386;10429.68710938673;12050.337231452004;12189;12383.75232495363;12573.622791316275;10747.731306717395;10922.890771092187;10970.286931349756;11125;11319.773604527698;10657.094146997282;10676.029339464845;10764.407957562096;10979.682438107478;11018.283811552223;11294.717632059264;11432.428378580871;11541.596044138414;11549.6535103947;11656;7697.076350837711;7733.786931775588;7848.097468791162;8050.436469447082;8055.597220138852;8163.959180204061;10814.459277036274;10865.18511111654;11216.775664486828;11333.773324533628 04:44:52 INFO - PROCESS | 1958 | |3;mootools.html;1331.6471135149184;1527.289810238239;1691.813900470948;1874.9062546872653;1933.9323123690665;8355.623996920138;8830.072842351557;8836.734897953056;8846.11538846115;8850.5574721264;6616.636085015322;7971.123176450586;8393.91606083939;8604.483730976146;8666.350023748228;2322.7909488146106;2352.5059737455153;2355.882205889707;2361.173589243768;2362.3267368799898;2309.3649246457276;2331.73185083715;2352.694149760529;2356.8114550835894;2357.8938947747392;2340.4031971847157;2351.1770880191966;2352.435415500276;2365.113082594027;2372.146027430124;2262.5135595846973;2277.840551161419;2279.0200213908015;2293.69035180251;2319.9420014499606;2284.348960546253;2333.57995560799;2339.7309309429465;2340.1575432844334;2342.0982921575314;8702.825943481159;8762.299016078698;8766.422044032071;8766.956165219197;8772.78068048304;5117.257997590365;5283.656562323436;5285.762140703678;5286.022085914118;5290.682559046431;3317.618473875511;3434.5706786651667;3574.821258937043;3579.1410061585293;3583.6595523425235;1045.0803293102097;1084.1326938449208;1089.6730980705756;1102.8070087734613;1107.7618312062946;11493.310401375944;11660.766784664344;11673.88326116745;11675.94162029193;11715.121365897678;4335.176316499855;4565.634749220121;4583.518730533246;4736.905261894812;4743.715377077387;9839.852402213972;9846.507674616241;9853.802923941625;9864.408135511892;9870.654527091518;5124.026434977342;5131.97434012835;5132.717700526432;5134.998675258364;5136.357955255593;4321.373400856921;4483.7533935633855;4570.771461426982;4709;4796.640251981115;3850.7689538627774;3861.3821788514265;3982.5021872265966;4010.0375909782024;4055.3105971984833;1286.4211105002598;1292.2475127977536;1294.9611511654666;1298.1302527306727;1305.3016636099642;889.7686601483531;916.7524768312518;919.94480331179;939.1219210038499;950.7528042708807;5627.493525582717;5869.3537525253105;5883.352831188566;5970.164177015221;6064.969675151684;404.0787005627175;416.895776055986;416.9624733773975;423.4748911349904;427.7690047374383;3753.849846006129;3893.6690381317903;3912.765234085964;3986.740861843971;4051.8987025324013;9417.434953902788;10659.25385223027;10660.466976651136;10662.520186591466;10679;10373.58505659765;10493.895061049445;10573.101286390589;10601.575936962436;10689.35863848156;8004.319632831158;8044.155363686846;8160.591970401456;8162.306203972729;8163.918360816316;5669.149627555899;5766.913496297475;5816.5055970243;5847.707614619337;5873.324567674772;9630.422174669542;9831.5084245786;9860.161886239463;9915.206783457163;9933.552990115606;10140.239482039024;10145.84781228297;10157.84763228567;10163.085322321027;10167.389956602627;6802.3537763912345;7012.859742805217;7018.894716579358;7374.520656157332;7444.851102977802;7594.620268986418;7802.726904558313;8097.433179677366;8197.057338406159;8347.499150051017;7257.782266532013;7345.1553071397475;7351.448641351813;7459.92540074614;7533.849323013399;7050.612216328152;7216.061911951413;7240.167380751071;7266.346028857428;7287.817804554928;6861.838520866111;6863.83314836469;6970.721171153097;6972.721091156297;6983.546069505466;4954.900901981868;4983.75081245929;5006.62450316235;5038.773255203598;5116.258142569381;4875.488073752205;5010.442585764148;5039.949600504095;5185.507376799198;5192.870178245574 04:44:52 INFO - PROCESS | 1958 | |4;prototype.html;283.1251433071808;287.57008272632413;297.6532339824105;307.0603951907163;307.40056889066324;5189.247559103927;5308.309919710432;5408.8918221635595;5468.890622187559;5484.177373393988;4483.798229079691;4981.153203955328;5199.532042116209;5280.287161233232;5294.014744470824;3598.208394153282;4118.094019315746;4152.584741525839;4170.977076118081;4207.810648520808;3712.106825518978;3953.011747063234;3969.0474286171398;4201.663866890657;4203.117345357478;3917.2948869203533;4076.694247931402;4112.6298633123015;4124.855075360825;4145.854895078673;4082.3876418537457;4084.2852500812387;4086.366613174933;4113.526944401372;4120.670346372298;2950.8819647214086;3266.4936934775146;3556.3065202285566;3567.8751243706347;3615.403458429356;4489.528599497038;4496;4966.8758281043265;5067.569256613192;5224.294720212761;4270.508891477489;4674.5325467452985;4681.227597446417;4687.531246875285;4696.248600223982;4759.691549598442;4954.702717836942;5606.859828504279;5627.831165065055;5643.209950606918;3446.9655303446893;3783.735138540303;3915.843366265346;3989.5611482736876;4006.399040144002;4839.3466881970835;5745.195672605839;5941.31674857397;5969.492593129589;6047.334793172748;3960.1683646433994;4031;4032.6370626643743;4037.5154981401834;4069.755814651071;4080.326746086922;4093;4104.671626269891;4144.378343248537;4150.418941348214;3733.9066523337133;4581.816727330869;4740.5733483986605;4747.430308363018;4801.207800712913;4550.567696068868;4688.882777930511;5664.52722292207;5735.2257445245195;5735.684537350486;1277.9361031948365;1291.9870801292054;1325.4896864707157;1371.3280492558574;1396.5391420831102;785.6386062411354;808.8058865872148;816.31429599137;851.2721623012359;866.5450638414757;3778.886633401002;4277.657787377002;4489.461264648263;4516.8193272268745;4544.818207271672;4417.933730994037;4429.623482004065;4809.214831242935;4878.609711223094;4967.236630995944;3705.7220708446976;3737.5141231639714;3996.880873355465;4102.097538541516;4475.978714367789;2380.6071998120465;2407.3981504623844;2522.2003576030593;2586.5344238037333;2593.351662084479;4432.756198409054;4456.863499807593;4605.976970115128;4932.482089380635;5101.9489805101475;4303;4417.911641767147;4998.925016124761;5028.164719877292;5052.621053421009;4484.228729651834;4711.081339138835;4715.858524244278;4967.633900677342;5012.8496145115705;4044.656204222674;4586.931196031996;4825.287023106746;4880.804767809248;4881.38777304799;4423;4810.254410566367;4828.165297187187;4846.140776941621;4876.609871210224;4481.327800829901;4687.9296810547185;4883.706977581356;5014.72419016943;5119.083900815805;3269.476883698597;3501.4047611905526;3508.7719298245534;3514.3674138654264;3519.9472007920413;4380.496242931976;4674;4936.802527898843;4951.3510272004305;4967.3045773591;4260.531341552489;4808.797800549863;4829.927551086666;5077.492250775041;5779.508741756998;5016.924746128738;5756.510696590669;5764.811669497486;5788.884222315614;5793.623414478046;5207.869803254949;5419.807642318684;5671.716414179192;5680.2107336188965;5692.430756924275;5109.5912327013;5309.256704061511;5434.559841641889;5619.241402410786;5683.5453163747625;5531.944680553143;5553.694546799965;5609.80365687199;5613.214150018919;5636.351819540641 04:44:52 INFO - PROCESS | 1958 | |5;yui.html;712.4122598855944;720.4397643273758;731.0678884422364;766.9607681591442;795.528376655904;13107.80338294924;15706.293216805243;16035.717142628562;16302.83697163028;16306.266218020188;14442.638934026654;14446.783298250535;14452.710945781078;14472.131672099682;14726.852731472682;671.7279501801754;690.6753825701912;693.6982412650514;694.6457306773557;834.1575009240665;684.8561802021582;689.9068625735512;861.7070196133313;866.6360128745911;867.6963062927988;882.5719526029902;887.343365909229;896.2650626486284;897.3539051882634;898.4384759525296;890.3945317184312;891.7324802559272;891.9464832110029;892.8125093730324;892.8303622311772;514.9974250128726;638.8626445314279;656.5699466849221;657.5594351784268;659.7855696898528;14591;14705.705885882222;14706.602872727073;14709.970302078858;14732;13385.370917511826;13736.450541978309;13773.724525509433;13853.030287879852;13983.29385363421;12137.15039947204;12399.194052386567;12467.501299947991;12514.624561263086;12959.740805183937;4382.0797632497215;4428;4480.753558554278;4532.206863798855;4637.118947400017;112930;114106;116118;118272.40863795627;118820;8906.287497000225;10650.147988160928;10790.67627971162;10793.514291856885;11122.388268645302;20937.233229995312;21062.578748424945;21067.473313167295;21089.15643374248;21102.155913763585;7435.85128297431;7559.584222867795;7568.962155189299;7603.239676032353;7706.499077559941;4191.11986482842;4229.407882896397;4245.214635292481;4267.722598031118;4387.605115539617;3219.7263232625487;3323.6344002159744;3324.434846076173;3324.966750332466;3328.2511434927433;598.2381885347696;602.2772672792667;606.9028955367208;611.9541034422347;614.8678034222663;568.7156421789106;577.9739911703983;579.837645459272;589.2016317889284;621.9582199815292;7474.177840437547;8650.264727498106;8796.516191609522;8809.251213646912;8950.597223124974;576.6194311754222;581.3053401185628;615.9753609855645;631.386229248617;632.1655414852443;4238.618524332825;4320.4599425071865;4354.9564504355185;4364.803583838735;4375.256206444848;95839;136428;142910;155621.22189389207;155941;109211;110018;112331;123898;136341;99995.50002250087;102555.4872225634;104503;106571.99999999844;116296.8370316286;9068.90931090707;9132.49771262587;9409.200217981275;9605.646616578983;9735.853962190431;54382.72808635931;55464.168037480275;58153.70923145357;58224.4177558219;58418.70790646019;86627.56686216529;87905;91538.54230728804;93012.53493732488;96630;12569.183003104768;12970.092093553361;13025.544105956245;13309.00182486298;13506.459741610619;14620.415183392544;14757;15091.622709431913;15398.384064637738;15424.46014389536;13490.932545337211;13818;13826.239556824472;13884.930575347058;14499.492517761828;10289.84565231507;14047.719045619235;14214.857851421768;14439.566812995627;14559.85440145585;13216.669583260495;13561.389737462036;13570;13767.793483097961;13892.722145557234;11068.667939961813;11130.332180069221;11237.4381280934;11531.88468115308;11875.287482751062;10481.375386815056;10569.101626361538;10870.510827012646;10952.397618131079;10998.450077495932 04:44:52 INFO - PROCESS | 1958 | __end_tp_report 04:44:52 INFO - PROCESS | 1958 | __start_cc_report 04:44:52 INFO - PROCESS | 1958 | _x_x_mozilla_cycle_collect,1241 04:44:52 INFO - PROCESS | 1958 | __end_cc_report 04:44:52 INFO - PROCESS | 1958 | __startTimestamp1472643892943__endTimestamp 04:44:52 INFO - PROCESS | 1958 | 04:44:52 INFO - PROCESS | 1958 | ------- Summary: start ------- 04:44:52 INFO - PROCESS | 1958 | Number of tests: 6 04:44:52 INFO - PROCESS | 1958 | 04:44:52 INFO - PROCESS | 1958 | [#0] dojo.html Cycles:165 Average:5736.92 Median:5925.32 stddev:653.58 (11.0%) stddev-sans-first:627.04 04:44:52 INFO - PROCESS | 1958 | Values: 3301.4 3315.2 3318.1 3366.3 3403.8 5526.2 5645.4 5645.5 5744.1 5744.3 5660.2 5736.4 5738.0 5784.3 5837.1 5122.1 5172.2 5200.1 5202.7 5295.4 5308.0 5313.3 5321.7 5332.9 5355.6 5100.7 5131.8 5136.3 5192.7 5221.8 5175.5 5184.3 5190.7 5191.4 5196.8 5146.3 5150.2 5162.3 5175.5 5203.9 5618.6 5664.2 5782.9 5794.4 5830.9 6196.7 6219.0 6252.2 6271.0 6272.2 6066.5 6280.1 6333.5 6347.4 6347.8 5763.3 5769.4 5816.5 5816.8 5824.4 6279.2 6310.1 6321.4 6321.6 6323.0 5982.4 5991.1 6178.0 6188.2 6200.4 6206.6 6212.8 6216.3 6267.6 6271.1 6235.6 6261.3 6266.9 6270.4 6281.5 6240.2 6241.8 6283.2 6289.3 6346.9 4815.9 4862.8 4867.0 4942.6 4973.0 4400.0 4405.9 4422.0 4487.1 4499.2 5789.4 5795.9 5826.9 5827.7 5877.1 6123.2 6157.4 6232.8 6263.4 6313.4 5692.0 5743.9 5753.7 5776.4 5856.1 4539.4 4723.2 5265.9 5355.7 5381.3 6189.4 6300.4 6314.9 6322.6 6323.2 6208.8 6264.1 6269.6 6310.3 6320.3 6296.8 6302.4 6302.5 6305.6 6308.7 6049.2 6050.9 6184.9 6207.6 6213.6 5567.9 5931.6 6161.5 6283.3 6305.1 6072.8 6077.5 6088.3 6113.0 6122.2 5920.9 6075.7 6097.2 6106.7 6142.6 5575.8 5856.2 5884.9 5949.3 5979.3 6108.6 6134.3 6138.9 6143.8 6159.1 5735.2 5854.4 5925.3 5925.5 5934.5 04:44:52 INFO - PROCESS | 1958 | 04:44:52 INFO - PROCESS | 1958 | [#1] ext.html Cycles:180 Average:73521.05 Median:20373.22 stddev:169857.48 (833.7%) stddev-sans-first:170245.42 04:44:52 INFO - PROCESS | 1958 | Values: 470.0 519.6 524.4 525.0 525.2 30712.1 39924.6 41201.2 41260.2 42046.0 42337.0 42622.6 42692.0 42694.8 42804.6 8761.9 9517.6 9679.4 9679.6 9680.5 8771.9 11954.8 12192.3 12224.4 12400.6 12129.1 12137.2 12206.0 12229.3 12233.2 12045.9 12135.4 12136.8 12139.3 12143.8 11857.4 11926.3 11949.6 11960.3 11993.2 40209.4 40481.0 40495.2 40546.6 40553.2 3329.0 3390.5 3527.8 3668.0 3955.2 30925.2 30963.8 30983.8 31486.0 32304.4 2855.8 3211.6 3218.9 3242.9 3352.1 386961.0 387362.0 387691.0 388252.0 398377.0 56950.0 57048.4 57218.1 57226.7 57235.7 34557.7 34979.5 35011.8 35851.6 51477.0 16194.3 16315.3 16344.8 16391.3 16400.3 11265.5 11269.8 11368.6 11531.5 12383.0 18767.9 18789.0 18794.2 18814.2 18856.9 7694.7 7762.6 7794.5 7831.0 7982.4 857.6 977.6 980.7 983.4 1003.0 22695.3 27023.6 30098.5 30170.1 30872.7 981.3 1035.6 1055.1 1078.0 1086.5 13212.1 14831.1 15587.7 15829.1 16000.8 655522.0 754519.0 761728.0 763131.0 1009172.0 59234.4 59677.4 59814.0 62577.7 62792.4 1280.1 1312.9 1387.7 1414.8 1438.8 31198.6 31530.0 32684.0 32784.8 33079.8 57843.4 58631.7 58690.7 58705.8 58732.0 633870.0 638765.0 639780.0 640251.0 640445.0 35504.6 36378.5 37078.4 41891.8 43889.3 48769.5 48792.5 48930.0 49328.8 49532.8 38107.8 38376.0 40280.4 41224.2 41730.6 38480.8 38548.6 38561.0 40046.0 40120.8 38216.0 38661.0 38891.4 39066.0 39261.8 27112.3 32467.0 32995.3 33702.7 35039.0 23013.7 25493.9 25527.6 25978.7 26039.3 04:44:52 INFO - PROCESS | 1958 | 04:44:52 INFO - PROCESS | 1958 | [#2] jquery.html Cycles:180 Average:34089.78 Median:404.16 stddev:101864.36 (25204.0%) stddev-sans-first:102118.66 04:44:52 INFO - PROCESS | 1958 | Values: 374.5 375.0 386.3 395.6 405.8 19251.0 21208.4 21768.8 22049.3 22139.3 20407.7 20792.7 21118.4 21314.6 21383.4 5880.2 5952.9 6124.0 6175.5 6181.0 4223.7 4319.0 6073.8 6163.6 6183.6 5132.9 6854.2 6856.6 6861.5 6871.2 4706.8 6114.4 6119.1 6852.7 6868.3 5873.0 5885.5 5886.9 5895.1 5897.4 12619.4 12817.6 12823.1 12828.1 12836.8 2162.8 2172.3 2224.8 2304.1 2407.0 9067.7 9549.5 9868.4 9942.1 10036.3 1477.6 1671.5 1865.0 1900.0 1909.4 90439.5 114708.9 118571.0 123437.0 123849.0 9092.5 10691.1 10802.3 11090.6 11091.6 13509.3 13560.8 13564.7 13835.2 14266.9 8302.8 8423.8 8450.5 8567.9 8586.4 8270.3 8289.4 8292.2 8460.5 8468.5 4383.7 4436.7 4439.3 4473.8 4597.7 1647.3 1818.6 1830.5 1906.2 1941.5 362.3 367.5 387.2 818.4 898.5 11114.5 11139.7 11452.4 11468.6 11602.9 393.9 402.5 406.7 409.8 417.2 5738.1 5789.0 5795.1 5934.0 5961.5 596760.0 607082.0 609186.0 617797.0 618664.0 112698.0 131763.0 132037.0 134860.0 135722.0 411.1 425.9 426.2 426.4 428.7 8721.6 10785.0 10870.9 11154.3 11219.0 52133.2 52449.0 52475.7 53270.2 54081.0 71222.6 74860.0 75451.6 76479.6 77416.8 4450.5 5219.5 6684.9 7556.2 8145.5 10429.7 12050.3 12189.0 12383.8 12573.6 10747.7 10922.9 10970.3 11125.0 11319.8 10657.1 10676.0 10764.4 10979.7 11018.3 11294.7 11432.4 11541.6 11549.7 11656.0 7697.1 7733.8 7848.1 8050.4 8055.6 8164.0 10814.5 10865.2 11216.8 11333.8 04:44:52 INFO - PROCESS | 1958 | 04:44:52 INFO - PROCESS | 1958 | [#3] mootools.html Cycles:180 Average:5625.47 Median:4969.33 stddev:3169.12 (63.8%) stddev-sans-first:3161.58 04:44:52 INFO - PROCESS | 1958 | Values: 1331.6 1527.3 1691.8 1874.9 1933.9 8355.6 8830.1 8836.7 8846.1 8850.6 6616.6 7971.1 8393.9 8604.5 8666.4 2322.8 2352.5 2355.9 2361.2 2362.3 2309.4 2331.7 2352.7 2356.8 2357.9 2340.4 2351.2 2352.4 2365.1 2372.1 2262.5 2277.8 2279.0 2293.7 2319.9 2284.3 2333.6 2339.7 2340.2 2342.1 8702.8 8762.3 8766.4 8767.0 8772.8 5117.3 5283.7 5285.8 5286.0 5290.7 3317.6 3434.6 3574.8 3579.1 3583.7 1045.1 1084.1 1089.7 1102.8 1107.8 11493.3 11660.8 11673.9 11675.9 11715.1 4335.2 4565.6 4583.5 4736.9 4743.7 9839.9 9846.5 9853.8 9864.4 9870.7 5124.0 5132.0 5132.7 5135.0 5136.4 4321.4 4483.8 4570.8 4709.0 4796.6 3850.8 3861.4 3982.5 4010.0 4055.3 1286.4 1292.2 1295.0 1298.1 1305.3 889.8 916.8 919.9 939.1 950.8 5627.5 5869.4 5883.4 5970.2 6065.0 404.1 416.9 417.0 423.5 427.8 3753.8 3893.7 3912.8 3986.7 4051.9 9417.4 10659.3 10660.5 10662.5 10679.0 10373.6 10493.9 10573.1 10601.6 10689.4 8004.3 8044.2 8160.6 8162.3 8163.9 5669.1 5766.9 5816.5 5847.7 5873.3 9630.4 9831.5 9860.2 9915.2 9933.6 10140.2 10145.8 10157.8 10163.1 10167.4 6802.4 7012.9 7018.9 7374.5 7444.9 7594.6 7802.7 8097.4 8197.1 8347.5 7257.8 7345.2 7351.4 7459.9 7533.8 7050.6 7216.1 7240.2 7266.3 7287.8 6861.8 6863.8 6970.7 6972.7 6983.5 4954.9 4983.8 5006.6 5038.8 5116.3 4875.5 5010.4 5039.9 5185.5 5192.9 04:44:52 INFO - PROCESS | 1958 | 04:44:52 INFO - PROCESS | 1958 | [#4] prototype.html Cycles:180 Average:4281.12 Median:4639.99 stddev:1283.99 (27.7%) stddev-sans-first:1252.03 04:44:52 INFO - PROCESS | 1958 | Values: 283.1 287.6 297.7 307.1 307.4 5189.2 5308.3 5408.9 5468.9 5484.2 4483.8 4981.2 5199.5 5280.3 5294.0 3598.2 4118.1 4152.6 4171.0 4207.8 3712.1 3953.0 3969.0 4201.7 4203.1 3917.3 4076.7 4112.6 4124.9 4145.9 4082.4 4084.3 4086.4 4113.5 4120.7 2950.9 3266.5 3556.3 3567.9 3615.4 4489.5 4496.0 4966.9 5067.6 5224.3 4270.5 4674.5 4681.2 4687.5 4696.2 4759.7 4954.7 5606.9 5627.8 5643.2 3447.0 3783.7 3915.8 3989.6 4006.4 4839.3 5745.2 5941.3 5969.5 6047.3 3960.2 4031.0 4032.6 4037.5 4069.8 4080.3 4093.0 4104.7 4144.4 4150.4 3733.9 4581.8 4740.6 4747.4 4801.2 4550.6 4688.9 5664.5 5735.2 5735.7 1277.9 1292.0 1325.5 1371.3 1396.5 785.6 808.8 816.3 851.3 866.5 3778.9 4277.7 4489.5 4516.8 4544.8 4417.9 4429.6 4809.2 4878.6 4967.2 3705.7 3737.5 3996.9 4102.1 4476.0 2380.6 2407.4 2522.2 2586.5 2593.4 4432.8 4456.9 4606.0 4932.5 5101.9 4303.0 4417.9 4998.9 5028.2 5052.6 4484.2 4711.1 4715.9 4967.6 5012.8 4044.7 4586.9 4825.3 4880.8 4881.4 4423.0 4810.3 4828.2 4846.1 4876.6 4481.3 4687.9 4883.7 5014.7 5119.1 3269.5 3501.4 3508.8 3514.4 3519.9 4380.5 4674.0 4936.8 4951.4 4967.3 4260.5 4808.8 4829.9 5077.5 5779.5 5016.9 5756.5 5764.8 5788.9 5793.6 5207.9 5419.8 5671.7 5680.2 5692.4 5109.6 5309.3 5434.6 5619.2 5683.5 5531.9 5553.7 5609.8 5613.2 5636.4 04:44:52 INFO - PROCESS | 1958 | 04:44:52 INFO - PROCESS | 1958 | [#5] yui.html Cycles:180 Average:24108.41 Median:3326.61 stddev:38078.98 (1144.7%) stddev-sans-first:38145.28 04:44:52 INFO - PROCESS | 1958 | Values: 712.4 720.4 731.1 767.0 795.5 13107.8 15706.3 16035.7 16302.8 16306.3 14442.6 14446.8 14452.7 14472.1 14726.9 671.7 690.7 693.7 694.6 834.2 684.9 689.9 861.7 866.6 867.7 882.6 887.3 896.3 897.4 898.4 890.4 891.7 891.9 892.8 892.8 515.0 638.9 656.6 657.6 659.8 14591.0 14705.7 14706.6 14710.0 14732.0 13385.4 13736.5 13773.7 13853.0 13983.3 12137.2 12399.2 12467.5 12514.6 12959.7 4382.1 4428.0 4480.8 4532.2 4637.1 112930.0 114106.0 116118.0 118272.4 118820.0 8906.3 10650.1 10790.7 10793.5 11122.4 20937.2 21062.6 21067.5 21089.2 21102.2 7435.9 7559.6 7569.0 7603.2 7706.5 4191.1 4229.4 4245.2 4267.7 4387.6 3219.7 3323.6 3324.4 3325.0 3328.3 598.2 602.3 606.9 612.0 614.9 568.7 578.0 579.8 589.2 622.0 7474.2 8650.3 8796.5 8809.3 8950.6 576.6 581.3 616.0 631.4 632.2 4238.6 4320.5 4355.0 4364.8 4375.3 95839.0 136428.0 142910.0 155621.2 155941.0 109211.0 110018.0 112331.0 123898.0 136341.0 99995.5 102555.5 104503.0 106572.0 116296.8 9068.9 9132.5 9409.2 9605.6 9735.9 54382.7 55464.2 58153.7 58224.4 58418.7 86627.6 87905.0 91538.5 93012.5 96630.0 12569.2 12970.1 13025.5 13309.0 13506.5 14620.4 14757.0 15091.6 15398.4 15424.5 13490.9 13818.0 13826.2 13884.9 14499.5 10289.8 14047.7 14214.9 14439.6 14559.9 13216.7 13561.4 13570.0 13767.8 13892.7 11068.7 11130.3 11237.4 11531.9 11875.3 10481.4 10569.1 10870.5 10952.4 10998.5 04:44:52 INFO - PROCESS | 1958 | -------- Summary: end -------- 04:44:52 INFO - PROCESS | 1958 | 04:44:53 INFO - TEST-INFO | 1958: exit 0 04:44:53 INFO - TEST-OK | dromaeo_css | took 1117465ms 04:44:53 INFO - TEST-START | kraken 04:44:53 INFO - operating with platform_type : mac_ 04:44:53 INFO - Initialising browser for kraken test... 04:44:53 INFO - TEST-INFO | started process 1966 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/tmptKVE7q/profile http://localhost:50879/getInfo.html) 04:44:56 INFO - PROCESS | 1966 | __metrics Screen width/height:1600/1200 04:44:56 INFO - PROCESS | 1966 | colorDepth:24 04:44:56 INFO - PROCESS | 1966 | Browser inner width/height: 1024/689 04:44:56 INFO - PROCESS | 1966 | __metrics 04:44:56 INFO - PROCESS | 1966 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:44:56 INFO - PROCESS | 1966 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:44:56 INFO - TEST-INFO | 1966: exit 0 04:44:56 INFO - Browser initialized. 04:44:56 INFO - Running cycle 1/1 for kraken test... 04:44:56 INFO - TEST-INFO | started process 1967 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/T/tmptKVE7q/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:45:08 INFO - PROCESS | 1967 | RSS: Main: 234528768 04:45:08 INFO - PROCESS | 1967 | 04:45:11 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:50879/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50879/tests/kraken/driver.html?testName=audio-beat-detection) 04:45:12 INFO - PROCESS | 1967 | RSS: Main: 293416960 04:45:12 INFO - PROCESS | 1967 | 04:45:14 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:50879/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50879/tests/kraken/driver.html?testName=audio-dft) 04:45:15 INFO - PROCESS | 1967 | RSS: Main: 288657408 04:45:15 INFO - PROCESS | 1967 | 04:45:19 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:50879/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50879/tests/kraken/driver.html?testName=audio-fft) 04:45:19 INFO - PROCESS | 1967 | RSS: Main: 380280832 04:45:19 INFO - PROCESS | 1967 | 04:45:21 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:50879/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50879/tests/kraken/driver.html?testName=audio-oscillator) 04:45:22 INFO - PROCESS | 1967 | RSS: Main: 281255936 04:45:22 INFO - PROCESS | 1967 | 04:45:24 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:50879/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50879/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:45:25 INFO - PROCESS | 1967 | RSS: Main: 285491200 04:45:25 INFO - PROCESS | 1967 | 04:45:27 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:50879/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50879/tests/kraken/driver.html?testName=imaging-darkroom) 04:45:28 INFO - PROCESS | 1967 | RSS: Main: 322691072 04:45:28 INFO - PROCESS | 1967 | 04:45:31 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:50879/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50879/tests/kraken/driver.html?testName=imaging-desaturate) 04:45:32 INFO - PROCESS | 1967 | RSS: Main: 324894720 04:45:32 INFO - PROCESS | 1967 | 04:45:34 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:50879/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50879/tests/kraken/driver.html?testName=json-parse-financial) 04:45:35 INFO - PROCESS | 1967 | RSS: Main: 322502656 04:45:35 INFO - PROCESS | 1967 | 04:45:36 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:50879/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50879/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:45:37 INFO - PROCESS | 1967 | RSS: Main: 298307584 04:45:37 INFO - PROCESS | 1967 | 04:45:39 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:50879/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50879/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:45:40 INFO - PROCESS | 1967 | RSS: Main: 302817280 04:45:40 INFO - PROCESS | 1967 | 04:45:42 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:50879/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50879/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:45:43 INFO - PROCESS | 1967 | RSS: Main: 305594368 04:45:43 INFO - PROCESS | 1967 | 04:45:45 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:50879/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50879/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:45:46 INFO - PROCESS | 1967 | RSS: Main: 320335872 04:45:46 INFO - PROCESS | 1967 | 04:45:49 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:50879/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50879/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:45:50 INFO - PROCESS | 1967 | RSS: Main: 316280832 04:45:50 INFO - PROCESS | 1967 | 04:45:51 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:50879/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50879/tests/kraken/driver.html?testName=ai-astar) 04:45:52 INFO - PROCESS | 1967 | RSS: Main: 359587840 04:45:52 INFO - PROCESS | 1967 | 04:45:52 INFO - PROCESS | 1967 | __start_tp_report 04:45:52 INFO - PROCESS | 1967 | _x_x_mozilla_page_load 04:45:52 INFO - PROCESS | 1967 | _x_x_mozilla_page_load_details 04:45:52 INFO - PROCESS | 1967 | |i|pagename|runs| 04:45:52 INFO - PROCESS | 1967 | |0;ai-astar;112;112;131;112;112;112;113;113;114;113 04:45:52 INFO - PROCESS | 1967 | |1;audio-beat-detection;148;108;133;163;110;128;129;105;160;107 04:45:52 INFO - PROCESS | 1967 | |2;audio-dft;184;184;183;184;184;183;182;182;181;184 04:45:52 INFO - PROCESS | 1967 | |3;audio-fft;78;75;77;149;56;58;57;69;148;68 04:45:52 INFO - PROCESS | 1967 | |4;audio-oscillator;82;84;131;71;71;91;120;69;70;120 04:45:52 INFO - PROCESS | 1967 | |5;imaging-gaussian-blur;90;87;90;87;90;86;128;88;90;87 04:45:52 INFO - PROCESS | 1967 | |6;imaging-darkroom;96;95;108;150;105;95;96;96;95;95 04:45:52 INFO - PROCESS | 1967 | |7;imaging-desaturate;85;86;86;87;85;85;86;86;86;86 04:45:52 INFO - PROCESS | 1967 | |8;json-parse-financial;55;55;55;55;53;53;53;54;52;52 04:45:52 INFO - PROCESS | 1967 | |9;json-stringify-tinderbox;66;65;73;73;75;73;71;71;72;97 04:45:52 INFO - PROCESS | 1967 | |10;stanford-crypto-aes;86;88;86;84;88;83;87;89;86;85 04:45:52 INFO - PROCESS | 1967 | |11;stanford-crypto-ccm;137;139;135;173;151;141;137;141;182;137 04:45:52 INFO - PROCESS | 1967 | |12;stanford-crypto-pbkdf2;169;168;170;170;167;170;168;173;165;176 04:45:52 INFO - PROCESS | 1967 | |13;stanford-crypto-sha256-iterative;65;68;70;68;66;67;65;66;64;67 04:45:52 INFO - PROCESS | 1967 | __end_tp_report 04:45:52 INFO - PROCESS | 1967 | __start_cc_report 04:45:52 INFO - PROCESS | 1967 | _x_x_mozilla_cycle_collect,2036 04:45:52 INFO - PROCESS | 1967 | __end_cc_report 04:45:52 INFO - PROCESS | 1967 | __startTimestamp1472643952050__endTimestamp 04:45:52 INFO - PROCESS | 1967 | 04:45:52 INFO - PROCESS | 1967 | ------- Summary: start ------- 04:45:52 INFO - PROCESS | 1967 | Number of tests: 14 04:45:52 INFO - PROCESS | 1967 | 04:45:52 INFO - PROCESS | 1967 | [#0] ai-astar Cycles:10 Average:114.40 Median:113.00 stddev:5.87 (5.2%) stddev-sans-first:6.16 04:45:52 INFO - PROCESS | 1967 | Values: 112.0 112.0 131.0 112.0 112.0 112.0 113.0 113.0 114.0 113.0 04:45:52 INFO - PROCESS | 1967 | 04:45:52 INFO - PROCESS | 1967 | [#1] audio-beat-detection Cycles:10 Average:129.10 Median:131.00 stddev:22.00 (16.8%) stddev-sans-first:22.25 04:45:52 INFO - PROCESS | 1967 | Values: 148.0 108.0 133.0 163.0 110.0 128.0 129.0 105.0 160.0 107.0 04:45:52 INFO - PROCESS | 1967 | 04:45:52 INFO - PROCESS | 1967 | [#2] audio-dft Cycles:10 Average:183.10 Median:184.00 stddev:1.10 (0.6%) stddev-sans-first:1.12 04:45:52 INFO - PROCESS | 1967 | Values: 184.0 184.0 183.0 184.0 184.0 183.0 182.0 182.0 181.0 184.0 04:45:52 INFO - PROCESS | 1967 | 04:45:52 INFO - PROCESS | 1967 | [#3] audio-fft Cycles:10 Average:83.50 Median:68.50 stddev:35.20 (51.4%) stddev-sans-first:37.28 04:45:52 INFO - PROCESS | 1967 | Values: 78.0 75.0 77.0 149.0 56.0 58.0 57.0 69.0 148.0 68.0 04:45:52 INFO - PROCESS | 1967 | 04:45:52 INFO - PROCESS | 1967 | [#4] audio-oscillator Cycles:10 Average:90.90 Median:71.00 stddev:23.89 (33.6%) stddev-sans-first:25.12 04:45:52 INFO - PROCESS | 1967 | Values: 82.0 84.0 131.0 71.0 71.0 91.0 120.0 69.0 70.0 120.0 04:45:52 INFO - PROCESS | 1967 | 04:45:52 INFO - PROCESS | 1967 | [#5] imaging-gaussian-blur Cycles:10 Average:92.30 Median:89.00 stddev:12.64 (14.2%) stddev-sans-first:13.38 04:45:52 INFO - PROCESS | 1967 | Values: 90.0 87.0 90.0 87.0 90.0 86.0 128.0 88.0 90.0 87.0 04:45:52 INFO - PROCESS | 1967 | 04:45:52 INFO - PROCESS | 1967 | [#6] imaging-darkroom Cycles:10 Average:103.10 Median:95.00 stddev:17.13 (18.0%) stddev-sans-first:17.98 04:45:52 INFO - PROCESS | 1967 | Values: 96.0 95.0 108.0 150.0 105.0 95.0 96.0 96.0 95.0 95.0 04:45:52 INFO - PROCESS | 1967 | 04:45:52 INFO - PROCESS | 1967 | [#7] imaging-desaturate Cycles:10 Average:85.80 Median:86.00 stddev:0.63 (0.7%) stddev-sans-first:0.60 04:45:52 INFO - PROCESS | 1967 | Values: 85.0 86.0 86.0 87.0 85.0 85.0 86.0 86.0 86.0 86.0 04:45:52 INFO - PROCESS | 1967 | 04:45:52 INFO - PROCESS | 1967 | [#8] json-parse-financial Cycles:10 Average:53.70 Median:54.50 stddev:1.25 (2.3%) stddev-sans-first:1.24 04:45:52 INFO - PROCESS | 1967 | Values: 55.0 55.0 55.0 55.0 53.0 53.0 53.0 54.0 52.0 52.0 04:45:52 INFO - PROCESS | 1967 | 04:45:52 INFO - PROCESS | 1967 | [#9] json-stringify-tinderbox Cycles:10 Average:73.60 Median:73.00 stddev:8.81 (12.1%) stddev-sans-first:8.90 04:45:52 INFO - PROCESS | 1967 | Values: 66.0 65.0 73.0 73.0 75.0 73.0 71.0 71.0 72.0 97.0 04:45:52 INFO - PROCESS | 1967 | 04:45:52 INFO - PROCESS | 1967 | [#10] stanford-crypto-aes Cycles:10 Average:86.20 Median:86.50 stddev:1.87 (2.2%) stddev-sans-first:1.99 04:45:52 INFO - PROCESS | 1967 | Values: 86.0 88.0 86.0 84.0 88.0 83.0 87.0 89.0 86.0 85.0 04:45:52 INFO - PROCESS | 1967 | 04:45:52 INFO - PROCESS | 1967 | [#11] stanford-crypto-ccm Cycles:10 Average:147.30 Median:141.00 stddev:16.65 (11.8%) stddev-sans-first:17.24 04:45:52 INFO - PROCESS | 1967 | Values: 137.0 139.0 135.0 173.0 151.0 141.0 137.0 141.0 182.0 137.0 04:45:52 INFO - PROCESS | 1967 | 04:45:52 INFO - PROCESS | 1967 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.60 Median:170.00 stddev:3.10 (1.8%) stddev-sans-first:3.28 04:45:52 INFO - PROCESS | 1967 | Values: 169.0 168.0 170.0 170.0 167.0 170.0 168.0 173.0 165.0 176.0 04:45:52 INFO - PROCESS | 1967 | 04:45:52 INFO - PROCESS | 1967 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.60 Median:67.00 stddev:1.78 (2.7%) stddev-sans-first:1.79 04:45:52 INFO - PROCESS | 1967 | Values: 65.0 68.0 70.0 68.0 66.0 67.0 65.0 66.0 64.0 67.0 04:45:52 INFO - PROCESS | 1967 | -------- Summary: end -------- 04:45:52 INFO - PROCESS | 1967 | 04:45:52 INFO - TEST-INFO | 1967: exit 0 04:45:52 INFO - TEST-OK | kraken | took 59199ms 04:45:52 INFO - SUITE-END | took 1176s 04:45:53 INFO - Completed test suite (00:19:36) 04:45:53 INFO - javascript benchmark 04:45:53 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3301.4057469655468, 3315.2043509557707, 3318.13728430608, 3366.2594229269553, 3403.8127902965343, 5526.198701188326, 5645.379008309088, 5645.491905728483, 5744.080947048473, 5744.2819647544, 5660.2075709400715, 5736.426357364272, 5738.0, 5784.3058832940005, 5837.066069428893, 5122.129238029525, 5172.224166375055, 5200.063988482072, 5202.739863006854, 5295.417504074549, 5308.0, 5313.309269794921, 5321.680699158043, 5332.92000619991, 5355.571554275668, 5100.719460429675, 5131.7690703918415, 5136.332276803992, 5192.688438693691, 5221.765020574083, 5175.534201921845, 5184.300119483897, 5190.740462976836, 5191.351081114861, 5196.76614552346, 5146.279520867081, 5150.227465880111, 5162.251473536354, 5175.456577059392, 5203.947960520384, 5618.634788738719, 5664.20701101846, 5782.942170578324, 5794.391588883191, 5830.941690583082, 6196.690165491753, 6219.0, 6252.155958945576, 6271.027990661455, 6272.247330320345, 6066.493309820571, 6280.057991301295, 6333.524985626096, 6347.39699728525, 6347.777827776005, 5763.337216220189, 5769.423057694197, 5816.534677225812, 5816.796412125555, 5824.359320474744, 6279.152314437492, 6310.1481300024825, 6321.3678632137335, 6321.557490975588, 6322.968385158045, 5982.4316689914385, 5991.101334799815, 6177.96911015451, 6188.195534580475, 6200.410960958792, 6206.565540412218, 6212.81361559154, 6216.316205217422, 6267.592606480564, 6271.122042913996, 6235.594686345373, 6261.342559031327, 6266.874662506724, 6270.435660790551, 6281.528885333527, 6240.219972503437, 6241.750329986749, 6283.183186185858, 6289.339619339903, 6346.873062538723, 4815.903681926342, 4862.805487780449, 4867.002264535761, 4942.589613166903, 4973.0, 4399.978000109979, 4405.911881762347, 4422.0050488640445, 4487.080148569536, 4499.21263778844, 5789.392113828072, 5795.94204057954, 5826.912596311059, 5827.679477628686, 5877.089051197071, 6123.203983482119, 6157.384261573897, 6232.750689972441, 6263.404976527223, 6313.43179113882, 5691.971540142273, 5743.913841292384, 5753.741081651336, 5776.393478684678, 5856.092305692625, 4539.387182730356, 4723.220668589644, 5265.868353291198, 5355.678659280456, 5381.300430943952, 6189.381061893775, 6300.432961033529, 6314.936850631527, 6322.620642761449, 6323.177986861679, 6208.751649934133, 6264.060390941395, 6269.592476489013, 6310.337414571404, 6320.336364681826, 6296.779612713533, 6302.401271879165, 6302.464290535356, 6305.621662700252, 6308.747650094128, 6049.18336024631, 6050.878982420415, 6184.907226391518, 6207.596506227081, 6213.565050446426, 5567.944320556905, 5931.555133365101, 6161.476274516716, 6283.308836027942, 6305.1172835803945, 6072.848178795565, 6077.48341390969, 6088.299845517822, 6113.0, 6122.234720659918, 5920.851978700394, 6075.696215189311, 6097.213687255483, 6106.6946652668075, 6142.600730952474, 5575.804846830341, 5856.150858125463, 5884.882302354014, 5949.25634295713, 5979.302414718303, 6108.5723999319625, 6134.325224225242, 6138.907916381348, 6143.784967526115, 6159.076138579249, 5735.225744524436, 5854.356020837619, 5925.318588362221, 5925.496332811759, 5934.465898069195], "value": 5693.822417968313, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [470.0317346266689, 519.6102922807894, 524.43885043004, 524.9921251181233, 525.2069375243384, 30712.07863764088, 39924.600753992454, 41201.17597648045, 41260.17479650405, 42046.0, 42337.0, 42622.57377426225, 42692.0, 42694.78652606733, 42804.571954280524, 8761.868571971425, 9517.619295228182, 9679.419234845896, 9679.612815487408, 9680.515974201297, 8771.868421973675, 11954.760904781899, 12192.268463892151, 12224.388780560936, 12400.62798116058, 12129.090318226125, 12137.15039947204, 12206.023518118529, 12229.266244025343, 12233.204841685307, 12045.879541204564, 12135.393230338535, 12136.757264854741, 12139.332336721476, 12143.817842732366, 11857.407129643483, 11926.344051077187, 11949.581764638282, 11960.34218118003, 11993.16047876649, 40209.39685904714, 40481.0, 40495.1900961982, 40546.59453405458, 40553.188936221406, 3328.983355083233, 3390.491426286052, 3527.770694904849, 3668.0025625363332, 3955.248502784462, 30925.22686932822, 30963.845180774177, 30983.845080774678, 31486.0, 32304.353912921844, 2855.8286502809897, 3211.550382946389, 3218.855151518187, 3242.91892702682, 3352.0614228016193, 386961.0, 387362.0, 387691.0, 388252.0, 398377.00811496045, 56950.0, 57048.42951570514, 57218.141727874114, 57226.7138664304, 57235.71382143146, 34557.65442345595, 34979.4753078704, 35011.824940875646, 35851.64148358535, 51477.0, 16194.271257793427, 16315.347386104422, 16344.836551634331, 16391.34434622602, 16400.343986240416, 11265.549378024787, 11269.830952535718, 11368.602098926498, 11531.48108335127, 12383.0, 18767.87392756439, 18789.0, 18794.248230070643, 18814.24743010264, 18856.905715471334, 7694.692212311556, 7762.573058481725, 7794.454388192886, 7830.960845195737, 7982.361411087099, 857.5926434943352, 977.6333874796951, 980.6812785844486, 983.3657291047377, 1003.0220534978338, 22695.319140425814, 27023.594646080324, 30098.54852177219, 30170.09489715312, 30872.691273087432, 981.3228872078386, 1035.6168217759327, 1055.145332280855, 1077.9622713205158, 1086.5436516662862, 13212.141210821266, 14831.102930202649, 15587.688246235011, 15829.050256984387, 16000.75998860018, 655522.0, 754519.0, 761727.9753631992, 763131.0, 1009172.0, 59234.40765592289, 59677.40322596805, 59814.0, 62577.68711156415, 62792.372076278654, 1280.1423046558828, 1312.8818406343476, 1387.6947071644222, 1414.7736362182002, 1438.7913752505829, 31198.6032993078, 31530.0, 32684.0, 32784.83607581947, 33079.83460082684, 57843.42156578381, 58631.70684146552, 58690.706546466994, 58705.82588348295, 58732.0, 633870.0, 638765.0, 639780.0, 640251.0, 640445.0, 35504.64495355013, 36378.45432318465, 37078.44382334213, 41891.7905410471, 43889.34165987449, 48769.51230487791, 48792.53622391334, 48930.021399571095, 49328.75335623299, 49532.75233623809, 38107.809460952514, 38376.0, 40280.395794062526, 41224.151863841966, 41730.58269417267, 38480.80759596184, 38548.6145138545, 38561.0, 40046.0, 40120.79939600283, 38216.04459888414, 38661.0, 38891.416628751154, 39066.0, 39261.82214533569, 27112.32219194536, 32467.0, 32995.34009319848, 33702.662973370934, 35039.0, 23013.65479517775, 25493.87253063723, 25527.6170857441, 25978.66895047618, 26039.349016274744], "value": 20539.7198210363, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [374.46825507778954, 374.9700023998082, 386.3104358719686, 395.5905637665017, 405.8437501561898, 19251.0, 21208.36374908751, 21768.782312176874, 22049.338519844412, 22139.33581992539, 20407.693884591743, 20792.68810967833, 21118.366449006517, 21314.57370852582, 21383.35849924505, 5880.206172166767, 5952.9107063394085, 6123.969380153093, 6175.475084617813, 6180.969095154518, 4223.66210703143, 4318.9784051079705, 6073.7874174403905, 6163.568550201488, 6183.628982261056, 5132.948670513303, 6854.21176564694, 6856.554323968926, 6861.451083913275, 6871.175458944934, 4706.764661766915, 6114.388561143895, 6119.143319935212, 6852.691628876688, 6868.2788307227775, 5873.0, 5885.470307672287, 5886.882262354772, 5895.145203945404, 5897.4102589741115, 12619.432125554373, 12817.551385701548, 12823.102382833205, 12828.102032857703, 12836.780505852023, 2162.76209616942, 2172.2940044485604, 2224.799768020886, 2304.1129165271323, 2406.975930240693, 9067.727968160965, 9549.47477888716, 9868.407895526292, 9942.055504727039, 10036.347637403545, 1477.578890016351, 1671.5319710481085, 1864.9533761655932, 1900.0309841980552, 1909.4080834941212, 90439.54780226057, 114708.85291147065, 118571.0, 123437.0, 123849.0, 9092.454452732858, 10691.09125724322, 10802.297850639683, 11090.55637774496, 11091.611793587186, 13509.324533773468, 13560.796588051187, 13564.660883477794, 13835.239061851493, 14266.85733142676, 8302.750917472486, 8423.831523369498, 8450.492970421672, 8567.914320856711, 8586.398952073296, 8270.297024752963, 8289.41974061822, 8292.212239837207, 8460.450070745383, 8468.534230617375, 4383.736975781463, 4436.689431739812, 4439.267520859086, 4473.823585254506, 4597.7241365518175, 1647.250501022032, 1818.5908170661496, 1830.5240637434097, 1906.2470324221854, 1941.5340318323783, 362.32788177929564, 367.4579994508079, 387.17338482340404, 818.4352796570466, 898.4609234459272, 11114.49984750672, 11139.665810025712, 11452.427378631035, 11468.559432170316, 11602.88397116018, 393.91136994176657, 402.4767801857573, 406.74578388507183, 409.8094386110473, 417.23229258164514, 5738.139279108167, 5788.9710551447815, 5795.1017592273265, 5934.0, 5961.5230781537375, 596760.0, 607082.0, 609186.0, 617797.0, 618664.0, 112698.0, 131763.0, 132037.0, 134860.0, 135722.0, 411.06892887609587, 425.8871399079246, 426.2306536701222, 426.35833071226983, 428.6892003297584, 8721.56392180401, 10785.02934735846, 10870.891291086988, 11154.274972126786, 11219.0, 52133.21800173076, 52449.0, 52475.73762131165, 53270.20094698505, 54081.0, 71222.6438867823, 74860.0, 75451.62274188593, 76479.61760191164, 77416.83874741771, 4450.465944086601, 5219.5041471060185, 6684.866302673822, 7556.168821429748, 8145.51126932386, 10429.68710938673, 12050.337231452004, 12189.0, 12383.75232495363, 12573.622791316275, 10747.731306717395, 10922.890771092187, 10970.286931349756, 11125.0, 11319.773604527698, 10657.094146997282, 10676.029339464845, 10764.407957562096, 10979.682438107478, 11018.283811552223, 11294.717632059264, 11432.428378580871, 11541.596044138414, 11549.6535103947, 11656.0, 7697.076350837711, 7733.786931775588, 7848.097468791162, 8050.436469447082, 8055.597220138852, 8163.959180204061, 10814.459277036274, 10865.18511111654, 11216.775664486828, 11333.773324533628], "value": 8290.822702839476, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1331.6471135149184, 1527.289810238239, 1691.813900470948, 1874.9062546872653, 1933.9323123690665, 8355.623996920138, 8830.072842351557, 8836.734897953056, 8846.11538846115, 8850.5574721264, 6616.636085015322, 7971.123176450586, 8393.91606083939, 8604.483730976146, 8666.350023748228, 2322.7909488146106, 2352.5059737455153, 2355.882205889707, 2361.173589243768, 2362.3267368799898, 2309.3649246457276, 2331.73185083715, 2352.694149760529, 2356.8114550835894, 2357.8938947747392, 2340.4031971847157, 2351.1770880191966, 2352.435415500276, 2365.113082594027, 2372.146027430124, 2262.5135595846973, 2277.840551161419, 2279.0200213908015, 2293.69035180251, 2319.9420014499606, 2284.348960546253, 2333.57995560799, 2339.7309309429465, 2340.1575432844334, 2342.0982921575314, 8702.825943481159, 8762.299016078698, 8766.422044032071, 8766.956165219197, 8772.78068048304, 5117.257997590365, 5283.656562323436, 5285.762140703678, 5286.022085914118, 5290.682559046431, 3317.618473875511, 3434.5706786651667, 3574.821258937043, 3579.1410061585293, 3583.6595523425235, 1045.0803293102097, 1084.1326938449208, 1089.6730980705756, 1102.8070087734613, 1107.7618312062946, 11493.310401375944, 11660.766784664344, 11673.88326116745, 11675.94162029193, 11715.121365897678, 4335.176316499855, 4565.634749220121, 4583.518730533246, 4736.905261894812, 4743.715377077387, 9839.852402213972, 9846.507674616241, 9853.802923941625, 9864.408135511892, 9870.654527091518, 5124.026434977342, 5131.97434012835, 5132.717700526432, 5134.998675258364, 5136.357955255593, 4321.373400856921, 4483.7533935633855, 4570.771461426982, 4709.0, 4796.640251981115, 3850.7689538627774, 3861.3821788514265, 3982.5021872265966, 4010.0375909782024, 4055.3105971984833, 1286.4211105002598, 1292.2475127977536, 1294.9611511654666, 1298.1302527306727, 1305.3016636099642, 889.7686601483531, 916.7524768312518, 919.94480331179, 939.1219210038499, 950.7528042708807, 5627.493525582717, 5869.3537525253105, 5883.352831188566, 5970.164177015221, 6064.969675151684, 404.0787005627175, 416.895776055986, 416.9624733773975, 423.4748911349904, 427.7690047374383, 3753.849846006129, 3893.6690381317903, 3912.765234085964, 3986.740861843971, 4051.8987025324013, 9417.434953902788, 10659.25385223027, 10660.466976651136, 10662.520186591466, 10679.0, 10373.58505659765, 10493.895061049445, 10573.101286390589, 10601.575936962436, 10689.35863848156, 8004.319632831158, 8044.155363686846, 8160.591970401456, 8162.306203972729, 8163.918360816316, 5669.149627555899, 5766.913496297475, 5816.5055970243, 5847.707614619337, 5873.324567674772, 9630.422174669542, 9831.5084245786, 9860.161886239463, 9915.206783457163, 9933.552990115606, 10140.239482039024, 10145.84781228297, 10157.84763228567, 10163.085322321027, 10167.389956602627, 6802.3537763912345, 7012.859742805217, 7018.894716579358, 7374.520656157332, 7444.851102977802, 7594.620268986418, 7802.726904558313, 8097.433179677366, 8197.057338406159, 8347.499150051017, 7257.782266532013, 7345.1553071397475, 7351.448641351813, 7459.92540074614, 7533.849323013399, 7050.612216328152, 7216.061911951413, 7240.167380751071, 7266.346028857428, 7287.817804554928, 6861.838520866111, 6863.83314836469, 6970.721171153097, 6972.721091156297, 6983.546069505466, 4954.900901981868, 4983.75081245929, 5006.62450316235, 5038.773255203598, 5116.258142569381, 4875.488073752205, 5010.442585764148, 5039.949600504095, 5185.507376799198, 5192.870178245574], "value": 4451.439813216169, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [283.1251433071808, 287.57008272632413, 297.6532339824105, 307.0603951907163, 307.40056889066324, 5189.247559103927, 5308.309919710432, 5408.8918221635595, 5468.890622187559, 5484.177373393988, 4483.798229079691, 4981.153203955328, 5199.532042116209, 5280.287161233232, 5294.014744470824, 3598.208394153282, 4118.094019315746, 4152.584741525839, 4170.977076118081, 4207.810648520808, 3712.106825518978, 3953.011747063234, 3969.0474286171398, 4201.663866890657, 4203.117345357478, 3917.2948869203533, 4076.694247931402, 4112.6298633123015, 4124.855075360825, 4145.854895078673, 4082.3876418537457, 4084.2852500812387, 4086.366613174933, 4113.526944401372, 4120.670346372298, 2950.8819647214086, 3266.4936934775146, 3556.3065202285566, 3567.8751243706347, 3615.403458429356, 4489.528599497038, 4496.0, 4966.8758281043265, 5067.569256613192, 5224.294720212761, 4270.508891477489, 4674.5325467452985, 4681.227597446417, 4687.531246875285, 4696.248600223982, 4759.691549598442, 4954.702717836942, 5606.859828504279, 5627.831165065055, 5643.209950606918, 3446.9655303446893, 3783.735138540303, 3915.843366265346, 3989.5611482736876, 4006.399040144002, 4839.3466881970835, 5745.195672605839, 5941.31674857397, 5969.492593129589, 6047.334793172748, 3960.1683646433994, 4031.0, 4032.6370626643743, 4037.5154981401834, 4069.755814651071, 4080.326746086922, 4093.0, 4104.671626269891, 4144.378343248537, 4150.418941348214, 3733.9066523337133, 4581.816727330869, 4740.5733483986605, 4747.430308363018, 4801.207800712913, 4550.567696068868, 4688.882777930511, 5664.52722292207, 5735.2257445245195, 5735.684537350486, 1277.9361031948365, 1291.9870801292054, 1325.4896864707157, 1371.3280492558574, 1396.5391420831102, 785.6386062411354, 808.8058865872148, 816.31429599137, 851.2721623012359, 866.5450638414757, 3778.886633401002, 4277.657787377002, 4489.461264648263, 4516.8193272268745, 4544.818207271672, 4417.933730994037, 4429.623482004065, 4809.214831242935, 4878.609711223094, 4967.236630995944, 3705.7220708446976, 3737.5141231639714, 3996.880873355465, 4102.097538541516, 4475.978714367789, 2380.6071998120465, 2407.3981504623844, 2522.2003576030593, 2586.5344238037333, 2593.351662084479, 4432.756198409054, 4456.863499807593, 4605.976970115128, 4932.482089380635, 5101.9489805101475, 4303.0, 4417.911641767147, 4998.925016124761, 5028.164719877292, 5052.621053421009, 4484.228729651834, 4711.081339138835, 4715.858524244278, 4967.633900677342, 5012.8496145115705, 4044.656204222674, 4586.931196031996, 4825.287023106746, 4880.804767809248, 4881.38777304799, 4423.0, 4810.254410566367, 4828.165297187187, 4846.140776941621, 4876.609871210224, 4481.327800829901, 4687.9296810547185, 4883.706977581356, 5014.72419016943, 5119.083900815805, 3269.476883698597, 3501.4047611905526, 3508.7719298245534, 3514.3674138654264, 3519.9472007920413, 4380.496242931976, 4674.0, 4936.802527898843, 4951.3510272004305, 4967.3045773591, 4260.531341552489, 4808.797800549863, 4829.927551086666, 5077.492250775041, 5779.508741756998, 5016.924746128738, 5756.510696590669, 5764.811669497486, 5788.884222315614, 5793.623414478046, 5207.869803254949, 5419.807642318684, 5671.716414179192, 5680.2107336188965, 5692.430756924275, 5109.5912327013, 5309.256704061511, 5434.559841641889, 5619.241402410786, 5683.5453163747625, 5531.944680553143, 5553.694546799965, 5609.80365687199, 5613.214150018919, 5636.351819540641], "value": 3874.9681278897497, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [712.4122598855944, 720.4397643273758, 731.0678884422364, 766.9607681591442, 795.528376655904, 13107.80338294924, 15706.293216805243, 16035.717142628562, 16302.83697163028, 16306.266218020188, 14442.638934026654, 14446.783298250535, 14452.710945781078, 14472.131672099682, 14726.852731472682, 671.7279501801754, 690.6753825701912, 693.6982412650514, 694.6457306773557, 834.1575009240665, 684.8561802021582, 689.9068625735512, 861.7070196133313, 866.6360128745911, 867.6963062927988, 882.5719526029902, 887.343365909229, 896.2650626486284, 897.3539051882634, 898.4384759525296, 890.3945317184312, 891.7324802559272, 891.9464832110029, 892.8125093730324, 892.8303622311772, 514.9974250128726, 638.8626445314279, 656.5699466849221, 657.5594351784268, 659.7855696898528, 14591.0, 14705.705885882222, 14706.602872727073, 14709.970302078858, 14732.0, 13385.370917511826, 13736.450541978309, 13773.724525509433, 13853.030287879852, 13983.29385363421, 12137.15039947204, 12399.194052386567, 12467.501299947991, 12514.624561263086, 12959.740805183937, 4382.0797632497215, 4428.0, 4480.753558554278, 4532.206863798855, 4637.118947400017, 112930.0, 114106.0, 116118.0, 118272.40863795627, 118820.0, 8906.287497000225, 10650.147988160928, 10790.67627971162, 10793.514291856885, 11122.388268645302, 20937.233229995312, 21062.578748424945, 21067.473313167295, 21089.15643374248, 21102.155913763585, 7435.85128297431, 7559.584222867795, 7568.962155189299, 7603.239676032353, 7706.499077559941, 4191.11986482842, 4229.407882896397, 4245.214635292481, 4267.722598031118, 4387.605115539617, 3219.7263232625487, 3323.6344002159744, 3324.434846076173, 3324.966750332466, 3328.2511434927433, 598.2381885347696, 602.2772672792667, 606.9028955367208, 611.9541034422347, 614.8678034222663, 568.7156421789106, 577.9739911703983, 579.837645459272, 589.2016317889284, 621.9582199815292, 7474.177840437547, 8650.264727498106, 8796.516191609522, 8809.251213646912, 8950.597223124974, 576.6194311754222, 581.3053401185628, 615.9753609855645, 631.386229248617, 632.1655414852443, 4238.618524332825, 4320.4599425071865, 4354.9564504355185, 4364.803583838735, 4375.256206444848, 95839.0, 136428.0, 142910.0, 155621.22189389207, 155941.0, 109211.0, 110018.0, 112331.0, 123898.0, 136341.0, 99995.50002250087, 102555.4872225634, 104503.0, 106571.99999999844, 116296.8370316286, 9068.90931090707, 9132.49771262587, 9409.200217981275, 9605.646616578983, 9735.853962190431, 54382.72808635931, 55464.168037480275, 58153.70923145357, 58224.4177558219, 58418.70790646019, 86627.56686216529, 87905.0, 91538.54230728804, 93012.53493732488, 96630.0, 12569.183003104768, 12970.092093553361, 13025.544105956245, 13309.00182486298, 13506.459741610619, 14620.415183392544, 14757.0, 15091.622709431913, 15398.384064637738, 15424.46014389536, 13490.932545337211, 13818.0, 13826.239556824472, 13884.930575347058, 14499.492517761828, 10289.84565231507, 14047.719045619235, 14214.857851421768, 14439.566812995627, 14559.85440145585, 13216.669583260495, 13561.389737462036, 13570.0, 13767.793483097961, 13892.722145557234, 11068.667939961813, 11130.332180069221, 11237.4381280934, 11531.88468115308, 11875.287482751062, 10481.375386815056, 10569.101626361538, 10870.510827012646, 10952.397618131079, 10998.450077495932], "value": 7638.487413828702, "unit": "score"}], "name": "dromaeo_css", "value": 7096.924700614853, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 112.0, 131.0, 112.0, 112.0, 112.0, 113.0, 113.0, 114.0, 113.0], "value": 114.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 108.0, 133.0, 163.0, 110.0, 128.0, 129.0, 105.0, 160.0, 107.0], "value": 129.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 184.0, 183.0, 184.0, 184.0, 183.0, 182.0, 182.0, 181.0, 184.0], "value": 183.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 75.0, 77.0, 149.0, 56.0, 58.0, 57.0, 69.0, 148.0, 68.0], "value": 83.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 84.0, 131.0, 71.0, 71.0, 91.0, 120.0, 69.0, 70.0, 120.0], "value": 90.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 90.0, 87.0, 90.0, 86.0, 128.0, 88.0, 90.0, 87.0], "value": 92.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 108.0, 150.0, 105.0, 95.0, 96.0, 96.0, 95.0, 95.0], "value": 103.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 86.0, 87.0, 85.0, 85.0, 86.0, 86.0, 86.0, 86.0], "value": 85.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 55.0, 55.0, 53.0, 53.0, 53.0, 54.0, 52.0, 52.0], "value": 53.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 73.0, 73.0, 75.0, 73.0, 71.0, 71.0, 72.0, 97.0], "value": 73.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 88.0, 86.0, 84.0, 88.0, 83.0, 87.0, 89.0, 86.0, 85.0], "value": 86.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 139.0, 135.0, 173.0, 151.0, 141.0, 137.0, 141.0, 182.0, 137.0], "value": 147.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 168.0, 170.0, 170.0, 167.0, 170.0, 168.0, 173.0, 165.0, 176.0], "value": 169.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 68.0, 70.0, 68.0, 66.0, 67.0, 65.0, 66.0, 64.0, 67.0], "value": 66.6, "unit": "score"}], "name": "kraken", "value": 1479.1999999999998, "alertThreshold": 2.0}]} 04:45:53 INFO - Return code: 0 04:45:53 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:45:53 INFO - # TBPL SUCCESS # 04:45:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:45:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:45:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:45:53 INFO - 2016-08-31 04:45:53.390 screenresolution[1971:18073] starting screenresolution argv=screenresolution get 04:45:53 INFO - 2016-08-31 04:45:53.392 screenresolution[1971:18073] Display 0: 1600x1200x32@60 04:45:53 INFO - 2016-08-31 04:45:53.398 screenresolution[1972:18075] starting screenresolution argv=screenresolution list 04:45:53 INFO - Available Modes on Display 0 04:45:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:45:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:45:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:45:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:45:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:45:53 INFO - Intel Iris: 04:45:53 INFO - Chipset Model: Intel Iris 04:45:53 INFO - Type: GPU 04:45:53 INFO - Bus: Built-In 04:45:53 INFO - VRAM (Dynamic, Max): 1536 MB 04:45:53 INFO - Vendor: Intel (0x8086) 04:45:53 INFO - Device ID: 0x0a2e 04:45:53 INFO - Revision ID: 0x0009 04:45:53 INFO - Displays: 04:45:53 INFO - Display: 04:45:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:45:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:45:53 INFO - Display Serial Number: 1600x1200 60 04:45:53 INFO - Main Display: Yes 04:45:53 INFO - Mirror: Off 04:45:53 INFO - Online: Yes 04:45:53 INFO - Rotation: Supported 04:45:53 INFO - Return code: 0 04:45:53 INFO - Running post-action listener: _resource_record_post_action 04:45:53 INFO - [mozharness: 2016-08-31 11:45:53.639091Z] Finished run-tests step (success) 04:45:53 INFO - Running post-run listener: _resource_record_post_run 04:45:53 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 1708032; Write bytes: 706966528; Read time: 17; Write time: 1955 04:45:53 INFO - TinderboxPrint: CPU usage
53.7% 04:45:53 INFO - TinderboxPrint: I/O read bytes / time
1,708,032 / 17 04:45:53 INFO - TinderboxPrint: I/O write bytes / time
706,966,528 / 1,955 04:45:53 INFO - TinderboxPrint: CPU idle
2,247.3 (46.3%) 04:45:53 INFO - TinderboxPrint: CPU system
970.1 (20.0%) 04:45:53 INFO - TinderboxPrint: CPU user
1,632.4 (33.7%) 04:45:53 INFO - TinderboxPrint: Swap in / out
407,138,304 / 0 04:45:53 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 385927680; Write bytes: 380040704; Read time: 28980; Write time: 777 04:45:53 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 1708032; Write bytes: 297396224; Read time: 17; Write time: 1109 04:45:54 INFO - Running post-run listener: _upload_blobber_files 04:45:54 INFO - Blob upload gear active. 04:45:54 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:45:54 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 04:45:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:45:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:45:54 INFO - (blobuploader) - INFO - Open directory for files ... 04:45:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:45:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:45:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:45:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:45:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:45:56 INFO - (blobuploader) - INFO - Done attempting. 04:45:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:45:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:45:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:45:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:45:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:45:57 INFO - (blobuploader) - INFO - Done attempting. 04:45:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:45:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:45:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:45:59 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:45:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:45:59 INFO - (blobuploader) - INFO - Done attempting. 04:45:59 INFO - (blobuploader) - INFO - Iteration through files over. 04:45:59 INFO - Return code: 0 04:45:59 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:45:59 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:45:59 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/68fd079da75f44650b8c90d2b08413e46544f6ed50f635a9934a2ca0883469f30656314d854d536fbd2abffb948d1f215d42fb35d03228b49a6a1938b335a894", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/7aaef938ca4ce0fc2bf90a37c80a90cffc4ee21bf8bb074d807b14d4263939920440e73545e5135f011a7fb3b38527838c4f808a0fa2b3e081ecc163d535cc96", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9c8e35769fa19f0fad6db5efc0a0b61cc50cefa283a5f7cfdec5423a2e339128586dd4eacc08db826cdc27afddb661cf9c1794445ab2a9191ef37eddd270dc4d"} 04:45:59 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:45:59 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:45:59 INFO - Contents: 04:45:59 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/68fd079da75f44650b8c90d2b08413e46544f6ed50f635a9934a2ca0883469f30656314d854d536fbd2abffb948d1f215d42fb35d03228b49a6a1938b335a894", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/7aaef938ca4ce0fc2bf90a37c80a90cffc4ee21bf8bb074d807b14d4263939920440e73545e5135f011a7fb3b38527838c4f808a0fa2b3e081ecc163d535cc96", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9c8e35769fa19f0fad6db5efc0a0b61cc50cefa283a5f7cfdec5423a2e339128586dd4eacc08db826cdc27afddb661cf9c1794445ab2a9191ef37eddd270dc4d"} 04:45:59 INFO - Running post-run listener: copy_logs_to_upload_dir 04:45:59 INFO - Copying logs to upload dir... 04:45:59 INFO - mkdir: /builds/slave/test/build/upload/logs 04:45:59 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.936458 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-08-31 04:45:59.780769) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-31 04:45:59.784819) ========= 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.jBPAMdFuo0/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.zTuK0r4L93/Listeners TMPDIR=/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/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_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/68fd079da75f44650b8c90d2b08413e46544f6ed50f635a9934a2ca0883469f30656314d854d536fbd2abffb948d1f215d42fb35d03228b49a6a1938b335a894", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/7aaef938ca4ce0fc2bf90a37c80a90cffc4ee21bf8bb074d807b14d4263939920440e73545e5135f011a7fb3b38527838c4f808a0fa2b3e081ecc163d535cc96", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9c8e35769fa19f0fad6db5efc0a0b61cc50cefa283a5f7cfdec5423a2e339128586dd4eacc08db826cdc27afddb661cf9c1794445ab2a9191ef37eddd270dc4d"} build_url:https://queue.taskcluster.net/v1/task/cJHAre0_TE682_DlK7RbqQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011230 build_url: 'https://queue.taskcluster.net/v1/task/cJHAre0_TE682_DlK7RbqQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/68fd079da75f44650b8c90d2b08413e46544f6ed50f635a9934a2ca0883469f30656314d854d536fbd2abffb948d1f215d42fb35d03228b49a6a1938b335a894", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/7aaef938ca4ce0fc2bf90a37c80a90cffc4ee21bf8bb074d807b14d4263939920440e73545e5135f011a7fb3b38527838c4f808a0fa2b3e081ecc163d535cc96", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9c8e35769fa19f0fad6db5efc0a0b61cc50cefa283a5f7cfdec5423a2e339128586dd4eacc08db826cdc27afddb661cf9c1794445ab2a9191ef37eddd270dc4d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-31 04:45:59.818728) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 04:45:59.819079) ========= 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.jBPAMdFuo0/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.zTuK0r4L93/Listeners TMPDIR=/var/folders/yv/7cgcw59j42ddm7qwtql8l57m00000w/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.005134 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-31 04:45:59.867342) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-31 04:45:59.867670) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-31 04:45:59.868236) ========= ========= Total master_lag: 0.17 =========