builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0212 starttime: 1487862600.7 results: success (0) buildid: 20170223055822 builduid: 0b4a1849b127477f80fddab871f8daf9 revision: 27dade5e0c8350189eeb6495d70a9fb25ce137a9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-23 07:10:00.702949) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-23 07:10:00.703477) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-23 07:10:00.703844) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-23 07:10:00.727508) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-23 07:10:00.727872) ========= 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.cVmfSsmwFp/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.5CiygProhD/Listeners TMPDIR=/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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 --2017-02-23 07:10:00-- 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% 10.3M=0.001s 2017-02-23 07:10:01 (10.3 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.604587 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-23 07:10:01.356016) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-23 07:10:01.356401) ========= 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.cVmfSsmwFp/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.5CiygProhD/Listeners TMPDIR=/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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.020425 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-23 07:10:01.406859) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-23 07:10:01.407603) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 27dade5e0c8350189eeb6495d70a9fb25ce137a9 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 27dade5e0c8350189eeb6495d70a9fb25ce137a9 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.cVmfSsmwFp/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.5CiygProhD/Listeners TMPDIR=/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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 2017-02-23 07:10:01,500 truncating revision to first 12 chars 2017-02-23 07:10:01,500 Setting DEBUG logging. 2017-02-23 07:10:01,500 attempt 1/10 2017-02-23 07:10:01,500 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/27dade5e0c83?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-23 07:10:02,437 unpacking tar archive at: mozilla-central-27dade5e0c83/testing/mozharness/ program finished with exit code 0 elapsedTime=1.188208 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-23 07:10:02.619658) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-23 07:10:02.619974) ========= script_repo_revision: 27dade5e0c8350189eeb6495d70a9fb25ce137a9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-23 07:10:02.620423) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-23 07:10:02.620686) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-23 07:10:02.641859) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 23 secs) (at 2017-02-23 07:10:02.642494) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.cVmfSsmwFp/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.5CiygProhD/Listeners TMPDIR=/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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 07:10:02 INFO - MultiFileLogger online at 20170223 07:10:02 in /builds/slave/test 07:10:02 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 07:10:02 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:10:02 INFO - {'append_to_log': False, 07:10:02 INFO - 'base_work_dir': '/builds/slave/test', 07:10:02 INFO - 'blob_upload_branch': 'Firefox', 07:10:02 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:10:02 INFO - 'branch': 'Firefox', 07:10:02 INFO - 'buildbot_json_path': 'buildprops.json', 07:10:02 INFO - 'config_files': ('talos/mac_config.py',), 07:10:02 INFO - 'default_actions': ('clobber', 07:10:02 INFO - 'read-buildbot-config', 07:10:02 INFO - 'download-and-extract', 07:10:02 INFO - 'populate-webroot', 07:10:02 INFO - 'create-virtualenv', 07:10:02 INFO - 'install', 07:10:02 INFO - 'run-tests'), 07:10:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:10:02 INFO - 'download_minidump_stackwalk': True, 07:10:02 INFO - 'download_symbols': 'ondemand', 07:10:02 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:10:02 INFO - 'tooltool.py': '/tools/tooltool.py', 07:10:02 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:10:02 INFO - '/tools/misc-python/virtualenv.py')}, 07:10:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:10:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:10:02 INFO - 'gecko_profile': False, 07:10:02 INFO - 'gecko_profile_interval': 0, 07:10:02 INFO - 'installer_path': 'installer.exe', 07:10:02 INFO - 'log_level': 'info', 07:10:02 INFO - 'log_name': 'talos', 07:10:02 INFO - 'log_to_console': True, 07:10:02 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:10:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:10:02 INFO - 'opt_config_files': (), 07:10:02 INFO - 'pip_index': False, 07:10:02 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:10:02 INFO - 'cmd': ('bash', 07:10:02 INFO - '-c', 07:10:02 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:10:02 INFO - 'enabled': True, 07:10:02 INFO - 'halt_on_failure': False, 07:10:02 INFO - 'name': 'check_screen_resolution'},), 07:10:02 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:10:02 INFO - 'cmd': ('bash', 07:10:02 INFO - '-c', 07:10:02 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:10:02 INFO - 'enabled': True, 07:10:02 INFO - 'halt_on_failure': False, 07:10:02 INFO - 'name': 'check_screen_resolution'},), 07:10:02 INFO - 'run_cmd_checks_enabled': True, 07:10:02 INFO - 'suite': 'dromaeojs', 07:10:02 INFO - 'system_bits': '32', 07:10:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:10:02 INFO - 'title': 't-yosemite-r7-0212', 07:10:02 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:10:02 INFO - 'use_talos_json': True, 07:10:02 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:10:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:10:02 INFO - 'work_dir': 'build'} 07:10:02 INFO - [mozharness: 2017-02-23 15:10:02.852429Z] Running clobber step. 07:10:02 INFO - Running pre-action listener: _resource_record_pre_action 07:10:02 INFO - Running main action method: clobber 07:10:02 INFO - rmtree: /builds/slave/test/build 07:10:02 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:10:03 INFO - Running post-action listener: _resource_record_post_action 07:10:03 INFO - [mozharness: 2017-02-23 15:10:03.495782Z] Finished clobber step (success) 07:10:03 INFO - [mozharness: 2017-02-23 15:10:03.496052Z] Running read-buildbot-config step. 07:10:03 INFO - Running pre-action listener: _resource_record_pre_action 07:10:03 INFO - Running main action method: read_buildbot_config 07:10:03 INFO - Using buildbot properties: 07:10:03 INFO - { 07:10:03 INFO - "project": "", 07:10:03 INFO - "product": "firefox", 07:10:03 INFO - "script_repo_revision": "production", 07:10:03 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 07:10:03 INFO - "repository": "", 07:10:03 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 07:10:03 INFO - "buildid": "20170223055822", 07:10:03 INFO - "pgo_build": "False", 07:10:03 INFO - "basedir": "/builds/slave/test", 07:10:03 INFO - "buildnumber": 143, 07:10:03 INFO - "slavename": "t-yosemite-r7-0212", 07:10:03 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 07:10:03 INFO - "platform": "yosemite_r7", 07:10:03 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 07:10:03 INFO - "branch": "mozilla-central", 07:10:03 INFO - "repo_path": "mozilla-central", 07:10:03 INFO - "revision": "27dade5e0c8350189eeb6495d70a9fb25ce137a9", 07:10:03 INFO - "stage_platform": "macosx64", 07:10:03 INFO - "builduid": "0b4a1849b127477f80fddab871f8daf9", 07:10:03 INFO - "slavebuilddir": "test" 07:10:03 INFO - } 07:10:03 INFO - Found installer url https://queue.taskcluster.net/v1/task/KcJ43fEsQ-yH2Nxw7F4_Pw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg. 07:10:03 INFO - Running post-action listener: _resource_record_post_action 07:10:03 INFO - [mozharness: 2017-02-23 15:10:03.502012Z] Finished read-buildbot-config step (success) 07:10:03 INFO - [mozharness: 2017-02-23 15:10:03.502170Z] Running download-and-extract step. 07:10:03 INFO - Running pre-action listener: _resource_record_pre_action 07:10:03 INFO - Running main action method: download_and_extract 07:10:03 INFO - mkdir: /builds/slave/test/build/tests 07:10:03 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')]} 07:10:03 INFO - trying https://queue.taskcluster.net/v1/task/KcJ43fEsQ-yH2Nxw7F4_Pw/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json 07:10:03 INFO - Downloading https://queue.taskcluster.net/v1/task/KcJ43fEsQ-yH2Nxw7F4_Pw/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 07:10:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KcJ43fEsQ-yH2Nxw7F4_Pw/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json'}, attempt #1 07:10:04 INFO - Downloaded 1199 bytes. 07:10:04 INFO - Reading from file /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 07:10:04 INFO - Using the following test package requirements: 07:10:04 INFO - {u'common': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 07:10:04 INFO - u'cppunittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 07:10:04 INFO - u'firefox-54.0a1.en-US.mac.cppunittest.tests.zip'], 07:10:04 INFO - u'gtest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 07:10:04 INFO - u'firefox-54.0a1.en-US.mac.gtest.tests.zip'], 07:10:04 INFO - u'jittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 07:10:04 INFO - u'jsshell-mac.zip'], 07:10:04 INFO - u'mochitest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 07:10:04 INFO - u'firefox-54.0a1.en-US.mac.mochitest.tests.zip'], 07:10:04 INFO - u'mozbase': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 07:10:04 INFO - u'reftest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 07:10:04 INFO - u'firefox-54.0a1.en-US.mac.reftest.tests.zip'], 07:10:04 INFO - u'talos': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 07:10:04 INFO - u'firefox-54.0a1.en-US.mac.talos.tests.zip'], 07:10:04 INFO - u'web-platform': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 07:10:04 INFO - u'firefox-54.0a1.en-US.mac.web-platform.tests.zip'], 07:10:04 INFO - u'xpcshell': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 07:10:04 INFO - u'firefox-54.0a1.en-US.mac.xpcshell.tests.zip']} 07:10:04 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip'] for test suite category: common 07:10:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KcJ43fEsQ-yH2Nxw7F4_Pw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 07:10:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KcJ43fEsQ-yH2Nxw7F4_Pw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:10:04 INFO - Fetch https://queue.taskcluster.net/v1/task/KcJ43fEsQ-yH2Nxw7F4_Pw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 07:10:06 INFO - Expected file size: 51467455 07:10:06 INFO - Obtained file size: 51467455 07:10:22 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip', u'firefox-54.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 07:10:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KcJ43fEsQ-yH2Nxw7F4_Pw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 07:10:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KcJ43fEsQ-yH2Nxw7F4_Pw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:10:22 INFO - Fetch https://queue.taskcluster.net/v1/task/KcJ43fEsQ-yH2Nxw7F4_Pw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 07:10:24 INFO - Expected file size: 51467455 07:10:24 INFO - Obtained file size: 51467455 07:10:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KcJ43fEsQ-yH2Nxw7F4_Pw/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip 07:10:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KcJ43fEsQ-yH2Nxw7F4_Pw/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip'}, attempt #1 07:10:39 INFO - Fetch https://queue.taskcluster.net/v1/task/KcJ43fEsQ-yH2Nxw7F4_Pw/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip into memory 07:10:41 INFO - Expected file size: 14404306 07:10:41 INFO - Obtained file size: 14404306 07:10:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:10:41 INFO - trying https://queue.taskcluster.net/v1/task/KcJ43fEsQ-yH2Nxw7F4_Pw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 07:10:41 INFO - Downloading https://queue.taskcluster.net/v1/task/KcJ43fEsQ-yH2Nxw7F4_Pw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg 07:10:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KcJ43fEsQ-yH2Nxw7F4_Pw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg'}, attempt #1 07:10:43 INFO - Downloaded 74942729 bytes. 07:10:43 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KcJ43fEsQ-yH2Nxw7F4_Pw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 07:10:43 INFO - mkdir: /builds/slave/test/properties 07:10:43 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:10:43 INFO - Writing to file /builds/slave/test/properties/build_url 07:10:43 INFO - Contents: 07:10:43 INFO - build_url:https://queue.taskcluster.net/v1/task/KcJ43fEsQ-yH2Nxw7F4_Pw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 07:10:44 INFO - Running post-action listener: _resource_record_post_action 07:10:44 INFO - Running post-action listener: set_extra_try_arguments 07:10:44 INFO - [mozharness: 2017-02-23 15:10:44.259016Z] Finished download-and-extract step (success) 07:10:44 INFO - [mozharness: 2017-02-23 15:10:44.259179Z] Running populate-webroot step. 07:10:44 INFO - Running pre-action listener: _resource_record_pre_action 07:10:44 INFO - Running main action method: populate_webroot 07:10:44 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:10:44 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:10:44 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:10:44 INFO - '--tppagecycles', 07:10:44 INFO - '7'], 07:10:44 INFO - 'tests': ['tsvgm']}}, 07:10:44 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:10:44 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:10:44 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:10:44 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:10:44 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:10:44 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:10:44 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 07:10:44 INFO - 'talos_options': ['--disable-e10s'], 07:10:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:10:44 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 07:10:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:10:44 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 07:10:44 INFO - 'talos_options': ['--disable-e10s'], 07:10:44 INFO - 'tests': ['damp', 'tps']}, 07:10:44 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 07:10:44 INFO - 'tests': ['damp', 'tps']}, 07:10:44 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:10:44 INFO - 'tests': ['dromaeo_dom']}, 07:10:44 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:10:44 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:10:44 INFO - 'tests': ['basic_compositor_video']}, 07:10:44 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:10:44 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:10:44 INFO - 'tests': ['a11yr', 07:10:44 INFO - 'ts_paint', 07:10:44 INFO - 'tpaint', 07:10:44 INFO - 'sessionrestore', 07:10:44 INFO - 'sessionrestore_no_auto_restore', 07:10:44 INFO - 'tabpaint']}, 07:10:44 INFO - 'other-e10s': {'tests': ['a11yr', 07:10:44 INFO - 'ts_paint', 07:10:44 INFO - 'tpaint', 07:10:44 INFO - 'sessionrestore', 07:10:44 INFO - 'sessionrestore_no_auto_restore', 07:10:44 INFO - 'tabpaint']}, 07:10:44 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:10:44 INFO - 'ts_paint', 07:10:44 INFO - 'tpaint', 07:10:44 INFO - 'sessionrestore', 07:10:44 INFO - 'sessionrestore_no_auto_restore', 07:10:44 INFO - 'tabpaint']}, 07:10:44 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:10:44 INFO - 'ts_paint', 07:10:44 INFO - 'tpaint', 07:10:44 INFO - 'sessionrestore', 07:10:44 INFO - 'sessionrestore_no_auto_restore', 07:10:44 INFO - 'tabpaint']}, 07:10:44 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:10:44 INFO - 'tests': ['a11yr', 07:10:44 INFO - 'ts_paint', 07:10:44 INFO - 'tpaint', 07:10:44 INFO - 'sessionrestore', 07:10:44 INFO - 'sessionrestore_no_auto_restore', 07:10:44 INFO - 'tabpaint']}, 07:10:44 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:10:44 INFO - 'tests': ['a11yr', 07:10:44 INFO - 'ts_paint', 07:10:44 INFO - 'tpaint', 07:10:44 INFO - 'sessionrestore', 07:10:44 INFO - 'sessionrestore_no_auto_restore', 07:10:44 INFO - 'tabpaint']}, 07:10:44 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:10:44 INFO - 'tests': ['tsvgx', 07:10:44 INFO - 'tsvgr_opacity', 07:10:44 INFO - 'tart', 07:10:44 INFO - 'tscrollx', 07:10:44 INFO - 'cart', 07:10:44 INFO - 'tsvg_static']}, 07:10:44 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:10:44 INFO - 'tsvgr_opacity', 07:10:44 INFO - 'tart', 07:10:44 INFO - 'tscrollx', 07:10:44 INFO - 'cart', 07:10:44 INFO - 'tsvg_static']}, 07:10:44 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 07:10:44 INFO - 'talos_options': ['--disable-e10s'], 07:10:44 INFO - 'tests': ['tp5o']}, 07:10:44 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 07:10:44 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 07:10:44 INFO - 'talos_options': ['--disable-e10s', 07:10:44 INFO - '--xperf_path', 07:10:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:10:44 INFO - 'tests': ['tp5n']}, 07:10:44 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 07:10:44 INFO - 'talos_options': ['--xperf_path', 07:10:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:10:44 INFO - 'tests': ['tp5n']}}, 07:10:44 INFO - 'talos.zip': {'path': '', 07:10:44 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:10:44 INFO - Running post-action listener: _resource_record_post_action 07:10:44 INFO - [mozharness: 2017-02-23 15:10:44.277451Z] Finished populate-webroot step (success) 07:10:44 INFO - [mozharness: 2017-02-23 15:10:44.277588Z] Running create-virtualenv step. 07:10:44 INFO - Running pre-action listener: _resource_record_pre_action 07:10:44 INFO - Running main action method: create_virtualenv 07:10:44 INFO - Creating virtualenv /builds/slave/test/build/venv 07:10:44 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 07:10:44 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:10:44 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:10:44 INFO - Using real prefix '/tools/python27' 07:10:44 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:10:45 INFO - Installing distribute.............................................................................................................................................................................................done. 07:10:47 INFO - Installing pip.................done. 07:10:47 INFO - Return code: 0 07:10:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:10:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:10:48 INFO - Reading from file tmpfile_stdout 07:10:48 INFO - Output received: 07:10:48 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 07:10:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:10: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')]} 07:10:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:10:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:10: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')]} 07:10:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:10:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:10: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105851b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105854768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda11578cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10562fd30>, '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 0x105556db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105624be8>, '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.cVmfSsmwFp/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.5CiygProhD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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 07:10: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', 'pip>=1.5'] in /builds/slave/test/build 07:10: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 pip>=1.5 07:10:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cVmfSsmwFp/Render', 07:10:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:10:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:10:48 INFO - 'HOME': '/Users/cltbld', 07:10:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:10:48 INFO - 'LOGNAME': 'cltbld', 07:10:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:10:48 INFO - 'MOZ_NO_REMOTE': '1', 07:10:48 INFO - 'NO_EM_RESTART': '1', 07:10:48 INFO - 'PAGER': '/bin/cat', 07:10:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:10:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:10:48 INFO - 'PWD': '/builds/slave/test', 07:10:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:10:48 INFO - 'SHELL': '/bin/bash', 07:10:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5CiygProhD/Listeners', 07:10:48 INFO - 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', 07:10:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:10:48 INFO - 'USER': 'cltbld', 07:10:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:10:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:10:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:10:48 INFO - 'XPC_FLAGS': '0x0', 07:10:48 INFO - 'XPC_SERVICE_NAME': '0', 07:10:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:10:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:10:48 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 07:10:48 INFO - Cleaning up... 07:10:48 INFO - Return code: 0 07:10:48 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:10: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')]} 07:10:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:10:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:10: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')]} 07:10:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:10:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:10: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', '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 0x105851b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105854768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda11578cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10562fd30>, '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 0x105556db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105624be8>, '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.cVmfSsmwFp/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.5CiygProhD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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 07:10: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', 'psutil>=3.1.1'] in /builds/slave/test/build 07:10: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 psutil>=3.1.1 07:10:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cVmfSsmwFp/Render', 07:10:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:10:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:10:48 INFO - 'HOME': '/Users/cltbld', 07:10:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:10:48 INFO - 'LOGNAME': 'cltbld', 07:10:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:10:48 INFO - 'MOZ_NO_REMOTE': '1', 07:10:48 INFO - 'NO_EM_RESTART': '1', 07:10:48 INFO - 'PAGER': '/bin/cat', 07:10:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:10:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:10:48 INFO - 'PWD': '/builds/slave/test', 07:10:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:10:48 INFO - 'SHELL': '/bin/bash', 07:10:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5CiygProhD/Listeners', 07:10:48 INFO - 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', 07:10:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:10:48 INFO - 'USER': 'cltbld', 07:10:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:10:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:10:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:10:48 INFO - 'XPC_FLAGS': '0x0', 07:10:48 INFO - 'XPC_SERVICE_NAME': '0', 07:10:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:10:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:10:48 INFO - Downloading/unpacking psutil>=3.1.1 07:10: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 07:10: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 07:10: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 07:10: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 07:10: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 07:10: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 07:10:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:10:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:10:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:10:52 INFO - Installing collected packages: psutil 07:10:52 INFO - Running setup.py install for psutil 07:10:53 INFO - building 'psutil._psutil_osx' extension 07:10:53 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 07:10:53 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 07:10:53 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 07:10:53 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 07:10:53 INFO - building 'psutil._psutil_posix' extension 07:10:53 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 07:10:53 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:10:53 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:10:53 INFO - ^ 07:10:53 INFO - 1 warning generated. 07:10:53 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 07:10:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:10:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:10:53 INFO - Successfully installed psutil 07:10:53 INFO - Cleaning up... 07:10:53 INFO - Return code: 0 07:10:53 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:10:53 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')]} 07:10:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:10:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:10:53 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')]} 07:10:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:10:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:10:53 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 0x105851b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105854768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda11578cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10562fd30>, '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 0x105556db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105624be8>, '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.cVmfSsmwFp/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.5CiygProhD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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 07:10:53 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 07:10:53 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 07:10:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cVmfSsmwFp/Render', 07:10:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:10:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:10:53 INFO - 'HOME': '/Users/cltbld', 07:10:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:10:53 INFO - 'LOGNAME': 'cltbld', 07:10:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:10:53 INFO - 'MOZ_NO_REMOTE': '1', 07:10:53 INFO - 'NO_EM_RESTART': '1', 07:10:53 INFO - 'PAGER': '/bin/cat', 07:10:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:10:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:10:53 INFO - 'PWD': '/builds/slave/test', 07:10:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:10:53 INFO - 'SHELL': '/bin/bash', 07:10:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5CiygProhD/Listeners', 07:10:53 INFO - 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', 07:10:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:10:53 INFO - 'USER': 'cltbld', 07:10:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:10:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:10:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:10:53 INFO - 'XPC_FLAGS': '0x0', 07:10:53 INFO - 'XPC_SERVICE_NAME': '0', 07:10:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:10:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:10:54 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:10:54 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 07:10:54 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 07:10:54 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 07:10:54 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 07:10:54 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 07:10:54 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 07:10:56 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:10:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:10:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:10:56 INFO - Installing collected packages: mozsystemmonitor 07:10:56 INFO - Running setup.py install for mozsystemmonitor 07:10:56 INFO - Successfully installed mozsystemmonitor 07:10:56 INFO - Cleaning up... 07:10:57 INFO - Return code: 0 07:10:57 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:10: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')]} 07:10:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:10:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:10: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')]} 07:10:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:10:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:10:57 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 0x105851b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105854768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda11578cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10562fd30>, '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 0x105556db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105624be8>, '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.cVmfSsmwFp/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.5CiygProhD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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 07:10:57 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 07:10:57 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 07:10:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cVmfSsmwFp/Render', 07:10:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:10:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:10:57 INFO - 'HOME': '/Users/cltbld', 07:10:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:10:57 INFO - 'LOGNAME': 'cltbld', 07:10:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:10:57 INFO - 'MOZ_NO_REMOTE': '1', 07:10:57 INFO - 'NO_EM_RESTART': '1', 07:10:57 INFO - 'PAGER': '/bin/cat', 07:10:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:10:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:10:57 INFO - 'PWD': '/builds/slave/test', 07:10:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:10:57 INFO - 'SHELL': '/bin/bash', 07:10:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5CiygProhD/Listeners', 07:10:57 INFO - 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', 07:10:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:10:57 INFO - 'USER': 'cltbld', 07:10:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:10:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:10:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:10:57 INFO - 'XPC_FLAGS': '0x0', 07:10:57 INFO - 'XPC_SERVICE_NAME': '0', 07:10:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:10:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:10:57 INFO - Downloading/unpacking jsonschema==2.5.1 07:10:57 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 07:10:57 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 07:10:57 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 07:10:57 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 07:10:57 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 07:10:57 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 07:10:59 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:10:59 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:10:59 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 07:10:59 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 07:10:59 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 07:10:59 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 07:10:59 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 07:10:59 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 07:11:00 INFO - Downloading functools32-3.2.3-2.tar.gz 07:11:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:11:00 INFO - warning: no files found matching '*.txt' 07:11:00 INFO - no previously-included directories found matching 'build' 07:11:00 INFO - no previously-included directories found matching 'dist' 07:11:00 INFO - no previously-included directories found matching '.git*' 07:11:00 INFO - Installing collected packages: jsonschema, functools32 07:11:00 INFO - Running setup.py install for functools32 07:11:00 INFO - warning: no files found matching '*.txt' 07:11:00 INFO - no previously-included directories found matching 'build' 07:11:00 INFO - no previously-included directories found matching 'dist' 07:11:00 INFO - no previously-included directories found matching '.git*' 07:11:00 INFO - Successfully installed jsonschema functools32 07:11:00 INFO - Cleaning up... 07:11:00 INFO - Return code: 0 07:11:00 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:11:00 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')]} 07:11:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:11:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:11:00 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')]} 07:11:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:11:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:11:00 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 0x105851b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105854768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda11578cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10562fd30>, '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 0x105556db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105624be8>, '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.cVmfSsmwFp/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.5CiygProhD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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 07:11:00 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 07:11:00 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 07:11:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cVmfSsmwFp/Render', 07:11:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:11:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:11:00 INFO - 'HOME': '/Users/cltbld', 07:11:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:11:00 INFO - 'LOGNAME': 'cltbld', 07:11:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:11:00 INFO - 'MOZ_NO_REMOTE': '1', 07:11:00 INFO - 'NO_EM_RESTART': '1', 07:11:00 INFO - 'PAGER': '/bin/cat', 07:11:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:11:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:11:00 INFO - 'PWD': '/builds/slave/test', 07:11:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:11:00 INFO - 'SHELL': '/bin/bash', 07:11:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5CiygProhD/Listeners', 07:11:00 INFO - 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', 07:11:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:11:00 INFO - 'USER': 'cltbld', 07:11:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:11:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:11:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:11:00 INFO - 'XPC_FLAGS': '0x0', 07:11:00 INFO - 'XPC_SERVICE_NAME': '0', 07:11:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:11:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:11:01 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:11:01 INFO - Cleaning up... 07:11:01 INFO - Return code: 0 07:11:01 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:11: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')]} 07:11:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:11:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:11: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')]} 07:11:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:11:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:11:01 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 0x105851b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105854768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda11578cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10562fd30>, '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 0x105556db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105624be8>, '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.cVmfSsmwFp/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.5CiygProhD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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 07:11:01 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 07:11:01 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 07:11:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cVmfSsmwFp/Render', 07:11:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:11:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:11:01 INFO - 'HOME': '/Users/cltbld', 07:11:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:11:01 INFO - 'LOGNAME': 'cltbld', 07:11:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:11:01 INFO - 'MOZ_NO_REMOTE': '1', 07:11:01 INFO - 'NO_EM_RESTART': '1', 07:11:01 INFO - 'PAGER': '/bin/cat', 07:11:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:11:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:11:01 INFO - 'PWD': '/builds/slave/test', 07:11:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:11:01 INFO - 'SHELL': '/bin/bash', 07:11:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5CiygProhD/Listeners', 07:11:01 INFO - 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', 07:11:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:11:01 INFO - 'USER': 'cltbld', 07:11:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:11:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:11:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:11:01 INFO - 'XPC_FLAGS': '0x0', 07:11:01 INFO - 'XPC_SERVICE_NAME': '0', 07:11:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:11:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:11:01 INFO - Downloading/unpacking blobuploader==1.2.4 07:11:01 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 07:11:01 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 07:11:01 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 07:11:01 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 07:11:01 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 07:11:01 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 07:11:03 INFO - Downloading blobuploader-1.2.4.tar.gz 07:11:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:11:04 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:11: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 07:11: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 07:11: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 07:11: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 07:11: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 07:11: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 07:11:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:11:04 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:11: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 07:11: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 07:11: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 07:11: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 07:11: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 07:11: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 07:11:05 INFO - Downloading docopt-0.6.1.tar.gz 07:11:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:11:05 INFO - Installing collected packages: blobuploader, requests, docopt 07:11:05 INFO - Running setup.py install for blobuploader 07:11:05 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:11:05 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:11:05 INFO - Running setup.py install for requests 07:11:05 INFO - Running setup.py install for docopt 07:11:06 INFO - Successfully installed blobuploader requests docopt 07:11:06 INFO - Cleaning up... 07:11:06 INFO - Return code: 0 07:11:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:11:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:11:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:11:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:11:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:11:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:11:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:11:06 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 0x105851b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105854768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda11578cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10562fd30>, '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 0x105556db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105624be8>, '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.cVmfSsmwFp/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.5CiygProhD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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 07:11:06 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 07:11:06 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 07:11:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cVmfSsmwFp/Render', 07:11:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:11:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:11:06 INFO - 'HOME': '/Users/cltbld', 07:11:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:11:06 INFO - 'LOGNAME': 'cltbld', 07:11:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:11:06 INFO - 'MOZ_NO_REMOTE': '1', 07:11:06 INFO - 'NO_EM_RESTART': '1', 07:11:06 INFO - 'PAGER': '/bin/cat', 07:11:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:11:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:11:06 INFO - 'PWD': '/builds/slave/test', 07:11:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:11:06 INFO - 'SHELL': '/bin/bash', 07:11:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5CiygProhD/Listeners', 07:11:06 INFO - 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', 07:11:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:11:06 INFO - 'USER': 'cltbld', 07:11:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:11:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:11:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:11:06 INFO - 'XPC_FLAGS': '0x0', 07:11:06 INFO - 'XPC_SERVICE_NAME': '0', 07:11:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:11:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:11:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:11:06 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-PIAdnI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:11:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:11:06 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-ZYp5gF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:11:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:11:06 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-pII7nt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:11:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:11:06 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-wbxiCm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:11:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:11:06 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-zpqQKx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:11:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:11:06 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-dQCyTF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:11:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:11:07 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-gr6c42-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:11:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:11:07 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-524Peh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:11:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:11:07 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-pvcCxR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:11:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:11:07 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-5kZuq_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:11:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:11:07 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-1w9h3A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:11:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:11:07 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-MtmiJ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:11:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:11:07 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-dHImvy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:11:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:11:07 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-Uxiapl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:11:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:11:07 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-UF2f2D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:11:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:11:07 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-OMUdjU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:11:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:11:08 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-St6O_8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:11:08 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:11:08 INFO - Running setup.py install for manifestparser 07:11:08 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:11:08 INFO - Running setup.py install for mozcrash 07:11:08 INFO - Running setup.py install for mozdebug 07:11:08 INFO - Running setup.py install for mozdevice 07:11:08 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:11:08 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:11:08 INFO - Running setup.py install for mozfile 07:11:08 INFO - Running setup.py install for mozhttpd 07:11:09 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:11:09 INFO - Running setup.py install for mozinfo 07:11:09 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:11:09 INFO - Running setup.py install for mozInstall 07:11:09 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:11:09 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:11:09 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:11:09 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:11:09 INFO - Running setup.py install for mozleak 07:11:09 INFO - Running setup.py install for mozlog 07:11:09 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:11:09 INFO - Running setup.py install for moznetwork 07:11:09 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:11:09 INFO - Running setup.py install for mozprocess 07:11:10 INFO - Running setup.py install for mozprofile 07:11:10 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:11:10 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:11:10 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:11:10 INFO - Running setup.py install for mozrunner 07:11:10 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:11:10 INFO - Running setup.py install for mozscreenshot 07:11:10 INFO - Running setup.py install for moztest 07:11:10 INFO - Running setup.py install for mozversion 07:11:10 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:11:10 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:11:10 INFO - Cleaning up... 07:11:10 INFO - Return code: 0 07:11:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:11:10 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')]} 07:11:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:11:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:11:10 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')]} 07:11:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:11:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:11:10 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 0x105851b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105854768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda11578cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10562fd30>, '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 0x105556db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105624be8>, '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.cVmfSsmwFp/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.5CiygProhD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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 07:11:10 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 07:11:10 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 07:11:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cVmfSsmwFp/Render', 07:11:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:11:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:11:10 INFO - 'HOME': '/Users/cltbld', 07:11:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:11:10 INFO - 'LOGNAME': 'cltbld', 07:11:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:11:10 INFO - 'MOZ_NO_REMOTE': '1', 07:11:10 INFO - 'NO_EM_RESTART': '1', 07:11:10 INFO - 'PAGER': '/bin/cat', 07:11:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:11:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:11:10 INFO - 'PWD': '/builds/slave/test', 07:11:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:11:10 INFO - 'SHELL': '/bin/bash', 07:11:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5CiygProhD/Listeners', 07:11:10 INFO - 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', 07:11:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:11:10 INFO - 'USER': 'cltbld', 07:11:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:11:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:11:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:11:10 INFO - 'XPC_FLAGS': '0x0', 07:11:10 INFO - 'XPC_SERVICE_NAME': '0', 07:11:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:11:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:11:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:11:11 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-YylTtZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:11:11 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)) 07:11:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:11:11 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-DkhAiI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:11:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:11:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:11:11 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-dmn4ol-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:11:11 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)) 07:11:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:11:11 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-rgP1Ix-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:11:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:11:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:11:11 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-nwG9ex-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:11:11 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)) 07:11:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:11:11 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-GhYj4q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:11:11 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)) 07:11:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:11:11 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-oNeb12-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:11:11 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)) 07:11:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:11:11 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-BjW8_T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:11:11 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)) 07:11:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:11:11 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-AaFZjO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:11:12 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)) 07:11:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:11:12 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-Xxlxxf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:11:12 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)) 07:11:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:11:12 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-P4ypY4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:11:12 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)) 07:11:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:11:12 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-87qHss-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:11:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 07:11:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:11:12 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-h_tIPS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:11:12 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)) 07:11:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:11:12 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-z2mBbz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:11:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 07:11:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:11:12 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-yGwZVo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:11:12 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)) 07:11:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:11:12 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-oOr8AQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:11:12 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 07:11:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:11:12 INFO - Running setup.py (path:/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/pip-sWbWdp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:11:12 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)) 07:11:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:11:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:11:12 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)) 07:11:12 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:11:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:11:12 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:11:12 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 07:11:12 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 07:11:12 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 07:11:12 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 07:11:12 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 07:11:12 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 07:11:15 INFO - Downloading blessings-1.6.tar.gz 07:11:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:11:15 INFO - Installing collected packages: blessings 07:11:15 INFO - Running setup.py install for blessings 07:11:15 INFO - Successfully installed blessings 07:11:15 INFO - Cleaning up... 07:11:15 INFO - Return code: 0 07:11:15 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:11:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:11:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:11:16 INFO - Reading from file tmpfile_stdout 07:11:16 INFO - Current package versions: 07:11:16 INFO - blessings == 1.6 07:11:16 INFO - blobuploader == 1.2.4 07:11:16 INFO - docopt == 0.6.1 07:11:16 INFO - functools32 == 3.2.3-2 07:11:16 INFO - jsonschema == 2.5.1 07:11:16 INFO - manifestparser == 1.1 07:11:16 INFO - mozInstall == 1.12 07:11:16 INFO - mozcrash == 1.0 07:11:16 INFO - mozdebug == 0.1 07:11:16 INFO - mozdevice == 0.49 07:11:16 INFO - mozfile == 1.2 07:11:16 INFO - mozhttpd == 0.7 07:11:16 INFO - mozinfo == 0.9 07:11:16 INFO - mozleak == 0.1 07:11:16 INFO - mozlog == 3.4 07:11:16 INFO - moznetwork == 0.27 07:11:16 INFO - mozprocess == 0.25 07:11:16 INFO - mozprofile == 0.28 07:11:16 INFO - mozrunner == 6.13 07:11:16 INFO - mozscreenshot == 0.1 07:11:16 INFO - mozsystemmonitor == 0.3 07:11:16 INFO - moztest == 0.8 07:11:16 INFO - mozversion == 1.4 07:11:16 INFO - psutil == 3.1.1 07:11:16 INFO - requests == 1.2.3 07:11:16 INFO - wsgiref == 0.1.2 07:11:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:11:16 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')]} 07:11:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:11:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:11:16 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')]} 07:11:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:11:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:11:16 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 0x105851b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105854768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda11578cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10562fd30>, '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 0x105556db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105624be8>, '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.cVmfSsmwFp/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.5CiygProhD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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 07:11:16 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 07:11:16 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 07:11:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cVmfSsmwFp/Render', 07:11:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:11:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:11:16 INFO - 'HOME': '/Users/cltbld', 07:11:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:11:16 INFO - 'LOGNAME': 'cltbld', 07:11:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:11:16 INFO - 'MOZ_NO_REMOTE': '1', 07:11:16 INFO - 'NO_EM_RESTART': '1', 07:11:16 INFO - 'PAGER': '/bin/cat', 07:11:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:11:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:11:16 INFO - 'PWD': '/builds/slave/test', 07:11:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:11:16 INFO - 'SHELL': '/bin/bash', 07:11:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5CiygProhD/Listeners', 07:11:16 INFO - 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', 07:11:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:11:16 INFO - 'USER': 'cltbld', 07:11:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:11:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:11:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:11:16 INFO - 'XPC_FLAGS': '0x0', 07:11:16 INFO - 'XPC_SERVICE_NAME': '0', 07:11:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:11:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:11:16 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)) 07:11:16 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)) 07:11:16 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)) 07:11:16 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)) 07:11:16 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)) 07:11:16 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)) 07:11:16 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)) 07:11:16 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)) 07:11:16 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)) 07:11:16 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)) 07:11:16 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)) 07:11:16 INFO - Cleaning up... 07:11:16 INFO - Return code: 0 07:11:16 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:11:16 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')]} 07:11:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:11:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:11:16 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')]} 07:11:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:11:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:11:16 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 0x105851b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105854768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda11578cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10562fd30>, '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 0x105556db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105624be8>, '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.cVmfSsmwFp/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.5CiygProhD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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 07:11:16 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 07:11:16 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 07:11:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cVmfSsmwFp/Render', 07:11:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:11:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:11:16 INFO - 'HOME': '/Users/cltbld', 07:11:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:11:16 INFO - 'LOGNAME': 'cltbld', 07:11:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:11:16 INFO - 'MOZ_NO_REMOTE': '1', 07:11:16 INFO - 'NO_EM_RESTART': '1', 07:11:16 INFO - 'PAGER': '/bin/cat', 07:11:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:11:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:11:16 INFO - 'PWD': '/builds/slave/test', 07:11:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:11:16 INFO - 'SHELL': '/bin/bash', 07:11:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5CiygProhD/Listeners', 07:11:16 INFO - 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', 07:11:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:11:16 INFO - 'USER': 'cltbld', 07:11:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:11:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:11:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:11:16 INFO - 'XPC_FLAGS': '0x0', 07:11:16 INFO - 'XPC_SERVICE_NAME': '0', 07:11:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:11:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:11:16 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:11:16 INFO - Cleaning up... 07:11:16 INFO - Return code: 0 07:11:16 INFO - Running post-action listener: _resource_record_post_action 07:11:16 INFO - Running post-action listener: _start_resource_monitoring 07:11:16 INFO - Starting resource monitoring. 07:11:16 INFO - [mozharness: 2017-02-23 15:11:16.712433Z] Finished create-virtualenv step (success) 07:11:16 INFO - [mozharness: 2017-02-23 15:11:16.713426Z] Running install step. 07:11:16 INFO - Running pre-action listener: _resource_record_pre_action 07:11:16 INFO - Running main action method: install 07:11:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:11:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:11:16 INFO - Reading from file tmpfile_stdout 07:11:16 INFO - Detecting whether we're running mozinstall >=1.0... 07:11:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:11:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:11:17 INFO - Reading from file tmpfile_stdout 07:11:17 INFO - Output received: 07:11:17 INFO - Usage: mozinstall [options] installer 07:11:17 INFO - Options: 07:11:17 INFO - -h, --help show this help message and exit 07:11:17 INFO - -d DEST, --destination=DEST 07:11:17 INFO - Directory to install application into. [default: 07:11:17 INFO - "/builds/slave/test"] 07:11:17 INFO - --app=APP Application being installed. [default: firefox] 07:11:17 INFO - mkdir: /builds/slave/test/build/application 07:11:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 07:11:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 07:11:37 INFO - Reading from file tmpfile_stdout 07:11:37 INFO - Output received: 07:11:37 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 07:11:37 INFO - Running post-action listener: _resource_record_post_action 07:11:37 INFO - [mozharness: 2017-02-23 15:11:37.254416Z] Finished install step (success) 07:11:37 INFO - [mozharness: 2017-02-23 15:11:37.254619Z] Running run-tests step. 07:11:37 INFO - Running pre-action listener: _resource_record_pre_action 07:11:37 INFO - Running main action method: run_tests 07:11:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:11:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:11:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:11:37 INFO - 2017-02-23 07:11:37.269 screenresolution[29746:273677] starting screenresolution argv=screenresolution get 07:11:37 INFO - 2017-02-23 07:11:37.271 screenresolution[29746:273677] Display 0: 1600x1200x32@60 07:11:37 INFO - 2017-02-23 07:11:37.277 screenresolution[29747:273680] starting screenresolution argv=screenresolution list 07:11:37 INFO - Available Modes on Display 0 07:11:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:11:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:11:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:11:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:11:37 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:11:37 INFO - Intel Iris: 07:11:37 INFO - Chipset Model: Intel Iris 07:11:37 INFO - Type: GPU 07:11:37 INFO - Bus: Built-In 07:11:37 INFO - VRAM (Dynamic, Max): 1536 MB 07:11:37 INFO - Vendor: Intel (0x8086) 07:11:37 INFO - Device ID: 0x0a2e 07:11:37 INFO - Revision ID: 0x0009 07:11:37 INFO - Displays: 07:11:37 INFO - Display: 07:11:37 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:11:37 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:11:37 INFO - Display Serial Number: 1600x1200 60 07:11:37 INFO - Main Display: Yes 07:11:37 INFO - Mirror: Off 07:11:37 INFO - Online: Yes 07:11:37 INFO - Rotation: Supported 07:11:37 INFO - Return code: 0 07:11:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:11:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:11:37 INFO - Python 2.7.3 07:11:37 INFO - Return code: 0 07:11:37 INFO - grabbing minidump binary from tooltool 07:11:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:11:37 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 0x10562fd30>, '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 0x105556db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105624be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:11:37 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 07:11:37 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 07:11:37 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 07:11:37 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:11:37 INFO - Return code: 0 07:11:37 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:11:37 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:11:37 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:11:37 INFO - ENV: RUST_BACKTRACE is now 1 07:11:37 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:11:37 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:11:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KcJ43fEsQ-yH2Nxw7F4_Pw/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0212', '--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 07:11:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/KcJ43fEsQ-yH2Nxw7F4_Pw/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0212 --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 07:11:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cVmfSsmwFp/Render', 07:11:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:11:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:11:37 INFO - 'HOME': '/Users/cltbld', 07:11:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:11:37 INFO - 'LOGNAME': 'cltbld', 07:11:37 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:11:37 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:11:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:11:37 INFO - 'MOZ_NO_REMOTE': '1', 07:11:37 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:11:37 INFO - 'NO_EM_RESTART': '1', 07:11:37 INFO - 'PAGER': '/bin/cat', 07:11:37 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:11:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:11:37 INFO - 'PWD': '/builds/slave/test', 07:11:37 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:11:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:11:37 INFO - 'RUST_BACKTRACE': '1', 07:11:37 INFO - 'SHELL': '/bin/bash', 07:11:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5CiygProhD/Listeners', 07:11:37 INFO - 'TMPDIR': '/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/', 07:11:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:11:37 INFO - 'USER': 'cltbld', 07:11:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:11:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:11:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:11:37 INFO - 'XPC_FLAGS': '0x0', 07:11:37 INFO - 'XPC_SERVICE_NAME': '0', 07:11:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:11:37 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KcJ43fEsQ-yH2Nxw7F4_Pw/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0212', '--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 07:11:37 INFO - mozversion application_buildid: 20170223055822 07:11:37 INFO - mozversion application_changeset: 27dade5e0c8350189eeb6495d70a9fb25ce137a9 07:11:37 INFO - mozversion application_display_name: Nightly 07:11:37 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:11:37 INFO - mozversion application_name: Firefox 07:11:37 INFO - mozversion application_remotingname: firefox 07:11:37 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 07:11:37 INFO - mozversion application_vendor: Mozilla 07:11:37 INFO - mozversion application_version: 54.0a1 07:11:37 INFO - mozversion platform_buildid: 20170223055822 07:11:37 INFO - mozversion platform_changeset: 27dade5e0c8350189eeb6495d70a9fb25ce137a9 07:11:37 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 07:11:37 INFO - mozversion platform_version: 54.0a1 07:11:37 INFO - using testdate: 1487862697 07:11:37 INFO - actual date: 1487862697 07:11:37 INFO - starting webserver on 'localhost:49301' 07:11:37 INFO - SUITE-START | Running 2 tests 07:11:37 INFO - TEST-START | dromaeo_css 07:11:37 INFO - operating with platform_type : mac_ 07:11:37 INFO - Initialising browser for dromaeo_css test... 07:11:37 INFO - TEST-INFO | started process 29757 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/tmpu2GBZ6/profile http://localhost:49301/getInfo.html) 07:11:38 INFO - PROCESS | 29757 | 1487862698430 addons.xpi WARN Error loading bootstrap.js for disableSHA1rollout@mozilla.org: Error opening input stream (invalid filename?): jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/disableSHA1rollout@mozilla.org.xpi!/bootstrap.js 07:11:38 INFO - PROCESS | 29757 | 1487862698431 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method install 07:11:38 INFO - PROCESS | 29757 | 1487862698471 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method startup 07:11:40 INFO - PROCESS | 29757 | __metrics Screen width/height:1600/1200 07:11:40 INFO - PROCESS | 29757 | colorDepth:24 07:11:40 INFO - PROCESS | 29757 | Browser inner width/height: 1024/689 07:11:40 INFO - PROCESS | 29757 | __metrics 07:11:40 INFO - PROCESS | 29757 | 1487862700353 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method shutdown 07:11:40 INFO - PROCESS | 29757 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:11:40 INFO - PROCESS | 29757 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:11:41 INFO - TEST-INFO | 29757: exit 0 07:11:41 INFO - Browser initialized. 07:11:41 INFO - Running cycle 1/1 for dromaeo_css test... 07:11:41 INFO - TEST-INFO | started process 29760 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/tmpu2GBZ6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:11:41 INFO - PROCESS | 29760 | 1487862701560 addons.xpi WARN Error loading bootstrap.js for disableSHA1rollout@mozilla.org: Error opening input stream (invalid filename?): jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/disableSHA1rollout@mozilla.org.xpi!/bootstrap.js 07:11:41 INFO - PROCESS | 29760 | 1487862701561 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method startup 07:11:53 INFO - PROCESS | 29760 | RSS: Main: 231534592 07:11:53 INFO - PROCESS | 29760 | 07:14:57 INFO - PROCESS | 29760 | Cycle 1(1): loaded http://localhost:49301/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49301/tests/dromaeo/cssquery-ext.html) 07:14:59 INFO - PROCESS | 29760 | RSS: Main: 375521280 07:14:59 INFO - PROCESS | 29760 | 07:16:43 INFO - PROCESS | 29760 | ************************* 07:16:43 INFO - PROCESS | 29760 | A coding exception was thrown and uncaught in a Task. 07:16:43 INFO - PROCESS | 29760 | 07:16:43 ERROR - PROCESS | 29760 | Full message: TypeError: NetworkError when attempting to fetch resource. 07:16:43 INFO - PROCESS | 29760 | Full stack: 07:16:43 INFO - PROCESS | 29760 | ************************* 07:18:02 INFO - PROCESS | 29760 | Cycle 1(1): loaded http://localhost:49301/tests/dromaeo/cssquery-ext.html (next: http://localhost:49301/tests/dromaeo/cssquery-jquery.html) 07:18:03 INFO - PROCESS | 29760 | RSS: Main: 374771712 07:18:03 INFO - PROCESS | 29760 | 07:21:05 INFO - PROCESS | 29760 | Cycle 1(1): loaded http://localhost:49301/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49301/tests/dromaeo/cssquery-mootools.html) 07:21:06 INFO - PROCESS | 29760 | RSS: Main: 337776640 07:21:06 INFO - PROCESS | 29760 | 07:24:08 INFO - PROCESS | 29760 | Cycle 1(1): loaded http://localhost:49301/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49301/tests/dromaeo/cssquery-prototype.html) 07:24:09 INFO - PROCESS | 29760 | RSS: Main: 342192128 07:24:09 INFO - PROCESS | 29760 | 07:27:13 INFO - PROCESS | 29760 | Cycle 1(1): loaded http://localhost:49301/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49301/tests/dromaeo/cssquery-yui.html) 07:27:13 INFO - PROCESS | 29760 | RSS: Main: 351649792 07:27:13 INFO - PROCESS | 29760 | 07:30:17 INFO - PROCESS | 29760 | Cycle 1(1): loaded http://localhost:49301/tests/dromaeo/cssquery-yui.html (next: http://localhost:49301/tests/dromaeo/cssquery-dojo.html) 07:30:17 INFO - PROCESS | 29760 | RSS: Main: 453562368 07:30:17 INFO - PROCESS | 29760 | 07:30:17 INFO - PROCESS | 29760 | __start_tp_report 07:30:17 INFO - PROCESS | 29760 | _x_x_mozilla_page_load 07:30:17 INFO - PROCESS | 29760 | _x_x_mozilla_page_load_details 07:30:17 INFO - PROCESS | 29760 | |i|pagename|runs| 07:30:17 INFO - PROCESS | 29760 | |0;dojo.html;3140.9371812563745;3296.9505457418136;3309.5035744638317;3318.303156337169;3354.9329013419733;5461.18082287657;5529.585281103923;5631.746571404286;5692.658440493568;5710.543156547482;5565.220869078333;5583.218349431073;5607.046802043652;5638.718064096789;5650.660960342382;4819.301201325806;5009.4239162496415;5075.340205773245;5112.233165025257;5288.418273989858;5209.869753256161;5247.501487358695;5276.762545685434;5277.86805329866;5299.072662284085;4131.834726610962;5042.899142017158;5102.668326558799;5113.002964421939;5116.130257856174;5163.302954101186;5167.509086636764;5170.482951704837;5248.291480650139;5264;5048.29323894655;5122.590192784568;5140.460251673558;5140.537351638371;5194.324737784102;5471.480209380143;5533.170024496358;5545.057340252127;5591.6365436246515;5637.323521177444;6090.847728806771;6097.993831017877;6102;6107.725152368154;6113.694315284262;6041.184440100574;6117.623634236799;6119.258838874149;6127.017204504793;6136.746076527109;5626.634268772516;5669.376368599451;5677.403872593401;5685.772569097231;5691.886162276773;6222.471089957314;6246.187995560639;6246.937530624636;6249.843753906189;6250.781222657185;5986.161937328778;5995.100734889714;5996.910046349309;6008.759649614054;6032.728527216286;6168.383161683796;6206.379362063757;6229.4393504584805;6231.22109736283;6234.9688251558455;6166.969165154235;6169.2288463942;6181.474574661204;6182.907256391158;6190.535709821781;5919.67441790697;6199.783007594712;6205;6207.751689932443;6217.533684973645;4788.4253889533475;4888.511148885154;4894.853154405374;4899.657024008286;4904.950950490521;4255.340422234559;4323.567643235714;4328.112736888993;4452.198604251266;4458.9331160032625;3813.7711737295294;5519.613627046148;5581.967336042764;5654.123610840326;5664.773409063674;6257.812265632038;6268.028455589391;6268.059791031291;6268.811935641938;6270.2789179244055;5685.147227915763;5691.317041954909;5694.032014557535;5704.11586204139;5739.368669446358;5210.374755029421;5278.155495120763;5279.788808447696;5325.600579956441;5406.297181366476;6247.406496382836;6247.843803904848;6253.405926436982;6267.905981410191;6270.968645156745;6064.090386441892;6141.424638644238;6146.200993870947;6148.016317389196;6151.446369826737;6178.474829639531;6238.282597501164;6238.750449982132;6258.687065646608;6260;6069.514438844793;6071.301800293023;6076.817695469143;6079.331273559993;6080.604760690541;6120.264288666237;6124.77764545833;6129.817282374624;6134.265036186674;6140.6576278636085;5901.970490147522;5921.99326114553;5962.2249107615735;6001.849953751016;6009.609375390585;5940.1683764272175;6016.759329626766;6033.788817391546;6051.273847138372;6056.485198757979;4666.883327916694;5555.416681248573;5708.172315014217;5800.622959507618;5827.475527202402;6039.365866583946;6102.458955353496;6113.263474096151;6117.979653188475;6123.020316749299;5912.73392697321;5953;5956.13636022783;5957.7319020645045;5960.225170727777 07:30:17 INFO - PROCESS | 29760 | |1;ext.html;486.0740289748029;488.2212870471599;490.65163733749034;495.3609843302138;506.1951497119579;27038;27462.725372746292;27474.587881181775;28163.436731265414;28187.15438536842;38929.416058759074;40844.59155408452;41000;43047.35428968568;43366.349504757454;9293.95353023234;9499.952500237488;9500.71497855062;9505.52472376382;9508.47703376314;7336.376408005326;9244.260459163252;9357.532123393838;9371.95314023429;9376.671816486425;7375.114986201664;7483.812904677399;7505.36204422622;11065.668029959113;11466.31202127875;10267.794644107076;10724.410157441338;11207.935246151643;11276;11313;11108.833367499494;11214.215004949658;11225.438728063564;11227.607033753779;11228.101751859831;27381.86309068462;27974.440511189867;37610.62389376099;38027;38108.61891381079;3088.984555077233;3829.9617003829885;3861.613838616144;3893.7274390792654;4031.6371526562457;29127.563086553717;29447.96932107366;29599;30056.54915176274;30158.69841301581;2576.0414233821484;3133.514305282662;3220.871165153391;3279.180204948763;3283.343331333719;394814;396158.019209905;400606;401797;411347;55925;56190.719046404505;56211.71894140585;56275.71862140663;56299.303132405534;57855.71072144612;58032.12951805726;58087.41912580904;58312.12531812026;58313.12530312049;15110.622234444227;15336.463223787337;15338.693226135414;15350.923245383701;15425;11983.221090629082;12050.517979280907;12054.457549410123;12054.51781928713;12062.095342849147;13511.851492623236;14898.851011489747;15213.771793423108;15229.390824367123;15295.923520382326;6142.447179753844;6846.965765171142;6915.273896240822;7312.597807120553;7372.373348265351;813.3046245460165;830.8878301429351;870.5429649433971;874.711345256064;877.0746862060587;27556.724432755414;28449.857750711115;28922.710772892002;29119.85440072786;29224.26939326534;1022.9641962531275;1034.1158309645145;1068.2735739696925;1069.187417562658;1152.4525850221246;13221.603351899266;14630.414783408758;14646.487372941896;14904.329305181293;15001.624959376102;194991;195092;196103.01948490166;196190;479856;55304;55465.44534554603;55555.722221388634;55591.16613250804;55740.72129639326;1356.9389377478037;1390.6175801654626;1393.9930300348433;1394.385527136639;1401.4814518627968;27110.864445677646;27226.31934201661;28209.448480332958;28497;28662;45617.54382456133;46003.76998114988;46006.309905352115;46024.30963535616;46031;403801;404801;406685.69914353336;407449;407545;35815;36394;36456;36547;36684.81657591802;39941.600583993786;41743.16513669649;41771;43052.35421468743;43395.48115815931;31967;32526.83736581302;33791.15522111967;35170.47244291286;35198;33886.66113338835;33968.66031339655;34605;35108.12229694278;35112.47331289982;33165.83417082899;33911;34439.65560344364;34457.310853782285;34505;24673.506529868944;29701.702982970757;30423;30441.543376848924;30447.695523044487;21250.14999400007;21660.566788663822;23295.650565241194;23730.288091357288;23986 07:30:17 INFO - PROCESS | 29760 | |2;jquery.html;358.1083103073348;361.2449979542756;385.212240967222;395.0715817828104;408.81398963471605;13687.315634218287;13804.03371763977;19792.796349697404;20498.89750551249;20923.476913077182;20301.898490507563;20519.897400513015;20825.583488330223;20867.373978780623;21108.366748997516;4673.649476289275;6046.153538504613;6087.147799308078;6093.238345206849;6130.846728831771;5776.971115144418;5950.672713000805;6078.270607527103;6101.176341193948;6178.258608966908;5189.688618682872;6142.570020098595;6152.630842149463;6157.815265542018;6158.107074474198;6059;6563.868722625544;6717.059611654324;6737.292584278678;6740.427063699592;5084.618653600995;5732.856678583069;5880.088586269135;5961.5230781537375;5997.250343707036;12786.61640150797;12953.48186072556;12985.350732463434;12998.805017924738;13001.15492492985;2571.7556832100922;2573.3052075939577;2577.3943123365993;2586.405126820842;2593.818432709711;9868.851967220497;9952.502374881227;10083.798324033552;10243.436610986393;10579.576816927314;1747.7116275814476;1777.8311060449366;1784.8304411080926;1825.3063835742332;1845.7877344105466;81730;87464;107916;108037.45981270121;109270;9530.571124299424;11318.26431281964;11469;11488.425578721197;11729.765404692029;12192;13789;13794.034417590872;14164.220967846868;14182.716345673029;7060.7881763547175;7466.440016998747;7621.352185064332;8338.374621903382;8373.352789202052;7896.881546776803;8027.15714849944;8031.718889838828;8191;8201.917980820235;3891.7859517726797;4672.32251323563;4759.9524004759505;4828.61371090312;4847.394075740533;1936.6998115292151;1967.8622496425112;1977.4957385866512;1990.5819777846525;1993.4418362858423;750.6246876561719;850.6767428377177;865.9653613855501;905.4069584422214;907.6823111910778;20564;21924;21974.121035158736;23176.3047108587;23234.65148022781;811.217175425717;929.8977112517617;952.4808979106404;954.9713508594754;997.7804882925744;6733.3939945405145;6780.457563394916;6817.727290908408;6917.371398868398;6994.195667498303;490295;551456;566797;568499;572646;114589;144140;144230.27884860508;147029.26485367503;147951;1027.6557353286582;1057.571683468212;1073.4847273308856;1074.4949873559415;1076.4079756134095;11918.642440726791;11988.160828741904;12134.575289864812;12167.634970950885;12454.750904982031;57440.71279643742;58200.4179958195;59158;59678.403215967286;60092.29584668613;85858;86351;87832;88056;88320.5583972076;8257.545834979133;8296.958515207385;8411.3270938326;8483.74548763538;8486.787830304293;12237.87762122403;12997.675058123623;13081.084324097603;13311.334433278103;13502.662433439242;10849.511771970123;11066.170037247077;11345.035671967646;11364.147688923198;11419.257748246337;8978.653202019488;11147.777044459228;11151.386673733023;11500.25248358854;11533.480993355151;11186.216964812385;11467.197296189186;11532.538698451968;11795.823062654239;11872;6893.75871844483;7494.475386722877;7560.092788865151;8402.579871006303;8683.609237584451;9403.388779729295;10845.728856778644;11118.054965327714;11176.938190871853;11422.971932526112 07:30:17 INFO - PROCESS | 29760 | |3;mootools.html;1430.2920054572987;1433.527622783135;1454.432771219224;1456.5703117580313;1459.0880699562774;8086.070101938279;8539.444936079151;8543.700970466034;8557.614907329178;8558.871616925751;8121.75634730959;8398.958005209965;8408.285295749869;8560.10118937512;8563.78590535237;2240.8543444676125;2274.4996100857866;2277.6583512473094;2280.851744636602;2284.1662793080573;2096.6016456873144;2119.2264823339524;2121.7029615853794;2130.7230060092165;2132.0618927671776;2234.72065991751;2244.2930476899805;2264.864108153508;2272.284230467406;2273.27255278311;2208.6907832903407;2219.6670499425054;2229.687843701883;2235.10595761695;2237.2505210754334;2210.6573481110454;2283.9885800571055;2288.096202000217;2289.8740569268684;2294.713160854893;8275.420720549564;8433.95783021081;8446.23983841451;8457.53483558404;8461.53461559614;5073.264376665363;5210.583153347723;5212.296339994128;5216.765245563921;5223.190405487156;2790.1211118497813;3231.8222497762613;3478.9478157827652;3525.1010992197084;3526.6297038811067;1190.6190019193862;1195.4022988505747;1195.8744331845119;1252.768237875996;1268.0869773762877;10074.194064474897;10254.436006019667;10270.383776973405;10833.83749243762;10848.620298289521;3140.371925614886;3998.600489828595;4154.626083652486;4157.293260145782;4294.269974104347;8577.742667719978;8731.995820480599;8744.78138046554;8751.256143227896;8752.956235218911;5098.056859481007;5098.337216161875;5099.69401835891;5100.566451851634;5105.719185444762;4568.109218702321;4753.4295884493395;4754.381930349102;4816.470188279286;4817.686850354716;3500.789952602801;3578.906339791199;3618.5115009473384;3659.670629643345;3672.797996110186;949.5015117063459;1119.4682525800342;1123.775244951013;1131.5417256011328;1136.2160109524566;864.5547543015352;880.3969281042506;881.5900606218139;893.9553022348856;921.4335629429996;4888.584470319992;5399.568034557226;5404.810831620953;5613.831585052373;5644.5484361251;547.7206624621472;560.025555533369;587.6033677267827;590.3417689276512;590.9940900590939;3617.782933024027;3631.6368363164;3718.6467285607823;3729;3766.0020094674933;7918.960405197937;9937.354071985297;10062.396256224503;10086.092251697382;10095.192384609214;10012.199024078056;10036.096751292418;10041.297109202285;10046.899531004743;10084.49577521121;7613.8681169272695;7695.076590809139;7804.3366313862825;7832.84334313322;7884.053913530413;5307.41618421981;5432.891342173213;5445.59158063139;5503.3395992481155;5633.605647604629;9173.862392064259;9245.583948722458;9263.258939284706;9266.258699303904;9272.907270927204;6482.902756458751;8579.828403431771;9074.818503629758;9132.726018219464;9233.538323083685;6144.2319710036245;6258.249010118815;6261.24865016201;6269.467095296951;6272.655003974825;6520.706568204348;7106.218315985341;7191.20896701352;7442.516236444614;7693.6537855795505;5957.642541447527;7010.509264351446;7061.399781018466;7134.892976605459;7321.560706357635;7045.330693584101;7047.718091276497;7050.083489146378;7076.221615622175;7099.893501597377;6311.905321420274;6317.778877739257;6345.555811093179;6390.041493775785;6413.935860641333;4824.276358546246;4878.268259761065;4944.851654450372;4981;5122.282880396673;4279.636230920407;4743.430788305425;4984.102861484967;5006.324146240211;5017.272495488207 07:30:17 INFO - PROCESS | 29760 | |4;prototype.html;359.50029459051916;366.16331682106386;370.621965595093;375.47808546120916;376.9849206031761;4424.778761061946;5231.503007214314;5247.842564723064;5263.947360526394;5313.654612450197;4522.231220692482;4798.4961579034225;4894.09459250039;5168.586513078954;5267.57691461927;3683.9263214735756;3934.3311637021775;3991.2416640838146;3995.60043995601;4002.2995975704134;3903.238868420659;3953.169834334793;3972.423998520213;3988.80055997199;4050.1089760252844;3929.2141571685634;3937.0118090364326;3959.6832253419657;3962.3358189560454;3976.403539469088;3288.4574045282498;3569.964300357015;3637.290728307981;3987.820548075329;4014.3577027675433;2853.9155121054073;2946.7053294670577;3405.165734395089;3412.7269818414466;3440.3807314683345;4792.808287668489;5043.344365232533;5070.08738427083;5096.802251470901;5133.230015497668;4483.21543729846;4498.8425405110665;4499.572540608637;4502.482214545336;4601.309803529464;5128.615353848439;5397.434743924257;5434.4404415108165;5441.537469315113;5455.9633669603045;3732.2722069196516;3736.906577335561;3745.3632882410056;3751.4185301278344;3787.5833658297565;4838.346823178861;5321.574274058066;5483.616146869721;5580.91628625571;5654.4062873398525;3113.9879539149865;3556.182078121995;3625.3111908737255;3680.822136916161;3738.869139580156;3221.693939075784;3826.900210829237;3831.7700937943255;3834.750741201813;3866.149447121629;3611.656892595199;4077.857274995361;4202.768847713405;4429.579189976947;4443.311286750559;4155.815592556168;5140.614453915971;5281.445448227958;5384.730763461889;5460.672359658433;1162.2213117211488;1234.2532767675607;1239.9070069744805;1248.2385744695728;1250.7373451575272;672.794797586731;738.3945164964678;812.6058861452191;841.2807049972308;861.1517655109712;4138.503379594468;4139.627433530996;4585.128825523207;4629.907401852011;4817.0365926814775;4520.751358675238;4941.431735350408;4942.654014219042;5174.7153906535505;5270.578353731692;4079.4492743479846;4496.437945256843;4754.667173297836;4838.7338696372035;4853.93213493031;2213.756486786452;2768.9169324920285;2804.284907348613;2804.495190865623;2847.5443928971267;5135;5156.845294641167;5323.653962492425;5516.806911758069;5586.273784408082;4177.8328866845595;4671.252599584118;4943.777530011158;5126.359205099363;5470.890582188414;4498.370228168124;4925.827596034122;5016.921361907207;5501.614886957955;5547.44525547442;4731.012974550766;5051.4695956924;5291.095205725858;5341.436110666824;5368.087425137814;4864.36763220779;5057.8735531612;5302.867428314169;5310.495502927216;5394.460553944574;4671.392718946515;5391.433899440659;5433.375161856437;5487.451254874481;5630.5777066720975;3324.5844269466315;3615.9819200903826;3621.4386770050683;4016.64610050611;4069.7558146511305;4725.50382209863;5634.295713035871;5671.517920976763;5706.6005379624685;5740.90922724681;5230.34620672416;5327.334083239595;5462.235287059735;5474.479924407175;5515.558755299647;4823.469418363907;4894.518139683577;5061.822836200715;5093.617978651542;5471.753771080232;4337.609615134653;4533.735710288587;5277.709725965109;5315.617939335723;5372.782365842794;4741.739204343794;5168.198929165986;5331.253624492651;5430.728463576885;5434.663094869245;4869.634777391639;4894.902101958012;5338.3593968724;5357.303550538561;5373.274607928036 07:30:17 INFO - PROCESS | 29760 | |5;yui.html;722.479814533536;730.7734602273292;759.3393747439728;817.4155478832635;818.64388990789;14327;16499;17212.39756608519;17258;17269.913650431747;15825.762613560806;15916;15938.442154524593;15971.920140399281;15978.041317520956;878.156969309462;903.2954295649371;905.8822353094089;908.1644886704248;908.6365453818493;883.8055627761883;900.5767289373983;903.7153296711548;904.267543289934;930.9581068851884;946.1957336264155;948.184561277301;948.928830337724;950.5769932380093;952.6141912525403;944.0370821761833;945.754103932979;946.4179529589253;946.6118891254622;947.5877993073035;701.9929800701979;702.0066605752853;702.5082442290366;703.0017375326997;706.254901079361;14801.777973330409;15252.694946101126;15304;15319.540413787605;15328.616784580363;13669.04319693249;13987.79018314726;14133.646658833508;14285;14320.14079155254;9420.528973551294;11618.186726929118;11656.125790565657;11755;12124;2982.537706655472;3636.090977255686;3692.132348898007;3778.110944527736;3793.1275806564367;75253.99999999945;92512.0748792517;93638;94456.05543944541;95999.16265021199;10304.948475257726;10373.792524149625;10415.739606509898;10594.470276486145;11002.394868282161;21292.893535532225;21790.346289611338;21815.67276490854;21826.236081737065;21888.781112188994;7277.599940445235;7533.1336896256535;7591.506552074097;7594.81012974669;7815.3356964657505;4122.876313710593;4235.174141042528;4236.131593023423;4247.87256382309;4250.277452833026;3153.4639111351125;3207.935841283208;3220.0500852248642;3248.123006788201;3253.072874230833;668.9864854745065;672.9730810767612;679.8640271945532;685;689.3520091114336;626.8276224038426;627.5638431290209;670.7182983146993;679.6941376380549;690.9267538337953;8625.396222264506;9810.068043535854;9836.60653573849;9858.359206651545;9956.651517197008;635.7721154323692;674.1270055278426;684.6793053689565;687.2131409536054;714.2500374606642;3994.78028708418;4445.5776701213335;4479.17135329965;4726.645501587326;4886.523945056305;90952.54523727471;92980.60529092069;93401;96971;100613;46989.76505117521;52782.41652750424;53231.46768532343;54948.45051549433;81363.59318203489;41320;69667.65166174137;73003.63498182475;75179;76109;10136.310398963284;10531.420771857622;10550.683479495627;10653.254272200871;10670.73323166927;31298.843505782326;46670.53329466662;47441.76279118582;47577.76211118922;47910;47397.763011184725;64330;64647.35352646413;66139;66228;12555.058370622057;12565.18326308787;12626.494940202288;12687.429065692251;13332;11500.367479788358;13873.65315867071;14052.859471405154;14240.928795355956;14304.785428218376;11907.727270471738;12114.95917699253;12835.679108022374;13190.142640728322;13220.40508177115;12935.223886566837;13170.457876094826;13242.53651122206;13246.205227685985;13271.535496257584;12179.903808657264;12464.016568481009;12479.625611231677;12529.248245105322;12531.874681253435;9274.79427674398;9688.951555242178;10554.736131596772;10773;11301.444942700547;6837.487188460786;8973.28213742886;10283.331583447052;10409.531571079167;10411.947940260554 07:30:17 INFO - PROCESS | 29760 | __end_tp_report 07:30:17 INFO - PROCESS | 29760 | __start_cc_report 07:30:17 INFO - PROCESS | 29760 | _x_x_mozilla_cycle_collect,2378 07:30:17 INFO - PROCESS | 29760 | __end_cc_report 07:30:17 INFO - PROCESS | 29760 | __startTimestamp1487863817607__endTimestamp 07:30:17 INFO - PROCESS | 29760 | 07:30:17 INFO - PROCESS | 29760 | ------- Summary: start ------- 07:30:17 INFO - PROCESS | 29760 | Number of tests: 6 07:30:17 INFO - PROCESS | 29760 | 07:30:17 INFO - PROCESS | 29760 | [#0] dojo.html Cycles:165 Average:5648.55 Median:5919.67 stddev:667.91 (11.3%) stddev-sans-first:640.34 07:30:17 INFO - PROCESS | 29760 | Values: 3140.9 3297.0 3309.5 3318.3 3354.9 5461.2 5529.6 5631.7 5692.7 5710.5 5565.2 5583.2 5607.0 5638.7 5650.7 4819.3 5009.4 5075.3 5112.2 5288.4 5209.9 5247.5 5276.8 5277.9 5299.1 4131.8 5042.9 5102.7 5113.0 5116.1 5163.3 5167.5 5170.5 5248.3 5264.0 5048.3 5122.6 5140.5 5140.5 5194.3 5471.5 5533.2 5545.1 5591.6 5637.3 6090.8 6098.0 6102.0 6107.7 6113.7 6041.2 6117.6 6119.3 6127.0 6136.7 5626.6 5669.4 5677.4 5685.8 5691.9 6222.5 6246.2 6246.9 6249.8 6250.8 5986.2 5995.1 5996.9 6008.8 6032.7 6168.4 6206.4 6229.4 6231.2 6235.0 6167.0 6169.2 6181.5 6182.9 6190.5 5919.7 6199.8 6205.0 6207.8 6217.5 4788.4 4888.5 4894.9 4899.7 4905.0 4255.3 4323.6 4328.1 4452.2 4458.9 3813.8 5519.6 5582.0 5654.1 5664.8 6257.8 6268.0 6268.1 6268.8 6270.3 5685.1 5691.3 5694.0 5704.1 5739.4 5210.4 5278.2 5279.8 5325.6 5406.3 6247.4 6247.8 6253.4 6267.9 6271.0 6064.1 6141.4 6146.2 6148.0 6151.4 6178.5 6238.3 6238.8 6258.7 6260.0 6069.5 6071.3 6076.8 6079.3 6080.6 6120.3 6124.8 6129.8 6134.3 6140.7 5902.0 5922.0 5962.2 6001.8 6009.6 5940.2 6016.8 6033.8 6051.3 6056.5 4666.9 5555.4 5708.2 5800.6 5827.5 6039.4 6102.5 6113.3 6118.0 6123.0 5912.7 5953.0 5956.1 5957.7 5960.2 07:30:17 INFO - PROCESS | 29760 | 07:30:17 INFO - PROCESS | 29760 | [#1] ext.html Cycles:180 Average:50578.33 Median:34204.16 stddev:97087.10 (283.8%) stddev-sans-first:97286.61 07:30:17 INFO - PROCESS | 29760 | Values: 486.1 488.2 490.7 495.4 506.2 27038.0 27462.7 27474.6 28163.4 28187.2 38929.4 40844.6 41000.0 43047.4 43366.3 9294.0 9500.0 9500.7 9505.5 9508.5 7336.4 9244.3 9357.5 9372.0 9376.7 7375.1 7483.8 7505.4 11065.7 11466.3 10267.8 10724.4 11207.9 11276.0 11313.0 11108.8 11214.2 11225.4 11227.6 11228.1 27381.9 27974.4 37610.6 38027.0 38108.6 3089.0 3830.0 3861.6 3893.7 4031.6 29127.6 29448.0 29599.0 30056.5 30158.7 2576.0 3133.5 3220.9 3279.2 3283.3 394814.0 396158.0 400606.0 401797.0 411347.0 55925.0 56190.7 56211.7 56275.7 56299.3 57855.7 58032.1 58087.4 58312.1 58313.1 15110.6 15336.5 15338.7 15350.9 15425.0 11983.2 12050.5 12054.5 12054.5 12062.1 13511.9 14898.9 15213.8 15229.4 15295.9 6142.4 6847.0 6915.3 7312.6 7372.4 813.3 830.9 870.5 874.7 877.1 27556.7 28449.9 28922.7 29119.9 29224.3 1023.0 1034.1 1068.3 1069.2 1152.5 13221.6 14630.4 14646.5 14904.3 15001.6 194991.0 195092.0 196103.0 196190.0 479856.0 55304.0 55465.4 55555.7 55591.2 55740.7 1356.9 1390.6 1394.0 1394.4 1401.5 27110.9 27226.3 28209.4 28497.0 28662.0 45617.5 46003.8 46006.3 46024.3 46031.0 403801.0 404801.0 406685.7 407449.0 407545.0 35815.0 36394.0 36456.0 36547.0 36684.8 39941.6 41743.2 41771.0 43052.4 43395.5 31967.0 32526.8 33791.2 35170.5 35198.0 33886.7 33968.7 34605.0 35108.1 35112.5 33165.8 33911.0 34439.7 34457.3 34505.0 24673.5 29701.7 30423.0 30441.5 30447.7 21250.1 21660.6 23295.7 23730.3 23986.0 07:30:17 INFO - PROCESS | 29760 | 07:30:17 INFO - PROCESS | 29760 | [#2] jquery.html Cycles:180 Average:33216.84 Median:2138.50 stddev:92674.41 (4333.6%) stddev-sans-first:92901.55 07:30:17 INFO - PROCESS | 29760 | Values: 358.1 361.2 385.2 395.1 408.8 13687.3 13804.0 19792.8 20498.9 20923.5 20301.9 20519.9 20825.6 20867.4 21108.4 4673.6 6046.2 6087.1 6093.2 6130.8 5777.0 5950.7 6078.3 6101.2 6178.3 5189.7 6142.6 6152.6 6157.8 6158.1 6059.0 6563.9 6717.1 6737.3 6740.4 5084.6 5732.9 5880.1 5961.5 5997.3 12786.6 12953.5 12985.4 12998.8 13001.2 2571.8 2573.3 2577.4 2586.4 2593.8 9868.9 9952.5 10083.8 10243.4 10579.6 1747.7 1777.8 1784.8 1825.3 1845.8 81730.0 87464.0 107916.0 108037.5 109270.0 9530.6 11318.3 11469.0 11488.4 11729.8 12192.0 13789.0 13794.0 14164.2 14182.7 7060.8 7466.4 7621.4 8338.4 8373.4 7896.9 8027.2 8031.7 8191.0 8201.9 3891.8 4672.3 4760.0 4828.6 4847.4 1936.7 1967.9 1977.5 1990.6 1993.4 750.6 850.7 866.0 905.4 907.7 20564.0 21924.0 21974.1 23176.3 23234.7 811.2 929.9 952.5 955.0 997.8 6733.4 6780.5 6817.7 6917.4 6994.2 490295.0 551456.0 566797.0 568499.0 572646.0 114589.0 144140.0 144230.3 147029.3 147951.0 1027.7 1057.6 1073.5 1074.5 1076.4 11918.6 11988.2 12134.6 12167.6 12454.8 57440.7 58200.4 59158.0 59678.4 60092.3 85858.0 86351.0 87832.0 88056.0 88320.6 8257.5 8297.0 8411.3 8483.7 8486.8 12237.9 12997.7 13081.1 13311.3 13502.7 10849.5 11066.2 11345.0 11364.1 11419.3 8978.7 11147.8 11151.4 11500.3 11533.5 11186.2 11467.2 11532.5 11795.8 11872.0 6893.8 7494.5 7560.1 8402.6 8683.6 9403.4 10845.7 11118.1 11176.9 11423.0 07:30:17 INFO - PROCESS | 29760 | 07:30:17 INFO - PROCESS | 29760 | [#3] mootools.html Cycles:180 Average:5276.29 Median:5100.13 stddev:2917.86 (57.2%) stddev-sans-first:2911.73 07:30:17 INFO - PROCESS | 29760 | Values: 1430.3 1433.5 1454.4 1456.6 1459.1 8086.1 8539.4 8543.7 8557.6 8558.9 8121.8 8399.0 8408.3 8560.1 8563.8 2240.9 2274.5 2277.7 2280.9 2284.2 2096.6 2119.2 2121.7 2130.7 2132.1 2234.7 2244.3 2264.9 2272.3 2273.3 2208.7 2219.7 2229.7 2235.1 2237.3 2210.7 2284.0 2288.1 2289.9 2294.7 8275.4 8434.0 8446.2 8457.5 8461.5 5073.3 5210.6 5212.3 5216.8 5223.2 2790.1 3231.8 3478.9 3525.1 3526.6 1190.6 1195.4 1195.9 1252.8 1268.1 10074.2 10254.4 10270.4 10833.8 10848.6 3140.4 3998.6 4154.6 4157.3 4294.3 8577.7 8732.0 8744.8 8751.3 8753.0 5098.1 5098.3 5099.7 5100.6 5105.7 4568.1 4753.4 4754.4 4816.5 4817.7 3500.8 3578.9 3618.5 3659.7 3672.8 949.5 1119.5 1123.8 1131.5 1136.2 864.6 880.4 881.6 894.0 921.4 4888.6 5399.6 5404.8 5613.8 5644.5 547.7 560.0 587.6 590.3 591.0 3617.8 3631.6 3718.6 3729.0 3766.0 7919.0 9937.4 10062.4 10086.1 10095.2 10012.2 10036.1 10041.3 10046.9 10084.5 7613.9 7695.1 7804.3 7832.8 7884.1 5307.4 5432.9 5445.6 5503.3 5633.6 9173.9 9245.6 9263.3 9266.3 9272.9 6482.9 8579.8 9074.8 9132.7 9233.5 6144.2 6258.2 6261.2 6269.5 6272.7 6520.7 7106.2 7191.2 7442.5 7693.7 5957.6 7010.5 7061.4 7134.9 7321.6 7045.3 7047.7 7050.1 7076.2 7099.9 6311.9 6317.8 6345.6 6390.0 6413.9 4824.3 4878.3 4944.9 4981.0 5122.3 4279.6 4743.4 4984.1 5006.3 5017.3 07:30:17 INFO - PROCESS | 29760 | 07:30:17 INFO - PROCESS | 29760 | [#4] prototype.html Cycles:180 Average:4290.81 Median:4795.65 stddev:1302.00 (27.1%) stddev-sans-first:1271.77 07:30:17 INFO - PROCESS | 29760 | Values: 359.5 366.2 370.6 375.5 377.0 4424.8 5231.5 5247.8 5263.9 5313.7 4522.2 4798.5 4894.1 5168.6 5267.6 3683.9 3934.3 3991.2 3995.6 4002.3 3903.2 3953.2 3972.4 3988.8 4050.1 3929.2 3937.0 3959.7 3962.3 3976.4 3288.5 3570.0 3637.3 3987.8 4014.4 2853.9 2946.7 3405.2 3412.7 3440.4 4792.8 5043.3 5070.1 5096.8 5133.2 4483.2 4498.8 4499.6 4502.5 4601.3 5128.6 5397.4 5434.4 5441.5 5456.0 3732.3 3736.9 3745.4 3751.4 3787.6 4838.3 5321.6 5483.6 5580.9 5654.4 3114.0 3556.2 3625.3 3680.8 3738.9 3221.7 3826.9 3831.8 3834.8 3866.1 3611.7 4077.9 4202.8 4429.6 4443.3 4155.8 5140.6 5281.4 5384.7 5460.7 1162.2 1234.3 1239.9 1248.2 1250.7 672.8 738.4 812.6 841.3 861.2 4138.5 4139.6 4585.1 4629.9 4817.0 4520.8 4941.4 4942.7 5174.7 5270.6 4079.4 4496.4 4754.7 4838.7 4853.9 2213.8 2768.9 2804.3 2804.5 2847.5 5135.0 5156.8 5323.7 5516.8 5586.3 4177.8 4671.3 4943.8 5126.4 5470.9 4498.4 4925.8 5016.9 5501.6 5547.4 4731.0 5051.5 5291.1 5341.4 5368.1 4864.4 5057.9 5302.9 5310.5 5394.5 4671.4 5391.4 5433.4 5487.5 5630.6 3324.6 3616.0 3621.4 4016.6 4069.8 4725.5 5634.3 5671.5 5706.6 5740.9 5230.3 5327.3 5462.2 5474.5 5515.6 4823.5 4894.5 5061.8 5093.6 5471.8 4337.6 4533.7 5277.7 5315.6 5372.8 4741.7 5168.2 5331.3 5430.7 5434.7 4869.6 4894.9 5338.4 5357.3 5373.3 07:30:17 INFO - PROCESS | 29760 | 07:30:17 INFO - PROCESS | 29760 | [#5] yui.html Cycles:180 Average:18228.04 Median:25574.85 stddev:24984.64 (97.7%) stddev-sans-first:25020.15 07:30:17 INFO - PROCESS | 29760 | Values: 722.5 730.8 759.3 817.4 818.6 14327.0 16499.0 17212.4 17258.0 17269.9 15825.8 15916.0 15938.4 15971.9 15978.0 878.2 903.3 905.9 908.2 908.6 883.8 900.6 903.7 904.3 931.0 946.2 948.2 948.9 950.6 952.6 944.0 945.8 946.4 946.6 947.6 702.0 702.0 702.5 703.0 706.3 14801.8 15252.7 15304.0 15319.5 15328.6 13669.0 13987.8 14133.6 14285.0 14320.1 9420.5 11618.2 11656.1 11755.0 12124.0 2982.5 3636.1 3692.1 3778.1 3793.1 75254.0 92512.1 93638.0 94456.1 95999.2 10304.9 10373.8 10415.7 10594.5 11002.4 21292.9 21790.3 21815.7 21826.2 21888.8 7277.6 7533.1 7591.5 7594.8 7815.3 4122.9 4235.2 4236.1 4247.9 4250.3 3153.5 3207.9 3220.1 3248.1 3253.1 669.0 673.0 679.9 685.0 689.4 626.8 627.6 670.7 679.7 690.9 8625.4 9810.1 9836.6 9858.4 9956.7 635.8 674.1 684.7 687.2 714.3 3994.8 4445.6 4479.2 4726.6 4886.5 90952.5 92980.6 93401.0 96971.0 100613.0 46989.8 52782.4 53231.5 54948.5 81363.6 41320.0 69667.7 73003.6 75179.0 76109.0 10136.3 10531.4 10550.7 10653.3 10670.7 31298.8 46670.5 47441.8 47577.8 47910.0 47397.8 64330.0 64647.4 66139.0 66228.0 12555.1 12565.2 12626.5 12687.4 13332.0 11500.4 13873.7 14052.9 14240.9 14304.8 11907.7 12115.0 12835.7 13190.1 13220.4 12935.2 13170.5 13242.5 13246.2 13271.5 12179.9 12464.0 12479.6 12529.2 12531.9 9274.8 9689.0 10554.7 10773.0 11301.4 6837.5 8973.3 10283.3 10409.5 10411.9 07:30:17 INFO - PROCESS | 29760 | -------- Summary: end -------- 07:30:17 INFO - PROCESS | 29760 | 07:30:17 INFO - PROCESS | 29760 | 1487863817631 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method shutdown 07:30:18 INFO - TEST-INFO | 29760: exit 0 07:30:18 INFO - TEST-OK | dromaeo_css | took 1120459ms 07:30:18 INFO - TEST-START | kraken 07:30:18 INFO - operating with platform_type : mac_ 07:30:18 INFO - Initialising browser for kraken test... 07:30:18 INFO - TEST-INFO | started process 29768 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/tmpUUGLG_/profile http://localhost:49301/getInfo.html) 07:30:18 INFO - PROCESS | 29768 | 1487863818868 addons.xpi WARN Error loading bootstrap.js for disableSHA1rollout@mozilla.org: Error opening input stream (invalid filename?): jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/disableSHA1rollout@mozilla.org.xpi!/bootstrap.js 07:30:18 INFO - PROCESS | 29768 | 1487863818869 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method install 07:30:18 INFO - PROCESS | 29768 | 1487863818913 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method startup 07:30:21 INFO - PROCESS | 29768 | __metrics Screen width/height:1600/1200 07:30:21 INFO - PROCESS | 29768 | colorDepth:24 07:30:21 INFO - PROCESS | 29768 | Browser inner width/height: 1024/689 07:30:21 INFO - PROCESS | 29768 | __metrics 07:30:21 INFO - PROCESS | 29768 | 1487863821120 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method shutdown 07:30:21 INFO - PROCESS | 29768 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:30:21 INFO - PROCESS | 29768 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:30:21 INFO - TEST-INFO | 29768: exit 0 07:30:21 INFO - Browser initialized. 07:30:21 INFO - Running cycle 1/1 for kraken test... 07:30:21 INFO - TEST-INFO | started process 29769 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/T/tmpUUGLG_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:30:22 INFO - PROCESS | 29769 | 1487863822278 addons.xpi WARN Error loading bootstrap.js for disableSHA1rollout@mozilla.org: Error opening input stream (invalid filename?): jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/disableSHA1rollout@mozilla.org.xpi!/bootstrap.js 07:30:22 INFO - PROCESS | 29769 | 1487863822279 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method startup 07:30:34 INFO - PROCESS | 29769 | RSS: Main: 231559168 07:30:34 INFO - PROCESS | 29769 | 07:30:36 INFO - PROCESS | 29769 | Cycle 1(1): loaded http://localhost:49301/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49301/tests/kraken/driver.html?testName=audio-beat-detection) 07:30:37 INFO - PROCESS | 29769 | RSS: Main: 284082176 07:30:37 INFO - PROCESS | 29769 | 07:30:40 INFO - PROCESS | 29769 | Cycle 1(1): loaded http://localhost:49301/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49301/tests/kraken/driver.html?testName=audio-dft) 07:30:41 INFO - PROCESS | 29769 | RSS: Main: 285966336 07:30:41 INFO - PROCESS | 29769 | 07:30:44 INFO - PROCESS | 29769 | Cycle 1(1): loaded http://localhost:49301/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49301/tests/kraken/driver.html?testName=audio-fft) 07:30:45 INFO - PROCESS | 29769 | RSS: Main: 378470400 07:30:45 INFO - PROCESS | 29769 | 07:30:47 INFO - PROCESS | 29769 | Cycle 1(1): loaded http://localhost:49301/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49301/tests/kraken/driver.html?testName=audio-oscillator) 07:30:47 INFO - PROCESS | 29769 | RSS: Main: 281272320 07:30:47 INFO - PROCESS | 29769 | 07:30:49 INFO - PROCESS | 29769 | Cycle 1(1): loaded http://localhost:49301/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49301/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:30:50 INFO - PROCESS | 29769 | RSS: Main: 282763264 07:30:50 INFO - PROCESS | 29769 | 07:30:53 INFO - PROCESS | 29769 | Cycle 1(1): loaded http://localhost:49301/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49301/tests/kraken/driver.html?testName=imaging-darkroom) 07:30:53 INFO - PROCESS | 29769 | RSS: Main: 325840896 07:30:53 INFO - PROCESS | 29769 | 07:30:56 INFO - PROCESS | 29769 | Cycle 1(1): loaded http://localhost:49301/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49301/tests/kraken/driver.html?testName=imaging-desaturate) 07:30:57 INFO - PROCESS | 29769 | RSS: Main: 328228864 07:30:57 INFO - PROCESS | 29769 | 07:31:00 INFO - PROCESS | 29769 | Cycle 1(1): loaded http://localhost:49301/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49301/tests/kraken/driver.html?testName=json-parse-financial) 07:31:01 INFO - PROCESS | 29769 | RSS: Main: 326959104 07:31:01 INFO - PROCESS | 29769 | 07:31:02 INFO - PROCESS | 29769 | Cycle 1(1): loaded http://localhost:49301/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49301/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:31:03 INFO - PROCESS | 29769 | RSS: Main: 301076480 07:31:03 INFO - PROCESS | 29769 | 07:31:05 INFO - PROCESS | 29769 | Cycle 1(1): loaded http://localhost:49301/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49301/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:31:05 INFO - PROCESS | 29769 | RSS: Main: 304865280 07:31:05 INFO - PROCESS | 29769 | 07:31:08 INFO - PROCESS | 29769 | Cycle 1(1): loaded http://localhost:49301/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49301/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:31:08 INFO - PROCESS | 29769 | RSS: Main: 305868800 07:31:08 INFO - PROCESS | 29769 | 07:31:11 INFO - PROCESS | 29769 | Cycle 1(1): loaded http://localhost:49301/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49301/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:31:12 INFO - PROCESS | 29769 | RSS: Main: 321847296 07:31:12 INFO - PROCESS | 29769 | 07:31:15 INFO - PROCESS | 29769 | Cycle 1(1): loaded http://localhost:49301/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49301/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:31:16 INFO - PROCESS | 29769 | RSS: Main: 315486208 07:31:16 INFO - PROCESS | 29769 | 07:31:17 INFO - PROCESS | 29769 | Cycle 1(1): loaded http://localhost:49301/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49301/tests/kraken/driver.html?testName=ai-astar) 07:31:18 INFO - PROCESS | 29769 | RSS: Main: 360861696 07:31:18 INFO - PROCESS | 29769 | 07:31:18 INFO - PROCESS | 29769 | __start_tp_report 07:31:18 INFO - PROCESS | 29769 | _x_x_mozilla_page_load 07:31:18 INFO - PROCESS | 29769 | _x_x_mozilla_page_load_details 07:31:18 INFO - PROCESS | 29769 | |i|pagename|runs| 07:31:18 INFO - PROCESS | 29769 | |0;ai-astar;115;116;114;113;115;117;114;114;115;115 07:31:18 INFO - PROCESS | 29769 | |1;audio-beat-detection;147;113;113;151;112;119;141;140;171;114 07:31:18 INFO - PROCESS | 29769 | |2;audio-dft;184;187;186;186;184;182;185;187;185;182 07:31:18 INFO - PROCESS | 29769 | |3;audio-fft;79;63;62;91;68;60;60;61;77;58 07:31:18 INFO - PROCESS | 29769 | |4;audio-oscillator;88;70;88;69;69;71;87;91;96;87 07:31:18 INFO - PROCESS | 29769 | |5;imaging-gaussian-blur;86;86;87;87;87;87;87;86;87;86 07:31:18 INFO - PROCESS | 29769 | |6;imaging-darkroom;94;98;97;95;94;94;95;103;95;94 07:31:18 INFO - PROCESS | 29769 | |7;imaging-desaturate;85;87;87;87;85;86;110;134;141;86 07:31:18 INFO - PROCESS | 29769 | |8;json-parse-financial;58;59;59;57;59;59;59;59;59;59 07:31:18 INFO - PROCESS | 29769 | |9;json-stringify-tinderbox;65;78;75;75;72;70;70;72;71;72 07:31:18 INFO - PROCESS | 29769 | |10;stanford-crypto-aes;88;88;89;88;91;88;85;94;97;104 07:31:18 INFO - PROCESS | 29769 | |11;stanford-crypto-ccm;158;150;133;144;125;149;146;141;208;140 07:31:18 INFO - PROCESS | 29769 | |12;stanford-crypto-pbkdf2;174;175;174;171;172;172;174;171;172;171 07:31:18 INFO - PROCESS | 29769 | |13;stanford-crypto-sha256-iterative;67;68;69;68;67;68;66;67;66;68 07:31:18 INFO - PROCESS | 29769 | __end_tp_report 07:31:18 INFO - PROCESS | 29769 | __start_cc_report 07:31:18 INFO - PROCESS | 29769 | _x_x_mozilla_cycle_collect,2120 07:31:18 INFO - PROCESS | 29769 | __end_cc_report 07:31:18 INFO - PROCESS | 29769 | __startTimestamp1487863878153__endTimestamp 07:31:18 INFO - PROCESS | 29769 | 07:31:18 INFO - PROCESS | 29769 | ------- Summary: start ------- 07:31:18 INFO - PROCESS | 29769 | Number of tests: 14 07:31:18 INFO - PROCESS | 29769 | 07:31:18 INFO - PROCESS | 29769 | [#0] ai-astar Cycles:10 Average:114.80 Median:115.00 stddev:1.14 (1.0%) stddev-sans-first:1.20 07:31:18 INFO - PROCESS | 29769 | Values: 115.0 116.0 114.0 113.0 115.0 117.0 114.0 114.0 115.0 115.0 07:31:18 INFO - PROCESS | 29769 | 07:31:18 INFO - PROCESS | 29769 | [#1] audio-beat-detection Cycles:10 Average:132.10 Median:140.50 stddev:20.73 (14.8%) stddev-sans-first:21.27 07:31:18 INFO - PROCESS | 29769 | Values: 147.0 113.0 113.0 151.0 112.0 119.0 141.0 140.0 171.0 114.0 07:31:18 INFO - PROCESS | 29769 | 07:31:18 INFO - PROCESS | 29769 | [#2] audio-dft Cycles:10 Average:184.80 Median:185.50 stddev:1.81 (1.0%) stddev-sans-first:1.90 07:31:18 INFO - PROCESS | 29769 | Values: 184.0 187.0 186.0 186.0 184.0 182.0 185.0 187.0 185.0 182.0 07:31:18 INFO - PROCESS | 29769 | 07:31:18 INFO - PROCESS | 29769 | [#3] audio-fft Cycles:10 Average:67.90 Median:65.50 stddev:10.90 (16.6%) stddev-sans-first:10.79 07:31:18 INFO - PROCESS | 29769 | Values: 79.0 63.0 62.0 91.0 68.0 60.0 60.0 61.0 77.0 58.0 07:31:18 INFO - PROCESS | 29769 | 07:31:18 INFO - PROCESS | 29769 | [#4] audio-oscillator Cycles:10 Average:81.60 Median:87.50 stddev:10.54 (12.0%) stddev-sans-first:10.93 07:31:18 INFO - PROCESS | 29769 | Values: 88.0 70.0 88.0 69.0 69.0 71.0 87.0 91.0 96.0 87.0 07:31:18 INFO - PROCESS | 29769 | 07:31:18 INFO - PROCESS | 29769 | [#5] imaging-gaussian-blur Cycles:10 Average:86.60 Median:87.00 stddev:0.52 (0.6%) stddev-sans-first:0.50 07:31:18 INFO - PROCESS | 29769 | Values: 86.0 86.0 87.0 87.0 87.0 87.0 87.0 86.0 87.0 86.0 07:31:18 INFO - PROCESS | 29769 | 07:31:18 INFO - PROCESS | 29769 | [#6] imaging-darkroom Cycles:10 Average:95.90 Median:95.00 stddev:2.85 (3.0%) stddev-sans-first:2.93 07:31:18 INFO - PROCESS | 29769 | Values: 94.0 98.0 97.0 95.0 94.0 94.0 95.0 103.0 95.0 94.0 07:31:18 INFO - PROCESS | 29769 | 07:31:18 INFO - PROCESS | 29769 | [#7] imaging-desaturate Cycles:10 Average:98.80 Median:86.00 stddev:21.79 (25.3%) stddev-sans-first:22.53 07:31:18 INFO - PROCESS | 29769 | Values: 85.0 87.0 87.0 87.0 85.0 86.0 110.0 134.0 141.0 86.0 07:31:18 INFO - PROCESS | 29769 | 07:31:18 INFO - PROCESS | 29769 | [#8] json-parse-financial Cycles:10 Average:58.70 Median:59.00 stddev:0.67 (1.1%) stddev-sans-first:0.67 07:31:18 INFO - PROCESS | 29769 | Values: 58.0 59.0 59.0 57.0 59.0 59.0 59.0 59.0 59.0 59.0 07:31:18 INFO - PROCESS | 29769 | 07:31:18 INFO - PROCESS | 29769 | [#9] json-stringify-tinderbox Cycles:10 Average:72.00 Median:72.00 stddev:3.53 (4.9%) stddev-sans-first:2.68 07:31:18 INFO - PROCESS | 29769 | Values: 65.0 78.0 75.0 75.0 72.0 70.0 70.0 72.0 71.0 72.0 07:31:18 INFO - PROCESS | 29769 | 07:31:18 INFO - PROCESS | 29769 | [#10] stanford-crypto-aes Cycles:10 Average:91.20 Median:88.50 stddev:5.67 (6.4%) stddev-sans-first:5.90 07:31:18 INFO - PROCESS | 29769 | Values: 88.0 88.0 89.0 88.0 91.0 88.0 85.0 94.0 97.0 104.0 07:31:18 INFO - PROCESS | 29769 | 07:31:18 INFO - PROCESS | 29769 | [#11] stanford-crypto-ccm Cycles:10 Average:149.40 Median:147.50 stddev:22.54 (15.3%) stddev-sans-first:23.69 07:31:18 INFO - PROCESS | 29769 | Values: 158.0 150.0 133.0 144.0 125.0 149.0 146.0 141.0 208.0 140.0 07:31:18 INFO - PROCESS | 29769 | 07:31:18 INFO - PROCESS | 29769 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.60 Median:173.00 stddev:1.51 (0.9%) stddev-sans-first:1.51 07:31:18 INFO - PROCESS | 29769 | Values: 174.0 175.0 174.0 171.0 172.0 172.0 174.0 171.0 172.0 171.0 07:31:18 INFO - PROCESS | 29769 | 07:31:18 INFO - PROCESS | 29769 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.40 Median:68.00 stddev:0.97 (1.4%) stddev-sans-first:1.01 07:31:18 INFO - PROCESS | 29769 | Values: 67.0 68.0 69.0 68.0 67.0 68.0 66.0 67.0 66.0 68.0 07:31:18 INFO - PROCESS | 29769 | -------- Summary: end -------- 07:31:18 INFO - PROCESS | 29769 | 07:31:18 INFO - PROCESS | 29769 | 1487863878174 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method shutdown 07:31:19 INFO - TEST-INFO | 29769: exit 0 07:31:19 INFO - TEST-OK | kraken | took 60758ms 07:31:19 INFO - SUITE-END | took 1181s 07:31:19 INFO - Completed test suite (00:19:41) 07:31:19 INFO - javascript benchmark 07:31:19 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6942.690069935922, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3140.9371812563745, 3296.9505457418136, 3309.5035744638317, 3318.303156337169, 3354.9329013419733, 5461.18082287657, 5529.585281103923, 5631.746571404286, 5692.658440493568, 5710.543156547482, 5565.220869078333, 5583.218349431073, 5607.046802043652, 5638.718064096789, 5650.660960342382, 4819.301201325806, 5009.4239162496415, 5075.340205773245, 5112.233165025257, 5288.418273989858, 5209.869753256161, 5247.501487358695, 5276.762545685434, 5277.86805329866, 5299.072662284085, 4131.834726610962, 5042.899142017158, 5102.668326558799, 5113.002964421939, 5116.130257856174, 5163.302954101186, 5167.509086636764, 5170.482951704837, 5248.291480650139, 5264.0, 5048.29323894655, 5122.590192784568, 5140.460251673558, 5140.537351638371, 5194.324737784102, 5471.480209380143, 5533.170024496358, 5545.057340252127, 5591.6365436246515, 5637.323521177444, 6090.847728806771, 6097.993831017877, 6102.0, 6107.725152368154, 6113.694315284262, 6041.184440100574, 6117.623634236799, 6119.258838874149, 6127.017204504793, 6136.746076527109, 5626.634268772516, 5669.376368599451, 5677.403872593401, 5685.772569097231, 5691.886162276773, 6222.471089957314, 6246.187995560639, 6246.937530624636, 6249.843753906189, 6250.781222657185, 5986.161937328778, 5995.100734889714, 5996.910046349309, 6008.759649614054, 6032.728527216286, 6168.383161683796, 6206.379362063757, 6229.4393504584805, 6231.22109736283, 6234.9688251558455, 6166.969165154235, 6169.2288463942, 6181.474574661204, 6182.907256391158, 6190.535709821781, 5919.67441790697, 6199.783007594712, 6205.0, 6207.751689932443, 6217.533684973645, 4788.4253889533475, 4888.511148885154, 4894.853154405374, 4899.657024008286, 4904.950950490521, 4255.340422234559, 4323.567643235714, 4328.112736888993, 4452.198604251266, 4458.9331160032625, 3813.7711737295294, 5519.613627046148, 5581.967336042764, 5654.123610840326, 5664.773409063674, 6257.812265632038, 6268.028455589391, 6268.059791031291, 6268.811935641938, 6270.2789179244055, 5685.147227915763, 5691.317041954909, 5694.032014557535, 5704.11586204139, 5739.368669446358, 5210.374755029421, 5278.155495120763, 5279.788808447696, 5325.600579956441, 5406.297181366476, 6247.406496382836, 6247.843803904848, 6253.405926436982, 6267.905981410191, 6270.968645156745, 6064.090386441892, 6141.424638644238, 6146.200993870947, 6148.016317389196, 6151.446369826737, 6178.474829639531, 6238.282597501164, 6238.750449982132, 6258.687065646608, 6260.0, 6069.514438844793, 6071.301800293023, 6076.817695469143, 6079.331273559993, 6080.604760690541, 6120.264288666237, 6124.77764545833, 6129.817282374624, 6134.265036186674, 6140.6576278636085, 5901.970490147522, 5921.99326114553, 5962.2249107615735, 6001.849953751016, 6009.609375390585, 5940.1683764272175, 6016.759329626766, 6033.788817391546, 6051.273847138372, 6056.485198757979, 4666.883327916694, 5555.416681248573, 5708.172315014217, 5800.622959507618, 5827.475527202402, 6039.365866583946, 6102.458955353496, 6113.263474096151, 6117.979653188475, 6123.020316749299, 5912.73392697321, 5953.0, 5956.13636022783, 5957.7319020645045, 5960.225170727777], "value": 5605.096674676543, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [486.0740289748029, 488.2212870471599, 490.65163733749034, 495.3609843302138, 506.1951497119579, 27038.0, 27462.725372746292, 27474.587881181775, 28163.436731265414, 28187.15438536842, 38929.416058759074, 40844.59155408452, 41000.0, 43047.35428968568, 43366.349504757454, 9293.95353023234, 9499.952500237488, 9500.71497855062, 9505.52472376382, 9508.47703376314, 7336.376408005326, 9244.260459163252, 9357.532123393838, 9371.95314023429, 9376.671816486425, 7375.114986201664, 7483.812904677399, 7505.36204422622, 11065.668029959113, 11466.31202127875, 10267.794644107076, 10724.410157441338, 11207.935246151643, 11276.0, 11313.0, 11108.833367499494, 11214.215004949658, 11225.438728063564, 11227.607033753779, 11228.101751859831, 27381.86309068462, 27974.440511189867, 37610.62389376099, 38027.0, 38108.61891381079, 3088.984555077233, 3829.9617003829885, 3861.613838616144, 3893.7274390792654, 4031.6371526562457, 29127.563086553717, 29447.96932107366, 29599.0, 30056.54915176274, 30158.69841301581, 2576.0414233821484, 3133.514305282662, 3220.871165153391, 3279.180204948763, 3283.343331333719, 394814.0, 396158.019209905, 400606.0, 401797.0, 411347.0, 55925.0, 56190.719046404505, 56211.71894140585, 56275.71862140663, 56299.303132405534, 57855.71072144612, 58032.12951805726, 58087.41912580904, 58312.12531812026, 58313.12530312049, 15110.622234444227, 15336.463223787337, 15338.693226135414, 15350.923245383701, 15425.0, 11983.221090629082, 12050.517979280907, 12054.457549410123, 12054.51781928713, 12062.095342849147, 13511.851492623236, 14898.851011489747, 15213.771793423108, 15229.390824367123, 15295.923520382326, 6142.447179753844, 6846.965765171142, 6915.273896240822, 7312.597807120553, 7372.373348265351, 813.3046245460165, 830.8878301429351, 870.5429649433971, 874.711345256064, 877.0746862060587, 27556.724432755414, 28449.857750711115, 28922.710772892002, 29119.85440072786, 29224.26939326534, 1022.9641962531275, 1034.1158309645145, 1068.2735739696925, 1069.187417562658, 1152.4525850221246, 13221.603351899266, 14630.414783408758, 14646.487372941896, 14904.329305181293, 15001.624959376102, 194991.0, 195092.0, 196103.01948490166, 196190.0, 479856.0, 55304.0, 55465.44534554603, 55555.722221388634, 55591.16613250804, 55740.72129639326, 1356.9389377478037, 1390.6175801654626, 1393.9930300348433, 1394.385527136639, 1401.4814518627968, 27110.864445677646, 27226.31934201661, 28209.448480332958, 28497.0, 28662.0, 45617.54382456133, 46003.76998114988, 46006.309905352115, 46024.30963535616, 46031.0, 403801.0, 404801.0, 406685.69914353336, 407449.0, 407545.0, 35815.0, 36394.0, 36456.0, 36547.0, 36684.81657591802, 39941.600583993786, 41743.16513669649, 41771.0, 43052.35421468743, 43395.48115815931, 31967.0, 32526.83736581302, 33791.15522111967, 35170.47244291286, 35198.0, 33886.66113338835, 33968.66031339655, 34605.0, 35108.12229694278, 35112.47331289982, 33165.83417082899, 33911.0, 34439.65560344364, 34457.310853782285, 34505.0, 24673.506529868944, 29701.702982970757, 30423.0, 30441.543376848924, 30447.695523044487, 21250.14999400007, 21660.566788663822, 23295.650565241194, 23730.288091357288, 23986.0], "value": 18211.819638919413, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [358.1083103073348, 361.2449979542756, 385.212240967222, 395.0715817828104, 408.81398963471605, 13687.315634218287, 13804.03371763977, 19792.796349697404, 20498.89750551249, 20923.476913077182, 20301.898490507563, 20519.897400513015, 20825.583488330223, 20867.373978780623, 21108.366748997516, 4673.649476289275, 6046.153538504613, 6087.147799308078, 6093.238345206849, 6130.846728831771, 5776.971115144418, 5950.672713000805, 6078.270607527103, 6101.176341193948, 6178.258608966908, 5189.688618682872, 6142.570020098595, 6152.630842149463, 6157.815265542018, 6158.107074474198, 6059.0, 6563.868722625544, 6717.059611654324, 6737.292584278678, 6740.427063699592, 5084.618653600995, 5732.856678583069, 5880.088586269135, 5961.5230781537375, 5997.250343707036, 12786.61640150797, 12953.48186072556, 12985.350732463434, 12998.805017924738, 13001.15492492985, 2571.7556832100922, 2573.3052075939577, 2577.3943123365993, 2586.405126820842, 2593.818432709711, 9868.851967220497, 9952.502374881227, 10083.798324033552, 10243.436610986393, 10579.576816927314, 1747.7116275814476, 1777.8311060449366, 1784.8304411080926, 1825.3063835742332, 1845.7877344105466, 81730.0, 87464.0, 107916.0, 108037.45981270121, 109270.0, 9530.571124299424, 11318.26431281964, 11469.0, 11488.425578721197, 11729.765404692029, 12192.0, 13789.0, 13794.034417590872, 14164.220967846868, 14182.716345673029, 7060.7881763547175, 7466.440016998747, 7621.352185064332, 8338.374621903382, 8373.352789202052, 7896.881546776803, 8027.15714849944, 8031.718889838828, 8191.0, 8201.917980820235, 3891.7859517726797, 4672.32251323563, 4759.9524004759505, 4828.61371090312, 4847.394075740533, 1936.6998115292151, 1967.8622496425112, 1977.4957385866512, 1990.5819777846525, 1993.4418362858423, 750.6246876561719, 850.6767428377177, 865.9653613855501, 905.4069584422214, 907.6823111910778, 20564.0, 21924.0, 21974.121035158736, 23176.3047108587, 23234.65148022781, 811.217175425717, 929.8977112517617, 952.4808979106404, 954.9713508594754, 997.7804882925744, 6733.3939945405145, 6780.457563394916, 6817.727290908408, 6917.371398868398, 6994.195667498303, 490295.0, 551456.0, 566797.0, 568499.0, 572646.0, 114589.0, 144140.0, 144230.27884860508, 147029.26485367503, 147951.0, 1027.6557353286582, 1057.571683468212, 1073.4847273308856, 1074.4949873559415, 1076.4079756134095, 11918.642440726791, 11988.160828741904, 12134.575289864812, 12167.634970950885, 12454.750904982031, 57440.71279643742, 58200.4179958195, 59158.0, 59678.403215967286, 60092.29584668613, 85858.0, 86351.0, 87832.0, 88056.0, 88320.5583972076, 8257.545834979133, 8296.958515207385, 8411.3270938326, 8483.74548763538, 8486.787830304293, 12237.87762122403, 12997.675058123623, 13081.084324097603, 13311.334433278103, 13502.662433439242, 10849.511771970123, 11066.170037247077, 11345.035671967646, 11364.147688923198, 11419.257748246337, 8978.653202019488, 11147.777044459228, 11151.386673733023, 11500.25248358854, 11533.480993355151, 11186.216964812385, 11467.197296189186, 11532.538698451968, 11795.823062654239, 11872.0, 6893.75871844483, 7494.475386722877, 7560.092788865151, 8402.579871006303, 8683.609237584451, 9403.388779729295, 10845.728856778644, 11118.054965327714, 11176.938190871853, 11422.971932526112], "value": 9178.621439661923, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1430.2920054572987, 1433.527622783135, 1454.432771219224, 1456.5703117580313, 1459.0880699562774, 8086.070101938279, 8539.444936079151, 8543.700970466034, 8557.614907329178, 8558.871616925751, 8121.75634730959, 8398.958005209965, 8408.285295749869, 8560.10118937512, 8563.78590535237, 2240.8543444676125, 2274.4996100857866, 2277.6583512473094, 2280.851744636602, 2284.1662793080573, 2096.6016456873144, 2119.2264823339524, 2121.7029615853794, 2130.7230060092165, 2132.0618927671776, 2234.72065991751, 2244.2930476899805, 2264.864108153508, 2272.284230467406, 2273.27255278311, 2208.6907832903407, 2219.6670499425054, 2229.687843701883, 2235.10595761695, 2237.2505210754334, 2210.6573481110454, 2283.9885800571055, 2288.096202000217, 2289.8740569268684, 2294.713160854893, 8275.420720549564, 8433.95783021081, 8446.23983841451, 8457.53483558404, 8461.53461559614, 5073.264376665363, 5210.583153347723, 5212.296339994128, 5216.765245563921, 5223.190405487156, 2790.1211118497813, 3231.8222497762613, 3478.9478157827652, 3525.1010992197084, 3526.6297038811067, 1190.6190019193862, 1195.4022988505747, 1195.8744331845119, 1252.768237875996, 1268.0869773762877, 10074.194064474897, 10254.436006019667, 10270.383776973405, 10833.83749243762, 10848.620298289521, 3140.371925614886, 3998.600489828595, 4154.626083652486, 4157.293260145782, 4294.269974104347, 8577.742667719978, 8731.995820480599, 8744.78138046554, 8751.256143227896, 8752.956235218911, 5098.056859481007, 5098.337216161875, 5099.69401835891, 5100.566451851634, 5105.719185444762, 4568.109218702321, 4753.4295884493395, 4754.381930349102, 4816.470188279286, 4817.686850354716, 3500.789952602801, 3578.906339791199, 3618.5115009473384, 3659.670629643345, 3672.797996110186, 949.5015117063459, 1119.4682525800342, 1123.775244951013, 1131.5417256011328, 1136.2160109524566, 864.5547543015352, 880.3969281042506, 881.5900606218139, 893.9553022348856, 921.4335629429996, 4888.584470319992, 5399.568034557226, 5404.810831620953, 5613.831585052373, 5644.5484361251, 547.7206624621472, 560.025555533369, 587.6033677267827, 590.3417689276512, 590.9940900590939, 3617.782933024027, 3631.6368363164, 3718.6467285607823, 3729.0, 3766.0020094674933, 7918.960405197937, 9937.354071985297, 10062.396256224503, 10086.092251697382, 10095.192384609214, 10012.199024078056, 10036.096751292418, 10041.297109202285, 10046.899531004743, 10084.49577521121, 7613.8681169272695, 7695.076590809139, 7804.3366313862825, 7832.84334313322, 7884.053913530413, 5307.41618421981, 5432.891342173213, 5445.59158063139, 5503.3395992481155, 5633.605647604629, 9173.862392064259, 9245.583948722458, 9263.258939284706, 9266.258699303904, 9272.907270927204, 6482.902756458751, 8579.828403431771, 9074.818503629758, 9132.726018219464, 9233.538323083685, 6144.2319710036245, 6258.249010118815, 6261.24865016201, 6269.467095296951, 6272.655003974825, 6520.706568204348, 7106.218315985341, 7191.20896701352, 7442.516236444614, 7693.6537855795505, 5957.642541447527, 7010.509264351446, 7061.399781018466, 7134.892976605459, 7321.560706357635, 7045.330693584101, 7047.718091276497, 7050.083489146378, 7076.221615622175, 7099.893501597377, 6311.905321420274, 6317.778877739257, 6345.555811093179, 6390.041493775785, 6413.935860641333, 4824.276358546246, 4878.268259761065, 4944.851654450372, 4981.0, 5122.282880396673, 4279.636230920407, 4743.430788305425, 4984.102861484967, 5006.324146240211, 5017.272495488207], "value": 4236.110744995164, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [359.50029459051916, 366.16331682106386, 370.621965595093, 375.47808546120916, 376.9849206031761, 4424.778761061946, 5231.503007214314, 5247.842564723064, 5263.947360526394, 5313.654612450197, 4522.231220692482, 4798.4961579034225, 4894.09459250039, 5168.586513078954, 5267.57691461927, 3683.9263214735756, 3934.3311637021775, 3991.2416640838146, 3995.60043995601, 4002.2995975704134, 3903.238868420659, 3953.169834334793, 3972.423998520213, 3988.80055997199, 4050.1089760252844, 3929.2141571685634, 3937.0118090364326, 3959.6832253419657, 3962.3358189560454, 3976.403539469088, 3288.4574045282498, 3569.964300357015, 3637.290728307981, 3987.820548075329, 4014.3577027675433, 2853.9155121054073, 2946.7053294670577, 3405.165734395089, 3412.7269818414466, 3440.3807314683345, 4792.808287668489, 5043.344365232533, 5070.08738427083, 5096.802251470901, 5133.230015497668, 4483.21543729846, 4498.8425405110665, 4499.572540608637, 4502.482214545336, 4601.309803529464, 5128.615353848439, 5397.434743924257, 5434.4404415108165, 5441.537469315113, 5455.9633669603045, 3732.2722069196516, 3736.906577335561, 3745.3632882410056, 3751.4185301278344, 3787.5833658297565, 4838.346823178861, 5321.574274058066, 5483.616146869721, 5580.91628625571, 5654.4062873398525, 3113.9879539149865, 3556.182078121995, 3625.3111908737255, 3680.822136916161, 3738.869139580156, 3221.693939075784, 3826.900210829237, 3831.7700937943255, 3834.750741201813, 3866.149447121629, 3611.656892595199, 4077.857274995361, 4202.768847713405, 4429.579189976947, 4443.311286750559, 4155.815592556168, 5140.614453915971, 5281.445448227958, 5384.730763461889, 5460.672359658433, 1162.2213117211488, 1234.2532767675607, 1239.9070069744805, 1248.2385744695728, 1250.7373451575272, 672.794797586731, 738.3945164964678, 812.6058861452191, 841.2807049972308, 861.1517655109712, 4138.503379594468, 4139.627433530996, 4585.128825523207, 4629.907401852011, 4817.0365926814775, 4520.751358675238, 4941.431735350408, 4942.654014219042, 5174.7153906535505, 5270.578353731692, 4079.4492743479846, 4496.437945256843, 4754.667173297836, 4838.7338696372035, 4853.93213493031, 2213.756486786452, 2768.9169324920285, 2804.284907348613, 2804.495190865623, 2847.5443928971267, 5135.0, 5156.845294641167, 5323.653962492425, 5516.806911758069, 5586.273784408082, 4177.8328866845595, 4671.252599584118, 4943.777530011158, 5126.359205099363, 5470.890582188414, 4498.370228168124, 4925.827596034122, 5016.921361907207, 5501.614886957955, 5547.44525547442, 4731.012974550766, 5051.4695956924, 5291.095205725858, 5341.436110666824, 5368.087425137814, 4864.36763220779, 5057.8735531612, 5302.867428314169, 5310.495502927216, 5394.460553944574, 4671.392718946515, 5391.433899440659, 5433.375161856437, 5487.451254874481, 5630.5777066720975, 3324.5844269466315, 3615.9819200903826, 3621.4386770050683, 4016.64610050611, 4069.7558146511305, 4725.50382209863, 5634.295713035871, 5671.517920976763, 5706.6005379624685, 5740.90922724681, 5230.34620672416, 5327.334083239595, 5462.235287059735, 5474.479924407175, 5515.558755299647, 4823.469418363907, 4894.518139683577, 5061.822836200715, 5093.617978651542, 5471.753771080232, 4337.609615134653, 4533.735710288587, 5277.709725965109, 5315.617939335723, 5372.782365842794, 4741.739204343794, 5168.198929165986, 5331.253624492651, 5430.728463576885, 5434.663094869245, 4869.634777391639, 4894.902101958012, 5338.3593968724, 5357.303550538561, 5373.274607928036], "value": 3891.4972860545477, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [722.479814533536, 730.7734602273292, 759.3393747439728, 817.4155478832635, 818.64388990789, 14327.0, 16499.0, 17212.39756608519, 17258.0, 17269.913650431747, 15825.762613560806, 15916.0, 15938.442154524593, 15971.920140399281, 15978.041317520956, 878.156969309462, 903.2954295649371, 905.8822353094089, 908.1644886704248, 908.6365453818493, 883.8055627761883, 900.5767289373983, 903.7153296711548, 904.267543289934, 930.9581068851884, 946.1957336264155, 948.184561277301, 948.928830337724, 950.5769932380093, 952.6141912525403, 944.0370821761833, 945.754103932979, 946.4179529589253, 946.6118891254622, 947.5877993073035, 701.9929800701979, 702.0066605752853, 702.5082442290366, 703.0017375326997, 706.254901079361, 14801.777973330409, 15252.694946101126, 15304.0, 15319.540413787605, 15328.616784580363, 13669.04319693249, 13987.79018314726, 14133.646658833508, 14285.0, 14320.14079155254, 9420.528973551294, 11618.186726929118, 11656.125790565657, 11755.0, 12124.0, 2982.537706655472, 3636.090977255686, 3692.132348898007, 3778.110944527736, 3793.1275806564367, 75253.99999999945, 92512.0748792517, 93638.0, 94456.05543944541, 95999.16265021199, 10304.948475257726, 10373.792524149625, 10415.739606509898, 10594.470276486145, 11002.394868282161, 21292.893535532225, 21790.346289611338, 21815.67276490854, 21826.236081737065, 21888.781112188994, 7277.599940445235, 7533.1336896256535, 7591.506552074097, 7594.81012974669, 7815.3356964657505, 4122.876313710593, 4235.174141042528, 4236.131593023423, 4247.87256382309, 4250.277452833026, 3153.4639111351125, 3207.935841283208, 3220.0500852248642, 3248.123006788201, 3253.072874230833, 668.9864854745065, 672.9730810767612, 679.8640271945532, 685.0, 689.3520091114336, 626.8276224038426, 627.5638431290209, 670.7182983146993, 679.6941376380549, 690.9267538337953, 8625.396222264506, 9810.068043535854, 9836.60653573849, 9858.359206651545, 9956.651517197008, 635.7721154323692, 674.1270055278426, 684.6793053689565, 687.2131409536054, 714.2500374606642, 3994.78028708418, 4445.5776701213335, 4479.17135329965, 4726.645501587326, 4886.523945056305, 90952.54523727471, 92980.60529092069, 93401.0, 96971.0, 100613.0, 46989.76505117521, 52782.41652750424, 53231.46768532343, 54948.45051549433, 81363.59318203489, 41320.0, 69667.65166174137, 73003.63498182475, 75179.0, 76109.0, 10136.310398963284, 10531.420771857622, 10550.683479495627, 10653.254272200871, 10670.73323166927, 31298.843505782326, 46670.53329466662, 47441.76279118582, 47577.76211118922, 47910.0, 47397.763011184725, 64330.0, 64647.35352646413, 66139.0, 66228.0, 12555.058370622057, 12565.18326308787, 12626.494940202288, 12687.429065692251, 13332.0, 11500.367479788358, 13873.65315867071, 14052.859471405154, 14240.928795355956, 14304.785428218376, 11907.727270471738, 12114.95917699253, 12835.679108022374, 13190.142640728322, 13220.40508177115, 12935.223886566837, 13170.457876094826, 13242.53651122206, 13246.205227685985, 13271.535496257584, 12179.903808657264, 12464.016568481009, 12479.625611231677, 12529.248245105322, 12531.874681253435, 9274.79427674398, 9688.951555242178, 10554.736131596772, 10773.0, 11301.444942700547, 6837.487188460786, 8973.28213742886, 10283.331583447052, 10409.531571079167, 10411.947940260554], "value": 7249.72264767502, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1473.8000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 116.0, 114.0, 113.0, 115.0, 117.0, 114.0, 114.0, 115.0, 115.0], "value": 114.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 113.0, 113.0, 151.0, 112.0, 119.0, 141.0, 140.0, 171.0, 114.0], "value": 132.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 187.0, 186.0, 186.0, 184.0, 182.0, 185.0, 187.0, 185.0, 182.0], "value": 184.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 63.0, 62.0, 91.0, 68.0, 60.0, 60.0, 61.0, 77.0, 58.0], "value": 67.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 70.0, 88.0, 69.0, 69.0, 71.0, 87.0, 91.0, 96.0, 87.0], "value": 81.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 87.0, 87.0, 87.0, 87.0, 87.0, 86.0, 87.0, 86.0], "value": 86.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 98.0, 97.0, 95.0, 94.0, 94.0, 95.0, 103.0, 95.0, 94.0], "value": 95.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 87.0, 87.0, 87.0, 85.0, 86.0, 110.0, 134.0, 141.0, 86.0], "value": 98.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 59.0, 59.0, 57.0, 59.0, 59.0, 59.0, 59.0, 59.0, 59.0], "value": 58.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 78.0, 75.0, 75.0, 72.0, 70.0, 70.0, 72.0, 71.0, 72.0], "value": 72.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 88.0, 89.0, 88.0, 91.0, 88.0, 85.0, 94.0, 97.0, 104.0], "value": 91.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 150.0, 133.0, 144.0, 125.0, 149.0, 146.0, 141.0, 208.0, 140.0], "value": 149.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 175.0, 174.0, 171.0, 172.0, 172.0, 174.0, 171.0, 172.0, 171.0], "value": 172.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 69.0, 68.0, 67.0, 68.0, 66.0, 67.0, 66.0, 68.0], "value": 67.4, "unit": "score"}]}]} 07:31:19 INFO - Return code: 0 07:31:19 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:31:19 ERROR - # TBPL SUCCESS # 07:31:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:31:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:31:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:31:19 INFO - 2017-02-23 07:31:19.475 screenresolution[29773:276493] starting screenresolution argv=screenresolution get 07:31:19 INFO - 2017-02-23 07:31:19.477 screenresolution[29773:276493] Display 0: 1600x1200x32@60 07:31:19 INFO - 2017-02-23 07:31:19.484 screenresolution[29774:276496] starting screenresolution argv=screenresolution list 07:31:19 INFO - Available Modes on Display 0 07:31:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:31:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:31:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:31:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:31:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:31:19 INFO - Intel Iris: 07:31:19 INFO - Chipset Model: Intel Iris 07:31:19 INFO - Type: GPU 07:31:19 INFO - Bus: Built-In 07:31:19 INFO - VRAM (Dynamic, Max): 1536 MB 07:31:19 INFO - Vendor: Intel (0x8086) 07:31:19 INFO - Device ID: 0x0a2e 07:31:19 INFO - Revision ID: 0x0009 07:31:19 INFO - Displays: 07:31:19 INFO - Display: 07:31:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:31:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:31:19 INFO - Display Serial Number: 1600x1200 60 07:31:19 INFO - Main Display: Yes 07:31:19 INFO - Mirror: Off 07:31:19 INFO - Online: Yes 07:31:19 INFO - Rotation: Supported 07:31:19 INFO - Return code: 0 07:31:19 INFO - Running post-action listener: _resource_record_post_action 07:31:19 INFO - [mozharness: 2017-02-23 15:31:19.730624Z] Finished run-tests step (success) 07:31:19 INFO - Running post-run listener: _resource_record_post_run 07:31:19 INFO - Total resource usage - Wall time: 1202s; CPU: 54.0%; Read bytes: 30366720; Write bytes: 518476800; Read time: 176; Write time: 1646 07:31:19 INFO - TinderboxPrint: CPU usage
53.7% 07:31:19 INFO - TinderboxPrint: I/O read bytes / time
30,366,720 / 176 07:31:19 INFO - TinderboxPrint: I/O write bytes / time
518,476,800 / 1,646 07:31:19 INFO - TinderboxPrint: CPU idle
2,227.9 (46.3%) 07:31:19 INFO - TinderboxPrint: CPU system
957.0 (19.9%) 07:31:19 INFO - TinderboxPrint: CPU user
1,624.8 (33.8%) 07:31:19 INFO - TinderboxPrint: Swap in / out
235,520,000 / 0 07:31:19 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 206010880; Write bytes: 201801728; Read time: 15570; Write time: 448 07:31:19 INFO - run-tests - Wall time: 1182s; CPU: 54.0%; Read bytes: 30256128; Write bytes: 306045952; Read time: 175; Write time: 1161 07:31:20 INFO - Running post-run listener: _upload_blobber_files 07:31:20 INFO - Blob upload gear active. 07:31:20 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:31:20 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 07:31:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 07:31:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 07:31:20 INFO - (blobuploader) - INFO - Open directory for files ... 07:31:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:31:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:31:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:31:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:31:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:31:21 INFO - (blobuploader) - INFO - Done attempting. 07:31:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:31:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:31:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:31:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:31:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:31:22 INFO - (blobuploader) - INFO - Done attempting. 07:31:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 07:31:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:31:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:31:23 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 07:31:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:31:23 INFO - (blobuploader) - INFO - Done attempting. 07:31:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:31:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:31:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:31:25 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:31:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:31:25 INFO - (blobuploader) - INFO - Done attempting. 07:31:25 INFO - (blobuploader) - INFO - Iteration through files over. 07:31:25 INFO - Return code: 0 07:31:25 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:31:25 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:31:25 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/60c42d99f7bd92dd25c352ae5134133bf77bfd4fb6c8b6b157c5ada5ffbab0ff2b7e83089d3a5383a0b33cdaeeb2c9f40b8309ecfb1db078fa0995b92ea8f29c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8202ac69eb639e77c7bd6802b3939b9f626f82dffbfddbf9cc3616d05a54bd07bafa1d1da6a3f885cef2c08a3c0fb620ef32313340694370f2669a9bc591e9a8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/645df28e78e2c7d771bf1678f85b90928176de8b8492a46ce4332b2fc89b99b20bf086aed1b2931f32ec8b96e975bdf11806cb46de9efbcd3dd1e33086255349", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2c0ec20eedfe0bcf3e640c4250efbf8e9fd28f4d079e5ae18a96a76a3e9bd2a44360ec64a76fef536ed8187fcf7ac79896473567963d61bf2b3d52f7aacc4c89"} 07:31:25 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:31:25 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:31:25 INFO - Contents: 07:31:25 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/60c42d99f7bd92dd25c352ae5134133bf77bfd4fb6c8b6b157c5ada5ffbab0ff2b7e83089d3a5383a0b33cdaeeb2c9f40b8309ecfb1db078fa0995b92ea8f29c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8202ac69eb639e77c7bd6802b3939b9f626f82dffbfddbf9cc3616d05a54bd07bafa1d1da6a3f885cef2c08a3c0fb620ef32313340694370f2669a9bc591e9a8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/645df28e78e2c7d771bf1678f85b90928176de8b8492a46ce4332b2fc89b99b20bf086aed1b2931f32ec8b96e975bdf11806cb46de9efbcd3dd1e33086255349", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2c0ec20eedfe0bcf3e640c4250efbf8e9fd28f4d079e5ae18a96a76a3e9bd2a44360ec64a76fef536ed8187fcf7ac79896473567963d61bf2b3d52f7aacc4c89"} 07:31:25 INFO - Running post-run listener: copy_logs_to_upload_dir 07:31:25 INFO - Copying logs to upload dir... 07:31:25 INFO - mkdir: /builds/slave/test/build/upload/logs 07:31:25 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1283.168258 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 23 secs) (at 2017-02-23 07:31:25.857841) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-23 07:31:25.861446) ========= 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.cVmfSsmwFp/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.5CiygProhD/Listeners TMPDIR=/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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/Firefox/sha512/60c42d99f7bd92dd25c352ae5134133bf77bfd4fb6c8b6b157c5ada5ffbab0ff2b7e83089d3a5383a0b33cdaeeb2c9f40b8309ecfb1db078fa0995b92ea8f29c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8202ac69eb639e77c7bd6802b3939b9f626f82dffbfddbf9cc3616d05a54bd07bafa1d1da6a3f885cef2c08a3c0fb620ef32313340694370f2669a9bc591e9a8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/645df28e78e2c7d771bf1678f85b90928176de8b8492a46ce4332b2fc89b99b20bf086aed1b2931f32ec8b96e975bdf11806cb46de9efbcd3dd1e33086255349", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2c0ec20eedfe0bcf3e640c4250efbf8e9fd28f4d079e5ae18a96a76a3e9bd2a44360ec64a76fef536ed8187fcf7ac79896473567963d61bf2b3d52f7aacc4c89"} build_url:https://queue.taskcluster.net/v1/task/KcJ43fEsQ-yH2Nxw7F4_Pw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011149 build_url: 'https://queue.taskcluster.net/v1/task/KcJ43fEsQ-yH2Nxw7F4_Pw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/60c42d99f7bd92dd25c352ae5134133bf77bfd4fb6c8b6b157c5ada5ffbab0ff2b7e83089d3a5383a0b33cdaeeb2c9f40b8309ecfb1db078fa0995b92ea8f29c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8202ac69eb639e77c7bd6802b3939b9f626f82dffbfddbf9cc3616d05a54bd07bafa1d1da6a3f885cef2c08a3c0fb620ef32313340694370f2669a9bc591e9a8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/645df28e78e2c7d771bf1678f85b90928176de8b8492a46ce4332b2fc89b99b20bf086aed1b2931f32ec8b96e975bdf11806cb46de9efbcd3dd1e33086255349", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2c0ec20eedfe0bcf3e640c4250efbf8e9fd28f4d079e5ae18a96a76a3e9bd2a44360ec64a76fef536ed8187fcf7ac79896473567963d61bf2b3d52f7aacc4c89"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-23 07:31:25.896503) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-23 07:31:25.898968) ========= 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.cVmfSsmwFp/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.5CiygProhD/Listeners TMPDIR=/var/folders/tt/pgc51pmj37x56t_l_9rys0d000000w/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.005126 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-23 07:31:25.954811) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-23 07:31:25.955234) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-23 07:31:25.955628) ========= ========= Total master_lag: 0.20 =========