builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0236 starttime: 1479562210.28 results: success (0) buildid: 20161119030721 builduid: ba62ab4d34234f8891d01d8c9480e9a8 revision: 1fa9b812e6bd2c6e37963df9d11dfed164700357 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-19 05:30:10.277991) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-19 05:30:10.278503) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-19 05:30:10.278792) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-19 05:30:10.313709) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-19 05:30:10.314102) ========= 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.byhoGIIaHv/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.pjdP5jaEs6/Listeners TMPDIR=/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-11-19 05:30:10-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 159M=0s 2016-11-19 05:30:10 (159 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.494388 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-19 05:30:10.830652) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-19 05:30:10.834023) ========= 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.byhoGIIaHv/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.pjdP5jaEs6/Listeners TMPDIR=/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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.022313 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-19 05:30:10.882789) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-11-19 05:30:10.886183) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 1fa9b812e6bd2c6e37963df9d11dfed164700357 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 1fa9b812e6bd2c6e37963df9d11dfed164700357 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.byhoGIIaHv/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.pjdP5jaEs6/Listeners TMPDIR=/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-11-19 05:30:10,970 truncating revision to first 12 chars 2016-11-19 05:30:10,971 Setting DEBUG logging. 2016-11-19 05:30:10,971 attempt 1/10 2016-11-19 05:30:10,971 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/1fa9b812e6bd?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-19 05:30:12,739 unpacking tar archive at: mozilla-beta-1fa9b812e6bd/testing/mozharness/ program finished with exit code 0 elapsedTime=2.050011 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-11-19 05:30:12.961336) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-19 05:30:12.961771) ========= script_repo_revision: 1fa9b812e6bd2c6e37963df9d11dfed164700357 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-19 05:30:12.962235) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-19 05:30:12.962523) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-19 05:30:12.982588) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2016-11-19 05:30:12.983017) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.byhoGIIaHv/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.pjdP5jaEs6/Listeners TMPDIR=/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 05:30:13 INFO - MultiFileLogger online at 20161119 05:30:13 in /builds/slave/test 05:30:13 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 05:30:13 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:30:13 INFO - {'append_to_log': False, 05:30:13 INFO - 'base_work_dir': '/builds/slave/test', 05:30:13 INFO - 'blob_upload_branch': 'Mozilla-Beta', 05:30:13 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:30:13 INFO - 'branch': 'Mozilla-Beta', 05:30:13 INFO - 'buildbot_json_path': 'buildprops.json', 05:30:13 INFO - 'config_files': ('talos/mac_config.py',), 05:30:13 INFO - 'default_actions': ('clobber', 05:30:13 INFO - 'read-buildbot-config', 05:30:13 INFO - 'download-and-extract', 05:30:13 INFO - 'populate-webroot', 05:30:13 INFO - 'create-virtualenv', 05:30:13 INFO - 'install', 05:30:13 INFO - 'run-tests'), 05:30:13 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:30:13 INFO - 'download_minidump_stackwalk': True, 05:30:13 INFO - 'download_symbols': 'ondemand', 05:30:13 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:30:13 INFO - 'tooltool.py': '/tools/tooltool.py', 05:30:13 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:30:13 INFO - '/tools/misc-python/virtualenv.py')}, 05:30:13 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:30:13 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:30:13 INFO - 'installer_path': 'installer.exe', 05:30:13 INFO - 'log_level': 'info', 05:30:13 INFO - 'log_name': 'talos', 05:30:13 INFO - 'log_to_console': True, 05:30:13 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:30:13 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:30:13 INFO - 'opt_config_files': (), 05:30:13 INFO - 'pip_index': False, 05:30:13 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:30:13 INFO - 'cmd': ('bash', 05:30:13 INFO - '-c', 05:30:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:30:13 INFO - 'enabled': True, 05:30:13 INFO - 'halt_on_failure': False, 05:30:13 INFO - 'name': 'check_screen_resolution'},), 05:30:13 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:30:13 INFO - 'cmd': ('bash', 05:30:13 INFO - '-c', 05:30:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:30:13 INFO - 'enabled': True, 05:30:13 INFO - 'halt_on_failure': False, 05:30:13 INFO - 'name': 'check_screen_resolution'},), 05:30:13 INFO - 'run_cmd_checks_enabled': True, 05:30:13 INFO - 'sps_profile': False, 05:30:13 INFO - 'sps_profile_interval': 0, 05:30:13 INFO - 'suite': 'dromaeojs', 05:30:13 INFO - 'system_bits': '32', 05:30:13 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:30:13 INFO - 'title': 't-yosemite-r7-0236', 05:30:13 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:30:13 INFO - 'use_talos_json': True, 05:30:13 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:30:13 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:30:13 INFO - 'work_dir': 'build'} 05:30:13 INFO - [mozharness: 2016-11-19 13:30:13.200125Z] Running clobber step. 05:30:13 INFO - Running pre-action listener: _resource_record_pre_action 05:30:13 INFO - Running main action method: clobber 05:30:13 INFO - rmtree: /builds/slave/test/build 05:30:13 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:30:15 INFO - Running post-action listener: _resource_record_post_action 05:30:15 INFO - [mozharness: 2016-11-19 13:30:15.456777Z] Finished clobber step (success) 05:30:15 INFO - [mozharness: 2016-11-19 13:30:15.456907Z] Running read-buildbot-config step. 05:30:15 INFO - Running pre-action listener: _resource_record_pre_action 05:30:15 INFO - Running main action method: read_buildbot_config 05:30:15 INFO - Using buildbot properties: 05:30:15 INFO - { 05:30:15 INFO - "project": "", 05:30:15 INFO - "product": "firefox", 05:30:15 INFO - "script_repo_revision": "production", 05:30:15 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 05:30:15 INFO - "repository": "", 05:30:15 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 05:30:15 INFO - "buildid": "20161119030721", 05:30:15 INFO - "pgo_build": "False", 05:30:15 INFO - "basedir": "/builds/slave/test", 05:30:15 INFO - "buildnumber": 19, 05:30:15 INFO - "slavename": "t-yosemite-r7-0236", 05:30:15 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 05:30:15 INFO - "platform": "yosemite_r7", 05:30:15 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 05:30:15 INFO - "branch": "mozilla-beta", 05:30:15 INFO - "repo_path": "releases/mozilla-beta", 05:30:15 INFO - "revision": "1fa9b812e6bd2c6e37963df9d11dfed164700357", 05:30:15 INFO - "stage_platform": "macosx64", 05:30:15 INFO - "builduid": "ba62ab4d34234f8891d01d8c9480e9a8", 05:30:15 INFO - "slavebuilddir": "test" 05:30:15 INFO - } 05:30:15 INFO - Found installer url https://queue.taskcluster.net/v1/task/TKFXMxOyQ1eRKg11r00spw/artifacts/public/build/firefox-51.0.en-US.mac.dmg. 05:30:15 INFO - Running post-action listener: _resource_record_post_action 05:30:15 INFO - [mozharness: 2016-11-19 13:30:15.460175Z] Finished read-buildbot-config step (success) 05:30:15 INFO - [mozharness: 2016-11-19 13:30:15.460313Z] Running download-and-extract step. 05:30:15 INFO - Running pre-action listener: _resource_record_pre_action 05:30:15 INFO - Running main action method: download_and_extract 05:30:15 INFO - mkdir: /builds/slave/test/build/tests 05:30: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')]} 05:30:15 INFO - trying https://queue.taskcluster.net/v1/task/TKFXMxOyQ1eRKg11r00spw/artifacts/public/build/firefox-51.0.en-US.mac.test_packages.json 05:30:15 INFO - Downloading https://queue.taskcluster.net/v1/task/TKFXMxOyQ1eRKg11r00spw/artifacts/public/build/firefox-51.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0.en-US.mac.test_packages.json 05:30:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TKFXMxOyQ1eRKg11r00spw/artifacts/public/build/firefox-51.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0.en-US.mac.test_packages.json'}, attempt #1 05:30:16 INFO - Downloaded 1165 bytes. 05:30:16 INFO - Reading from file /builds/slave/test/build/firefox-51.0.en-US.mac.test_packages.json 05:30:16 INFO - Using the following test package requirements: 05:30:16 INFO - {u'common': [u'firefox-51.0.en-US.mac.common.tests.zip'], 05:30:16 INFO - u'cppunittest': [u'firefox-51.0.en-US.mac.common.tests.zip', 05:30:16 INFO - u'firefox-51.0.en-US.mac.cppunittest.tests.zip'], 05:30:16 INFO - u'gtest': [u'firefox-51.0.en-US.mac.common.tests.zip', 05:30:16 INFO - u'firefox-51.0.en-US.mac.gtest.tests.zip'], 05:30:16 INFO - u'jittest': [u'firefox-51.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 05:30:16 INFO - u'mochitest': [u'firefox-51.0.en-US.mac.common.tests.zip', 05:30:16 INFO - u'firefox-51.0.en-US.mac.mochitest.tests.zip'], 05:30:16 INFO - u'mozbase': [u'firefox-51.0.en-US.mac.common.tests.zip'], 05:30:16 INFO - u'reftest': [u'firefox-51.0.en-US.mac.common.tests.zip', 05:30:16 INFO - u'firefox-51.0.en-US.mac.reftest.tests.zip'], 05:30:16 INFO - u'talos': [u'firefox-51.0.en-US.mac.common.tests.zip', 05:30:16 INFO - u'firefox-51.0.en-US.mac.talos.tests.zip'], 05:30:16 INFO - u'web-platform': [u'firefox-51.0.en-US.mac.common.tests.zip', 05:30:16 INFO - u'firefox-51.0.en-US.mac.web-platform.tests.zip'], 05:30:16 INFO - u'xpcshell': [u'firefox-51.0.en-US.mac.common.tests.zip', 05:30:16 INFO - u'firefox-51.0.en-US.mac.xpcshell.tests.zip']} 05:30:16 INFO - Downloading packages: [u'firefox-51.0.en-US.mac.common.tests.zip'] for test suite category: common 05:30:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TKFXMxOyQ1eRKg11r00spw/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip 05:30:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TKFXMxOyQ1eRKg11r00spw/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip'}, attempt #1 05:30:16 INFO - Fetch https://queue.taskcluster.net/v1/task/TKFXMxOyQ1eRKg11r00spw/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip into memory 05:30:16 INFO - Expected file size: 16231204 05:30:24 INFO - Downloading packages: [u'firefox-51.0.en-US.mac.common.tests.zip', u'firefox-51.0.en-US.mac.talos.tests.zip'] for test suite category: talos 05:30:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TKFXMxOyQ1eRKg11r00spw/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip 05:30:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TKFXMxOyQ1eRKg11r00spw/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip'}, attempt #1 05:30:24 INFO - Fetch https://queue.taskcluster.net/v1/task/TKFXMxOyQ1eRKg11r00spw/artifacts/public/build/firefox-51.0.en-US.mac.common.tests.zip into memory 05:30:24 INFO - Expected file size: 16231204 05:30:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TKFXMxOyQ1eRKg11r00spw/artifacts/public/build/firefox-51.0.en-US.mac.talos.tests.zip 05:30:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TKFXMxOyQ1eRKg11r00spw/artifacts/public/build/firefox-51.0.en-US.mac.talos.tests.zip'}, attempt #1 05:30:31 INFO - Fetch https://queue.taskcluster.net/v1/task/TKFXMxOyQ1eRKg11r00spw/artifacts/public/build/firefox-51.0.en-US.mac.talos.tests.zip into memory 05:30:32 INFO - Expected file size: 14414089 05:30:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:30:33 INFO - trying https://queue.taskcluster.net/v1/task/TKFXMxOyQ1eRKg11r00spw/artifacts/public/build/firefox-51.0.en-US.mac.dmg 05:30:33 INFO - Downloading https://queue.taskcluster.net/v1/task/TKFXMxOyQ1eRKg11r00spw/artifacts/public/build/firefox-51.0.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0.en-US.mac.dmg 05:30:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TKFXMxOyQ1eRKg11r00spw/artifacts/public/build/firefox-51.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0.en-US.mac.dmg'}, attempt #1 05:30:36 INFO - Downloaded 86009060 bytes. 05:30:36 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TKFXMxOyQ1eRKg11r00spw/artifacts/public/build/firefox-51.0.en-US.mac.dmg 05:30:36 INFO - mkdir: /builds/slave/test/properties 05:30:36 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:30:36 INFO - Writing to file /builds/slave/test/properties/build_url 05:30:36 INFO - Contents: 05:30:36 INFO - build_url:https://queue.taskcluster.net/v1/task/TKFXMxOyQ1eRKg11r00spw/artifacts/public/build/firefox-51.0.en-US.mac.dmg 05:30:36 INFO - Running post-action listener: _resource_record_post_action 05:30:36 INFO - Running post-action listener: set_extra_try_arguments 05:30:36 INFO - [mozharness: 2016-11-19 13:30:36.723474Z] Finished download-and-extract step (success) 05:30:36 INFO - [mozharness: 2016-11-19 13:30:36.723608Z] Running populate-webroot step. 05:30:36 INFO - Running pre-action listener: _resource_record_pre_action 05:30:36 INFO - Running main action method: populate_webroot 05:30:36 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:30:36 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:30:36 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:30:36 INFO - '--tppagecycles', 05:30:36 INFO - '7'], 05:30:36 INFO - 'tests': ['tsvgm']}}, 05:30:36 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:30:36 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:30:36 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:30:36 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:30:36 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:30:36 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:30:36 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:30:36 INFO - 'talos_options': ['--disable-e10s'], 05:30:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:30:36 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:30:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:30:36 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:30:36 INFO - 'talos_options': ['--disable-e10s'], 05:30:36 INFO - 'tests': ['damp', 'tps']}, 05:30:36 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:30:36 INFO - 'tests': ['damp', 'tps']}, 05:30:36 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:30:36 INFO - 'tests': ['dromaeo_dom']}, 05:30:36 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:30:36 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:30:36 INFO - 'tests': ['basic_compositor_video']}, 05:30:36 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:30:36 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:30:36 INFO - 'tests': ['a11yr', 05:30:36 INFO - 'ts_paint', 05:30:36 INFO - 'tpaint', 05:30:36 INFO - 'sessionrestore', 05:30:36 INFO - 'sessionrestore_no_auto_restore', 05:30:36 INFO - 'tabpaint']}, 05:30:36 INFO - 'other-e10s': {'tests': ['a11yr', 05:30:36 INFO - 'ts_paint', 05:30:36 INFO - 'tpaint', 05:30:36 INFO - 'sessionrestore', 05:30:36 INFO - 'sessionrestore_no_auto_restore', 05:30:36 INFO - 'tabpaint']}, 05:30:36 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:30:36 INFO - 'ts_paint', 05:30:36 INFO - 'tpaint', 05:30:36 INFO - 'sessionrestore', 05:30:36 INFO - 'sessionrestore_no_auto_restore', 05:30:36 INFO - 'tabpaint']}, 05:30:36 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:30:36 INFO - 'ts_paint', 05:30:36 INFO - 'tpaint', 05:30:36 INFO - 'sessionrestore', 05:30:36 INFO - 'sessionrestore_no_auto_restore', 05:30:36 INFO - 'tabpaint']}, 05:30:36 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:30:36 INFO - 'tests': ['a11yr', 05:30:36 INFO - 'ts_paint', 05:30:36 INFO - 'tpaint', 05:30:36 INFO - 'sessionrestore', 05:30:36 INFO - 'sessionrestore_no_auto_restore', 05:30:36 INFO - 'tabpaint']}, 05:30:36 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:30:36 INFO - 'tests': ['a11yr', 05:30:36 INFO - 'ts_paint', 05:30:36 INFO - 'tpaint', 05:30:36 INFO - 'sessionrestore', 05:30:36 INFO - 'sessionrestore_no_auto_restore', 05:30:36 INFO - 'tabpaint']}, 05:30:36 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:30:36 INFO - 'tests': ['tsvgx', 05:30:36 INFO - 'tsvgr_opacity', 05:30:36 INFO - 'tart', 05:30:36 INFO - 'tscrollx', 05:30:36 INFO - 'cart']}, 05:30:36 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:30:36 INFO - 'tsvgr_opacity', 05:30:36 INFO - 'tart', 05:30:36 INFO - 'tscrollx', 05:30:36 INFO - 'cart']}, 05:30:36 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:30:36 INFO - 'talos_options': ['--disable-e10s'], 05:30:36 INFO - 'tests': ['tp5o']}, 05:30:36 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:30:36 INFO - 'tests': ['tp5o']}, 05:30:36 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:30:36 INFO - 'talos_options': ['--disable-e10s', 05:30:36 INFO - '--xperf_path', 05:30:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:30:36 INFO - 'tests': ['tp5n']}, 05:30:36 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:30:36 INFO - 'talos_options': ['--xperf_path', 05:30:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:30:36 INFO - 'tests': ['tp5n']}}, 05:30:36 INFO - 'talos.zip': {'path': '', 05:30:36 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:30:36 INFO - Running post-action listener: _resource_record_post_action 05:30:36 INFO - [mozharness: 2016-11-19 13:30:36.742137Z] Finished populate-webroot step (success) 05:30:36 INFO - [mozharness: 2016-11-19 13:30:36.742237Z] Running create-virtualenv step. 05:30:36 INFO - Running pre-action listener: _resource_record_pre_action 05:30:36 INFO - Running main action method: create_virtualenv 05:30:36 INFO - Creating virtualenv /builds/slave/test/build/venv 05:30:36 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:30:36 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:30:36 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:30:36 INFO - Using real prefix '/tools/python27' 05:30:36 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:30:37 INFO - Installing distribute.............................................................................................................................................................................................done. 05:30:40 INFO - Installing pip.................done. 05:30:40 INFO - Return code: 0 05:30:40 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:30: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')]} 05:30:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:30:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30: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')]} 05:30:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:30:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ebae490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb24570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdcd2496a00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e9c2d30>, '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 0x10e828db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e9bab20>, '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.byhoGIIaHv/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.pjdP5jaEs6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 05:30:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:30:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:30:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.byhoGIIaHv/Render', 05:30:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:30:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:30:40 INFO - 'HOME': '/Users/cltbld', 05:30:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:30:40 INFO - 'LOGNAME': 'cltbld', 05:30:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:30:40 INFO - 'MOZ_NO_REMOTE': '1', 05:30:40 INFO - 'NO_EM_RESTART': '1', 05:30:40 INFO - 'PAGER': '/bin/cat', 05:30:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:30:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:30:40 INFO - 'PWD': '/builds/slave/test', 05:30:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:30:40 INFO - 'SHELL': '/bin/bash', 05:30:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pjdP5jaEs6/Listeners', 05:30:40 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 05:30:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:30:40 INFO - 'USER': 'cltbld', 05:30:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:30:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:30:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:30:40 INFO - 'XPC_FLAGS': '0x0', 05:30:40 INFO - 'XPC_SERVICE_NAME': '0', 05:30:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:30:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:30:40 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:30:40 INFO - Cleaning up... 05:30:40 INFO - Return code: 0 05:30:40 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:30: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')]} 05:30:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:30:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30: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')]} 05:30:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:30:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ebae490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb24570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdcd2496a00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e9c2d30>, '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 0x10e828db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e9bab20>, '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.byhoGIIaHv/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.pjdP5jaEs6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 05:30:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:30:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:30:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.byhoGIIaHv/Render', 05:30:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:30:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:30:40 INFO - 'HOME': '/Users/cltbld', 05:30:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:30:40 INFO - 'LOGNAME': 'cltbld', 05:30:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:30:40 INFO - 'MOZ_NO_REMOTE': '1', 05:30:40 INFO - 'NO_EM_RESTART': '1', 05:30:40 INFO - 'PAGER': '/bin/cat', 05:30:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:30:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:30:40 INFO - 'PWD': '/builds/slave/test', 05:30:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:30:40 INFO - 'SHELL': '/bin/bash', 05:30:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pjdP5jaEs6/Listeners', 05:30:40 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 05:30:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:30:40 INFO - 'USER': 'cltbld', 05:30:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:30:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:30:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:30:40 INFO - 'XPC_FLAGS': '0x0', 05:30:40 INFO - 'XPC_SERVICE_NAME': '0', 05:30:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:30:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:30:41 INFO - Downloading/unpacking psutil>=3.1.1 05:30:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:30:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:30:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:30:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:30:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:30:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:30:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:30:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:30:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:30:44 INFO - Installing collected packages: psutil 05:30:44 INFO - Running setup.py install for psutil 05:30:44 INFO - building 'psutil._psutil_osx' extension 05:30:44 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 05:30:44 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 05:30:44 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 05:30:44 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 05:30:44 INFO - building 'psutil._psutil_posix' extension 05:30:44 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 05:30:44 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:30:44 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:30:44 INFO - ^ 05:30:45 INFO - 1 warning generated. 05:30:45 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 05:30:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:30:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:30:45 INFO - Successfully installed psutil 05:30:45 INFO - Cleaning up... 05:30:45 INFO - Return code: 0 05:30:45 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:30:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:30:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:30:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:30:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:30:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ebae490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb24570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdcd2496a00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e9c2d30>, '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 0x10e828db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e9bab20>, '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.byhoGIIaHv/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.pjdP5jaEs6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 05:30:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:30:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:30:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.byhoGIIaHv/Render', 05:30:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:30:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:30:45 INFO - 'HOME': '/Users/cltbld', 05:30:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:30:45 INFO - 'LOGNAME': 'cltbld', 05:30:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:30:45 INFO - 'MOZ_NO_REMOTE': '1', 05:30:45 INFO - 'NO_EM_RESTART': '1', 05:30:45 INFO - 'PAGER': '/bin/cat', 05:30:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:30:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:30:45 INFO - 'PWD': '/builds/slave/test', 05:30:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:30:45 INFO - 'SHELL': '/bin/bash', 05:30:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pjdP5jaEs6/Listeners', 05:30:45 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 05:30:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:30:45 INFO - 'USER': 'cltbld', 05:30:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:30:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:30:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:30:45 INFO - 'XPC_FLAGS': '0x0', 05:30:45 INFO - 'XPC_SERVICE_NAME': '0', 05:30:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:30:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:30:45 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:30:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:30:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:30:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:30:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:30:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:30:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:30:48 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:30:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:30:48 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:30:48 INFO - Installing collected packages: mozsystemmonitor 05:30:48 INFO - Running setup.py install for mozsystemmonitor 05:30:48 INFO - Successfully installed mozsystemmonitor 05:30:48 INFO - Cleaning up... 05:30:48 INFO - Return code: 0 05:30:48 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:30: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')]} 05:30:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:30:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30: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')]} 05:30:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:30:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ebae490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb24570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdcd2496a00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e9c2d30>, '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 0x10e828db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e9bab20>, '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.byhoGIIaHv/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.pjdP5jaEs6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 05:30:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:30:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 05:30:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.byhoGIIaHv/Render', 05:30:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:30:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:30:48 INFO - 'HOME': '/Users/cltbld', 05:30:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:30:48 INFO - 'LOGNAME': 'cltbld', 05:30:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:30:48 INFO - 'MOZ_NO_REMOTE': '1', 05:30:48 INFO - 'NO_EM_RESTART': '1', 05:30:48 INFO - 'PAGER': '/bin/cat', 05:30:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:30:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:30:48 INFO - 'PWD': '/builds/slave/test', 05:30:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:30:48 INFO - 'SHELL': '/bin/bash', 05:30:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pjdP5jaEs6/Listeners', 05:30:48 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 05:30:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:30:48 INFO - 'USER': 'cltbld', 05:30:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:30:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:30:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:30:48 INFO - 'XPC_FLAGS': '0x0', 05:30:48 INFO - 'XPC_SERVICE_NAME': '0', 05:30:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:30:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:30:48 INFO - Downloading/unpacking jsonschema==2.5.1 05:30:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:30:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:30:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:30:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:30:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:30:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:30:51 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:30:51 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:30:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:30:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:30:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:30:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:30:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:30:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:30:51 INFO - Downloading functools32-3.2.3-2.tar.gz 05:30:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:30:51 INFO - warning: no files found matching '*.txt' 05:30:51 INFO - no previously-included directories found matching 'build' 05:30:51 INFO - no previously-included directories found matching 'dist' 05:30:51 INFO - no previously-included directories found matching '.git*' 05:30:51 INFO - Installing collected packages: jsonschema, functools32 05:30:51 INFO - Running setup.py install for functools32 05:30:51 INFO - warning: no files found matching '*.txt' 05:30:51 INFO - no previously-included directories found matching 'build' 05:30:51 INFO - no previously-included directories found matching 'dist' 05:30:51 INFO - no previously-included directories found matching '.git*' 05:30:51 INFO - Successfully installed jsonschema functools32 05:30:51 INFO - Cleaning up... 05:30:51 INFO - Return code: 0 05:30:51 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:30:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:30:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:30:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:30:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:30:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ebae490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb24570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdcd2496a00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e9c2d30>, '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 0x10e828db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e9bab20>, '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.byhoGIIaHv/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.pjdP5jaEs6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 05:30:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:30:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 05:30:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.byhoGIIaHv/Render', 05:30:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:30:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:30:51 INFO - 'HOME': '/Users/cltbld', 05:30:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:30:51 INFO - 'LOGNAME': 'cltbld', 05:30:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:30:51 INFO - 'MOZ_NO_REMOTE': '1', 05:30:51 INFO - 'NO_EM_RESTART': '1', 05:30:51 INFO - 'PAGER': '/bin/cat', 05:30:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:30:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:30:51 INFO - 'PWD': '/builds/slave/test', 05:30:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:30:51 INFO - 'SHELL': '/bin/bash', 05:30:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pjdP5jaEs6/Listeners', 05:30:51 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 05:30:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:30:51 INFO - 'USER': 'cltbld', 05:30:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:30:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:30:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:30:51 INFO - 'XPC_FLAGS': '0x0', 05:30:51 INFO - 'XPC_SERVICE_NAME': '0', 05:30:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:30:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:30:52 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:30:52 INFO - Cleaning up... 05:30:52 INFO - Return code: 0 05:30:52 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:30:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:30:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:30:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:30:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:30:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ebae490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb24570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdcd2496a00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e9c2d30>, '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 0x10e828db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e9bab20>, '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.byhoGIIaHv/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.pjdP5jaEs6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 05:30:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:30:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:30:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.byhoGIIaHv/Render', 05:30:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:30:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:30:52 INFO - 'HOME': '/Users/cltbld', 05:30:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:30:52 INFO - 'LOGNAME': 'cltbld', 05:30:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:30:52 INFO - 'MOZ_NO_REMOTE': '1', 05:30:52 INFO - 'NO_EM_RESTART': '1', 05:30:52 INFO - 'PAGER': '/bin/cat', 05:30:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:30:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:30:52 INFO - 'PWD': '/builds/slave/test', 05:30:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:30:52 INFO - 'SHELL': '/bin/bash', 05:30:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pjdP5jaEs6/Listeners', 05:30:52 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 05:30:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:30:52 INFO - 'USER': 'cltbld', 05:30:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:30:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:30:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:30:52 INFO - 'XPC_FLAGS': '0x0', 05:30:52 INFO - 'XPC_SERVICE_NAME': '0', 05:30:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:30:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:30:52 INFO - Downloading/unpacking blobuploader==1.2.4 05:30:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:30:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:30:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:30:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:30:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:30:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:30:55 INFO - Downloading blobuploader-1.2.4.tar.gz 05:30:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:30:55 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:30:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:30:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:30:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:30:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:30:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:30:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:30:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:30:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:30:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:30:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:30:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:30:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:30:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:30:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:30:56 INFO - Downloading docopt-0.6.1.tar.gz 05:30:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:30:56 INFO - Installing collected packages: blobuploader, requests, docopt 05:30:56 INFO - Running setup.py install for blobuploader 05:30:56 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:30:56 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:30:56 INFO - Running setup.py install for requests 05:30:56 INFO - Running setup.py install for docopt 05:30:57 INFO - Successfully installed blobuploader requests docopt 05:30:57 INFO - Cleaning up... 05:30:57 INFO - Return code: 0 05:30:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:30: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')]} 05:30:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:30:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30: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')]} 05:30:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:30:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ebae490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb24570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdcd2496a00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e9c2d30>, '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 0x10e828db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e9bab20>, '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.byhoGIIaHv/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.pjdP5jaEs6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 05:30:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:30:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:30:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.byhoGIIaHv/Render', 05:30:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:30:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:30:57 INFO - 'HOME': '/Users/cltbld', 05:30:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:30:57 INFO - 'LOGNAME': 'cltbld', 05:30:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:30:57 INFO - 'MOZ_NO_REMOTE': '1', 05:30:57 INFO - 'NO_EM_RESTART': '1', 05:30:57 INFO - 'PAGER': '/bin/cat', 05:30:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:30:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:30:57 INFO - 'PWD': '/builds/slave/test', 05:30:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:30:57 INFO - 'SHELL': '/bin/bash', 05:30:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pjdP5jaEs6/Listeners', 05:30:57 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 05:30:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:30:57 INFO - 'USER': 'cltbld', 05:30:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:30:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:30:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:30:57 INFO - 'XPC_FLAGS': '0x0', 05:30:57 INFO - 'XPC_SERVICE_NAME': '0', 05:30:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:30:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:30:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:30:57 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-trIkIz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:30:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:30:57 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-hYEqes-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:30:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:30:57 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-u6SGQ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:30:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:30:57 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-r8MbTb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:30:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:30:57 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-hrGexg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:30:58 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-AGuzDZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:30:58 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-CchK8Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:30:58 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-7aJnFm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:30:58 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-rFqXwA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:30:58 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-22Ims1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:30:58 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-0U6lk1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:30:58 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-A7lYOO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:30:58 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-iIcyQL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:30:58 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-7lQG3L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:30:58 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-YCEaGZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:30:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:30:59 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-pIFLxj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:30:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:30:59 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-ZDrv9n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:30:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:30:59 INFO - Running setup.py install for manifestparser 05:30:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:30:59 INFO - Running setup.py install for mozcrash 05:30:59 INFO - Running setup.py install for mozdebug 05:30:59 INFO - Running setup.py install for mozdevice 05:30:59 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:30:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:30:59 INFO - Running setup.py install for mozfile 05:31:00 INFO - Running setup.py install for mozhttpd 05:31:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:31:00 INFO - Running setup.py install for mozinfo 05:31:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:31:00 INFO - Running setup.py install for mozInstall 05:31:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:31:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:31:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:31:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:31:00 INFO - Running setup.py install for mozleak 05:31:00 INFO - Running setup.py install for mozlog 05:31:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:31:00 INFO - Running setup.py install for moznetwork 05:31:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:31:00 INFO - Running setup.py install for mozprocess 05:31:01 INFO - Running setup.py install for mozprofile 05:31:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:31:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:31:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:31:01 INFO - Running setup.py install for mozrunner 05:31:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:31:01 INFO - Running setup.py install for mozscreenshot 05:31:01 INFO - Running setup.py install for moztest 05:31:01 INFO - Running setup.py install for mozversion 05:31:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:31:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:31:01 INFO - Cleaning up... 05:31:01 INFO - Return code: 0 05:31:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:31:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:31:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:31:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ebae490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb24570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdcd2496a00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e9c2d30>, '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 0x10e828db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e9bab20>, '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.byhoGIIaHv/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.pjdP5jaEs6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 05:31:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:31:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:31:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.byhoGIIaHv/Render', 05:31:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:31:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:31:01 INFO - 'HOME': '/Users/cltbld', 05:31:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:31:01 INFO - 'LOGNAME': 'cltbld', 05:31:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:01 INFO - 'MOZ_NO_REMOTE': '1', 05:31:01 INFO - 'NO_EM_RESTART': '1', 05:31:01 INFO - 'PAGER': '/bin/cat', 05:31:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:31:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:31:01 INFO - 'PWD': '/builds/slave/test', 05:31:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:31:01 INFO - 'SHELL': '/bin/bash', 05:31:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pjdP5jaEs6/Listeners', 05:31:01 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 05:31:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:31:01 INFO - 'USER': 'cltbld', 05:31:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:31:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:31:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:01 INFO - 'XPC_FLAGS': '0x0', 05:31:01 INFO - 'XPC_SERVICE_NAME': '0', 05:31:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:31:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:31:02 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-mSjLNR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:31:02 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:31:02 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-TKR43Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:31:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:31:02 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-rXzuva-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:31:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:31:02 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-9l6EyE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:31:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:31:02 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-QNplL7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:31:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:31:02 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-eHBI78-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:31:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:31:02 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-OjIVyg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:31:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:31:02 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-N6sTdd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:31:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:31:03 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-KqHMeN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:31:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:31:03 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-zWiJee-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:31:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:31:03 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-YqCbT5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:31:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:31:03 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-gSl3Iq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:31:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:31:03 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-A0WlRb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:31:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:31:03 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-IRDSgH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:31:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:31:03 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-8gtPk0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:31:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:31:03 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-ylWF3x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:31:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:31:03 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-KWVtNF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:31:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:31:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:31:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:31:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:31:04 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:31:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:31:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:31:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:31:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:31:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:31:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:31:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:31:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:31:06 INFO - Downloading blessings-1.6.tar.gz 05:31:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:31:06 INFO - Installing collected packages: blessings 05:31:06 INFO - Running setup.py install for blessings 05:31:06 INFO - Successfully installed blessings 05:31:06 INFO - Cleaning up... 05:31:06 INFO - Return code: 0 05:31:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:31:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:31:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:31:07 INFO - Reading from file tmpfile_stdout 05:31:07 INFO - Current package versions: 05:31:07 INFO - blessings == 1.6 05:31:07 INFO - blobuploader == 1.2.4 05:31:07 INFO - docopt == 0.6.1 05:31:07 INFO - functools32 == 3.2.3-2 05:31:07 INFO - jsonschema == 2.5.1 05:31:07 INFO - manifestparser == 1.1 05:31:07 INFO - mozInstall == 1.12 05:31:07 INFO - mozcrash == 0.17 05:31:07 INFO - mozdebug == 0.1 05:31:07 INFO - mozdevice == 0.48 05:31:07 INFO - mozfile == 1.2 05:31:07 INFO - mozhttpd == 0.7 05:31:07 INFO - mozinfo == 0.9 05:31:07 INFO - mozleak == 0.1 05:31:07 INFO - mozlog == 3.4 05:31:07 INFO - moznetwork == 0.27 05:31:07 INFO - mozprocess == 0.23 05:31:07 INFO - mozprofile == 0.28 05:31:07 INFO - mozrunner == 6.12 05:31:07 INFO - mozscreenshot == 0.1 05:31:07 INFO - mozsystemmonitor == 0.3 05:31:07 INFO - moztest == 0.7 05:31:07 INFO - mozversion == 1.4 05:31:07 INFO - psutil == 3.1.1 05:31:07 INFO - requests == 1.2.3 05:31:07 INFO - wsgiref == 0.1.2 05:31:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:31:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:31:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:31:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ebae490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb24570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdcd2496a00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e9c2d30>, '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 0x10e828db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e9bab20>, '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.byhoGIIaHv/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.pjdP5jaEs6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 05:31:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:31:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:31:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.byhoGIIaHv/Render', 05:31:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:31:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:31:07 INFO - 'HOME': '/Users/cltbld', 05:31:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:31:07 INFO - 'LOGNAME': 'cltbld', 05:31:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:07 INFO - 'MOZ_NO_REMOTE': '1', 05:31:07 INFO - 'NO_EM_RESTART': '1', 05:31:07 INFO - 'PAGER': '/bin/cat', 05:31:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:31:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:31:07 INFO - 'PWD': '/builds/slave/test', 05:31:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:31:07 INFO - 'SHELL': '/bin/bash', 05:31:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pjdP5jaEs6/Listeners', 05:31:07 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 05:31:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:31:07 INFO - 'USER': 'cltbld', 05:31:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:31:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:31:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:07 INFO - 'XPC_FLAGS': '0x0', 05:31:07 INFO - 'XPC_SERVICE_NAME': '0', 05:31:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:31:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:31:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:31:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:31:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:31:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:31:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:31:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:31:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:31:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:31:07 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:31:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:31:07 INFO - Cleaning up... 05:31:07 INFO - Return code: 0 05:31:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:31:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:31:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:31:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:31:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:31:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:31:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ebae490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb24570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdcd2496a00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e9c2d30>, '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 0x10e828db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e9bab20>, '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.byhoGIIaHv/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.pjdP5jaEs6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 05:31:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:31:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:31:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.byhoGIIaHv/Render', 05:31:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:31:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:31:07 INFO - 'HOME': '/Users/cltbld', 05:31:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:31:07 INFO - 'LOGNAME': 'cltbld', 05:31:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:07 INFO - 'MOZ_NO_REMOTE': '1', 05:31:07 INFO - 'NO_EM_RESTART': '1', 05:31:07 INFO - 'PAGER': '/bin/cat', 05:31:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:31:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:31:07 INFO - 'PWD': '/builds/slave/test', 05:31:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:31:07 INFO - 'SHELL': '/bin/bash', 05:31:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pjdP5jaEs6/Listeners', 05:31:07 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 05:31:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:31:07 INFO - 'USER': 'cltbld', 05:31:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:31:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:31:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:07 INFO - 'XPC_FLAGS': '0x0', 05:31:07 INFO - 'XPC_SERVICE_NAME': '0', 05:31:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:31:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:31:07 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:31:07 INFO - Cleaning up... 05:31:07 INFO - Return code: 0 05:31:07 INFO - Running post-action listener: _resource_record_post_action 05:31:07 INFO - Running post-action listener: _start_resource_monitoring 05:31:07 INFO - Starting resource monitoring. 05:31:07 INFO - [mozharness: 2016-11-19 13:31:07.776821Z] Finished create-virtualenv step (success) 05:31:07 INFO - [mozharness: 2016-11-19 13:31:07.777899Z] Running install step. 05:31:07 INFO - Running pre-action listener: _resource_record_pre_action 05:31:07 INFO - Running main action method: install 05:31:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:31:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:31:08 INFO - Reading from file tmpfile_stdout 05:31:08 INFO - Detecting whether we're running mozinstall >=1.0... 05:31:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:31:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:31:08 INFO - Reading from file tmpfile_stdout 05:31:08 INFO - Output received: 05:31:08 INFO - Usage: mozinstall [options] installer 05:31:08 INFO - Options: 05:31:08 INFO - -h, --help show this help message and exit 05:31:08 INFO - -d DEST, --destination=DEST 05:31:08 INFO - Directory to install application into. [default: 05:31:08 INFO - "/builds/slave/test"] 05:31:08 INFO - --app=APP Application being installed. [default: firefox] 05:31:08 INFO - mkdir: /builds/slave/test/build/application 05:31:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:31:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0.en-US.mac.dmg --destination /builds/slave/test/build/application 05:31:29 INFO - Reading from file tmpfile_stdout 05:31:29 INFO - Output received: 05:31:29 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 05:31:29 INFO - Running post-action listener: _resource_record_post_action 05:31:29 INFO - [mozharness: 2016-11-19 13:31:29.765818Z] Finished install step (success) 05:31:29 INFO - [mozharness: 2016-11-19 13:31:29.766024Z] Running run-tests step. 05:31:29 INFO - Running pre-action listener: _resource_record_pre_action 05:31:29 INFO - Running main action method: run_tests 05:31:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:31:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:31:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:31:29 INFO - 2016-11-19 05:31:29.780 screenresolution[2489:86873] starting screenresolution argv=screenresolution get 05:31:29 INFO - 2016-11-19 05:31:29.783 screenresolution[2489:86873] Display 0: 1600x1200x32@60 05:31:29 INFO - 2016-11-19 05:31:29.789 screenresolution[2490:86875] starting screenresolution argv=screenresolution list 05:31:29 INFO - Available Modes on Display 0 05:31:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:31:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:31:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:31:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:31:30 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:31:30 INFO - Intel Iris: 05:31:30 INFO - Chipset Model: Intel Iris 05:31:30 INFO - Type: GPU 05:31:30 INFO - Bus: Built-In 05:31:30 INFO - VRAM (Dynamic, Max): 1536 MB 05:31:30 INFO - Vendor: Intel (0x8086) 05:31:30 INFO - Device ID: 0x0a2e 05:31:30 INFO - Revision ID: 0x0009 05:31:30 INFO - Displays: 05:31:30 INFO - Display: 05:31:30 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:31:30 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:31:30 INFO - Display Serial Number: 1600x1200 60 05:31:30 INFO - Main Display: Yes 05:31:30 INFO - Mirror: Off 05:31:30 INFO - Online: Yes 05:31:30 INFO - Rotation: Supported 05:31:30 INFO - Return code: 0 05:31:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:31:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:31:30 INFO - Python 2.7.3 05:31:30 INFO - Return code: 0 05:31:30 INFO - grabbing minidump binary from tooltool 05:31:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:31:30 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 0x10e9c2d30>, '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 0x10e828db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e9bab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:31:30 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 05:31:30 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 05:31:30 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 05:31:30 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:31:30 INFO - Return code: 0 05:31:30 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:31:30 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:31:30 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:31:30 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:31:30 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:31:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TKFXMxOyQ1eRKg11r00spw/artifacts/public/build/firefox-51.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0236', '--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 05:31:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/TKFXMxOyQ1eRKg11r00spw/artifacts/public/build/firefox-51.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0236 --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 05:31:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.byhoGIIaHv/Render', 05:31:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:31:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:31:30 INFO - 'HOME': '/Users/cltbld', 05:31:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:31:30 INFO - 'LOGNAME': 'cltbld', 05:31:30 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:31:30 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:31:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:31:30 INFO - 'MOZ_NO_REMOTE': '1', 05:31:30 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:31:30 INFO - 'NO_EM_RESTART': '1', 05:31:30 INFO - 'PAGER': '/bin/cat', 05:31:30 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:31:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:31:30 INFO - 'PWD': '/builds/slave/test', 05:31:30 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:31:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:31:30 INFO - 'SHELL': '/bin/bash', 05:31:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pjdP5jaEs6/Listeners', 05:31:30 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 05:31:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:31:30 INFO - 'USER': 'cltbld', 05:31:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:31:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:31:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:31:30 INFO - 'XPC_FLAGS': '0x0', 05:31:30 INFO - 'XPC_SERVICE_NAME': '0', 05:31:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:31:30 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TKFXMxOyQ1eRKg11r00spw/artifacts/public/build/firefox-51.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0236', '--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 05:31:30 INFO - mozversion application_buildid: 20161119030721 05:31:30 INFO - mozversion application_changeset: 1fa9b812e6bd2c6e37963df9d11dfed164700357 05:31:30 INFO - mozversion application_display_name: Firefox 05:31:30 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:31:30 INFO - mozversion application_name: Firefox 05:31:30 INFO - mozversion application_remotingname: firefox 05:31:30 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 05:31:30 INFO - mozversion application_vendor: Mozilla 05:31:30 INFO - mozversion application_version: 51.0 05:31:30 INFO - mozversion platform_buildid: 20161119030721 05:31:30 INFO - mozversion platform_changeset: 1fa9b812e6bd2c6e37963df9d11dfed164700357 05:31:30 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 05:31:30 INFO - mozversion platform_version: 51.0 05:31:30 INFO - using testdate: 1479562290 05:31:30 INFO - actual date: 1479562290 05:31:30 INFO - starting webserver on 'localhost:62220' 05:31:30 INFO - SUITE-START | Running 2 tests 05:31:30 INFO - TEST-START | dromaeo_css 05:31:30 INFO - operating with platform_type : mac_ 05:31:30 INFO - Initialising browser for dromaeo_css test... 05:31:30 INFO - TEST-INFO | started process 2500 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/tmpuKfLTb/profile http://localhost:62220/getInfo.html) 05:31:32 INFO - PROCESS | 2500 | __metrics Screen width/height:1600/1200 05:31:32 INFO - PROCESS | 2500 | colorDepth:24 05:31:32 INFO - PROCESS | 2500 | Browser inner width/height: 1024/689 05:31:32 INFO - PROCESS | 2500 | __metrics 05:31:32 INFO - PROCESS | 2500 | 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" 05:31:32 INFO - PROCESS | 2500 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:31:33 INFO - TEST-INFO | 2500: exit 0 05:31:33 INFO - Browser initialized. 05:31:33 INFO - Running cycle 1/1 for dromaeo_css test... 05:31:33 INFO - TEST-INFO | started process 2502 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/tmpuKfLTb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:31:45 INFO - PROCESS | 2502 | RSS: Main: 168456192 05:31:45 INFO - PROCESS | 2502 | 05:32:36 INFO - PROCESS | 2502 | 1479562356466 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] 05:34:47 INFO - PROCESS | 2502 | Cycle 1(1): loaded http://localhost:62220/tests/dromaeo/cssquery-dojo.html (next: http://localhost:62220/tests/dromaeo/cssquery-ext.html) 05:34:48 INFO - PROCESS | 2502 | RSS: Main: 272678912 05:34:48 INFO - PROCESS | 2502 | 05:37:51 INFO - PROCESS | 2502 | Cycle 1(1): loaded http://localhost:62220/tests/dromaeo/cssquery-ext.html (next: http://localhost:62220/tests/dromaeo/cssquery-jquery.html) 05:37:51 INFO - PROCESS | 2502 | RSS: Main: 299130880 05:37:51 INFO - PROCESS | 2502 | 05:40:54 INFO - PROCESS | 2502 | Cycle 1(1): loaded http://localhost:62220/tests/dromaeo/cssquery-jquery.html (next: http://localhost:62220/tests/dromaeo/cssquery-mootools.html) 05:40:54 INFO - PROCESS | 2502 | RSS: Main: 274452480 05:40:54 INFO - PROCESS | 2502 | 05:43:56 INFO - PROCESS | 2502 | Cycle 1(1): loaded http://localhost:62220/tests/dromaeo/cssquery-mootools.html (next: http://localhost:62220/tests/dromaeo/cssquery-prototype.html) 05:43:57 INFO - PROCESS | 2502 | RSS: Main: 286359552 05:43:57 INFO - PROCESS | 2502 | 05:47:00 INFO - PROCESS | 2502 | Cycle 1(1): loaded http://localhost:62220/tests/dromaeo/cssquery-prototype.html (next: http://localhost:62220/tests/dromaeo/cssquery-yui.html) 05:47:01 INFO - PROCESS | 2502 | RSS: Main: 260198400 05:47:01 INFO - PROCESS | 2502 | 05:50:04 INFO - PROCESS | 2502 | Cycle 1(1): loaded http://localhost:62220/tests/dromaeo/cssquery-yui.html (next: http://localhost:62220/tests/dromaeo/cssquery-dojo.html) 05:50:04 INFO - PROCESS | 2502 | RSS: Main: 434360320 05:50:04 INFO - PROCESS | 2502 | 05:50:04 INFO - PROCESS | 2502 | __start_tp_report 05:50:04 INFO - PROCESS | 2502 | _x_x_mozilla_page_load 05:50:04 INFO - PROCESS | 2502 | _x_x_mozilla_page_load_details 05:50:04 INFO - PROCESS | 2502 | |i|pagename|runs| 05:50:04 INFO - PROCESS | 2502 | |0;dojo.html;2825.2795537138027;3166.366726654669;3387.2040070583416;3388.0513456232266;3454.481827725841;5706.1440783882435;5714.114312281593;5734.971325143379;5746.770129194827;5785.710714464276;4122.769563792709;5722.828315150542;5727.799527016556;5729.484346408823;5783.5373170146395;5154.536091751742;5186.7147306898105;5210.296609957664;5247.976644554314;5341.4925582069645;5152.690838549699;5160.174372100446;5220.373555173384;5268.420473747885;5270.0250453666;5178.093833579127;5216.478352164791;5223.582113430916;5226.738663066851;5226.947730522684;5200.427952925194;5215.374155101412;5223.52988231061;5290.523852853262;5297;5168.302279192299;5226.242194881721;5229.032628963654;5254.710990895499;5294.1529355303355;5612.522935550483;5645.971770141164;5666.291713535808;5670.319561652587;5674;6190.659513726743;6213.192285002966;6213.68931553425;6233.532485063593;6264.718087686065;6328.71520781566;6341.936580634181;6345.460635845912;6362.745490180388;6372.076048972919;5698.088305871082;5732.16883551891;5755.568332375088;5763.538916886639;5845.09401042839;6305.558610897193;6331.841703957346;6335.873282534323;6349.238091428966;6381.202349706286;6092.3603021682975;6120.479759220516;6122.846928826726;6130.080487926757;6140.2017737694705;6105.217149441772;6147.862645410613;6186.226721659793;6240.375962403723;6251.249850018027;6233.2208473940755;6239.656818875005;6265.154204182377;6277.874442511215;6287.402696743802;6218.129461875251;6260.154879091265;6269.435750782452;6300.747970081237;6331.366863313632;4875.366202393666;4932.556069953722;4941.827036053721;5026.271190677331;5035.924461133086;3808.961910380916;4474.306482495218;4522.163399771053;4522.638188944942;4585.747783871919;5803.158542011469;5812;5814.186013958049;5818.981678206297;5827.475527202572;6125.571219981412;6344.365563443619;6347.270063942706;6354.17395738551;6381.649009304533;5613.859653508695;5700.971495142498;5767.596268261265;5771.8557036074435;5855.3851845556455;5431.945680543223;5464.617476776667;5472.945270547323;5473.972630136904;5486.807961721321;6332.430081292706;6358.586691865014;6368.5860419072615;6385.68071596409;6389.2652344980925;6138.75626760427;6149.84625384369;6232.532560057924;6271.122042914087;6300.369963003663;6371.776987805404;6374.394432528902;6383.553151279366;6393.776217832354;6423.293437721753;6241.625502469867;6245.812625621239;6255.311915689362;6270.874582508233;6272.5922815016875;6090.725917333642;6144.648177400964;6239.126522286792;6267.843303917256;6336.7148478319505;6146.678464130234;6192.380761923771;6211.565190436626;6211.875762484816;6212.06818977157;6168.352323006199;6185.505159587312;6186.721597528032;6197.783077592263;6220.72006759706;5795.681237531976;5876.794312199053;5954.732037058429;5994.940050599613;6006;5489.7922457059385;6116.990696535022;6121.785737499166;6177.722002509808;6229.003359462646;6046.818595442144;6047.516198704181;6065;6067.029275315928;6068.423499767515 05:50:04 INFO - PROCESS | 2502 | |1;ext.html;472.7754316699566;500.78716545468166;524.3943245551388;535.7937194180242;546.1970902772925;32580.348393032124;41632;43451.78274108629;43492.912677183085;45460.772696136475;45237.09525809482;45321.32018019724;45687.086258274816;45716.54283457164;45766.77116614412;10084.949575252132;10266.948665256701;10275.332103413291;10277.794444111114;10284.177265818755;9411.858822117674;9537.1893389062;12361.69095772604;12689.492420303177;12731.809022864665;12633.747325053493;12715.046371522172;12728.490860365575;12735;12761.170523915962;12631.810522842165;12714.300713460756;12718.10973231874;12721.809172862368;12729.618111456579;12410.627681169579;12465.937670311681;12466.189697669713;12470.563530276397;12491.312977786218;43095;43259.56740432587;43355.783221084006;43393.78303108496;43429;3409.573803274591;3515.6660117288816;3669.6880765134997;3900.3564411872007;3983.203359328146;32442.026739197863;32478;33160;33323.6667633323;33563;3340.28183940454;3343.548620936167;3405.216800135958;3427.280271143038;3515.613282538944;402870;404280.91438171366;405282.97358513315;407268.96365517983;407283;35086.771962981176;35914.461283080775;65833;66279.66860165668;66371;36962;37076;37087.4436883447;37096.814515927246;37116.62883371186;15989.520314390587;16086;16136;16148.27332770028;16181.838181618268;12500.312482813351;12683.873161268453;13618.046736728533;13715.794263086062;13740.931295343658;7651.961740191375;7698.384129269645;7708.460407771402;7891.250331218525;8138.755837324889;3333.0667413124365;3349.732021438279;3350.731941444679;3353.8826141084946;3362.193073662352;960.7742180587557;992.0971915556809;1047.0785708576404;1048.528162326956;1098.2257508456519;30701;30845;30924.690753092633;31184.688153118634;31560.053198404086;1072.2922870905165;1077.5959015369237;1129.6836885672026;1147.7876592830187;1155.260633194756;14493.275336233146;15995.760063599055;16178.352865885232;16381.59046023835;16536.6692666146;617451;712213;716182;718783.4060829767;720161;61635.691821540604;61669.691651541456;62016.379836201966;62099.6895015522;62135.37864621296;1263.1599986009385;1295.294064734703;1383.087162472763;1403.059949833614;1433.168762117969;30711.925082622;32275;33156.33687326288;33255.50116748202;33266.04081307936;52236.21645675394;52891.47108528866;52969;52983.73508132435;53052.938941220185;481963;482280;483323;484739;485413;24692.382690432165;25324.873375633004;27074.593881091405;27921.860390697915;27923.720762792113;47356.52643473659;49048;49559;50104.248436272755;50469.74765126151;36672.81663591665;38908.6109138905;39121.41317880177;39164.60835391723;40496.78509644715;40543.391849122876;40673.593264068164;40806.18387632172;41319.58680413278;41698.95752606209;40252.79873600613;40537.79731101326;40705.1858962825;40797.59202407938;40945.59054409418;33913;36000.4599930996;36768.26463470769;37083.443748343256;38822.6117738819;19191.90404047971;26872.059477918177;27088.05191818277;27133.457330852878;27409.725902741517 05:50:04 INFO - PROCESS | 2502 | |2;jquery.html;335.28918692372173;364.675438859415;377.96814695880244;385.45073270589404;406.2200574896199;19707.704384434244;20534.48663783404;22310.33069007928;22675.773242267573;23252.65121023186;21570.89214553929;22107.226247081355;22160.445988850246;22353.55292894141;22677.546449071007;5858.73635686394;6311.495080393557;6321.273053598826;6338.936610633904;6362.459190968773;4447.221736196153;4532.546745325474;4555.453345598533;6259.812205633838;6276.748930042816;4517.864464066067;5989.460948514633;6991.790246292594;7058.435325173999;7076.36312731854;6789.185297764275;6971.511994160411;7017.71929122837;7041.57750534967;7044.788656340318;6097.3292937776805;6133.417325354084;6134.785282515135;6149.661768602725;6153.815385538441;27600.033998810148;27750.889964401398;27850.442991140266;27961.580576291373;28264;2129.4995676016124;2488.6142647889606;2652.124798816386;3083.8766449342;3112.1130477813713;10337.173026157889;10381.84427233592;10540.420276884768;10641.840372394421;10807.513661885234;1894.8484121270264;1945.3580318494862;1957.031269521596;1963.4404194804556;1995.451250905998;132427;135520;136937;138115;138678.30660846632;10587.676540432445;10771.515281812337;11722.472488738027;11880;11906;15101.546953591409;15124.54626361189;15125.848741512664;15139.167345795866;15168.544943651488;8974.37179397449;9111.635534578543;9123.635054597875;9148.130927561871;9235.07649235071;9111.81776364469;9124.270058395445;9138.314626403046;9258.67594634198;9304.53477326131;3784.4323351497496;4556.931765549612;4653;4703.247480403187;4735.242361222188;1904.7142928560827;1920.270297287022;1927.4121392974996;1928.3154480159621;1932.5072106613004;863.248973393152;921.9861702074475;925.5048549026374;990.6384169778123;1027.5324727249083;17448.127593620524;22459;22993.42516437069;23823.642645360334;24745.505089898103;956.799072194833;978.2614126334572;994.572333896417;1003.2776400991274;1047.9056884880397;9082;11299.305104234267;11866.991305738937;11888.167828252112;12107.515699371927;661267;664263;676573;676623;677194;122263;138842.3057884704;138926;151998.60983541489;158568;992.9751756206152;1042.718466014172;1053.3890343600692;1055.1716902231865;1057.6086847866338;17453.30186792514;19909.00440109382;20387.898060509604;20831.791682083593;21144.365669029954;59163.112553312596;59693;60368.09447858198;60851.391486086344;61447.385526144164;77266.2273377259;82836.58581707053;84458.57770711106;85663.57168214368;85914;7329.973803667384;7601.505902116345;8319.251267385964;8329.208725171098;8404.53775042354;11752.647420577054;12746.808797867854;12766.936165319114;13224.272665003133;13325.13386629866;11461.484233209692;11478.598249061242;11483.25358851672;11831.46758395857;12003.759924801281;9481.715548533555;11459.942700286445;11712.493491681615;11851.407429628656;11869.762604747684;11637.88362116368;11668.183227174017;11692.766144677229;12151.817722734342;12153.331566763913;6547.574407663486;7400.185979542353;7478.775636730907;7694.384449243935;7720.382369410321;10524.421156836448;11587.942060289644;11771;11782;11880.465379058136 05:50:04 INFO - PROCESS | 2502 | |3;mootools.html;1358.8573199814025;1539.138082673703;1543.6526781474163;1547.2418514927683;1918.3381733302015;8127.634256458458;8692.261157801586;9099.181073703374;9352.672656457025;9355.298352623546;9176;9292.953535232331;9315.767105822359;9345;9359.157675809176;2459.348272707734;2486.5151295497385;2488.4525404411;2490.962635560459;2492;2412.7225369082516;2463.322586288767;2464.963025554618;2466.8889899954456;2469.5184439034397;2216.9691093641454;2364.408897775556;2433.8783060846977;2456.8648724320155;2457.299669594166;2389.139909632531;2410.457647029422;2420.9273721788372;2421.1525965911965;2422.588160012802;2393.246127469841;2416.1905761569897;2420.8305418620703;2421.8425802322795;2425.3087869957153;9224.907750922539;9268.72193834186;9351.672691455835;9356;9363.71908842729;4418.668599854992;5522.751476183581;5527.060399732055;5629.986602411605;5638.182463542763;3026.273694313371;3532.876349327761;3728.5339332583426;3731.589525152231;3732.122951106488;1051.416463862556;1071.6624263357019;1073.7637719701654;1090.9672709818717;1095.309954728524;12351.93824030874;12397.814032789516;12441.129120961627;12459.12786104973;12490.313032783193;4572.199865023607;4644.86065418038;4808.783604737795;4822.662413631012;4830.6135509159185;9617.23062155026;9617.56720947545;9621.855672164924;9630.711078667651;9641.951790241004;5452.454754524595;5454.481824226692;5462.044142275086;5462.317210348707;5476.041692703761;4220.345846393814;4577.702449340849;4735.100330937111;4772.331873537708;5128.282040514331;3972.980135099364;3986.3023970804993;4013.2976729072298;4046.988252936766;4052.371882358239;1041.770810421706;1183.798754211786;1190.0360707826687;1199.0767109325946;1207.29373316609;923.7921467669721;950.8145911547182;952.6046690623314;967.7532229281488;995.7460847483845;5149.7940082397035;5997.220361352996;6015.789447369321;6117.174181485442;6226.40849119333;423.16213896484516;442.42042923769964;442.57070641477713;443.55422800085705;443.86683994802075;4016.5180178378782;4029.5366032906004;4035.031592417857;4081.2653722330265;4168.499780026355;10300.742481438025;11439.027682646902;11582.07343412525;11619.941900290614;11638.76722465546;11527.596534121265;11548.76902461946;11558.07535397183;11562.653120406401;11572.94213528927;8680.739577812676;8688.435251708619;8692;8721.65113395457;8739.169778870997;5798.710064496842;6003.879922401615;6017.488513476404;6163.3528479509005;6208.068789681584;10303.151176705345;10582.153427725609;10724.410157441416;10762.946185269024;10763.677089687322;9727.221822254343;11052.336859788438;11061.778764424505;11067.889321106686;11104.61133860311;6961.686724097326;7075;8202.876956845772;8382.790430239293;8575.142485751376;8521;8586.055533891142;8804.207621314112;8805.735827925173;8828.823423531621;7629.656665450179;7766.10689770661;7833.764987050397;7914.2085791420395;7925.445218834626;6244.843878903063;7555.395568354628;7603.429742769204;7618.5809780462605;7627.8855817161675;7069.434445244471;7090.680919358744;7145.249748776511;7222.277772222736;7309.122905251404;5062.594992400673;5131.3842338918075;5192.1952097424955;5225.555827754683;5254.97372513135;4999.400071991385;5096.923546146737;5135.717535535581;5350.705711185922;5389.595780316414 05:50:04 INFO - PROCESS | 2502 | |4;prototype.html;263.6072252344007;296.97178768017045;298.6729531163376;311.9438501069807;312.3066791722376;4502.4371953505815;4631.842039490128;5478.397376288605;5504.14685723713;5517.475839795213;4285.507166675833;5097.641516503392;5110.156824124015;5386.946130538693;5447.782088716456;4085.7742677196798;4108.979455102735;4127.442795222637;4135.986683262589;4155.522114956773;3617.824207132679;4026.8590599329027;4131.421600975866;4147.937780933288;4168.24971505129;3096.3807238552267;3639.5086663300513;3866.613338666125;4008.879733607982;4088.2845502037026;3518.032541051206;3620.800855952948;3980.144268982154;4092.529359123709;4093.9385909211387;2903.840288784116;3495.930081398383;3501.5272938153535;3502.562179727534;3506.2987402519343;3821.808909554511;4334.761588112652;5098.1842905135;5102.72431892027;5266.657667251617;4169.687273454473;4391.5827996340295;4725.747676865633;4727.929081063787;4730.314104454869;4742.857714268577;5211.635185537014;5667.433256674341;5704.686242256675;5706.457886500776;3878.3600705883496;3878.7394096918615;3894.6300101490597;3906.453096566483;3953.8418463261437;5753.798617048383;6022.307434644984;6032.728527216286;6043.072003197913;6055.788047418319;3582.802945838004;3638.7634803737674;3918.6277303656107;3929.44987701722;3962.821673024721;4024.71826972115;4031.4154447604933;4032.2137183248983;4053.8851815750904;4072.6334629883454;3921.352976758803;4368.956310436918;4644.117617652635;4653.837115700934;4722.70125715431;4944.505549445099;5737.569682273846;5748.425157484218;5762.4525670061275;5768.826935191951;1163.8196079607674;1271.6884363330857;1302.3618426970716;1370.458234486203;1372.7460419822364;754.4153281206999;826.3636999510463;839.8068444257855;873.5935144417483;882.761654353321;3922.1763429680086;4559.586528176276;4601;4614.377059096979;4619.4687610925175;4008.1341840584123;4373.453318335208;4469.994251293433;4904.4605093439695;4952.158133117378;3939.9015024624614;4006.659433948089;4187.764609440223;4347.869563913087;4456.844010459683;2251.8085962693353;2361.7992470639842;2388.9727417210765;2553.323369307135;2598.480303939182;4845.3767987724505;4895.951040489621;5062.772175252123;5089.262057001714;5121.437961421802;4389.0720261752385;4974.651774375831;4983.950160498349;5041.42023667283;5111.718855462986;4370.169667763115;4403.427554417905;4705.388928555385;4926.359573255454;5152.227165925141;4290.978545107255;4890.339804126397;4936.851894443173;4945.653804233669;4950.975245123751;4230.111676547959;4245.490541135084;4586.532309660876;4878.804847806048;4891.01913724942;4506.909861802811;4658.53414658545;4876.341693871282;4933.876653083702;5121.974390128025;2988.548226797965;3099.1787176398725;3419.956238947289;3427.365937301607;3427.9485807713404;3930.744501607386;4391.3193455014525;4901.9509904798115;4939.728314942713;4960.3462147752225;4366.930102124999;4615.0847398329315;4763.951930575268;4781.019890922283;4784.588546378757;4011.6130902113196;4323.96224906014;4777.522247775195;4791.904161916673;4813.350197723263;4212.91574168521;4691.413573303337;4722.598579120813;4738.33663287147;5400.513953744182;4592.578528995919;4649.744264065509;4720.386349774507;5769.653820770767;5800.6809625471005;5660.848877426552;5672.971635141798;5685;5688.971555142198;5706.800261990977 05:50:04 INFO - PROCESS | 2502 | |5;yui.html;651.6350843527623;711.5908352697201;721.4372789224406;728.0971595221927;797.5812698333374;12809.743805123904;16959.57601059974;16966.32134714613;16977.915110424463;17006.91496542517;14564.781528277057;14617.196054217015;14620.853791462083;14621.12273263605;14677.266136693175;708.5890183693458;731.2358585278371;876.8860048193758;900.1088921967238;905.597009330848;879.5690111845182;896.6503063805121;901.3465237702646;901.5402144906112;902.801383695586;963.86987756653;965.4400447740329;966.0484422843529;966.9903300967006;968.2157452463492;954.4273435938451;954.7279025477741;955.5222388805597;958.1328897347911;958.4776296918196;719.4352433339856;719.7912605344444;721.4589058206345;721.7185297733888;723.3562129704515;15218;15574.065556066673;15621.68756624861;15621.765673514907;15634.609134771608;12242.510299588006;15103.39586416542;15111.168885711282;15124.470643527424;15315.770263446057;11753.647390578297;11982.580609678707;12093.758124837543;12222.877771222351;12237.95977341927;3826.3877779555414;4005.899852503711;4014.116894283253;4030.1738143680477;4136.834526618932;119722;122841;123662.38168809099;124369;128216.99999999907;11326.603568875049;11634.243774154822;11662;11700;11748;23878.403039924142;23932.641010384858;23998.280051598478;24065.879670601535;24351;8402.915970840335;8467.364947628952;8512.446690965067;8513.95743021281;8730.69442569507;4586.541345865454;4665.533446655375;4707.150019205696;4743.501932297059;4856.538628830255;3321.78408403453;3430.9656903431146;3533.2756784859043;3629;3636.5272514572935;628.676231740654;641.3970867384737;643.9549231553747;651.3193712570376;657.1063353838775;664.8869692152346;683.2781390894917;684.9246582875879;706.629139469438;723.2984005514643;8349.499030058216;9357.719268421959;10087.495625218857;10138.290319677699;10231.693049208536;582.6940856050608;586.9589128761031;600.8317671052113;693.448708276921;704.1514974455769;3870.496835411435;4964;4979.875503112451;4986.326845875833;4995.70025798446;108153;122153;164738;170256;170737;125387.7461225376;149620;149624;150695;154416;103216;104124;104442.99999999849;104719.4764026175;123314;10002;10351.741206469898;10542.578296868347;10555.736106597396;10605.522751476356;54377;63962.36037639564;64795;66142.00786988295;67100.32899671137;91450.54274728584;99314.5034274824;100852;102743;104599.4770026145;13048.673783155496;13454.262296718514;13718.794218086538;14175.291235438393;14577.708445831237;14688;15224.086554806747;15482.225888705745;15527.76708349353;16688.582785430463;13828.37772300269;13960.930195348958;14224.146551206546;15032.473863414729;15371.923140384673;14351.569452916428;15107.018043827116;15227.93404461677;15430.30563624662;15477.380904763684;13040.546570547447;14468.927655361656;14880;15020.399184032953;15180.24098795078;10986.670399888017;11620.012298954684;11824.822627660764;11928.78988633071;12049.75900481968;10047.598096076075;10454.085977064346;10838.458077096271;11002.213404055798;11962.700932476757 05:50:04 INFO - PROCESS | 2502 | __end_tp_report 05:50:04 INFO - PROCESS | 2502 | __start_cc_report 05:50:04 INFO - PROCESS | 2502 | _x_x_mozilla_cycle_collect,1109 05:50:04 INFO - PROCESS | 2502 | __end_cc_report 05:50:04 INFO - PROCESS | 2502 | __startTimestamp1479563404361__endTimestamp 05:50:04 INFO - PROCESS | 2502 | 05:50:04 INFO - PROCESS | 2502 | ------- Summary: start ------- 05:50:04 INFO - PROCESS | 2502 | Number of tests: 6 05:50:04 INFO - PROCESS | 2502 | 05:50:04 INFO - PROCESS | 2502 | [#0] dojo.html Cycles:165 Average:5764.60 Median:6047.52 stddev:681.09 (11.3%) stddev-sans-first:642.97 05:50:04 INFO - PROCESS | 2502 | Values: 2825.3 3166.4 3387.2 3388.1 3454.5 5706.1 5714.1 5735.0 5746.8 5785.7 4122.8 5722.8 5727.8 5729.5 5783.5 5154.5 5186.7 5210.3 5248.0 5341.5 5152.7 5160.2 5220.4 5268.4 5270.0 5178.1 5216.5 5223.6 5226.7 5226.9 5200.4 5215.4 5223.5 5290.5 5297.0 5168.3 5226.2 5229.0 5254.7 5294.2 5612.5 5646.0 5666.3 5670.3 5674.0 6190.7 6213.2 6213.7 6233.5 6264.7 6328.7 6341.9 6345.5 6362.7 6372.1 5698.1 5732.2 5755.6 5763.5 5845.1 6305.6 6331.8 6335.9 6349.2 6381.2 6092.4 6120.5 6122.8 6130.1 6140.2 6105.2 6147.9 6186.2 6240.4 6251.2 6233.2 6239.7 6265.2 6277.9 6287.4 6218.1 6260.2 6269.4 6300.7 6331.4 4875.4 4932.6 4941.8 5026.3 5035.9 3809.0 4474.3 4522.2 4522.6 4585.7 5803.2 5812.0 5814.2 5819.0 5827.5 6125.6 6344.4 6347.3 6354.2 6381.6 5613.9 5701.0 5767.6 5771.9 5855.4 5431.9 5464.6 5472.9 5474.0 5486.8 6332.4 6358.6 6368.6 6385.7 6389.3 6138.8 6149.8 6232.5 6271.1 6300.4 6371.8 6374.4 6383.6 6393.8 6423.3 6241.6 6245.8 6255.3 6270.9 6272.6 6090.7 6144.6 6239.1 6267.8 6336.7 6146.7 6192.4 6211.6 6211.9 6212.1 6168.4 6185.5 6186.7 6197.8 6220.7 5795.7 5876.8 5954.7 5994.9 6006.0 5489.8 6117.0 6121.8 6177.7 6229.0 6046.8 6047.5 6065.0 6067.0 6068.4 05:50:04 INFO - PROCESS | 2502 | 05:50:04 INFO - PROCESS | 2502 | [#1] ext.html Cycles:180 Average:66773.75 Median:19715.06 stddev:145356.46 (737.3%) stddev-sans-first:145678.98 05:50:04 INFO - PROCESS | 2502 | Values: 472.8 500.8 524.4 535.8 546.2 32580.3 41632.0 43451.8 43492.9 45460.8 45237.1 45321.3 45687.1 45716.5 45766.8 10084.9 10266.9 10275.3 10277.8 10284.2 9411.9 9537.2 12361.7 12689.5 12731.8 12633.7 12715.0 12728.5 12735.0 12761.2 12631.8 12714.3 12718.1 12721.8 12729.6 12410.6 12465.9 12466.2 12470.6 12491.3 43095.0 43259.6 43355.8 43393.8 43429.0 3409.6 3515.7 3669.7 3900.4 3983.2 32442.0 32478.0 33160.0 33323.7 33563.0 3340.3 3343.5 3405.2 3427.3 3515.6 402870.0 404280.9 405283.0 407269.0 407283.0 35086.8 35914.5 65833.0 66279.7 66371.0 36962.0 37076.0 37087.4 37096.8 37116.6 15989.5 16086.0 16136.0 16148.3 16181.8 12500.3 12683.9 13618.0 13715.8 13740.9 7652.0 7698.4 7708.5 7891.3 8138.8 3333.1 3349.7 3350.7 3353.9 3362.2 960.8 992.1 1047.1 1048.5 1098.2 30701.0 30845.0 30924.7 31184.7 31560.1 1072.3 1077.6 1129.7 1147.8 1155.3 14493.3 15995.8 16178.4 16381.6 16536.7 617451.0 712213.0 716182.0 718783.4 720161.0 61635.7 61669.7 62016.4 62099.7 62135.4 1263.2 1295.3 1383.1 1403.1 1433.2 30711.9 32275.0 33156.3 33255.5 33266.0 52236.2 52891.5 52969.0 52983.7 53052.9 481963.0 482280.0 483323.0 484739.0 485413.0 24692.4 25324.9 27074.6 27921.9 27923.7 47356.5 49048.0 49559.0 50104.2 50469.7 36672.8 38908.6 39121.4 39164.6 40496.8 40543.4 40673.6 40806.2 41319.6 41699.0 40252.8 40537.8 40705.2 40797.6 40945.6 33913.0 36000.5 36768.3 37083.4 38822.6 19191.9 26872.1 27088.1 27133.5 27409.7 05:50:04 INFO - PROCESS | 2502 | 05:50:04 INFO - PROCESS | 2502 | [#2] jquery.html Cycles:180 Average:38708.05 Median:13617.06 stddev:112187.47 (823.9%) stddev-sans-first:112465.18 05:50:04 INFO - PROCESS | 2502 | Values: 335.3 364.7 378.0 385.5 406.2 19707.7 20534.5 22310.3 22675.8 23252.7 21570.9 22107.2 22160.4 22353.6 22677.5 5858.7 6311.5 6321.3 6338.9 6362.5 4447.2 4532.5 4555.5 6259.8 6276.7 4517.9 5989.5 6991.8 7058.4 7076.4 6789.2 6971.5 7017.7 7041.6 7044.8 6097.3 6133.4 6134.8 6149.7 6153.8 27600.0 27750.9 27850.4 27961.6 28264.0 2129.5 2488.6 2652.1 3083.9 3112.1 10337.2 10381.8 10540.4 10641.8 10807.5 1894.8 1945.4 1957.0 1963.4 1995.5 132427.0 135520.0 136937.0 138115.0 138678.3 10587.7 10771.5 11722.5 11880.0 11906.0 15101.5 15124.5 15125.8 15139.2 15168.5 8974.4 9111.6 9123.6 9148.1 9235.1 9111.8 9124.3 9138.3 9258.7 9304.5 3784.4 4556.9 4653.0 4703.2 4735.2 1904.7 1920.3 1927.4 1928.3 1932.5 863.2 922.0 925.5 990.6 1027.5 17448.1 22459.0 22993.4 23823.6 24745.5 956.8 978.3 994.6 1003.3 1047.9 9082.0 11299.3 11867.0 11888.2 12107.5 661267.0 664263.0 676573.0 676623.0 677194.0 122263.0 138842.3 138926.0 151998.6 158568.0 993.0 1042.7 1053.4 1055.2 1057.6 17453.3 19909.0 20387.9 20831.8 21144.4 59163.1 59693.0 60368.1 60851.4 61447.4 77266.2 82836.6 84458.6 85663.6 85914.0 7330.0 7601.5 8319.3 8329.2 8404.5 11752.6 12746.8 12766.9 13224.3 13325.1 11461.5 11478.6 11483.3 11831.5 12003.8 9481.7 11459.9 11712.5 11851.4 11869.8 11637.9 11668.2 11692.8 12151.8 12153.3 6547.6 7400.2 7478.8 7694.4 7720.4 10524.4 11587.9 11771.0 11782.0 11880.5 05:50:04 INFO - PROCESS | 2502 | 05:50:04 INFO - PROCESS | 2502 | [#3] mootools.html Cycles:180 Average:5916.58 Median:4790.56 stddev:3429.99 (71.6%) stddev-sans-first:3422.50 05:50:04 INFO - PROCESS | 2502 | Values: 1358.9 1539.1 1543.7 1547.2 1918.3 8127.6 8692.3 9099.2 9352.7 9355.3 9176.0 9293.0 9315.8 9345.0 9359.2 2459.3 2486.5 2488.5 2491.0 2492.0 2412.7 2463.3 2465.0 2466.9 2469.5 2217.0 2364.4 2433.9 2456.9 2457.3 2389.1 2410.5 2420.9 2421.2 2422.6 2393.2 2416.2 2420.8 2421.8 2425.3 9224.9 9268.7 9351.7 9356.0 9363.7 4418.7 5522.8 5527.1 5630.0 5638.2 3026.3 3532.9 3728.5 3731.6 3732.1 1051.4 1071.7 1073.8 1091.0 1095.3 12351.9 12397.8 12441.1 12459.1 12490.3 4572.2 4644.9 4808.8 4822.7 4830.6 9617.2 9617.6 9621.9 9630.7 9642.0 5452.5 5454.5 5462.0 5462.3 5476.0 4220.3 4577.7 4735.1 4772.3 5128.3 3973.0 3986.3 4013.3 4047.0 4052.4 1041.8 1183.8 1190.0 1199.1 1207.3 923.8 950.8 952.6 967.8 995.7 5149.8 5997.2 6015.8 6117.2 6226.4 423.2 442.4 442.6 443.6 443.9 4016.5 4029.5 4035.0 4081.3 4168.5 10300.7 11439.0 11582.1 11619.9 11638.8 11527.6 11548.8 11558.1 11562.7 11572.9 8680.7 8688.4 8692.0 8721.7 8739.2 5798.7 6003.9 6017.5 6163.4 6208.1 10303.2 10582.2 10724.4 10762.9 10763.7 9727.2 11052.3 11061.8 11067.9 11104.6 6961.7 7075.0 8202.9 8382.8 8575.1 8521.0 8586.1 8804.2 8805.7 8828.8 7629.7 7766.1 7833.8 7914.2 7925.4 6244.8 7555.4 7603.4 7618.6 7627.9 7069.4 7090.7 7145.2 7222.3 7309.1 5062.6 5131.4 5192.2 5225.6 5255.0 4999.4 5096.9 5135.7 5350.7 5389.6 05:50:04 INFO - PROCESS | 2502 | 05:50:04 INFO - PROCESS | 2502 | [#4] prototype.html Cycles:180 Average:4202.41 Median:4573.06 stddev:1273.34 (27.8%) stddev-sans-first:1242.12 05:50:04 INFO - PROCESS | 2502 | Values: 263.6 297.0 298.7 311.9 312.3 4502.4 4631.8 5478.4 5504.1 5517.5 4285.5 5097.6 5110.2 5386.9 5447.8 4085.8 4109.0 4127.4 4136.0 4155.5 3617.8 4026.9 4131.4 4147.9 4168.2 3096.4 3639.5 3866.6 4008.9 4088.3 3518.0 3620.8 3980.1 4092.5 4093.9 2903.8 3495.9 3501.5 3502.6 3506.3 3821.8 4334.8 5098.2 5102.7 5266.7 4169.7 4391.6 4725.7 4727.9 4730.3 4742.9 5211.6 5667.4 5704.7 5706.5 3878.4 3878.7 3894.6 3906.5 3953.8 5753.8 6022.3 6032.7 6043.1 6055.8 3582.8 3638.8 3918.6 3929.4 3962.8 4024.7 4031.4 4032.2 4053.9 4072.6 3921.4 4369.0 4644.1 4653.8 4722.7 4944.5 5737.6 5748.4 5762.5 5768.8 1163.8 1271.7 1302.4 1370.5 1372.7 754.4 826.4 839.8 873.6 882.8 3922.2 4559.6 4601.0 4614.4 4619.5 4008.1 4373.5 4470.0 4904.5 4952.2 3939.9 4006.7 4187.8 4347.9 4456.8 2251.8 2361.8 2389.0 2553.3 2598.5 4845.4 4896.0 5062.8 5089.3 5121.4 4389.1 4974.7 4984.0 5041.4 5111.7 4370.2 4403.4 4705.4 4926.4 5152.2 4291.0 4890.3 4936.9 4945.7 4951.0 4230.1 4245.5 4586.5 4878.8 4891.0 4506.9 4658.5 4876.3 4933.9 5122.0 2988.5 3099.2 3420.0 3427.4 3427.9 3930.7 4391.3 4902.0 4939.7 4960.3 4366.9 4615.1 4764.0 4781.0 4784.6 4011.6 4324.0 4777.5 4791.9 4813.4 4212.9 4691.4 4722.6 4738.3 5400.5 4592.6 4649.7 4720.4 5769.7 5800.7 5660.8 5673.0 5685.0 5689.0 5706.8 05:50:04 INFO - PROCESS | 2502 | 05:50:04 INFO - PROCESS | 2502 | [#5] yui.html Cycles:180 Average:26253.78 Median:16962.95 stddev:42116.06 (248.3%) stddev-sans-first:42190.33 05:50:04 INFO - PROCESS | 2502 | Values: 651.6 711.6 721.4 728.1 797.6 12809.7 16959.6 16966.3 16977.9 17006.9 14564.8 14617.2 14620.9 14621.1 14677.3 708.6 731.2 876.9 900.1 905.6 879.6 896.7 901.3 901.5 902.8 963.9 965.4 966.0 967.0 968.2 954.4 954.7 955.5 958.1 958.5 719.4 719.8 721.5 721.7 723.4 15218.0 15574.1 15621.7 15621.8 15634.6 12242.5 15103.4 15111.2 15124.5 15315.8 11753.6 11982.6 12093.8 12222.9 12238.0 3826.4 4005.9 4014.1 4030.2 4136.8 119722.0 122841.0 123662.4 124369.0 128217.0 11326.6 11634.2 11662.0 11700.0 11748.0 23878.4 23932.6 23998.3 24065.9 24351.0 8402.9 8467.4 8512.4 8514.0 8730.7 4586.5 4665.5 4707.2 4743.5 4856.5 3321.8 3431.0 3533.3 3629.0 3636.5 628.7 641.4 644.0 651.3 657.1 664.9 683.3 684.9 706.6 723.3 8349.5 9357.7 10087.5 10138.3 10231.7 582.7 587.0 600.8 693.4 704.2 3870.5 4964.0 4979.9 4986.3 4995.7 108153.0 122153.0 164738.0 170256.0 170737.0 125387.7 149620.0 149624.0 150695.0 154416.0 103216.0 104124.0 104443.0 104719.5 123314.0 10002.0 10351.7 10542.6 10555.7 10605.5 54377.0 63962.4 64795.0 66142.0 67100.3 91450.5 99314.5 100852.0 102743.0 104599.5 13048.7 13454.3 13718.8 14175.3 14577.7 14688.0 15224.1 15482.2 15527.8 16688.6 13828.4 13960.9 14224.1 15032.5 15371.9 14351.6 15107.0 15227.9 15430.3 15477.4 13040.5 14468.9 14880.0 15020.4 15180.2 10986.7 11620.0 11824.8 11928.8 12049.8 10047.6 10454.1 10838.5 11002.2 11962.7 05:50:04 INFO - PROCESS | 2502 | -------- Summary: end -------- 05:50:04 INFO - PROCESS | 2502 | 05:50:05 INFO - TEST-INFO | 2502: exit 0 05:50:05 INFO - TEST-OK | dromaeo_css | took 1114673ms 05:50:05 INFO - TEST-START | kraken 05:50:05 INFO - operating with platform_type : mac_ 05:50:05 INFO - Initialising browser for kraken test... 05:50:05 INFO - TEST-INFO | started process 2505 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/tmpFdGdZX/profile http://localhost:62220/getInfo.html) 05:50:07 INFO - PROCESS | 2505 | __metrics Screen width/height:1600/1200 05:50:07 INFO - PROCESS | 2505 | colorDepth:24 05:50:07 INFO - PROCESS | 2505 | Browser inner width/height: 1024/689 05:50:07 INFO - PROCESS | 2505 | __metrics 05:50:07 INFO - PROCESS | 2505 | 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" 05:50:07 INFO - PROCESS | 2505 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:50:07 INFO - TEST-INFO | 2505: exit 0 05:50:07 INFO - Browser initialized. 05:50:07 INFO - Running cycle 1/1 for kraken test... 05:50:07 INFO - TEST-INFO | started process 2507 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/tmpFdGdZX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:50:20 INFO - PROCESS | 2507 | RSS: Main: 168566784 05:50:20 INFO - PROCESS | 2507 | 05:50:22 INFO - PROCESS | 2507 | Cycle 1(1): loaded http://localhost:62220/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:62220/tests/kraken/driver.html?testName=audio-beat-detection) 05:50:23 INFO - PROCESS | 2507 | RSS: Main: 218107904 05:50:23 INFO - PROCESS | 2507 | 05:50:26 INFO - PROCESS | 2507 | Cycle 1(1): loaded http://localhost:62220/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:62220/tests/kraken/driver.html?testName=audio-dft) 05:50:26 INFO - PROCESS | 2507 | RSS: Main: 228831232 05:50:26 INFO - PROCESS | 2507 | 05:50:29 INFO - PROCESS | 2507 | Cycle 1(1): loaded http://localhost:62220/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:62220/tests/kraken/driver.html?testName=audio-fft) 05:50:30 INFO - PROCESS | 2507 | RSS: Main: 326242304 05:50:30 INFO - PROCESS | 2507 | 05:50:32 INFO - PROCESS | 2507 | Cycle 1(1): loaded http://localhost:62220/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:62220/tests/kraken/driver.html?testName=audio-oscillator) 05:50:33 INFO - PROCESS | 2507 | RSS: Main: 228229120 05:50:33 INFO - PROCESS | 2507 | 05:50:35 INFO - PROCESS | 2507 | Cycle 1(1): loaded http://localhost:62220/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:62220/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:50:35 INFO - PROCESS | 2507 | RSS: Main: 229703680 05:50:35 INFO - PROCESS | 2507 | 05:50:38 INFO - PROCESS | 2507 | Cycle 1(1): loaded http://localhost:62220/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:62220/tests/kraken/driver.html?testName=imaging-darkroom) 05:50:39 INFO - PROCESS | 2507 | RSS: Main: 265781248 05:50:39 INFO - PROCESS | 2507 | 05:50:42 INFO - PROCESS | 2507 | Cycle 1(1): loaded http://localhost:62220/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:62220/tests/kraken/driver.html?testName=imaging-desaturate) 05:50:42 INFO - PROCESS | 2507 | RSS: Main: 266059776 05:50:42 INFO - PROCESS | 2507 | 05:50:45 INFO - PROCESS | 2507 | Cycle 1(1): loaded http://localhost:62220/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:62220/tests/kraken/driver.html?testName=json-parse-financial) 05:50:46 INFO - PROCESS | 2507 | RSS: Main: 264253440 05:50:46 INFO - PROCESS | 2507 | 05:50:47 INFO - PROCESS | 2507 | Cycle 1(1): loaded http://localhost:62220/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:62220/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:50:48 INFO - PROCESS | 2507 | RSS: Main: 240762880 05:50:48 INFO - PROCESS | 2507 | 05:50:50 INFO - PROCESS | 2507 | Cycle 1(1): loaded http://localhost:62220/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:62220/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:50:50 INFO - PROCESS | 2507 | RSS: Main: 245342208 05:50:50 INFO - PROCESS | 2507 | 05:50:52 INFO - PROCESS | 2507 | Cycle 1(1): loaded http://localhost:62220/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:62220/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:50:53 INFO - PROCESS | 2507 | RSS: Main: 249335808 05:50:53 INFO - PROCESS | 2507 | 05:50:56 INFO - PROCESS | 2507 | Cycle 1(1): loaded http://localhost:62220/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:62220/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:50:57 INFO - PROCESS | 2507 | RSS: Main: 258609152 05:50:57 INFO - PROCESS | 2507 | 05:50:59 INFO - PROCESS | 2507 | Cycle 1(1): loaded http://localhost:62220/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:62220/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:51:00 INFO - PROCESS | 2507 | RSS: Main: 259379200 05:51:00 INFO - PROCESS | 2507 | 05:51:02 INFO - PROCESS | 2507 | Cycle 1(1): loaded http://localhost:62220/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:62220/tests/kraken/driver.html?testName=ai-astar) 05:51:02 INFO - PROCESS | 2507 | RSS: Main: 303493120 05:51:02 INFO - PROCESS | 2507 | 05:51:02 INFO - PROCESS | 2507 | __start_tp_report 05:51:02 INFO - PROCESS | 2507 | _x_x_mozilla_page_load 05:51:02 INFO - PROCESS | 2507 | _x_x_mozilla_page_load_details 05:51:02 INFO - PROCESS | 2507 | |i|pagename|runs| 05:51:02 INFO - PROCESS | 2507 | |0;ai-astar;114;112;113;112;114;114;111;112;111;111 05:51:02 INFO - PROCESS | 2507 | |1;audio-beat-detection;144;120;140;169;108;110;105;107;138;112 05:51:02 INFO - PROCESS | 2507 | |2;audio-dft;185;185;186;183;183;184;184;185;185;184 05:51:02 INFO - PROCESS | 2507 | |3;audio-fft;83;59;58;90;61;57;59;57;80;57 05:51:02 INFO - PROCESS | 2507 | |4;audio-oscillator;83;85;124;100;67;70;90;67;69;89 05:51:02 INFO - PROCESS | 2507 | |5;imaging-gaussian-blur;89;85;89;85;88;85;90;86;88;85 05:51:02 INFO - PROCESS | 2507 | |6;imaging-darkroom;108;149;148;93;91;93;93;93;92;93 05:51:02 INFO - PROCESS | 2507 | |7;imaging-desaturate;86;85;86;85;85;85;85;84;86;87 05:51:02 INFO - PROCESS | 2507 | |8;json-parse-financial;55;52;51;51;55;54;57;58;56;56 05:51:02 INFO - PROCESS | 2507 | |9;json-stringify-tinderbox;64;75;79;75;72;69;69;70;71;72 05:51:02 INFO - PROCESS | 2507 | |10;stanford-crypto-aes;83;84;83;82;84;86;86;88;100;89 05:51:02 INFO - PROCESS | 2507 | |11;stanford-crypto-ccm;152;151;132;133;132;138;147;133;159;152 05:51:02 INFO - PROCESS | 2507 | |12;stanford-crypto-pbkdf2;208;160;157;133;166;222;136;196;221;161 05:51:02 INFO - PROCESS | 2507 | |13;stanford-crypto-sha256-iterative;67;67;66;66;65;67;65;68;74;82 05:51:02 INFO - PROCESS | 2507 | __end_tp_report 05:51:02 INFO - PROCESS | 2507 | __start_cc_report 05:51:02 INFO - PROCESS | 2507 | _x_x_mozilla_cycle_collect,1858 05:51:02 INFO - PROCESS | 2507 | __end_cc_report 05:51:02 INFO - PROCESS | 2507 | __startTimestamp1479563462592__endTimestamp 05:51:02 INFO - PROCESS | 2507 | 05:51:02 INFO - PROCESS | 2507 | ------- Summary: start ------- 05:51:02 INFO - PROCESS | 2507 | Number of tests: 14 05:51:02 INFO - PROCESS | 2507 | 05:51:02 INFO - PROCESS | 2507 | [#0] ai-astar Cycles:10 Average:112.40 Median:112.50 stddev:1.26 (1.1%) stddev-sans-first:1.20 05:51:02 INFO - PROCESS | 2507 | Values: 114.0 112.0 113.0 112.0 114.0 114.0 111.0 112.0 111.0 111.0 05:51:02 INFO - PROCESS | 2507 | 05:51:02 INFO - PROCESS | 2507 | [#1] audio-beat-detection Cycles:10 Average:125.30 Median:129.00 stddev:21.40 (16.6%) stddev-sans-first:21.60 05:51:02 INFO - PROCESS | 2507 | Values: 144.0 120.0 140.0 169.0 108.0 110.0 105.0 107.0 138.0 112.0 05:51:02 INFO - PROCESS | 2507 | 05:51:02 INFO - PROCESS | 2507 | [#2] audio-dft Cycles:10 Average:184.40 Median:185.00 stddev:0.97 (0.5%) stddev-sans-first:1.00 05:51:02 INFO - PROCESS | 2507 | Values: 185.0 185.0 186.0 183.0 183.0 184.0 184.0 185.0 185.0 184.0 05:51:02 INFO - PROCESS | 2507 | 05:51:02 INFO - PROCESS | 2507 | [#3] audio-fft Cycles:10 Average:66.10 Median:60.00 stddev:12.87 (21.5%) stddev-sans-first:12.11 05:51:02 INFO - PROCESS | 2507 | Values: 83.0 59.0 58.0 90.0 61.0 57.0 59.0 57.0 80.0 57.0 05:51:02 INFO - PROCESS | 2507 | 05:51:02 INFO - PROCESS | 2507 | [#4] audio-oscillator Cycles:10 Average:84.40 Median:76.50 stddev:18.00 (23.5%) stddev-sans-first:19.09 05:51:02 INFO - PROCESS | 2507 | Values: 83.0 85.0 124.0 100.0 67.0 70.0 90.0 67.0 69.0 89.0 05:51:02 INFO - PROCESS | 2507 | 05:51:02 INFO - PROCESS | 2507 | [#5] imaging-gaussian-blur Cycles:10 Average:87.00 Median:88.00 stddev:2.00 (2.3%) stddev-sans-first:1.99 05:51:02 INFO - PROCESS | 2507 | Values: 89.0 85.0 89.0 85.0 88.0 85.0 90.0 86.0 88.0 85.0 05:51:02 INFO - PROCESS | 2507 | 05:51:02 INFO - PROCESS | 2507 | [#6] imaging-darkroom Cycles:10 Average:105.30 Median:93.00 stddev:23.28 (25.0%) stddev-sans-first:24.67 05:51:02 INFO - PROCESS | 2507 | Values: 108.0 149.0 148.0 93.0 91.0 93.0 93.0 93.0 92.0 93.0 05:51:02 INFO - PROCESS | 2507 | 05:51:02 INFO - PROCESS | 2507 | [#7] imaging-desaturate Cycles:10 Average:85.40 Median:85.50 stddev:0.84 (1.0%) stddev-sans-first:0.87 05:51:02 INFO - PROCESS | 2507 | Values: 86.0 85.0 86.0 85.0 85.0 85.0 85.0 84.0 86.0 87.0 05:51:02 INFO - PROCESS | 2507 | 05:51:02 INFO - PROCESS | 2507 | [#8] json-parse-financial Cycles:10 Average:54.50 Median:55.50 stddev:2.46 (4.4%) stddev-sans-first:2.60 05:51:02 INFO - PROCESS | 2507 | Values: 55.0 52.0 51.0 51.0 55.0 54.0 57.0 58.0 56.0 56.0 05:51:02 INFO - PROCESS | 2507 | 05:51:02 INFO - PROCESS | 2507 | [#9] json-stringify-tinderbox Cycles:10 Average:71.60 Median:72.00 stddev:4.12 (5.7%) stddev-sans-first:3.32 05:51:02 INFO - PROCESS | 2507 | Values: 64.0 75.0 79.0 75.0 72.0 69.0 69.0 70.0 71.0 72.0 05:51:02 INFO - PROCESS | 2507 | 05:51:02 INFO - PROCESS | 2507 | [#10] stanford-crypto-aes Cycles:10 Average:86.50 Median:85.00 stddev:5.25 (6.2%) stddev-sans-first:5.42 05:51:02 INFO - PROCESS | 2507 | Values: 83.0 84.0 83.0 82.0 84.0 86.0 86.0 88.0 100.0 89.0 05:51:02 INFO - PROCESS | 2507 | 05:51:02 INFO - PROCESS | 2507 | [#11] stanford-crypto-ccm Cycles:10 Average:142.90 Median:149.00 stddev:10.35 (6.9%) stddev-sans-first:10.45 05:51:02 INFO - PROCESS | 2507 | Values: 152.0 151.0 132.0 133.0 132.0 138.0 147.0 133.0 159.0 152.0 05:51:02 INFO - PROCESS | 2507 | 05:51:02 INFO - PROCESS | 2507 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.00 Median:181.00 stddev:33.26 (18.4%) stddev-sans-first:33.20 05:51:02 INFO - PROCESS | 2507 | Values: 208.0 160.0 157.0 133.0 166.0 222.0 136.0 196.0 221.0 161.0 05:51:02 INFO - PROCESS | 2507 | 05:51:02 INFO - PROCESS | 2507 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.70 Median:67.00 stddev:5.33 (8.0%) stddev-sans-first:5.62 05:51:02 INFO - PROCESS | 2507 | Values: 67.0 67.0 66.0 66.0 65.0 67.0 65.0 68.0 74.0 82.0 05:51:02 INFO - PROCESS | 2507 | -------- Summary: end -------- 05:51:02 INFO - PROCESS | 2507 | 05:51:03 INFO - TEST-INFO | 2507: exit 0 05:51:03 INFO - TEST-OK | kraken | took 58344ms 05:51:03 INFO - SUITE-END | took 1173s 05:51:03 INFO - Completed test suite (00:19:33) 05:51:03 INFO - javascript benchmark 05:51:03 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2825.2795537138027, 3166.366726654669, 3387.2040070583416, 3388.0513456232266, 3454.481827725841, 5706.1440783882435, 5714.114312281593, 5734.971325143379, 5746.770129194827, 5785.710714464276, 4122.769563792709, 5722.828315150542, 5727.799527016556, 5729.484346408823, 5783.5373170146395, 5154.536091751742, 5186.7147306898105, 5210.296609957664, 5247.976644554314, 5341.4925582069645, 5152.690838549699, 5160.174372100446, 5220.373555173384, 5268.420473747885, 5270.0250453666, 5178.093833579127, 5216.478352164791, 5223.582113430916, 5226.738663066851, 5226.947730522684, 5200.427952925194, 5215.374155101412, 5223.52988231061, 5290.523852853262, 5297.0, 5168.302279192299, 5226.242194881721, 5229.032628963654, 5254.710990895499, 5294.1529355303355, 5612.522935550483, 5645.971770141164, 5666.291713535808, 5670.319561652587, 5674.0, 6190.659513726743, 6213.192285002966, 6213.68931553425, 6233.532485063593, 6264.718087686065, 6328.71520781566, 6341.936580634181, 6345.460635845912, 6362.745490180388, 6372.076048972919, 5698.088305871082, 5732.16883551891, 5755.568332375088, 5763.538916886639, 5845.09401042839, 6305.558610897193, 6331.841703957346, 6335.873282534323, 6349.238091428966, 6381.202349706286, 6092.3603021682975, 6120.479759220516, 6122.846928826726, 6130.080487926757, 6140.2017737694705, 6105.217149441772, 6147.862645410613, 6186.226721659793, 6240.375962403723, 6251.249850018027, 6233.2208473940755, 6239.656818875005, 6265.154204182377, 6277.874442511215, 6287.402696743802, 6218.129461875251, 6260.154879091265, 6269.435750782452, 6300.747970081237, 6331.366863313632, 4875.366202393666, 4932.556069953722, 4941.827036053721, 5026.271190677331, 5035.924461133086, 3808.961910380916, 4474.306482495218, 4522.163399771053, 4522.638188944942, 4585.747783871919, 5803.158542011469, 5812.0, 5814.186013958049, 5818.981678206297, 5827.475527202572, 6125.571219981412, 6344.365563443619, 6347.270063942706, 6354.17395738551, 6381.649009304533, 5613.859653508695, 5700.971495142498, 5767.596268261265, 5771.8557036074435, 5855.3851845556455, 5431.945680543223, 5464.617476776667, 5472.945270547323, 5473.972630136904, 5486.807961721321, 6332.430081292706, 6358.586691865014, 6368.5860419072615, 6385.68071596409, 6389.2652344980925, 6138.75626760427, 6149.84625384369, 6232.532560057924, 6271.122042914087, 6300.369963003663, 6371.776987805404, 6374.394432528902, 6383.553151279366, 6393.776217832354, 6423.293437721753, 6241.625502469867, 6245.812625621239, 6255.311915689362, 6270.874582508233, 6272.5922815016875, 6090.725917333642, 6144.648177400964, 6239.126522286792, 6267.843303917256, 6336.7148478319505, 6146.678464130234, 6192.380761923771, 6211.565190436626, 6211.875762484816, 6212.06818977157, 6168.352323006199, 6185.505159587312, 6186.721597528032, 6197.783077592263, 6220.72006759706, 5795.681237531976, 5876.794312199053, 5954.732037058429, 5994.940050599613, 6006.0, 5489.7922457059385, 6116.990696535022, 6121.785737499166, 6177.722002509808, 6229.003359462646, 6046.818595442144, 6047.516198704181, 6065.0, 6067.029275315928, 6068.423499767515], "value": 5718.409080894288, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [472.7754316699566, 500.78716545468166, 524.3943245551388, 535.7937194180242, 546.1970902772925, 32580.348393032124, 41632.0, 43451.78274108629, 43492.912677183085, 45460.772696136475, 45237.09525809482, 45321.32018019724, 45687.086258274816, 45716.54283457164, 45766.77116614412, 10084.949575252132, 10266.948665256701, 10275.332103413291, 10277.794444111114, 10284.177265818755, 9411.858822117674, 9537.1893389062, 12361.69095772604, 12689.492420303177, 12731.809022864665, 12633.747325053493, 12715.046371522172, 12728.490860365575, 12735.0, 12761.170523915962, 12631.810522842165, 12714.300713460756, 12718.10973231874, 12721.809172862368, 12729.618111456579, 12410.627681169579, 12465.937670311681, 12466.189697669713, 12470.563530276397, 12491.312977786218, 43095.0, 43259.56740432587, 43355.783221084006, 43393.78303108496, 43429.0, 3409.573803274591, 3515.6660117288816, 3669.6880765134997, 3900.3564411872007, 3983.203359328146, 32442.026739197863, 32478.0, 33160.0, 33323.6667633323, 33563.0, 3340.28183940454, 3343.548620936167, 3405.216800135958, 3427.280271143038, 3515.613282538944, 402870.0, 404280.91438171366, 405282.97358513315, 407268.96365517983, 407283.0, 35086.771962981176, 35914.461283080775, 65833.0, 66279.66860165668, 66371.0, 36962.0, 37076.0, 37087.4436883447, 37096.814515927246, 37116.62883371186, 15989.520314390587, 16086.0, 16136.0, 16148.27332770028, 16181.838181618268, 12500.312482813351, 12683.873161268453, 13618.046736728533, 13715.794263086062, 13740.931295343658, 7651.961740191375, 7698.384129269645, 7708.460407771402, 7891.250331218525, 8138.755837324889, 3333.0667413124365, 3349.732021438279, 3350.731941444679, 3353.8826141084946, 3362.193073662352, 960.7742180587557, 992.0971915556809, 1047.0785708576404, 1048.528162326956, 1098.2257508456519, 30701.0, 30845.0, 30924.690753092633, 31184.688153118634, 31560.053198404086, 1072.2922870905165, 1077.5959015369237, 1129.6836885672026, 1147.7876592830187, 1155.260633194756, 14493.275336233146, 15995.760063599055, 16178.352865885232, 16381.59046023835, 16536.6692666146, 617451.0, 712213.0, 716182.0, 718783.4060829767, 720161.0, 61635.691821540604, 61669.691651541456, 62016.379836201966, 62099.6895015522, 62135.37864621296, 1263.1599986009385, 1295.294064734703, 1383.087162472763, 1403.059949833614, 1433.168762117969, 30711.925082622, 32275.0, 33156.33687326288, 33255.50116748202, 33266.04081307936, 52236.21645675394, 52891.47108528866, 52969.0, 52983.73508132435, 53052.938941220185, 481963.0, 482280.0, 483323.0, 484739.0, 485413.0, 24692.382690432165, 25324.873375633004, 27074.593881091405, 27921.860390697915, 27923.720762792113, 47356.52643473659, 49048.0, 49559.0, 50104.248436272755, 50469.74765126151, 36672.81663591665, 38908.6109138905, 39121.41317880177, 39164.60835391723, 40496.78509644715, 40543.391849122876, 40673.593264068164, 40806.18387632172, 41319.58680413278, 41698.95752606209, 40252.79873600613, 40537.79731101326, 40705.1858962825, 40797.59202407938, 40945.59054409418, 33913.0, 36000.4599930996, 36768.26463470769, 37083.443748343256, 38822.6117738819, 19191.90404047971, 26872.059477918177, 27088.05191818277, 27133.457330852878, 27409.725902741517], "value": 19710.184371353105, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [335.28918692372173, 364.675438859415, 377.96814695880244, 385.45073270589404, 406.2200574896199, 19707.704384434244, 20534.48663783404, 22310.33069007928, 22675.773242267573, 23252.65121023186, 21570.89214553929, 22107.226247081355, 22160.445988850246, 22353.55292894141, 22677.546449071007, 5858.73635686394, 6311.495080393557, 6321.273053598826, 6338.936610633904, 6362.459190968773, 4447.221736196153, 4532.546745325474, 4555.453345598533, 6259.812205633838, 6276.748930042816, 4517.864464066067, 5989.460948514633, 6991.790246292594, 7058.435325173999, 7076.36312731854, 6789.185297764275, 6971.511994160411, 7017.71929122837, 7041.57750534967, 7044.788656340318, 6097.3292937776805, 6133.417325354084, 6134.785282515135, 6149.661768602725, 6153.815385538441, 27600.033998810148, 27750.889964401398, 27850.442991140266, 27961.580576291373, 28264.0, 2129.4995676016124, 2488.6142647889606, 2652.124798816386, 3083.8766449342, 3112.1130477813713, 10337.173026157889, 10381.84427233592, 10540.420276884768, 10641.840372394421, 10807.513661885234, 1894.8484121270264, 1945.3580318494862, 1957.031269521596, 1963.4404194804556, 1995.451250905998, 132427.0, 135520.0, 136937.0, 138115.0, 138678.30660846632, 10587.676540432445, 10771.515281812337, 11722.472488738027, 11880.0, 11906.0, 15101.546953591409, 15124.54626361189, 15125.848741512664, 15139.167345795866, 15168.544943651488, 8974.37179397449, 9111.635534578543, 9123.635054597875, 9148.130927561871, 9235.07649235071, 9111.81776364469, 9124.270058395445, 9138.314626403046, 9258.67594634198, 9304.53477326131, 3784.4323351497496, 4556.931765549612, 4653.0, 4703.247480403187, 4735.242361222188, 1904.7142928560827, 1920.270297287022, 1927.4121392974996, 1928.3154480159621, 1932.5072106613004, 863.248973393152, 921.9861702074475, 925.5048549026374, 990.6384169778123, 1027.5324727249083, 17448.127593620524, 22459.0, 22993.42516437069, 23823.642645360334, 24745.505089898103, 956.799072194833, 978.2614126334572, 994.572333896417, 1003.2776400991274, 1047.9056884880397, 9082.0, 11299.305104234267, 11866.991305738937, 11888.167828252112, 12107.515699371927, 661267.0, 664263.0, 676573.0, 676623.0, 677194.0, 122263.0, 138842.3057884704, 138926.0, 151998.60983541489, 158568.0, 992.9751756206152, 1042.718466014172, 1053.3890343600692, 1055.1716902231865, 1057.6086847866338, 17453.30186792514, 19909.00440109382, 20387.898060509604, 20831.791682083593, 21144.365669029954, 59163.112553312596, 59693.0, 60368.09447858198, 60851.391486086344, 61447.385526144164, 77266.2273377259, 82836.58581707053, 84458.57770711106, 85663.57168214368, 85914.0, 7329.973803667384, 7601.505902116345, 8319.251267385964, 8329.208725171098, 8404.53775042354, 11752.647420577054, 12746.808797867854, 12766.936165319114, 13224.272665003133, 13325.13386629866, 11461.484233209692, 11478.598249061242, 11483.25358851672, 11831.46758395857, 12003.759924801281, 9481.715548533555, 11459.942700286445, 11712.493491681615, 11851.407429628656, 11869.762604747684, 11637.88362116368, 11668.183227174017, 11692.766144677229, 12151.817722734342, 12153.331566763913, 6547.574407663486, 7400.185979542353, 7478.775636730907, 7694.384449243935, 7720.382369410321, 10524.421156836448, 11587.942060289644, 11771.0, 11782.0, 11880.465379058136], "value": 10077.652205008499, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1358.8573199814025, 1539.138082673703, 1543.6526781474163, 1547.2418514927683, 1918.3381733302015, 8127.634256458458, 8692.261157801586, 9099.181073703374, 9352.672656457025, 9355.298352623546, 9176.0, 9292.953535232331, 9315.767105822359, 9345.0, 9359.157675809176, 2459.348272707734, 2486.5151295497385, 2488.4525404411, 2490.962635560459, 2492.0, 2412.7225369082516, 2463.322586288767, 2464.963025554618, 2466.8889899954456, 2469.5184439034397, 2216.9691093641454, 2364.408897775556, 2433.8783060846977, 2456.8648724320155, 2457.299669594166, 2389.139909632531, 2410.457647029422, 2420.9273721788372, 2421.1525965911965, 2422.588160012802, 2393.246127469841, 2416.1905761569897, 2420.8305418620703, 2421.8425802322795, 2425.3087869957153, 9224.907750922539, 9268.72193834186, 9351.672691455835, 9356.0, 9363.71908842729, 4418.668599854992, 5522.751476183581, 5527.060399732055, 5629.986602411605, 5638.182463542763, 3026.273694313371, 3532.876349327761, 3728.5339332583426, 3731.589525152231, 3732.122951106488, 1051.416463862556, 1071.6624263357019, 1073.7637719701654, 1090.9672709818717, 1095.309954728524, 12351.93824030874, 12397.814032789516, 12441.129120961627, 12459.12786104973, 12490.313032783193, 4572.199865023607, 4644.86065418038, 4808.783604737795, 4822.662413631012, 4830.6135509159185, 9617.23062155026, 9617.56720947545, 9621.855672164924, 9630.711078667651, 9641.951790241004, 5452.454754524595, 5454.481824226692, 5462.044142275086, 5462.317210348707, 5476.041692703761, 4220.345846393814, 4577.702449340849, 4735.100330937111, 4772.331873537708, 5128.282040514331, 3972.980135099364, 3986.3023970804993, 4013.2976729072298, 4046.988252936766, 4052.371882358239, 1041.770810421706, 1183.798754211786, 1190.0360707826687, 1199.0767109325946, 1207.29373316609, 923.7921467669721, 950.8145911547182, 952.6046690623314, 967.7532229281488, 995.7460847483845, 5149.7940082397035, 5997.220361352996, 6015.789447369321, 6117.174181485442, 6226.40849119333, 423.16213896484516, 442.42042923769964, 442.57070641477713, 443.55422800085705, 443.86683994802075, 4016.5180178378782, 4029.5366032906004, 4035.031592417857, 4081.2653722330265, 4168.499780026355, 10300.742481438025, 11439.027682646902, 11582.07343412525, 11619.941900290614, 11638.76722465546, 11527.596534121265, 11548.76902461946, 11558.07535397183, 11562.653120406401, 11572.94213528927, 8680.739577812676, 8688.435251708619, 8692.0, 8721.65113395457, 8739.169778870997, 5798.710064496842, 6003.879922401615, 6017.488513476404, 6163.3528479509005, 6208.068789681584, 10303.151176705345, 10582.153427725609, 10724.410157441416, 10762.946185269024, 10763.677089687322, 9727.221822254343, 11052.336859788438, 11061.778764424505, 11067.889321106686, 11104.61133860311, 6961.686724097326, 7075.0, 8202.876956845772, 8382.790430239293, 8575.142485751376, 8521.0, 8586.055533891142, 8804.207621314112, 8805.735827925173, 8828.823423531621, 7629.656665450179, 7766.10689770661, 7833.764987050397, 7914.2085791420395, 7925.445218834626, 6244.843878903063, 7555.395568354628, 7603.429742769204, 7618.5809780462605, 7627.8855817161675, 7069.434445244471, 7090.680919358744, 7145.249748776511, 7222.277772222736, 7309.122905251404, 5062.594992400673, 5131.3842338918075, 5192.1952097424955, 5225.555827754683, 5254.97372513135, 4999.400071991385, 5096.923546146737, 5135.717535535581, 5350.705711185922, 5389.595780316414], "value": 4622.901216725424, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [263.6072252344007, 296.97178768017045, 298.6729531163376, 311.9438501069807, 312.3066791722376, 4502.4371953505815, 4631.842039490128, 5478.397376288605, 5504.14685723713, 5517.475839795213, 4285.507166675833, 5097.641516503392, 5110.156824124015, 5386.946130538693, 5447.782088716456, 4085.7742677196798, 4108.979455102735, 4127.442795222637, 4135.986683262589, 4155.522114956773, 3617.824207132679, 4026.8590599329027, 4131.421600975866, 4147.937780933288, 4168.24971505129, 3096.3807238552267, 3639.5086663300513, 3866.613338666125, 4008.879733607982, 4088.2845502037026, 3518.032541051206, 3620.800855952948, 3980.144268982154, 4092.529359123709, 4093.9385909211387, 2903.840288784116, 3495.930081398383, 3501.5272938153535, 3502.562179727534, 3506.2987402519343, 3821.808909554511, 4334.761588112652, 5098.1842905135, 5102.72431892027, 5266.657667251617, 4169.687273454473, 4391.5827996340295, 4725.747676865633, 4727.929081063787, 4730.314104454869, 4742.857714268577, 5211.635185537014, 5667.433256674341, 5704.686242256675, 5706.457886500776, 3878.3600705883496, 3878.7394096918615, 3894.6300101490597, 3906.453096566483, 3953.8418463261437, 5753.798617048383, 6022.307434644984, 6032.728527216286, 6043.072003197913, 6055.788047418319, 3582.802945838004, 3638.7634803737674, 3918.6277303656107, 3929.44987701722, 3962.821673024721, 4024.71826972115, 4031.4154447604933, 4032.2137183248983, 4053.8851815750904, 4072.6334629883454, 3921.352976758803, 4368.956310436918, 4644.117617652635, 4653.837115700934, 4722.70125715431, 4944.505549445099, 5737.569682273846, 5748.425157484218, 5762.4525670061275, 5768.826935191951, 1163.8196079607674, 1271.6884363330857, 1302.3618426970716, 1370.458234486203, 1372.7460419822364, 754.4153281206999, 826.3636999510463, 839.8068444257855, 873.5935144417483, 882.761654353321, 3922.1763429680086, 4559.586528176276, 4601.0, 4614.377059096979, 4619.4687610925175, 4008.1341840584123, 4373.453318335208, 4469.994251293433, 4904.4605093439695, 4952.158133117378, 3939.9015024624614, 4006.659433948089, 4187.764609440223, 4347.869563913087, 4456.844010459683, 2251.8085962693353, 2361.7992470639842, 2388.9727417210765, 2553.323369307135, 2598.480303939182, 4845.3767987724505, 4895.951040489621, 5062.772175252123, 5089.262057001714, 5121.437961421802, 4389.0720261752385, 4974.651774375831, 4983.950160498349, 5041.42023667283, 5111.718855462986, 4370.169667763115, 4403.427554417905, 4705.388928555385, 4926.359573255454, 5152.227165925141, 4290.978545107255, 4890.339804126397, 4936.851894443173, 4945.653804233669, 4950.975245123751, 4230.111676547959, 4245.490541135084, 4586.532309660876, 4878.804847806048, 4891.01913724942, 4506.909861802811, 4658.53414658545, 4876.341693871282, 4933.876653083702, 5121.974390128025, 2988.548226797965, 3099.1787176398725, 3419.956238947289, 3427.365937301607, 3427.9485807713404, 3930.744501607386, 4391.3193455014525, 4901.9509904798115, 4939.728314942713, 4960.3462147752225, 4366.930102124999, 4615.0847398329315, 4763.951930575268, 4781.019890922283, 4784.588546378757, 4011.6130902113196, 4323.96224906014, 4777.522247775195, 4791.904161916673, 4813.350197723263, 4212.91574168521, 4691.413573303337, 4722.598579120813, 4738.33663287147, 5400.513953744182, 4592.578528995919, 4649.744264065509, 4720.386349774507, 5769.653820770767, 5800.6809625471005, 5660.848877426552, 5672.971635141798, 5685.0, 5688.971555142198, 5706.800261990977], "value": 3805.684623409012, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [651.6350843527623, 711.5908352697201, 721.4372789224406, 728.0971595221927, 797.5812698333374, 12809.743805123904, 16959.57601059974, 16966.32134714613, 16977.915110424463, 17006.91496542517, 14564.781528277057, 14617.196054217015, 14620.853791462083, 14621.12273263605, 14677.266136693175, 708.5890183693458, 731.2358585278371, 876.8860048193758, 900.1088921967238, 905.597009330848, 879.5690111845182, 896.6503063805121, 901.3465237702646, 901.5402144906112, 902.801383695586, 963.86987756653, 965.4400447740329, 966.0484422843529, 966.9903300967006, 968.2157452463492, 954.4273435938451, 954.7279025477741, 955.5222388805597, 958.1328897347911, 958.4776296918196, 719.4352433339856, 719.7912605344444, 721.4589058206345, 721.7185297733888, 723.3562129704515, 15218.0, 15574.065556066673, 15621.68756624861, 15621.765673514907, 15634.609134771608, 12242.510299588006, 15103.39586416542, 15111.168885711282, 15124.470643527424, 15315.770263446057, 11753.647390578297, 11982.580609678707, 12093.758124837543, 12222.877771222351, 12237.95977341927, 3826.3877779555414, 4005.899852503711, 4014.116894283253, 4030.1738143680477, 4136.834526618932, 119722.0, 122841.0, 123662.38168809099, 124369.0, 128216.99999999907, 11326.603568875049, 11634.243774154822, 11662.0, 11700.0, 11748.0, 23878.403039924142, 23932.641010384858, 23998.280051598478, 24065.879670601535, 24351.0, 8402.915970840335, 8467.364947628952, 8512.446690965067, 8513.95743021281, 8730.69442569507, 4586.541345865454, 4665.533446655375, 4707.150019205696, 4743.501932297059, 4856.538628830255, 3321.78408403453, 3430.9656903431146, 3533.2756784859043, 3629.0, 3636.5272514572935, 628.676231740654, 641.3970867384737, 643.9549231553747, 651.3193712570376, 657.1063353838775, 664.8869692152346, 683.2781390894917, 684.9246582875879, 706.629139469438, 723.2984005514643, 8349.499030058216, 9357.719268421959, 10087.495625218857, 10138.290319677699, 10231.693049208536, 582.6940856050608, 586.9589128761031, 600.8317671052113, 693.448708276921, 704.1514974455769, 3870.496835411435, 4964.0, 4979.875503112451, 4986.326845875833, 4995.70025798446, 108153.0, 122153.0, 164738.0, 170256.0, 170737.0, 125387.7461225376, 149620.0, 149624.0, 150695.0, 154416.0, 103216.0, 104124.0, 104442.99999999849, 104719.4764026175, 123314.0, 10002.0, 10351.741206469898, 10542.578296868347, 10555.736106597396, 10605.522751476356, 54377.0, 63962.36037639564, 64795.0, 66142.00786988295, 67100.32899671137, 91450.54274728584, 99314.5034274824, 100852.0, 102743.0, 104599.4770026145, 13048.673783155496, 13454.262296718514, 13718.794218086538, 14175.291235438393, 14577.708445831237, 14688.0, 15224.086554806747, 15482.225888705745, 15527.76708349353, 16688.582785430463, 13828.37772300269, 13960.930195348958, 14224.146551206546, 15032.473863414729, 15371.923140384673, 14351.569452916428, 15107.018043827116, 15227.93404461677, 15430.30563624662, 15477.380904763684, 13040.546570547447, 14468.927655361656, 14880.0, 15020.399184032953, 15180.24098795078, 10986.670399888017, 11620.012298954684, 11824.822627660764, 11928.78988633071, 12049.75900481968, 10047.598096076075, 10454.085977064346, 10838.458077096271, 11002.213404055798, 11962.700932476757], "value": 8194.606442154582, "unit": "score"}], "name": "dromaeo_css", "value": 7396.764126938959, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 112.0, 113.0, 112.0, 114.0, 114.0, 111.0, 112.0, 111.0, 111.0], "value": 112.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 120.0, 140.0, 169.0, 108.0, 110.0, 105.0, 107.0, 138.0, 112.0], "value": 125.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 185.0, 186.0, 183.0, 183.0, 184.0, 184.0, 185.0, 185.0, 184.0], "value": 184.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 59.0, 58.0, 90.0, 61.0, 57.0, 59.0, 57.0, 80.0, 57.0], "value": 66.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 85.0, 124.0, 100.0, 67.0, 70.0, 90.0, 67.0, 69.0, 89.0], "value": 84.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 85.0, 89.0, 85.0, 88.0, 85.0, 90.0, 86.0, 88.0, 85.0], "value": 87.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 149.0, 148.0, 93.0, 91.0, 93.0, 93.0, 93.0, 92.0, 93.0], "value": 105.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 86.0, 85.0, 85.0, 85.0, 85.0, 84.0, 86.0, 87.0], "value": 85.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 52.0, 51.0, 51.0, 55.0, 54.0, 57.0, 58.0, 56.0, 56.0], "value": 54.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 75.0, 79.0, 75.0, 72.0, 69.0, 69.0, 70.0, 71.0, 72.0], "value": 71.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 83.0, 82.0, 84.0, 86.0, 86.0, 88.0, 100.0, 89.0], "value": 86.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 151.0, 132.0, 133.0, 132.0, 138.0, 147.0, 133.0, 159.0, 152.0], "value": 142.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [208.0, 160.0, 157.0, 133.0, 166.0, 222.0, 136.0, 196.0, 221.0, 161.0], "value": 176.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 66.0, 66.0, 65.0, 67.0, 65.0, 68.0, 74.0, 82.0], "value": 68.7, "unit": "score"}], "name": "kraken", "value": 1450.5000000000002, "alertThreshold": 2.0}]} 05:51:03 INFO - Return code: 0 05:51:03 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:51:03 INFO - # TBPL SUCCESS # 05:51:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:51:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:51:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:51:03 INFO - 2016-11-19 05:51:03.885 screenresolution[2511:89063] starting screenresolution argv=screenresolution get 05:51:03 INFO - 2016-11-19 05:51:03.888 screenresolution[2511:89063] Display 0: 1600x1200x32@60 05:51:03 INFO - 2016-11-19 05:51:03.894 screenresolution[2512:89066] starting screenresolution argv=screenresolution list 05:51:03 INFO - Available Modes on Display 0 05:51:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:51:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:51:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:51:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:51:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:51:04 INFO - Intel Iris: 05:51:04 INFO - Chipset Model: Intel Iris 05:51:04 INFO - Type: GPU 05:51:04 INFO - Bus: Built-In 05:51:04 INFO - VRAM (Dynamic, Max): 1536 MB 05:51:04 INFO - Vendor: Intel (0x8086) 05:51:04 INFO - Device ID: 0x0a2e 05:51:04 INFO - Revision ID: 0x0009 05:51:04 INFO - Displays: 05:51:04 INFO - Display: 05:51:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:51:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:51:04 INFO - Display Serial Number: 1600x1200 60 05:51:04 INFO - Main Display: Yes 05:51:04 INFO - Mirror: Off 05:51:04 INFO - Online: Yes 05:51:04 INFO - Rotation: Supported 05:51:04 INFO - Return code: 0 05:51:04 INFO - Running post-action listener: _resource_record_post_action 05:51:04 INFO - [mozharness: 2016-11-19 13:51:04.130258Z] Finished run-tests step (success) 05:51:04 INFO - Running post-run listener: _resource_record_post_run 05:51:04 INFO - Total resource usage - Wall time: 1195s; CPU: 54.0%; Read bytes: 905216; Write bytes: 494595584; Read time: 4; Write time: 1522 05:51:04 INFO - TinderboxPrint: CPU usage
53.5% 05:51:04 INFO - TinderboxPrint: I/O read bytes / time
905,216 / 4 05:51:04 INFO - TinderboxPrint: I/O write bytes / time
494,595,584 / 1,522 05:51:04 INFO - TinderboxPrint: CPU idle
2,222.1 (46.5%) 05:51:04 INFO - TinderboxPrint: CPU system
961.4 (20.1%) 05:51:04 INFO - TinderboxPrint: CPU user
1,598.2 (33.4%) 05:51:04 INFO - TinderboxPrint: Swap in / out
195,387,392 / 0 05:51:04 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 183259648; Write bytes: 179136000; Read time: 16698; Write time: 369 05:51:04 INFO - run-tests - Wall time: 1174s; CPU: 54.0%; Read bytes: 905216; Write bytes: 295401472; Read time: 4; Write time: 1092 05:51:04 INFO - Running post-run listener: _upload_blobber_files 05:51:04 INFO - Blob upload gear active. 05:51:04 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:51:04 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 05:51:04 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', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:51:04 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 Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:51:05 INFO - (blobuploader) - INFO - Open directory for files ... 05:51:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:51:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:51:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:51:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:51:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:51:06 INFO - (blobuploader) - INFO - Done attempting. 05:51:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:51:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:51:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:51:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:51:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:51:07 INFO - (blobuploader) - INFO - Done attempting. 05:51:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:51:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:51:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:51:09 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:51:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:51:09 INFO - (blobuploader) - INFO - Done attempting. 05:51:09 INFO - (blobuploader) - INFO - Iteration through files over. 05:51:09 INFO - Return code: 0 05:51:09 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:51:09 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:51:09 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/62d0db0d1cbb43cfab347db96657adfae56bda630dd27a9d88168c3fd3140aa940bcf5d3c19e8af08f49c3c6f5cd69d5a2a04ec5eca0c9c05871284de3bfe6ab", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f209c559808eafce6e28816ef8e84b726a474c82ae527ba4ca3ead5d609249709729ef288b7ac3b0dc7c1cb75028d7d8a8e8749037278fac191426465b4ab4be", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/71586eaa2173a99e95d8a734add6d1fe3192bab46f5ce4ab726290aab8aab3a7f0585976448523b190e5e84c83bc3bc2845903f0f67c6d5c26e0471815264fc0"} 05:51:09 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:51:09 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:51:09 INFO - Contents: 05:51:09 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/62d0db0d1cbb43cfab347db96657adfae56bda630dd27a9d88168c3fd3140aa940bcf5d3c19e8af08f49c3c6f5cd69d5a2a04ec5eca0c9c05871284de3bfe6ab", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f209c559808eafce6e28816ef8e84b726a474c82ae527ba4ca3ead5d609249709729ef288b7ac3b0dc7c1cb75028d7d8a8e8749037278fac191426465b4ab4be", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/71586eaa2173a99e95d8a734add6d1fe3192bab46f5ce4ab726290aab8aab3a7f0585976448523b190e5e84c83bc3bc2845903f0f67c6d5c26e0471815264fc0"} 05:51:09 INFO - Running post-run listener: copy_logs_to_upload_dir 05:51:09 INFO - Copying logs to upload dir... 05:51:09 INFO - mkdir: /builds/slave/test/build/upload/logs 05:51:09 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1256.558772 ========= master_lag: 0.25 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2016-11-19 05:51:09.790669) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-19 05:51:09.794365) ========= 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.byhoGIIaHv/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.pjdP5jaEs6/Listeners TMPDIR=/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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/Mozilla-Beta/sha512/62d0db0d1cbb43cfab347db96657adfae56bda630dd27a9d88168c3fd3140aa940bcf5d3c19e8af08f49c3c6f5cd69d5a2a04ec5eca0c9c05871284de3bfe6ab", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f209c559808eafce6e28816ef8e84b726a474c82ae527ba4ca3ead5d609249709729ef288b7ac3b0dc7c1cb75028d7d8a8e8749037278fac191426465b4ab4be", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/71586eaa2173a99e95d8a734add6d1fe3192bab46f5ce4ab726290aab8aab3a7f0585976448523b190e5e84c83bc3bc2845903f0f67c6d5c26e0471815264fc0"} build_url:https://queue.taskcluster.net/v1/task/TKFXMxOyQ1eRKg11r00spw/artifacts/public/build/firefox-51.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011309 build_url: 'https://queue.taskcluster.net/v1/task/TKFXMxOyQ1eRKg11r00spw/artifacts/public/build/firefox-51.0.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/62d0db0d1cbb43cfab347db96657adfae56bda630dd27a9d88168c3fd3140aa940bcf5d3c19e8af08f49c3c6f5cd69d5a2a04ec5eca0c9c05871284de3bfe6ab", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f209c559808eafce6e28816ef8e84b726a474c82ae527ba4ca3ead5d609249709729ef288b7ac3b0dc7c1cb75028d7d8a8e8749037278fac191426465b4ab4be", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/71586eaa2173a99e95d8a734add6d1fe3192bab46f5ce4ab726290aab8aab3a7f0585976448523b190e5e84c83bc3bc2845903f0f67c6d5c26e0471815264fc0"}' ========= master_lag: 0.07 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-19 05:51:09.872648) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-19 05:51:09.873019) ========= 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.byhoGIIaHv/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.pjdP5jaEs6/Listeners TMPDIR=/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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.005340 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-19 05:51:09.899482) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-19 05:51:09.899838) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-19 05:51:09.900213) ========= ========= Total master_lag: 0.41 =========