builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0132 starttime: 1493890592.41 results: success (0) buildid: 20170504015521 builduid: 73f4e7edd7934f48985290e55dabd21d revision: a8d597ee6dd58306e62e55a07c4ab20958726d6a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-04 02:36:32.409271) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-04 02:36:32.409743) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-04 02:36:32.410157) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-04 02:36:32.437694) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-04 02:36:32.438107) ========= 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.Rg2KtSitaG/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.MuFujmHIqF/Listeners TMPDIR=/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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-05-04 02:36:32-- 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% 63.8M=0s 2017-05-04 02:36:32 (63.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.132227 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-04 02:36:32.597118) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-04 02:36:32.597443) ========= 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.Rg2KtSitaG/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.MuFujmHIqF/Listeners TMPDIR=/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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.029745 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-04 02:36:32.650528) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-04 02:36:32.650891) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev a8d597ee6dd58306e62e55a07c4ab20958726d6a --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 a8d597ee6dd58306e62e55a07c4ab20958726d6a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Rg2KtSitaG/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.MuFujmHIqF/Listeners TMPDIR=/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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-05-04 02:36:32,734 truncating revision to first 12 chars 2017-05-04 02:36:32,734 Setting DEBUG logging. 2017-05-04 02:36:32,734 attempt 1/10 2017-05-04 02:36:32,734 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/a8d597ee6dd5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-04 02:36:33,948 unpacking tar archive at: mozilla-central-a8d597ee6dd5/testing/mozharness/ program finished with exit code 0 elapsedTime=1.487881 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-04 02:36:34.162905) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-04 02:36:34.163226) ========= script_repo_revision: a8d597ee6dd58306e62e55a07c4ab20958726d6a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-04 02:36:34.163627) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-04 02:36:34.163908) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-04 02:36:34.184610) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2017-05-04 02:36:34.185064) ========= /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.Rg2KtSitaG/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.MuFujmHIqF/Listeners TMPDIR=/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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 02:36:34 INFO - MultiFileLogger online at 20170504 02:36:34 in /builds/slave/test 02:36:34 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 02:36:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:36:34 INFO - {'append_to_log': False, 02:36:34 INFO - 'base_work_dir': '/builds/slave/test', 02:36:34 INFO - 'blob_upload_branch': 'Firefox', 02:36:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:36:34 INFO - 'branch': 'Firefox', 02:36:34 INFO - 'buildbot_json_path': 'buildprops.json', 02:36:34 INFO - 'config_files': ('talos/mac_config.py',), 02:36:34 INFO - 'default_actions': ('clobber', 02:36:34 INFO - 'read-buildbot-config', 02:36:34 INFO - 'download-and-extract', 02:36:34 INFO - 'populate-webroot', 02:36:34 INFO - 'create-virtualenv', 02:36:34 INFO - 'install', 02:36:34 INFO - 'run-tests'), 02:36:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:36:34 INFO - 'download_minidump_stackwalk': True, 02:36:34 INFO - 'download_symbols': 'ondemand', 02:36:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:36:34 INFO - 'tooltool.py': '/tools/tooltool.py', 02:36:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:36:34 INFO - '/tools/misc-python/virtualenv.py')}, 02:36:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:36:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:36:34 INFO - 'gecko_profile': False, 02:36:34 INFO - 'gecko_profile_interval': 0, 02:36:34 INFO - 'installer_path': 'installer.exe', 02:36:34 INFO - 'log_level': 'info', 02:36:34 INFO - 'log_name': 'talos', 02:36:34 INFO - 'log_to_console': True, 02:36:34 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:36:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:36:34 INFO - 'opt_config_files': (), 02:36:34 INFO - 'pip_index': False, 02:36:34 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:36:34 INFO - 'cmd': ('bash', 02:36:34 INFO - '-c', 02:36:34 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:36:34 INFO - 'enabled': True, 02:36:34 INFO - 'halt_on_failure': False, 02:36:34 INFO - 'name': 'check_screen_resolution'},), 02:36:34 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:36:34 INFO - 'cmd': ('bash', 02:36:34 INFO - '-c', 02:36:34 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:36:34 INFO - 'enabled': True, 02:36:34 INFO - 'halt_on_failure': False, 02:36:34 INFO - 'name': 'check_screen_resolution'},), 02:36:34 INFO - 'run_cmd_checks_enabled': True, 02:36:34 INFO - 'suite': 'dromaeojs', 02:36:34 INFO - 'system_bits': '32', 02:36:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:36:34 INFO - 'title': 't-yosemite-r7-0132', 02:36:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:36:34 INFO - 'use_talos_json': True, 02:36:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:36:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:36:34 INFO - 'work_dir': 'build'} 02:36:34 INFO - [mozharness: 2017-05-04 09:36:34.377390Z] Running clobber step. 02:36:34 INFO - Running pre-action listener: _resource_record_pre_action 02:36:34 INFO - Running main action method: clobber 02:36:34 INFO - rmtree: /builds/slave/test/build 02:36:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:36:36 INFO - Running post-action listener: _resource_record_post_action 02:36:36 INFO - [mozharness: 2017-05-04 09:36:36.816531Z] Finished clobber step (success) 02:36:36 INFO - [mozharness: 2017-05-04 09:36:36.816788Z] Running read-buildbot-config step. 02:36:36 INFO - Running pre-action listener: _resource_record_pre_action 02:36:36 INFO - Running main action method: read_buildbot_config 02:36:36 INFO - Using buildbot properties: 02:36:36 INFO - { 02:36:36 INFO - "project": "", 02:36:36 INFO - "product": "firefox", 02:36:36 INFO - "script_repo_revision": "production", 02:36:36 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 02:36:36 INFO - "repository": "", 02:36:36 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 02:36:36 INFO - "buildid": "20170504015521", 02:36:36 INFO - "pgo_build": "False", 02:36:36 INFO - "basedir": "/builds/slave/test", 02:36:36 INFO - "buildnumber": 111, 02:36:36 INFO - "slavename": "t-yosemite-r7-0132", 02:36:36 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 02:36:36 INFO - "platform": "yosemite_r7", 02:36:36 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 02:36:36 INFO - "branch": "mozilla-central", 02:36:36 INFO - "repo_path": "mozilla-central", 02:36:36 INFO - "revision": "a8d597ee6dd58306e62e55a07c4ab20958726d6a", 02:36:36 INFO - "stage_platform": "macosx64", 02:36:36 INFO - "builduid": "73f4e7edd7934f48985290e55dabd21d", 02:36:36 INFO - "slavebuilddir": "test" 02:36:36 INFO - } 02:36:36 INFO - Found installer url https://queue.taskcluster.net/v1/task/XNyrCkSHRFW3Q7EjWPSctw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 02:36:36 INFO - Running post-action listener: _resource_record_post_action 02:36:36 INFO - [mozharness: 2017-05-04 09:36:36.822610Z] Finished read-buildbot-config step (success) 02:36:36 INFO - [mozharness: 2017-05-04 09:36:36.822837Z] Running download-and-extract step. 02:36:36 INFO - Running pre-action listener: _resource_record_pre_action 02:36:36 INFO - Running main action method: download_and_extract 02:36:36 INFO - mkdir: /builds/slave/test/build/tests 02:36:36 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')]} 02:36:36 INFO - trying https://queue.taskcluster.net/v1/task/XNyrCkSHRFW3Q7EjWPSctw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 02:36:36 INFO - Downloading https://queue.taskcluster.net/v1/task/XNyrCkSHRFW3Q7EjWPSctw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 02:36:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XNyrCkSHRFW3Q7EjWPSctw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 02:36:38 INFO - Downloaded 1325 bytes. 02:36:38 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 02:36:38 INFO - Using the following test package requirements: 02:36:38 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:36:38 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 02:36:38 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 02:36:38 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:36:38 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 02:36:38 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:36:38 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 02:36:38 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:36:38 INFO - u'jsshell-mac.zip'], 02:36:38 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:36:38 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 02:36:38 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 02:36:38 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:36:38 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 02:36:38 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:36:38 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 02:36:38 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:36:38 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 02:36:38 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:36:38 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 02:36:38 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 02:36:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XNyrCkSHRFW3Q7EjWPSctw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 02:36:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XNyrCkSHRFW3Q7EjWPSctw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 02:36:38 INFO - Fetch https://queue.taskcluster.net/v1/task/XNyrCkSHRFW3Q7EjWPSctw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 02:36:39 INFO - Content-Length response header: 52700574 02:36:39 INFO - Bytes received: 52700574 02:36:56 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 02:36:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XNyrCkSHRFW3Q7EjWPSctw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 02:36:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XNyrCkSHRFW3Q7EjWPSctw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 02:36:56 INFO - Fetch https://queue.taskcluster.net/v1/task/XNyrCkSHRFW3Q7EjWPSctw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 02:36:58 INFO - Content-Length response header: 52700574 02:36:58 INFO - Bytes received: 52700574 02:37:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XNyrCkSHRFW3Q7EjWPSctw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 02:37:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XNyrCkSHRFW3Q7EjWPSctw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 02:37:13 INFO - Fetch https://queue.taskcluster.net/v1/task/XNyrCkSHRFW3Q7EjWPSctw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 02:37:14 INFO - Content-Length response header: 14408809 02:37:14 INFO - Bytes received: 14408809 02:37:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:37:14 INFO - trying https://queue.taskcluster.net/v1/task/XNyrCkSHRFW3Q7EjWPSctw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 02:37:14 INFO - Downloading https://queue.taskcluster.net/v1/task/XNyrCkSHRFW3Q7EjWPSctw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 02:37:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XNyrCkSHRFW3Q7EjWPSctw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 02:37:16 INFO - Downloaded 70880481 bytes. 02:37:16 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/XNyrCkSHRFW3Q7EjWPSctw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 02:37:16 INFO - mkdir: /builds/slave/test/properties 02:37:16 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:37:16 INFO - Writing to file /builds/slave/test/properties/build_url 02:37:16 INFO - Contents: 02:37:16 INFO - build_url:https://queue.taskcluster.net/v1/task/XNyrCkSHRFW3Q7EjWPSctw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 02:37:17 INFO - Running post-action listener: _resource_record_post_action 02:37:17 INFO - Running post-action listener: set_extra_try_arguments 02:37:17 INFO - [mozharness: 2017-05-04 09:37:17.363308Z] Finished download-and-extract step (success) 02:37:17 INFO - [mozharness: 2017-05-04 09:37:17.363463Z] Running populate-webroot step. 02:37:17 INFO - Running pre-action listener: _resource_record_pre_action 02:37:17 INFO - Running main action method: populate_webroot 02:37:17 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:37:17 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:37:17 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:37:17 INFO - '--tppagecycles', 02:37:17 INFO - '7'], 02:37:17 INFO - 'tests': ['tsvgm']}}, 02:37:17 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:37:17 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:37:17 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:37:17 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:37:17 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:37:17 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:37:17 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 02:37:17 INFO - 'talos_options': ['--disable-e10s'], 02:37:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:37:17 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 02:37:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:37:17 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 02:37:17 INFO - 'talos_options': ['--disable-e10s'], 02:37:17 INFO - 'tests': ['damp', 'tps']}, 02:37:17 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 02:37:17 INFO - 'tests': ['damp', 'tps']}, 02:37:17 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:37:17 INFO - 'tests': ['dromaeo_dom']}, 02:37:17 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:37:17 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:37:17 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 02:37:17 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 02:37:17 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:37:17 INFO - 'tests': ['a11yr', 02:37:17 INFO - 'ts_paint', 02:37:17 INFO - 'tpaint', 02:37:17 INFO - 'sessionrestore', 02:37:17 INFO - 'sessionrestore_no_auto_restore', 02:37:17 INFO - 'tabpaint']}, 02:37:17 INFO - 'other-e10s': {'tests': ['a11yr', 02:37:17 INFO - 'ts_paint', 02:37:17 INFO - 'tpaint', 02:37:17 INFO - 'sessionrestore', 02:37:17 INFO - 'sessionrestore_no_auto_restore', 02:37:17 INFO - 'tabpaint']}, 02:37:17 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:37:17 INFO - 'ts_paint', 02:37:17 INFO - 'tpaint', 02:37:17 INFO - 'sessionrestore', 02:37:17 INFO - 'sessionrestore_no_auto_restore', 02:37:17 INFO - 'tabpaint']}, 02:37:17 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:37:17 INFO - 'ts_paint', 02:37:17 INFO - 'tpaint', 02:37:17 INFO - 'sessionrestore', 02:37:17 INFO - 'sessionrestore_no_auto_restore', 02:37:17 INFO - 'tabpaint']}, 02:37:17 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:37:17 INFO - 'tests': ['a11yr', 02:37:17 INFO - 'ts_paint', 02:37:17 INFO - 'tpaint', 02:37:17 INFO - 'sessionrestore', 02:37:17 INFO - 'sessionrestore_no_auto_restore', 02:37:17 INFO - 'tabpaint']}, 02:37:17 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:37:17 INFO - 'tests': ['a11yr', 02:37:17 INFO - 'ts_paint', 02:37:17 INFO - 'tpaint', 02:37:17 INFO - 'sessionrestore', 02:37:17 INFO - 'sessionrestore_no_auto_restore', 02:37:17 INFO - 'tabpaint']}, 02:37:17 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 02:37:17 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 02:37:17 INFO - 'bloom_basic_ref']}, 02:37:17 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:37:17 INFO - 'tests': ['tsvgx', 02:37:17 INFO - 'tsvgr_opacity', 02:37:17 INFO - 'tart', 02:37:17 INFO - 'tscrollx', 02:37:17 INFO - 'cart', 02:37:17 INFO - 'tsvg_static']}, 02:37:17 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:37:17 INFO - 'tsvgr_opacity', 02:37:17 INFO - 'tart', 02:37:17 INFO - 'tscrollx', 02:37:17 INFO - 'cart', 02:37:17 INFO - 'tsvg_static']}, 02:37:17 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 02:37:17 INFO - 'talos_options': ['--disable-e10s'], 02:37:17 INFO - 'tests': ['tp5o']}, 02:37:17 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 02:37:17 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 02:37:17 INFO - 'talos_options': ['--disable-e10s', 02:37:17 INFO - '--xperf_path', 02:37:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:37:17 INFO - 'tests': ['tp5n']}, 02:37:17 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 02:37:17 INFO - 'talos_options': ['--xperf_path', 02:37:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:37:17 INFO - 'tests': ['tp5n']}}, 02:37:17 INFO - 'talos.zip': {'path': '', 02:37:17 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:37:17 INFO - Running post-action listener: _resource_record_post_action 02:37:17 INFO - [mozharness: 2017-05-04 09:37:17.382572Z] Finished populate-webroot step (success) 02:37:17 INFO - [mozharness: 2017-05-04 09:37:17.382660Z] Running create-virtualenv step. 02:37:17 INFO - Running pre-action listener: _resource_record_pre_action 02:37:17 INFO - Running main action method: create_virtualenv 02:37:17 INFO - Creating virtualenv /builds/slave/test/build/venv 02:37:17 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 02:37:17 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:37:17 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:37:17 INFO - Using real prefix '/tools/python27' 02:37:17 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:37:18 INFO - Installing distribute.............................................................................................................................................................................................done. 02:37:21 INFO - Installing pip.................done. 02:37:21 INFO - Return code: 0 02:37:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:37:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:37:21 INFO - Reading from file tmpfile_stdout 02:37:21 INFO - Output received: 02:37:21 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) 02:37:21 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:37:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:37:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:37:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:37:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:37:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:37:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:37:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103a34b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a37768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf58c8b0c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103812d30>, '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 0x103738db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103807be8>, '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.Rg2KtSitaG/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.MuFujmHIqF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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 02:37:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 02:37:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 02:37:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Rg2KtSitaG/Render', 02:37:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:37:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:37:21 INFO - 'HOME': '/Users/cltbld', 02:37:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:37:21 INFO - 'LOGNAME': 'cltbld', 02:37:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:37:21 INFO - 'MOZ_NO_REMOTE': '1', 02:37:21 INFO - 'NO_EM_RESTART': '1', 02:37:21 INFO - 'PAGER': '/bin/cat', 02:37:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:37:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:37:21 INFO - 'PWD': '/builds/slave/test', 02:37:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:37:21 INFO - 'SHELL': '/bin/bash', 02:37:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MuFujmHIqF/Listeners', 02:37:21 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 02:37:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:37:21 INFO - 'USER': 'cltbld', 02:37:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:37:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:37:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:37:21 INFO - 'XPC_FLAGS': '0x0', 02:37:21 INFO - 'XPC_SERVICE_NAME': '0', 02:37:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:37:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:37:21 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 02:37:21 INFO - Cleaning up... 02:37:21 INFO - Return code: 0 02:37:21 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:37:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:37:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:37:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:37:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:37:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:37:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:37:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x103a34b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a37768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf58c8b0c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103812d30>, '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 0x103738db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103807be8>, '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.Rg2KtSitaG/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.MuFujmHIqF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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 02:37:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 02:37:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 02:37:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Rg2KtSitaG/Render', 02:37:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:37:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:37:21 INFO - 'HOME': '/Users/cltbld', 02:37:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:37:21 INFO - 'LOGNAME': 'cltbld', 02:37:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:37:21 INFO - 'MOZ_NO_REMOTE': '1', 02:37:21 INFO - 'NO_EM_RESTART': '1', 02:37:21 INFO - 'PAGER': '/bin/cat', 02:37:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:37:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:37:21 INFO - 'PWD': '/builds/slave/test', 02:37:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:37:21 INFO - 'SHELL': '/bin/bash', 02:37:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MuFujmHIqF/Listeners', 02:37:21 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 02:37:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:37:21 INFO - 'USER': 'cltbld', 02:37:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:37:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:37:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:37:21 INFO - 'XPC_FLAGS': '0x0', 02:37:21 INFO - 'XPC_SERVICE_NAME': '0', 02:37:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:37:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:37:21 INFO - Downloading/unpacking psutil>=3.1.1 02:37:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:37:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:37:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:37:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:37:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:37:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:37:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:37:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:37:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:37:24 INFO - Installing collected packages: psutil 02:37:24 INFO - Running setup.py install for psutil 02:37:24 INFO - building 'psutil._psutil_osx' extension 02:37:24 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 02:37:25 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 02:37:25 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 02:37:25 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 02:37:25 INFO - building 'psutil._psutil_posix' extension 02:37:25 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 02:37:25 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:37:25 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:37:25 INFO - ^ 02:37:25 INFO - 1 warning generated. 02:37:25 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 02:37:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:37:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:37:25 INFO - Successfully installed psutil 02:37:25 INFO - Cleaning up... 02:37:25 INFO - Return code: 0 02:37:25 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:37:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:37:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:37:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:37:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:37:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:37:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:37:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103a34b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a37768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf58c8b0c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103812d30>, '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 0x103738db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103807be8>, '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.Rg2KtSitaG/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.MuFujmHIqF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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 02:37:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 02:37:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 02:37:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Rg2KtSitaG/Render', 02:37:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:37:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:37:25 INFO - 'HOME': '/Users/cltbld', 02:37:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:37:25 INFO - 'LOGNAME': 'cltbld', 02:37:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:37:25 INFO - 'MOZ_NO_REMOTE': '1', 02:37:25 INFO - 'NO_EM_RESTART': '1', 02:37:25 INFO - 'PAGER': '/bin/cat', 02:37:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:37:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:37:25 INFO - 'PWD': '/builds/slave/test', 02:37:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:37:25 INFO - 'SHELL': '/bin/bash', 02:37:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MuFujmHIqF/Listeners', 02:37:25 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 02:37:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:37:25 INFO - 'USER': 'cltbld', 02:37:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:37:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:37:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:37:25 INFO - 'XPC_FLAGS': '0x0', 02:37:25 INFO - 'XPC_SERVICE_NAME': '0', 02:37:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:37:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:37:25 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:37:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:37:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:37:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:37:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:37:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:37:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:37:27 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:37:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:37:27 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:37:27 INFO - Installing collected packages: mozsystemmonitor 02:37:27 INFO - Running setup.py install for mozsystemmonitor 02:37:27 INFO - Successfully installed mozsystemmonitor 02:37:27 INFO - Cleaning up... 02:37:27 INFO - Return code: 0 02:37:27 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:37:27 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')]} 02:37:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:37:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:37:27 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')]} 02:37:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:37:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:37:27 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 0x103a34b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a37768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf58c8b0c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103812d30>, '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 0x103738db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103807be8>, '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.Rg2KtSitaG/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.MuFujmHIqF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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 02:37:27 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 02:37:27 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 02:37:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Rg2KtSitaG/Render', 02:37:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:37:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:37:27 INFO - 'HOME': '/Users/cltbld', 02:37:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:37:27 INFO - 'LOGNAME': 'cltbld', 02:37:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:37:27 INFO - 'MOZ_NO_REMOTE': '1', 02:37:27 INFO - 'NO_EM_RESTART': '1', 02:37:27 INFO - 'PAGER': '/bin/cat', 02:37:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:37:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:37:27 INFO - 'PWD': '/builds/slave/test', 02:37:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:37:27 INFO - 'SHELL': '/bin/bash', 02:37:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MuFujmHIqF/Listeners', 02:37:27 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 02:37:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:37:27 INFO - 'USER': 'cltbld', 02:37:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:37:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:37:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:37:27 INFO - 'XPC_FLAGS': '0x0', 02:37:27 INFO - 'XPC_SERVICE_NAME': '0', 02:37:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:37:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:37:27 INFO - Downloading/unpacking jsonschema==2.5.1 02:37:27 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 02:37:27 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 02:37:27 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 02:37:27 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 02:37:27 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 02:37:27 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 02:37:28 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:37:28 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:37:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:37:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:37:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:37:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:37:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:37:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:37:29 INFO - Downloading functools32-3.2.3-2.tar.gz 02:37:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:37:29 INFO - warning: no files found matching '*.txt' 02:37:29 INFO - no previously-included directories found matching 'build' 02:37:29 INFO - no previously-included directories found matching 'dist' 02:37:29 INFO - no previously-included directories found matching '.git*' 02:37:29 INFO - Installing collected packages: jsonschema, functools32 02:37:29 INFO - Running setup.py install for functools32 02:37:29 INFO - warning: no files found matching '*.txt' 02:37:29 INFO - no previously-included directories found matching 'build' 02:37:29 INFO - no previously-included directories found matching 'dist' 02:37:29 INFO - no previously-included directories found matching '.git*' 02:37:29 INFO - Successfully installed jsonschema functools32 02:37:29 INFO - Cleaning up... 02:37:29 INFO - Return code: 0 02:37:29 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:37:29 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')]} 02:37:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:37:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:37:29 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')]} 02:37:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:37:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:37:29 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 0x103a34b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a37768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf58c8b0c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103812d30>, '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 0x103738db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103807be8>, '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.Rg2KtSitaG/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.MuFujmHIqF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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 02:37:29 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 02:37:29 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 02:37:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Rg2KtSitaG/Render', 02:37:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:37:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:37:29 INFO - 'HOME': '/Users/cltbld', 02:37:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:37:29 INFO - 'LOGNAME': 'cltbld', 02:37:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:37:29 INFO - 'MOZ_NO_REMOTE': '1', 02:37:29 INFO - 'NO_EM_RESTART': '1', 02:37:29 INFO - 'PAGER': '/bin/cat', 02:37:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:37:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:37:29 INFO - 'PWD': '/builds/slave/test', 02:37:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:37:29 INFO - 'SHELL': '/bin/bash', 02:37:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MuFujmHIqF/Listeners', 02:37:29 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 02:37:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:37:29 INFO - 'USER': 'cltbld', 02:37:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:37:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:37:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:37:29 INFO - 'XPC_FLAGS': '0x0', 02:37:29 INFO - 'XPC_SERVICE_NAME': '0', 02:37:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:37:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:37:30 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:37:30 INFO - Cleaning up... 02:37:30 INFO - Return code: 0 02:37:30 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:37:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:37:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:37:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:37:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:37:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:37:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:37:30 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 0x103a34b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a37768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf58c8b0c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103812d30>, '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 0x103738db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103807be8>, '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.Rg2KtSitaG/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.MuFujmHIqF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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 02:37:30 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 02:37:30 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 02:37:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Rg2KtSitaG/Render', 02:37:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:37:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:37:30 INFO - 'HOME': '/Users/cltbld', 02:37:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:37:30 INFO - 'LOGNAME': 'cltbld', 02:37:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:37:30 INFO - 'MOZ_NO_REMOTE': '1', 02:37:30 INFO - 'NO_EM_RESTART': '1', 02:37:30 INFO - 'PAGER': '/bin/cat', 02:37:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:37:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:37:30 INFO - 'PWD': '/builds/slave/test', 02:37:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:37:30 INFO - 'SHELL': '/bin/bash', 02:37:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MuFujmHIqF/Listeners', 02:37:30 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 02:37:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:37:30 INFO - 'USER': 'cltbld', 02:37:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:37:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:37:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:37:30 INFO - 'XPC_FLAGS': '0x0', 02:37:30 INFO - 'XPC_SERVICE_NAME': '0', 02:37:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:37:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:37:30 INFO - Downloading/unpacking blobuploader==1.2.4 02:37:30 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 02:37:30 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 02:37:30 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 02:37:30 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 02:37:30 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 02:37:30 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 02:37:32 INFO - Downloading blobuploader-1.2.4.tar.gz 02:37:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:37:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:37:32 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 02:37:32 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 02:37:32 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 02:37:32 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 02:37:32 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 02:37:32 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 02:37:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:37:33 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:37:33 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 02:37:33 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 02:37:33 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 02:37:33 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 02:37:33 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 02:37:33 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 02:37:33 INFO - Downloading docopt-0.6.1.tar.gz 02:37:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:37:33 INFO - Installing collected packages: blobuploader, requests, docopt 02:37:33 INFO - Running setup.py install for blobuploader 02:37:33 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:37:33 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:37:33 INFO - Running setup.py install for requests 02:37:34 INFO - Running setup.py install for docopt 02:37:34 INFO - Successfully installed blobuploader requests docopt 02:37:34 INFO - Cleaning up... 02:37:34 INFO - Return code: 0 02:37:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:37:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:37:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:37:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:37:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:37:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:37:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:37:34 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 0x103a34b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a37768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf58c8b0c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103812d30>, '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 0x103738db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103807be8>, '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.Rg2KtSitaG/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.MuFujmHIqF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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 02:37:34 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 02:37:34 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 02:37:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Rg2KtSitaG/Render', 02:37:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:37:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:37:34 INFO - 'HOME': '/Users/cltbld', 02:37:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:37:34 INFO - 'LOGNAME': 'cltbld', 02:37:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:37:34 INFO - 'MOZ_NO_REMOTE': '1', 02:37:34 INFO - 'NO_EM_RESTART': '1', 02:37:34 INFO - 'PAGER': '/bin/cat', 02:37:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:37:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:37:34 INFO - 'PWD': '/builds/slave/test', 02:37:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:37:34 INFO - 'SHELL': '/bin/bash', 02:37:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MuFujmHIqF/Listeners', 02:37:34 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 02:37:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:37:34 INFO - 'USER': 'cltbld', 02:37:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:37:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:37:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:37:34 INFO - 'XPC_FLAGS': '0x0', 02:37:34 INFO - 'XPC_SERVICE_NAME': '0', 02:37:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:37:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:37:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:37:34 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-rZfn3y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:37:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:37:34 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-SMu8PI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:37:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:37:34 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-j19Qqb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:37:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:37:34 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-WhhujX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:37:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:37:34 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-ZcRFQB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:37:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:37:35 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-38MDKK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:37:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:37:35 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-0V0nzE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:37:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:37:35 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-qjLlgr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:37:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:37:35 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-VfVQVe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:37:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:37:35 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-k2nqWW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:37:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:37:35 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-TzJv2t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:37:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:37:35 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-mSkZLD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:37:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:37:35 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-75Kh3T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:37:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:37:35 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-rglDER-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:37:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:37:35 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-Dt00pT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:37:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:37:36 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-A4y384-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:37:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:37:36 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-gu4gah-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:37:36 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:37:36 INFO - Running setup.py install for manifestparser 02:37:36 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:37:36 INFO - Running setup.py install for mozcrash 02:37:36 INFO - Running setup.py install for mozdebug 02:37:36 INFO - Running setup.py install for mozdevice 02:37:36 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:37:36 INFO - Running setup.py install for mozfile 02:37:37 INFO - Running setup.py install for mozhttpd 02:37:37 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:37:37 INFO - Running setup.py install for mozinfo 02:37:37 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:37:37 INFO - Running setup.py install for mozInstall 02:37:37 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:37:37 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:37:37 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:37:37 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:37:37 INFO - Running setup.py install for mozleak 02:37:37 INFO - Running setup.py install for mozlog 02:37:37 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:37:37 INFO - Running setup.py install for moznetwork 02:37:37 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:37:37 INFO - Running setup.py install for mozprocess 02:37:38 INFO - Running setup.py install for mozprofile 02:37:38 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:37:38 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:37:38 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:37:38 INFO - Running setup.py install for mozrunner 02:37:38 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:37:38 INFO - Running setup.py install for mozscreenshot 02:37:38 INFO - Running setup.py install for moztest 02:37:38 INFO - Running setup.py install for mozversion 02:37:38 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:37:38 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:37:38 INFO - Cleaning up... 02:37:38 INFO - Return code: 0 02:37:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:37:38 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')]} 02:37:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:37:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:37:38 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')]} 02:37:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:37:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:37:38 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 0x103a34b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a37768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf58c8b0c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103812d30>, '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 0x103738db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103807be8>, '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.Rg2KtSitaG/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.MuFujmHIqF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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 02:37:38 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 02:37:38 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 02:37:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Rg2KtSitaG/Render', 02:37:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:37:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:37:38 INFO - 'HOME': '/Users/cltbld', 02:37:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:37:38 INFO - 'LOGNAME': 'cltbld', 02:37:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:37:38 INFO - 'MOZ_NO_REMOTE': '1', 02:37:38 INFO - 'NO_EM_RESTART': '1', 02:37:38 INFO - 'PAGER': '/bin/cat', 02:37:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:37:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:37:38 INFO - 'PWD': '/builds/slave/test', 02:37:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:37:38 INFO - 'SHELL': '/bin/bash', 02:37:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MuFujmHIqF/Listeners', 02:37:38 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 02:37:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:37:38 INFO - 'USER': 'cltbld', 02:37:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:37:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:37:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:37:38 INFO - 'XPC_FLAGS': '0x0', 02:37:38 INFO - 'XPC_SERVICE_NAME': '0', 02:37:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:37:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:37:39 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-0k5ZgJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:37:39 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)) 02:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:37:39 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-Wqygcg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:37:39 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)) 02:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:37:39 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-QUpwiG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:37:39 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)) 02:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:37:39 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-99aJQH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:37:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 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)) 02:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:37:39 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-YzeZ0u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:37:39 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)) 02:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:37:39 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-a5HN9n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:37:39 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)) 02:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:37:39 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-ekmrt4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:37:39 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)) 02:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:37:39 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-Nr_wY6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:37:39 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)) 02:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:37:39 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-xHFlRs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:37:40 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)) 02:37:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:37:40 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-gFOhJT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:37:40 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)) 02:37:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:37:40 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-gSe6gY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:37:40 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)) 02:37:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:37:40 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-0gQ5pV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:37:40 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)) 02:37:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:37:40 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-ZQua8U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:37:40 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)) 02:37:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:37:40 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-MWnv3K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:37:40 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)) 02:37:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:37:40 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-ZADa17-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:37:40 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)) 02:37:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:37:40 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-26c7X0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:37:40 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)) 02:37:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:37:40 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-vWDtwD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:37:40 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)) 02:37:40 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)) 02:37:40 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)) 02:37:40 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)) 02:37:40 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:37:40 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:37:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:37:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:37:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:37:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:37:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:37:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:37:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:37:42 INFO - Downloading blessings-1.6.tar.gz 02:37:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:37:42 INFO - Installing collected packages: blessings 02:37:42 INFO - Running setup.py install for blessings 02:37:42 INFO - Successfully installed blessings 02:37:42 INFO - Cleaning up... 02:37:42 INFO - Return code: 0 02:37:42 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:37:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:37:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:37:42 INFO - Reading from file tmpfile_stdout 02:37:42 INFO - Current package versions: 02:37:42 INFO - blessings == 1.6 02:37:42 INFO - blobuploader == 1.2.4 02:37:42 INFO - docopt == 0.6.1 02:37:42 INFO - functools32 == 3.2.3-2 02:37:42 INFO - jsonschema == 2.5.1 02:37:42 INFO - manifestparser == 1.1 02:37:42 INFO - mozInstall == 1.12 02:37:42 INFO - mozcrash == 1.0 02:37:42 INFO - mozdebug == 0.1 02:37:42 INFO - mozdevice == 0.50 02:37:42 INFO - mozfile == 1.2 02:37:42 INFO - mozhttpd == 0.7 02:37:42 INFO - mozinfo == 0.9 02:37:42 INFO - mozleak == 0.1 02:37:42 INFO - mozlog == 3.4 02:37:42 INFO - moznetwork == 0.27 02:37:42 INFO - mozprocess == 0.25 02:37:42 INFO - mozprofile == 0.28 02:37:42 INFO - mozrunner == 6.13 02:37:42 INFO - mozscreenshot == 0.1 02:37:42 INFO - mozsystemmonitor == 0.3 02:37:42 INFO - moztest == 0.8 02:37:42 INFO - mozversion == 1.4 02:37:42 INFO - psutil == 3.1.1 02:37:42 INFO - requests == 1.2.3 02:37:42 INFO - wsgiref == 0.1.2 02:37:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:37:42 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')]} 02:37:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:37:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:37:42 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')]} 02:37:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:37:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:37:42 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 0x103a34b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a37768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf58c8b0c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103812d30>, '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 0x103738db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103807be8>, '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.Rg2KtSitaG/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.MuFujmHIqF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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 02:37:42 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 02:37:42 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 02:37:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Rg2KtSitaG/Render', 02:37:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:37:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:37:42 INFO - 'HOME': '/Users/cltbld', 02:37:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:37:42 INFO - 'LOGNAME': 'cltbld', 02:37:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:37:42 INFO - 'MOZ_NO_REMOTE': '1', 02:37:42 INFO - 'NO_EM_RESTART': '1', 02:37:42 INFO - 'PAGER': '/bin/cat', 02:37:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:37:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:37:42 INFO - 'PWD': '/builds/slave/test', 02:37:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:37:42 INFO - 'SHELL': '/bin/bash', 02:37:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MuFujmHIqF/Listeners', 02:37:42 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 02:37:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:37:42 INFO - 'USER': 'cltbld', 02:37:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:37:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:37:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:37:42 INFO - 'XPC_FLAGS': '0x0', 02:37:42 INFO - 'XPC_SERVICE_NAME': '0', 02:37:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:37:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:37:43 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)) 02:37:43 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)) 02:37:43 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)) 02:37:43 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)) 02:37:43 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)) 02:37:43 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)) 02:37:43 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)) 02:37:43 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)) 02:37:43 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)) 02:37:43 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)) 02:37:43 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)) 02:37:43 INFO - Cleaning up... 02:37:43 INFO - Return code: 0 02:37:43 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:37:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:37:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:37:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:37:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:37:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:37:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:37:43 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 0x103a34b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a37768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf58c8b0c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103812d30>, '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 0x103738db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103807be8>, '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.Rg2KtSitaG/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.MuFujmHIqF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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 02:37:43 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 02:37:43 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 02:37:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Rg2KtSitaG/Render', 02:37:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:37:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:37:43 INFO - 'HOME': '/Users/cltbld', 02:37:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:37:43 INFO - 'LOGNAME': 'cltbld', 02:37:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:37:43 INFO - 'MOZ_NO_REMOTE': '1', 02:37:43 INFO - 'NO_EM_RESTART': '1', 02:37:43 INFO - 'PAGER': '/bin/cat', 02:37:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:37:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:37:43 INFO - 'PWD': '/builds/slave/test', 02:37:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:37:43 INFO - 'SHELL': '/bin/bash', 02:37:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MuFujmHIqF/Listeners', 02:37:43 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 02:37:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:37:43 INFO - 'USER': 'cltbld', 02:37:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:37:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:37:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:37:43 INFO - 'XPC_FLAGS': '0x0', 02:37:43 INFO - 'XPC_SERVICE_NAME': '0', 02:37:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:37:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:37:43 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:37:43 INFO - Cleaning up... 02:37:43 INFO - Return code: 0 02:37:43 INFO - Running post-action listener: _resource_record_post_action 02:37:43 INFO - Running post-action listener: _start_resource_monitoring 02:37:43 INFO - Starting resource monitoring. 02:37:43 INFO - [mozharness: 2017-05-04 09:37:43.569201Z] Finished create-virtualenv step (success) 02:37:43 INFO - [mozharness: 2017-05-04 09:37:43.570159Z] Running install step. 02:37:43 INFO - Running pre-action listener: _resource_record_pre_action 02:37:43 INFO - Running main action method: install 02:37:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:37:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:37:43 INFO - Reading from file tmpfile_stdout 02:37:43 INFO - Detecting whether we're running mozinstall >=1.0... 02:37:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:37:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:37:43 INFO - Reading from file tmpfile_stdout 02:37:43 INFO - Output received: 02:37:43 INFO - Usage: mozinstall [options] installer 02:37:43 INFO - Options: 02:37:43 INFO - -h, --help show this help message and exit 02:37:43 INFO - -d DEST, --destination=DEST 02:37:43 INFO - Directory to install application into. [default: 02:37:43 INFO - "/builds/slave/test"] 02:37:43 INFO - --app=APP Application being installed. [default: firefox] 02:37:43 INFO - mkdir: /builds/slave/test/build/application 02:37:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 02:37:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 02:38:03 INFO - Reading from file tmpfile_stdout 02:38:03 INFO - Output received: 02:38:03 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 02:38:03 INFO - Running post-action listener: _resource_record_post_action 02:38:03 INFO - [mozharness: 2017-05-04 09:38:03.928688Z] Finished install step (success) 02:38:03 INFO - [mozharness: 2017-05-04 09:38:03.928848Z] Running run-tests step. 02:38:03 INFO - Running pre-action listener: _resource_record_pre_action 02:38:03 INFO - Running main action method: run_tests 02:38:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:38:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:38:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:38:03 INFO - 2017-05-04 02:38:03.943 screenresolution[1620:7358] starting screenresolution argv=screenresolution get 02:38:03 INFO - 2017-05-04 02:38:03.946 screenresolution[1620:7358] Display 0: 1600x1200x32@60 02:38:03 INFO - 2017-05-04 02:38:03.952 screenresolution[1621:7361] starting screenresolution argv=screenresolution list 02:38:03 INFO - Available Modes on Display 0 02:38:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:38:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:38:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:38:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:38:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:38:04 INFO - Intel Iris: 02:38:04 INFO - Chipset Model: Intel Iris 02:38:04 INFO - Type: GPU 02:38:04 INFO - Bus: Built-In 02:38:04 INFO - VRAM (Dynamic, Max): 1536 MB 02:38:04 INFO - Vendor: Intel (0x8086) 02:38:04 INFO - Device ID: 0x0a2e 02:38:04 INFO - Revision ID: 0x0009 02:38:04 INFO - Displays: 02:38:04 INFO - Display: 02:38:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:38:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:38:04 INFO - Display Serial Number: 1600x1200 60 02:38:04 INFO - Main Display: Yes 02:38:04 INFO - Mirror: Off 02:38:04 INFO - Online: Yes 02:38:04 INFO - Rotation: Supported 02:38:04 INFO - Return code: 0 02:38:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:38:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:38:04 INFO - Python 2.7.3 02:38:04 INFO - Return code: 0 02:38:04 INFO - grabbing minidump binary from tooltool 02:38:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:38:04 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 0x103812d30>, '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 0x103738db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103807be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:38:04 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 02:38:04 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 02:38:04 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 02:38:04 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:38:04 INFO - Return code: 0 02:38:04 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:38:04 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:38:04 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:38:04 INFO - ENV: RUST_BACKTRACE is now 1 02:38:04 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:38:04 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:38:04 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/XNyrCkSHRFW3Q7EjWPSctw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0132', '--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 02:38:04 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/XNyrCkSHRFW3Q7EjWPSctw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0132 --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 02:38:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Rg2KtSitaG/Render', 02:38:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:38:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:38:04 INFO - 'HOME': '/Users/cltbld', 02:38:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:38:04 INFO - 'LOGNAME': 'cltbld', 02:38:04 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:38:04 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:38:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:38:04 INFO - 'MOZ_NO_REMOTE': '1', 02:38:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:38:04 INFO - 'NO_EM_RESTART': '1', 02:38:04 INFO - 'PAGER': '/bin/cat', 02:38:04 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:38:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:38:04 INFO - 'PWD': '/builds/slave/test', 02:38:04 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:38:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:38:04 INFO - 'RUST_BACKTRACE': '1', 02:38:04 INFO - 'SHELL': '/bin/bash', 02:38:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MuFujmHIqF/Listeners', 02:38:04 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 02:38:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:38:04 INFO - 'USER': 'cltbld', 02:38:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:38:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:38:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:38:04 INFO - 'XPC_FLAGS': '0x0', 02:38:04 INFO - 'XPC_SERVICE_NAME': '0', 02:38:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:38:04 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/XNyrCkSHRFW3Q7EjWPSctw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0132', '--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 02:38:04 INFO - mozversion application_buildid: 20170504015521 02:38:04 INFO - mozversion application_changeset: a8d597ee6dd58306e62e55a07c4ab20958726d6a 02:38:04 INFO - mozversion application_display_name: Nightly 02:38:04 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:38:04 INFO - mozversion application_name: Firefox 02:38:04 INFO - mozversion application_remotingname: firefox 02:38:04 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 02:38:04 INFO - mozversion application_vendor: Mozilla 02:38:04 INFO - mozversion application_version: 55.0a1 02:38:04 INFO - mozversion platform_buildid: 20170504015521 02:38:04 INFO - mozversion platform_changeset: a8d597ee6dd58306e62e55a07c4ab20958726d6a 02:38:04 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 02:38:04 INFO - mozversion platform_version: 55.0a1 02:38:04 INFO - using testdate: 1493890684 02:38:04 INFO - actual date: 1493890684 02:38:04 INFO - starting webserver on 'localhost:49240' 02:38:04 INFO - SUITE-START | Running 2 tests 02:38:04 INFO - TEST-START | dromaeo_css 02:38:04 INFO - operating with platform_type : mac_ 02:38:04 INFO - Initialising browser for dromaeo_css test... 02:38:04 INFO - TEST-INFO | started process 1631 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/tmpFY5Cwk/profile http://localhost:49240/getInfo.html) 02:38:07 INFO - PID 1631 | __metrics Screen width/height:1600/1200 02:38:07 INFO - PID 1631 | colorDepth:24 02:38:07 INFO - PID 1631 | Browser inner width/height: 1024/691 02:38:07 INFO - PID 1631 | __metrics 02:38:07 INFO - PID 1631 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 02:38:07 INFO - PID 1631 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 334: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:38:08 INFO - TEST-INFO | 1631: exit 0 02:38:08 INFO - Browser initialized. 02:38:08 INFO - Running cycle 1/1 for dromaeo_css test... 02:38:08 INFO - TEST-INFO | started process 1633 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/tmpFY5Cwk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:38:20 INFO - PID 1633 | RSS: Main: 240017408 02:38:20 INFO - PID 1633 | 02:41:25 INFO - PID 1633 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49240/tests/dromaeo/cssquery-ext.html) 02:41:27 INFO - PID 1633 | RSS: Main: 393314304 02:41:27 INFO - PID 1633 | 02:44:29 INFO - PID 1633 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-ext.html (next: http://localhost:49240/tests/dromaeo/cssquery-jquery.html) 02:44:30 INFO - PID 1633 | RSS: Main: 364208128 02:44:30 INFO - PID 1633 | 02:47:32 INFO - PID 1633 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49240/tests/dromaeo/cssquery-mootools.html) 02:47:32 INFO - PID 1633 | RSS: Main: 332963840 02:47:32 INFO - PID 1633 | 02:50:35 INFO - PID 1633 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49240/tests/dromaeo/cssquery-prototype.html) 02:50:35 INFO - PID 1633 | RSS: Main: 357044224 02:50:35 INFO - PID 1633 | 02:53:39 INFO - PID 1633 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49240/tests/dromaeo/cssquery-yui.html) 02:53:40 INFO - PID 1633 | RSS: Main: 325632000 02:53:40 INFO - PID 1633 | 02:56:42 INFO - PID 1633 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-yui.html (next: http://localhost:49240/tests/dromaeo/cssquery-dojo.html) 02:56:42 INFO - PID 1633 | RSS: Main: 372277248 02:56:42 INFO - PID 1633 | 02:56:42 INFO - PID 1633 | __start_tp_report 02:56:42 INFO - PID 1633 | _x_x_mozilla_page_load 02:56:42 INFO - PID 1633 | _x_x_mozilla_page_load_details 02:56:42 INFO - PID 1633 | |i|pagename|runs| 02:56:42 INFO - PID 1633 | |0;dojo.html;2758.793090518211;3484.1812174139072;3497.178163131662;3543.309054734327;3589.1206654369685;6142.570020098595;6153.200083989075;6166.209134015473;6196.0396138598535;6247.250329960411;6115.41903519166;6168.599041062328;6170.290416602092;6217.720202590883;6217.906731399033;4902.779374928128;5338.599605029619;5382.3541175059045;5424.349078110613;5439.891202175954;4841.7821198045995;5454.318210223722;5455.318085239345;5489.835304940858;5522.502974732273;4039.9192016159805;5328.6802791832615;5365.517103460668;5368.812091576815;5378.542823859977;5356.571474282087;5373.24774531562;5377.4084850666395;5386.703731294777;5391.406945236021;5307.044731948247;5351.759170837283;5364.6512976656395;5365.543928766059;5376.354837419534;5046.26829109777;6114.768109639994;6127.505970454377;6143.849652621591;6144.907826382608;6389.105525226472;6410.551261411703;6414.134091897581;6425.389587989181;6477.967610161966;6692.598444093322;6693.297203793629;6701.43037841784;6711.798646040626;6718.764843230463;6276.717547710318;6279.623222606659;6292.370762923717;6293.150424692608;6293.181886354791;6560.212774466999;6837.179538455417;6856.862862742717;6865.794026179123;6872.62200578963;6321.589096708699;6429.38920802523;6474.546781725328;6509.739610415625;6511.251206111263;6703.832404189934;6706.865862682816;6710.597364158166;6713.832154196184;6724.697388617524;5713.171590119493;6583;6584.5061620378665;6603.867922641521;6609.6364699941005;6365.554411191265;6446.677666116675;6534.9673251634385;6572.671366431659;6615.140031795836;5160.92258616121;5197.792088316501;5242.97378513105;5244.475552444725;5254.816081437131;4671.649626278043;4675.13510000651;4680.742559159211;4685.297205419146;4704.1767690654;6108.22065524731;6129.570930034944;6153.384661533811;6253.624782513064;6280.026595877646;6698.5980841149685;6719.731210751515;6728.798136055926;6733.427658648971;6738.124043874363;4720.95279047212;5427.9185812212845;6296.464800491918;6305.527085468517;6330.936690633127;5705.942940570624;5827.184194212814;5842.503387212135;5857.121431785266;5860.267466566679;6726.630035348005;6744.224414192235;6811.89782153278;6869.347411995853;6885.414739747178;6476.255230648536;6579.638119903451;6590.80227593173;6693.364130407604;6734.225564060196;6647.60114393138;6730.394264516217;6759.357861003196;6770.119884414995;6777.457803375817;6414.711337989708;6435.903461447989;6438.259600146043;6439.517036222208;6440.903386449113;6642.667866606747;6726.461883049442;6740.88775352081;6743.089682892747;6746.494012949147;6324.177856878577;6485.708143133476;6514.2834288229205;6515.990021546668;6525.673716314261;6274.749010039547;6325.55721099537;6392.520560957853;6481.189851268591;6495.285518593046;6260.28006779208;6332.651704156316;6339.2392912850755;6346.682665866781;6359.90460143089;6462.676866156768;6483.708233129426;6487.091807147098;6487.740490380332;6488.448481879093;6205.875882482415;6281.214848143982;6287.088372186099;6311.147995020613;6319 02:56:42 INFO - PID 1633 | |1;ext.html;755.8223817402909;775.2480094308521;789.4513313247294;1092.388262572959;1108.3793075877504;21663.35009949702;25303.49393012141;25994;31540.8960686376;34596.82701586495;35878.820605897;36222.63777362232;36399.454008189896;36413.81793091037;36423;7484.363829074535;7611.124720657126;8251.876221856677;8255.711050113247;8278.627461764205;5643.238162848024;5965.38934487688;8099.635516401747;8137.30832879206;8259.587020648974;8247.711330103448;8301.958490207571;8310.792230194233;8315.667373305061;8348.248657620812;8218.876716849252;8282.254597086292;8283.213094755989;8283.668653253862;8293.377996650215;5828.679422631753;5916.822495325104;7958.243966823143;8161.469504482249;8239.423240373177;36146.91559253226;36510.63489365125;36535.81732091349;36547.451788223196;36559.4516082259;3077.322988942429;3537.2748586539697;3537.6462353764673;3537.911552211215;3545.7163426925786;29356;29357.412851742847;29444.8527757362;29469.70530294691;29481.70518294811;3068.125584208512;3147.401993621205;3169.9841500792577;3186.410514054884;3189.984050079735;208286;283158.5842070797;283171;284217;285554;47271.76364118226;47498.762506187246;47583.10045043519;47593.762031189624;47739.5226047742;49686.75156624194;49775.25337119946;49783.00433991372;49856.750716246184;49911.75044124756;13930.930345348208;14013.719725605635;14021.158730476203;14027.78958315626;14030.579082627537;11398.658040258806;11417.31496110236;11419.543218271176;11430.885691143149;11448.771024579462;9884.703458896245;9901;9914.058164474363;11601.941990290163;11725.589604363806;5116.488351164928;5141.331626888481;5155.61332900034;5180.119379705459;5187;953.4994128082812;1002.4636819301638;1037.2894567221476;1049.234059136819;1054.7468607534136;23053.76946230516;24355.025798968196;27845.303867403072;27998.079407586454;30336.393272134876;1090.5583238788304;1090.6019302954364;1116.2242241641982;1133.387970495923;1144.6165534545853;10479.790404191874;11821.940890295493;12389.690257743629;12448.688782780502;12534.310612916195;318311;320046;320135;320703;321589;42013;50640;50652.74673626608;50687.746561266955;50699.746501267255;1474.292339676961;1485.0569888121122;1524.3597688970438;1526.5649289952532;1536.4468791235147;23276.95253713553;23294.3011709649;23325;23407.765922340557;23497.17759878396;36300.09249768777;36342.63657363498;36402.27195456129;36461;36544.63455365413;279237;280174;280283;280395;280825;30708.00795896519;31304.686953131088;31329.8433507831;31428;31468.2132946669;30627.387452251274;31285.843570782;34631;34670.133246668025;41778;27461.03886363968;28513.28716782097;29184;29320.413591728473;29600.703992959796;28327.575086374134;29159.979400720873;29267;29330.853345733136;29465;33221.66778332186;34158.82920585381;34306.48540271848;34321;34399.484007739404;28760.56859147156;29649.110526684235;29741.702582973892;29891;30018.849905750332;22019;22797;23016.654750178426;23039.76960230376;23203 02:56:42 INFO - PID 1633 | |2;jquery.html;421.20887912172657;426.72476252816915;430.39099673961346;441.097954682674;449.9280115181572;21170.25904093357;21490.677639835416;21861.453463663416;22802.54394912101;23007.654885176693;21147.259845905395;22458.98934547945;23267;23503.76496235037;23583.76416235837;5489.298317521561;5780.364159942403;6066.150738896558;6279.120923070774;6295.244570651528;6076.6961651917445;6225.688715564226;6257.843553911143;6262.436380725734;6270.31026587075;5866.413358664142;5949.9107513387335;6027.879442411149;6213.782517611885;6230.5638605297645;6058.061000544901;6075.210222671025;6086.5435092368025;6089.299730530957;6093.78671746489;5343.65266257692;5352.4915133062295;5404.756785944642;5433.945660543423;5438.483344082306;27281.9528387249;27420.725792742218;27469.86265068662;27685.446291074266;27766;2214.402111429921;2272.4318920269766;2274.4996100857784;2277.555876604063;2278.475950531387;17870.91064544669;17983.820161798478;18039.819601803945;18107.91352518853;18283.542911427186;2085.8331333493434;2110.6095372356162;2158.3093410108772;2158.6222411078156;2174.5759576882515;136082;136493;137216.31391842978;137490.31254843762;139083;11021.614243501439;11740.823887641693;12010.879891201152;12413.93793031029;12523.185992910432;16280.837191628169;16283.511494655178;16294.940828846087;16303.266353014144;16331.83668163327;8603.741887743377;8789.033206347296;8796.34027447944;8902.065283145177;8925.821483570293;8355.289800366916;8383.664653413794;8527.360447966428;8540.103289154673;8563.486190828571;6355.332690067568;7756.4182686298755;7815.25755053269;7817.843643127106;7826.1391246962785;2081.9479513012298;2087.2277257414853;2090.1221486975505;2097.674860396641;2119.319061387346;888.5512816027865;970.2141265574766;984.3552473130111;1004.598160735697;1031.138998935897;19044.238230470903;19136.9474678894;22109.557808844056;22122.44693882666;22153.778462215494;1010.7416266747946;1014.4167103915278;1043.8068957242783;1049.8582691336571;1050.6322787024633;9568.890110714046;10968.287061340987;11030.566026416514;11377.37424441647;11386.08911287095;544370;552906;557025;558211;560857;129792;135792.32103839616;135801;137489;140970;1171.8066519024435;1185.6383802940013;1213.7087099096154;1215.1251099208555;1217.0507004536476;18549.814501855348;18668.719969200745;18729.81270187281;18881.035102432295;18965.905170474613;48939.14851920805;55472.890542189736;56421.153682695614;58898.411015889295;60065.69967150136;79791.80312295437;82555;83479.58260208661;83931.58034210034;84678;8745;8824.249938755022;9015.323850711091;9037.09629037091;9165.450072995642;13822.861771382157;13886.09740366873;14199.432022718975;14287.285635717964;14309.856901430852;11753.059755219732;12044.397780111134;12089.159047623625;12175.086868484723;12250.75498490043;11419.31484110956;11419.657410277372;11545.48045337945;11560.710982225512;11796.29222246668;11963.940180299043;12249;12260.386980650754;12319.507219711111;12387.69030774238;8351.916480835114;8589.828203436022;8945.329100317373;9052;9197.77005574866;11710.648680539598;11894.286342819458;12078.51685932588;12088.818667720167;12123.81814272769 02:56:42 INFO - PID 1633 | |3;mootools.html;1090.7927493776187;1244.415124891301;1303.9869601303988;1448.9420423183074;1453.3968403112701;9775.36460130092;11150.107991360692;11827.586034488795;11851.822222666668;11854.288742675435;11375;11485.25345852519;11509.712257193554;11511.30932144072;11524.884751152485;2487.6517287579754;2533.6959564852245;2538.6318983747346;2541.136013755323;2541.809364297685;2047.651899177136;2124.4476436126643;2201.152556265833;2555.9872200639065;2562.3466016165853;2512.7110382306087;2539.1113110411393;2540.4157043879827;2544.6564713763682;2547.1085120207963;2482.8262021658493;2519.2694118705554;2519.307190522621;2522.445062086338;2522.5207210630015;2426.7451917548756;2440.804735621146;2447.9388015299583;2451.63225516174;2459.532688789124;11620.6513804586;11735.23720958135;11749.177557570973;11763.705907352385;11765;6508.869822603521;6740.022696708998;6758.087658166134;6763.357481039294;6769.52613317068;3386.8306584670668;4076.6331030207425;4178.477690288714;4233.576642335773;4276.166147601219;1252.5678640868887;1276.7510335484585;1279.5777393460228;1295.598364507006;1321.590307004822;16391.754123688155;16507.91746041262;16514.33942642293;16544.83455165457;16579.253933572898;5593.272874526286;5858.970705146447;6100.664463454464;6113.816585502442;6119.051547010132;13749.793753093712;13857.03000789935;13863.098898571763;13885.514007009706;13928.442862285396;6811.9659401702675;6819.931800681929;6823.658817059227;6824.488163387766;6827.897581536281;5872.619934315432;6235.189425374672;6249.0938813871735;6252.249730032425;6258.593191442633;3573.3032058748295;3808.5810560838286;4240.893977650583;4263.211305908417;4666.066786642685;1452.2158034661377;1456.5484699743113;1464.8974571780086;1484.6288427893028;1486.4351546412295;821.9068638710501;827.420805436197;836.180543067797;845.6152450634946;877.236803980541;5469.890602187934;6344.206974128234;6434.581752186186;6533.967330163414;6883.036374907517;927.5640448988964;942.0814705661925;954.8997355277735;979.3242662562809;981.7545613596601;3800.25894950482;3807.238552289498;3873.2059927715313;3914;3926.5680775114715;14333.56999290002;14982.775258371134;14992.70014599702;15035.473758418622;15070.397184112513;14856.18290993984;14886.181260030802;14895.255237238094;14907.329170187368;14951.775723364159;6624.867502650017;8109.229623185788;8434.957825210835;8635.654573816977;8647.654093836301;6524.445422139172;6595.175603049619;6685.832854178684;6710.932890671226;6747.055412242192;11228.4385780709;13138.868611313765;13319.800202996768;13343.132696374705;13646.181229126285;13591.388387522784;13860.792088118485;13968.4412623498;13983.44066237339;14107.929460352632;8515.233628973423;9156.267498599962;9284.535773211177;9308.022657621123;9325.720228393158;9661.082197191256;9935.801283974424;9939.701808945743;10569.101626361846;10671.679849604525;8085.272325490734;8975.237104846161;9034.367594268339;9037.593308301011;9242.491662958602;8825.02924678272;8838;8904;8923;8923.73228803137;8227.54748488815;8261.586920654063;8264.586770661563;8269;8276.958615206886;5048.697078175322;5955.196048533393;6054.39456054391;6056.242969628796;6142.04798256271;5989.161517387656;6071.908921366271;6107.633541987495;6208.410201030895;6362.586431881913 02:56:42 INFO - PID 1633 | |4;prototype.html;375.63563343556757;389;397.9661728753056;398.39841838823384;399.1697269679068;5951.31559870615;6006.519478441725;6007.609505382153;6044.455998960093;6049;5208.838332092187;5579.944200557993;5620.819627878151;5876.002159265849;5904.409559044094;4256.97871510642;4306.676999225055;4308.246056940038;4310.547392523787;4359.427937516864;4316.82732690692;4324.351347297915;4331.870043898688;4338.980339620187;4341.50072741634;3954.703397245218;4079.9592004079877;4296.613304802567;4304.397384366192;4320.092780516095;4274.807633656477;4275.978620106911;4284.807818175448;4288.228118938589;4292.978535107336;3101.565780790691;3692.5015122958325;3711.6659500645073;3722.8138593070507;3733.178700685845;5545.585875601736;5628.789810190829;5740.7703691852275;5791.160281759121;5810.883782324372;4405.493368262659;5059.494050594911;5070.6957582545165;5075.365579302587;5108.591312694975;4776.4268287805335;5582.888342233173;5660.971695141498;5693.743781529841;6354.140636713498;3232.9816107926163;3973.145773658677;3988.6809055275507;4198.685098617585;4212.283911735012;5900.734466948955;5962.165296858487;6505.21937367519;6531.281559028503;6617.503687223478;4112.485939257593;4113.025747641149;4146.647534959502;4147.921540399518;4267.25323068468;3704.333220020368;3975.582563830814;4299.484061912591;4311.417958575615;4333.544977777351;4701.260533602589;4705.71772690245;5049.293098966147;5073.822416215415;5091.847244582668;5465.945340546544;6198.690065496708;6241.065269766387;6267;6281.560290779693;1246.6260121963555;1446.616646588655;1474.388128926505;1475.7196132734746;1480.195030417353;815.9918400816034;955.7276176289724;960.6013504395597;968.5109019945023;977.8533220017055;5232.686038837682;5886.23478947734;5897.557683173779;5907.616004959664;6011.458968692838;5428.348598168245;5495.945040549544;5526.585506086979;6413.662239089165;6435.839104022343;4624.95375046252;5602.523785478198;5639.971800141055;5722.427757224327;5955.40445955401;3339.766216364831;3389.7627166098137;3391.406503861814;3391.6269210386836;3404.268082362304;6537.705803238866;6607.423104989058;6645.169353830771;6682.398584127452;6728.226253980855;6639.568428052257;6639.933600663931;6642.767503137464;6650.96674516634;6672.83317917056;5986.820395388146;6079.3008803988105;6083.0542063726125;6652.234992975869;6677.833054173684;5568.023351710084;6425.389587989133;6454.128692626436;6482.70827812759;6492.772752953624;6220.813375598739;6328.968355158379;6368.617882927039;6369.522285828674;6390.93609063922;6232.844178895564;6606.8348291293105;6627.3372662733345;6655.667216639052;6657.933420665731;4260.488741351058;4273.63674087706;4277.165952639206;4279.3096726793365;4303.526612072732;5819.592628515963;5890.410958904075;5897.351291358033;6089.934261504308;6413.807585772434;5241.318628578413;6218.782342617987;6229.2524897012645;6252.406021428139;6278.968605156945;4605.723656580616;6215.34738852414;6222.875542489034;6242.750289988349;6250;6150.7847225346895;6156.26124865019;6160.014397696347;6193.907091393543;6235.906461402992;5006.624503162205;6228.781992630236;6311.27420346666;6324.494040476843;6354.745810167541;4596.494385617512;5645.322561292671;5715.199872018004;6046.425589568984;6155.907661384993 02:56:42 INFO - PID 1633 | |5;yui.html;816.7958010497375;837.284122075625;840.6385254340631;846.3187134356842;861.7199410191689;14912.850871491282;14956.700865982673;15117.92441037796;15122.319495622696;16618;14974.475893343735;15739.291731872065;15898.284577194025;15905;15915.84084159158;932.8041111366604;948.4735971535795;955.3742298794266;955.7610597350663;958.4488918871676;978.9916386122305;990.1484723138095;990.732502224399;992.0773680477154;992.5235886774352;906.931980101495;1037.486444210117;1048.6015314180638;1049.0506091986765;1049.3861091261622;1035.30634474902;1041.7760181561;1043.4626167523732;1045.095991966943;1047.649037572414;741.8924255982852;742.1131747561666;744.6946751831723;752.1801236652021;775.0970119810414;16562.917185414117;16830.747538786927;17016.31934722622;17418.390356337466;17658.205380757896;16630.75053874193;16859.157042147843;17050.74423883643;17122.914385427994;17237.224324905284;13749.931250343685;13944.721105577933;14047.929760351133;14122.01145919786;14187.077839940372;3089.552014957841;3518.700910422617;3521.0317162780184;3525.2773181497732;3526.647335266453;91748;129339.35330323382;130423.34788326093;130818.03772943413;132703;8765.561721913879;10921.563137474572;11131.276467029616;11422.25755325901;11424.942875285571;17842.10789460522;21715;22293.777062229496;22535.661965070536;22650.320490385315;6790.796276111724;7740.993670822757;7928.326092282221;7931.405144614177;7935.603219838985;3500.7024402926036;3559.875404360887;3562.6437356264164;3563.3051554946537;3563.322968635951;4241.87274381769;4319.157764236007;4354.847580334673;4355.891102722458;4363.040131171138;927.4157280912982;957.5684351893824;963.720521048902;966.6713317471953;967.1923943507251;637.3212528656966;645.2321737132891;648.8377905523619;652.9771457998947;680.3808534233922;10527.368357898551;11239.494222759995;11357.602483913024;11506.482208300478;11515.71210719722;542.5117394345122;554.3237250554317;637.1239545624766;640.2477089419913;666.027599704437;4953.331300274417;5029.245613158056;5036.370453693288;5092.6435149539175;5233.685978841282;90072;103243;103287;103495.4825225869;106008.15885775998;90533.54733226291;91945.54027229821;92123;94220.28898555045;94780.57829132568;51725.965480690174;70070.64964675144;70389.64805175942;71436.64281678663;71961;10214.948925255327;10377.688669339932;10439.060484556123;10454.529546170592;10648.094911932572;33886;41705.58294417139;41791.79104104581;42307.1538569233;43480.91297717582;54710;55207;55324.44675553354;55934.72032639811;56626.43373566212;14067.718645626825;14339.498117565836;14343.641408964442;14351.138931664134;14351.354189061309;15363.38546458174;15423.460178893685;16265;16423.42518011864;17519.82480175182;14198.3610737515;16507.9174604131;17305.39431119905;17425.82574174242;17705.91147044308;16600.169991500617;16983;16985.065821379943;17004.744928826323;17248.396306129227;13372;14524;16303.592410189365;17072.317107315568;17217.74173387375;11780.882191177978;11819.704507387383;12183.90344868966;12213.816792747939;12235.95994340491;12500.312482813533;13250.271235082164;13264.403101860633;13548.187108773505;13843.4462621494 02:56:42 INFO - PID 1633 | __end_tp_report 02:56:42 INFO - PID 1633 | __start_cc_report 02:56:42 INFO - PID 1633 | _x_x_mozilla_cycle_collect,1558 02:56:42 INFO - PID 1633 | __end_cc_report 02:56:42 INFO - PID 1633 | __startTimestamp1493891802526__endTimestamp 02:56:42 INFO - PID 1633 | 02:56:42 INFO - PID 1633 | ------- Summary: start ------- 02:56:42 INFO - PID 1633 | Number of tests: 6 02:56:42 INFO - PID 1633 | 02:56:42 INFO - PID 1633 | [#0] dojo.html Cycles:165 Average:6064.95 Median:6321.59 stddev:765.62 (12.1%) stddev-sans-first:722.70 02:56:42 INFO - PID 1633 | Values: 2758.8 3484.2 3497.2 3543.3 3589.1 6142.6 6153.2 6166.2 6196.0 6247.3 6115.4 6168.6 6170.3 6217.7 6217.9 4902.8 5338.6 5382.4 5424.3 5439.9 4841.8 5454.3 5455.3 5489.8 5522.5 4039.9 5328.7 5365.5 5368.8 5378.5 5356.6 5373.2 5377.4 5386.7 5391.4 5307.0 5351.8 5364.7 5365.5 5376.4 5046.3 6114.8 6127.5 6143.8 6144.9 6389.1 6410.6 6414.1 6425.4 6478.0 6692.6 6693.3 6701.4 6711.8 6718.8 6276.7 6279.6 6292.4 6293.2 6293.2 6560.2 6837.2 6856.9 6865.8 6872.6 6321.6 6429.4 6474.5 6509.7 6511.3 6703.8 6706.9 6710.6 6713.8 6724.7 5713.2 6583.0 6584.5 6603.9 6609.6 6365.6 6446.7 6535.0 6572.7 6615.1 5160.9 5197.8 5243.0 5244.5 5254.8 4671.6 4675.1 4680.7 4685.3 4704.2 6108.2 6129.6 6153.4 6253.6 6280.0 6698.6 6719.7 6728.8 6733.4 6738.1 4721.0 5427.9 6296.5 6305.5 6330.9 5705.9 5827.2 5842.5 5857.1 5860.3 6726.6 6744.2 6811.9 6869.3 6885.4 6476.3 6579.6 6590.8 6693.4 6734.2 6647.6 6730.4 6759.4 6770.1 6777.5 6414.7 6435.9 6438.3 6439.5 6440.9 6642.7 6726.5 6740.9 6743.1 6746.5 6324.2 6485.7 6514.3 6516.0 6525.7 6274.7 6325.6 6392.5 6481.2 6495.3 6260.3 6332.7 6339.2 6346.7 6359.9 6462.7 6483.7 6487.1 6487.7 6488.4 6205.9 6281.2 6287.1 6311.1 6319.0 02:56:42 INFO - PID 1633 | 02:56:42 INFO - PID 1633 | [#1] ext.html Cycles:180 Average:42930.60 Median:17344.15 stddev:76458.93 (440.8%) stddev-sans-first:76607.85 02:56:42 INFO - PID 1633 | Values: 755.8 775.2 789.5 1092.4 1108.4 21663.4 25303.5 25994.0 31540.9 34596.8 35878.8 36222.6 36399.5 36413.8 36423.0 7484.4 7611.1 8251.9 8255.7 8278.6 5643.2 5965.4 8099.6 8137.3 8259.6 8247.7 8302.0 8310.8 8315.7 8348.2 8218.9 8282.3 8283.2 8283.7 8293.4 5828.7 5916.8 7958.2 8161.5 8239.4 36146.9 36510.6 36535.8 36547.5 36559.5 3077.3 3537.3 3537.6 3537.9 3545.7 29356.0 29357.4 29444.9 29469.7 29481.7 3068.1 3147.4 3170.0 3186.4 3190.0 208286.0 283158.6 283171.0 284217.0 285554.0 47271.8 47498.8 47583.1 47593.8 47739.5 49686.8 49775.3 49783.0 49856.8 49911.8 13930.9 14013.7 14021.2 14027.8 14030.6 11398.7 11417.3 11419.5 11430.9 11448.8 9884.7 9901.0 9914.1 11601.9 11725.6 5116.5 5141.3 5155.6 5180.1 5187.0 953.5 1002.5 1037.3 1049.2 1054.7 23053.8 24355.0 27845.3 27998.1 30336.4 1090.6 1090.6 1116.2 1133.4 1144.6 10479.8 11821.9 12389.7 12448.7 12534.3 318311.0 320046.0 320135.0 320703.0 321589.0 42013.0 50640.0 50652.7 50687.7 50699.7 1474.3 1485.1 1524.4 1526.6 1536.4 23277.0 23294.3 23325.0 23407.8 23497.2 36300.1 36342.6 36402.3 36461.0 36544.6 279237.0 280174.0 280283.0 280395.0 280825.0 30708.0 31304.7 31329.8 31428.0 31468.2 30627.4 31285.8 34631.0 34670.1 41778.0 27461.0 28513.3 29184.0 29320.4 29600.7 28327.6 29160.0 29267.0 29330.9 29465.0 33221.7 34158.8 34306.5 34321.0 34399.5 28760.6 29649.1 29741.7 29891.0 30018.8 22019.0 22797.0 23016.7 23039.8 23203.0 02:56:42 INFO - PID 1633 | 02:56:42 INFO - PID 1633 | [#2] jquery.html Cycles:180 Average:35565.56 Median:22306.38 stddev:93692.90 (420.0%) stddev-sans-first:93918.58 02:56:42 INFO - PID 1633 | Values: 421.2 426.7 430.4 441.1 449.9 21170.3 21490.7 21861.5 22802.5 23007.7 21147.3 22459.0 23267.0 23503.8 23583.8 5489.3 5780.4 6066.2 6279.1 6295.2 6076.7 6225.7 6257.8 6262.4 6270.3 5866.4 5949.9 6027.9 6213.8 6230.6 6058.1 6075.2 6086.5 6089.3 6093.8 5343.7 5352.5 5404.8 5433.9 5438.5 27282.0 27420.7 27469.9 27685.4 27766.0 2214.4 2272.4 2274.5 2277.6 2278.5 17870.9 17983.8 18039.8 18107.9 18283.5 2085.8 2110.6 2158.3 2158.6 2174.6 136082.0 136493.0 137216.3 137490.3 139083.0 11021.6 11740.8 12010.9 12413.9 12523.2 16280.8 16283.5 16294.9 16303.3 16331.8 8603.7 8789.0 8796.3 8902.1 8925.8 8355.3 8383.7 8527.4 8540.1 8563.5 6355.3 7756.4 7815.3 7817.8 7826.1 2081.9 2087.2 2090.1 2097.7 2119.3 888.6 970.2 984.4 1004.6 1031.1 19044.2 19136.9 22109.6 22122.4 22153.8 1010.7 1014.4 1043.8 1049.9 1050.6 9568.9 10968.3 11030.6 11377.4 11386.1 544370.0 552906.0 557025.0 558211.0 560857.0 129792.0 135792.3 135801.0 137489.0 140970.0 1171.8 1185.6 1213.7 1215.1 1217.1 18549.8 18668.7 18729.8 18881.0 18965.9 48939.1 55472.9 56421.2 58898.4 60065.7 79791.8 82555.0 83479.6 83931.6 84678.0 8745.0 8824.2 9015.3 9037.1 9165.5 13822.9 13886.1 14199.4 14287.3 14309.9 11753.1 12044.4 12089.2 12175.1 12250.8 11419.3 11419.7 11545.5 11560.7 11796.3 11963.9 12249.0 12260.4 12319.5 12387.7 8351.9 8589.8 8945.3 9052.0 9197.8 11710.6 11894.3 12078.5 12088.8 12123.8 02:56:42 INFO - PID 1633 | 02:56:42 INFO - PID 1633 | [#3] mootools.html Cycles:180 Average:7102.77 Median:3840.89 stddev:4538.38 (118.2%) stddev-sans-first:4528.63 02:56:42 INFO - PID 1633 | Values: 1090.8 1244.4 1304.0 1448.9 1453.4 9775.4 11150.1 11827.6 11851.8 11854.3 11375.0 11485.3 11509.7 11511.3 11524.9 2487.7 2533.7 2538.6 2541.1 2541.8 2047.7 2124.4 2201.2 2556.0 2562.3 2512.7 2539.1 2540.4 2544.7 2547.1 2482.8 2519.3 2519.3 2522.4 2522.5 2426.7 2440.8 2447.9 2451.6 2459.5 11620.7 11735.2 11749.2 11763.7 11765.0 6508.9 6740.0 6758.1 6763.4 6769.5 3386.8 4076.6 4178.5 4233.6 4276.2 1252.6 1276.8 1279.6 1295.6 1321.6 16391.8 16507.9 16514.3 16544.8 16579.3 5593.3 5859.0 6100.7 6113.8 6119.1 13749.8 13857.0 13863.1 13885.5 13928.4 6812.0 6819.9 6823.7 6824.5 6827.9 5872.6 6235.2 6249.1 6252.2 6258.6 3573.3 3808.6 4240.9 4263.2 4666.1 1452.2 1456.5 1464.9 1484.6 1486.4 821.9 827.4 836.2 845.6 877.2 5469.9 6344.2 6434.6 6534.0 6883.0 927.6 942.1 954.9 979.3 981.8 3800.3 3807.2 3873.2 3914.0 3926.6 14333.6 14982.8 14992.7 15035.5 15070.4 14856.2 14886.2 14895.3 14907.3 14951.8 6624.9 8109.2 8435.0 8635.7 8647.7 6524.4 6595.2 6685.8 6710.9 6747.1 11228.4 13138.9 13319.8 13343.1 13646.2 13591.4 13860.8 13968.4 13983.4 14107.9 8515.2 9156.3 9284.5 9308.0 9325.7 9661.1 9935.8 9939.7 10569.1 10671.7 8085.3 8975.2 9034.4 9037.6 9242.5 8825.0 8838.0 8904.0 8923.0 8923.7 8227.5 8261.6 8264.6 8269.0 8277.0 5048.7 5955.2 6054.4 6056.2 6142.0 5989.2 6071.9 6107.6 6208.4 6362.6 02:56:42 INFO - PID 1633 | 02:56:42 INFO - PID 1633 | [#4] prototype.html Cycles:180 Average:5008.38 Median:5653.15 stddev:1575.53 (27.9%) stddev-sans-first:1541.10 02:56:42 INFO - PID 1633 | Values: 375.6 389.0 398.0 398.4 399.2 5951.3 6006.5 6007.6 6044.5 6049.0 5208.8 5579.9 5620.8 5876.0 5904.4 4257.0 4306.7 4308.2 4310.5 4359.4 4316.8 4324.4 4331.9 4339.0 4341.5 3954.7 4080.0 4296.6 4304.4 4320.1 4274.8 4276.0 4284.8 4288.2 4293.0 3101.6 3692.5 3711.7 3722.8 3733.2 5545.6 5628.8 5740.8 5791.2 5810.9 4405.5 5059.5 5070.7 5075.4 5108.6 4776.4 5582.9 5661.0 5693.7 6354.1 3233.0 3973.1 3988.7 4198.7 4212.3 5900.7 5962.2 6505.2 6531.3 6617.5 4112.5 4113.0 4146.6 4147.9 4267.3 3704.3 3975.6 4299.5 4311.4 4333.5 4701.3 4705.7 5049.3 5073.8 5091.8 5465.9 6198.7 6241.1 6267.0 6281.6 1246.6 1446.6 1474.4 1475.7 1480.2 816.0 955.7 960.6 968.5 977.9 5232.7 5886.2 5897.6 5907.6 6011.5 5428.3 5495.9 5526.6 6413.7 6435.8 4625.0 5602.5 5640.0 5722.4 5955.4 3339.8 3389.8 3391.4 3391.6 3404.3 6537.7 6607.4 6645.2 6682.4 6728.2 6639.6 6639.9 6642.8 6651.0 6672.8 5986.8 6079.3 6083.1 6652.2 6677.8 5568.0 6425.4 6454.1 6482.7 6492.8 6220.8 6329.0 6368.6 6369.5 6390.9 6232.8 6606.8 6627.3 6655.7 6657.9 4260.5 4273.6 4277.2 4279.3 4303.5 5819.6 5890.4 5897.4 6089.9 6413.8 5241.3 6218.8 6229.3 6252.4 6279.0 4605.7 6215.3 6222.9 6242.8 6250.0 6150.8 6156.3 6160.0 6193.9 6235.9 5006.6 6228.8 6311.3 6324.5 6354.7 4596.5 5645.3 5715.2 6046.4 6155.9 02:56:42 INFO - PID 1633 | 02:56:42 INFO - PID 1633 | [#5] yui.html Cycles:180 Average:20837.28 Median:17774.01 stddev:29779.31 (167.5%) stddev-sans-first:29824.91 02:56:42 INFO - PID 1633 | Values: 816.8 837.3 840.6 846.3 861.7 14912.9 14956.7 15117.9 15122.3 16618.0 14974.5 15739.3 15898.3 15905.0 15915.8 932.8 948.5 955.4 955.8 958.4 979.0 990.1 990.7 992.1 992.5 906.9 1037.5 1048.6 1049.1 1049.4 1035.3 1041.8 1043.5 1045.1 1047.6 741.9 742.1 744.7 752.2 775.1 16562.9 16830.7 17016.3 17418.4 17658.2 16630.8 16859.2 17050.7 17122.9 17237.2 13749.9 13944.7 14047.9 14122.0 14187.1 3089.6 3518.7 3521.0 3525.3 3526.6 91748.0 129339.4 130423.3 130818.0 132703.0 8765.6 10921.6 11131.3 11422.3 11424.9 17842.1 21715.0 22293.8 22535.7 22650.3 6790.8 7741.0 7928.3 7931.4 7935.6 3500.7 3559.9 3562.6 3563.3 3563.3 4241.9 4319.2 4354.8 4355.9 4363.0 927.4 957.6 963.7 966.7 967.2 637.3 645.2 648.8 653.0 680.4 10527.4 11239.5 11357.6 11506.5 11515.7 542.5 554.3 637.1 640.2 666.0 4953.3 5029.2 5036.4 5092.6 5233.7 90072.0 103243.0 103287.0 103495.5 106008.2 90533.5 91945.5 92123.0 94220.3 94780.6 51726.0 70070.6 70389.6 71436.6 71961.0 10214.9 10377.7 10439.1 10454.5 10648.1 33886.0 41705.6 41791.8 42307.2 43480.9 54710.0 55207.0 55324.4 55934.7 56626.4 14067.7 14339.5 14343.6 14351.1 14351.4 15363.4 15423.5 16265.0 16423.4 17519.8 14198.4 16507.9 17305.4 17425.8 17705.9 16600.2 16983.0 16985.1 17004.7 17248.4 13372.0 14524.0 16303.6 17072.3 17217.7 11780.9 11819.7 12183.9 12213.8 12236.0 12500.3 13250.3 13264.4 13548.2 13843.4 02:56:42 INFO - PID 1633 | -------- Summary: end -------- 02:56:42 INFO - PID 1633 | 02:56:43 INFO - TEST-INFO | 1633: exit 0 02:56:43 INFO - TEST-OK | dromaeo_css | took 1118625ms 02:56:43 INFO - TEST-START | kraken 02:56:43 INFO - operating with platform_type : mac_ 02:56:43 INFO - Initialising browser for kraken test... 02:56:43 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/tmpEg9Tvp/profile http://localhost:49240/getInfo.html) 02:56:45 INFO - PID 1643 | __metrics Screen width/height:1600/1200 02:56:45 INFO - PID 1643 | colorDepth:24 02:56:45 INFO - PID 1643 | Browser inner width/height: 1024/691 02:56:45 INFO - PID 1643 | __metrics 02:56:45 INFO - PID 1643 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 02:56:45 INFO - PID 1643 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 334: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:56:46 INFO - TEST-INFO | 1643: exit 0 02:56:46 INFO - Browser initialized. 02:56:46 INFO - Running cycle 1/1 for kraken test... 02:56:46 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/tmpEg9Tvp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:56:58 INFO - PID 1644 | RSS: Main: 240103424 02:56:58 INFO - PID 1644 | 02:57:01 INFO - PID 1644 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection) 02:57:02 INFO - PID 1644 | RSS: Main: 308883456 02:57:02 INFO - PID 1644 | 02:57:05 INFO - PID 1644 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-dft) 02:57:05 INFO - PID 1644 | RSS: Main: 291454976 02:57:05 INFO - PID 1644 | 02:57:09 INFO - PID 1644 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-fft) 02:57:09 INFO - PID 1644 | RSS: Main: 385380352 02:57:09 INFO - PID 1644 | 02:57:11 INFO - PID 1644 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator) 02:57:12 INFO - PID 1644 | RSS: Main: 287432704 02:57:12 INFO - PID 1644 | 02:57:14 INFO - PID 1644 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:57:15 INFO - PID 1644 | RSS: Main: 289517568 02:57:15 INFO - PID 1644 | 02:57:17 INFO - PID 1644 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom) 02:57:18 INFO - PID 1644 | RSS: Main: 352997376 02:57:18 INFO - PID 1644 | 02:57:21 INFO - PID 1644 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate) 02:57:22 INFO - PID 1644 | RSS: Main: 368947200 02:57:22 INFO - PID 1644 | 02:57:24 INFO - PID 1644 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial) 02:57:25 INFO - PID 1644 | RSS: Main: 376049664 02:57:25 INFO - PID 1644 | 02:57:27 INFO - PID 1644 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:57:27 INFO - PID 1644 | RSS: Main: 327507968 02:57:27 INFO - PID 1644 | 02:57:29 INFO - PID 1644 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:57:30 INFO - PID 1644 | RSS: Main: 327163904 02:57:30 INFO - PID 1644 | 02:57:32 INFO - PID 1644 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:57:32 INFO - PID 1644 | RSS: Main: 330240000 02:57:32 INFO - PID 1644 | 02:57:35 INFO - PID 1644 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:57:36 INFO - PID 1644 | RSS: Main: 334356480 02:57:36 INFO - PID 1644 | 02:57:39 INFO - PID 1644 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:57:40 INFO - PID 1644 | RSS: Main: 344227840 02:57:40 INFO - PID 1644 | 02:57:41 INFO - PID 1644 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49240/tests/kraken/driver.html?testName=ai-astar) 02:57:42 INFO - PID 1644 | RSS: Main: 387149824 02:57:42 INFO - PID 1644 | 02:57:42 INFO - PID 1644 | __start_tp_report 02:57:42 INFO - PID 1644 | _x_x_mozilla_page_load 02:57:42 INFO - PID 1644 | _x_x_mozilla_page_load_details 02:57:42 INFO - PID 1644 | |i|pagename|runs| 02:57:42 INFO - PID 1644 | |0;ai-astar;120;161;147;112;110;110;111;110;111;111 02:57:42 INFO - PID 1644 | |1;audio-beat-detection;142;109;108;149;112;107;117;137;140;129 02:57:42 INFO - PID 1644 | |2;audio-dft;183;184;185;187;186;185;183;186;181;185 02:57:42 INFO - PID 1644 | |3;audio-fft;77;57;60;85;59;57;60;57;86;56 02:57:42 INFO - PID 1644 | |4;audio-oscillator;87;69;85;68;71;76;104;100;95;89 02:57:42 INFO - PID 1644 | |5;imaging-gaussian-blur;95;94;93;90;93;94;94;86;129;89 02:57:42 INFO - PID 1644 | |6;imaging-darkroom;97;96;97;96;96;96;97;97;97;96 02:57:42 INFO - PID 1644 | |7;imaging-desaturate;86;85;86;87;86;85;85;85;87;88 02:57:42 INFO - PID 1644 | |8;json-parse-financial;48;47;48;48;47;48;50;48;48;49 02:57:42 INFO - PID 1644 | |9;json-stringify-tinderbox;55;59;60;61;54;57;74;66;58;59 02:57:42 INFO - PID 1644 | |10;stanford-crypto-aes;82;82;82;97;81;82;81;81;81;81 02:57:42 INFO - PID 1644 | |11;stanford-crypto-ccm;145;128;141;147;145;138;137;136;176;149 02:57:42 INFO - PID 1644 | |12;stanford-crypto-pbkdf2;184;185;199;207;183;183;187;184;182;171 02:57:42 INFO - PID 1644 | |13;stanford-crypto-sha256-iterative;68;68;67;68;67;67;68;69;68;68 02:57:42 INFO - PID 1644 | __end_tp_report 02:57:42 INFO - PID 1644 | __start_cc_report 02:57:42 INFO - PID 1644 | _x_x_mozilla_cycle_collect,2130 02:57:42 INFO - PID 1644 | __end_cc_report 02:57:42 INFO - PID 1644 | __startTimestamp1493891862188__endTimestamp 02:57:42 INFO - PID 1644 | 02:57:42 INFO - PID 1644 | ------- Summary: start ------- 02:57:42 INFO - PID 1644 | Number of tests: 14 02:57:42 INFO - PID 1644 | 02:57:42 INFO - PID 1644 | [#0] ai-astar Cycles:10 Average:120.30 Median:111.50 stddev:18.31 (16.4%) stddev-sans-first:19.42 02:57:42 INFO - PID 1644 | Values: 120.0 161.0 147.0 112.0 110.0 110.0 111.0 110.0 111.0 111.0 02:57:42 INFO - PID 1644 | 02:57:42 INFO - PID 1644 | [#1] audio-beat-detection Cycles:10 Average:125.00 Median:133.00 stddev:16.17 (12.2%) stddev-sans-first:15.93 02:57:42 INFO - PID 1644 | Values: 142.0 109.0 108.0 149.0 112.0 107.0 117.0 137.0 140.0 129.0 02:57:42 INFO - PID 1644 | 02:57:42 INFO - PID 1644 | [#2] audio-dft Cycles:10 Average:184.50 Median:185.00 stddev:1.78 (1.0%) stddev-sans-first:1.80 02:57:42 INFO - PID 1644 | Values: 183.0 184.0 185.0 187.0 186.0 185.0 183.0 186.0 181.0 185.0 02:57:42 INFO - PID 1644 | 02:57:42 INFO - PID 1644 | [#3] audio-fft Cycles:10 Average:65.40 Median:60.00 stddev:12.21 (20.4%) stddev-sans-first:12.21 02:57:42 INFO - PID 1644 | Values: 77.0 57.0 60.0 85.0 59.0 57.0 60.0 57.0 86.0 56.0 02:57:42 INFO - PID 1644 | 02:57:42 INFO - PID 1644 | [#4] audio-oscillator Cycles:10 Average:84.40 Median:80.50 stddev:13.01 (16.2%) stddev-sans-first:13.77 02:57:42 INFO - PID 1644 | Values: 87.0 69.0 85.0 68.0 71.0 76.0 104.0 100.0 95.0 89.0 02:57:42 INFO - PID 1644 | 02:57:42 INFO - PID 1644 | [#5] imaging-gaussian-blur Cycles:10 Average:95.70 Median:93.50 stddev:12.04 (12.9%) stddev-sans-first:12.76 02:57:42 INFO - PID 1644 | Values: 95.0 94.0 93.0 90.0 93.0 94.0 94.0 86.0 129.0 89.0 02:57:42 INFO - PID 1644 | 02:57:42 INFO - PID 1644 | [#6] imaging-darkroom Cycles:10 Average:96.50 Median:97.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 02:57:42 INFO - PID 1644 | Values: 97.0 96.0 97.0 96.0 96.0 96.0 97.0 97.0 97.0 96.0 02:57:42 INFO - PID 1644 | 02:57:42 INFO - PID 1644 | [#7] imaging-desaturate Cycles:10 Average:86.00 Median:86.00 stddev:1.05 (1.2%) stddev-sans-first:1.12 02:57:42 INFO - PID 1644 | Values: 86.0 85.0 86.0 87.0 86.0 85.0 85.0 85.0 87.0 88.0 02:57:42 INFO - PID 1644 | 02:57:42 INFO - PID 1644 | [#8] json-parse-financial Cycles:10 Average:48.10 Median:48.00 stddev:0.88 (1.8%) stddev-sans-first:0.93 02:57:42 INFO - PID 1644 | Values: 48.0 47.0 48.0 48.0 47.0 48.0 50.0 48.0 48.0 49.0 02:57:42 INFO - PID 1644 | 02:57:42 INFO - PID 1644 | [#9] json-stringify-tinderbox Cycles:10 Average:60.30 Median:59.50 stddev:5.85 (9.8%) stddev-sans-first:5.88 02:57:42 INFO - PID 1644 | Values: 55.0 59.0 60.0 61.0 54.0 57.0 74.0 66.0 58.0 59.0 02:57:42 INFO - PID 1644 | 02:57:42 INFO - PID 1644 | [#10] stanford-crypto-aes Cycles:10 Average:83.00 Median:82.00 stddev:4.94 (6.0%) stddev-sans-first:5.23 02:57:42 INFO - PID 1644 | Values: 82.0 82.0 82.0 97.0 81.0 82.0 81.0 81.0 81.0 81.0 02:57:42 INFO - PID 1644 | 02:57:42 INFO - PID 1644 | [#11] stanford-crypto-ccm Cycles:10 Average:144.20 Median:145.00 stddev:12.80 (8.8%) stddev-sans-first:13.57 02:57:42 INFO - PID 1644 | Values: 145.0 128.0 141.0 147.0 145.0 138.0 137.0 136.0 176.0 149.0 02:57:42 INFO - PID 1644 | 02:57:42 INFO - PID 1644 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:186.50 Median:184.50 stddev:9.87 (5.3%) stddev-sans-first:10.43 02:57:42 INFO - PID 1644 | Values: 184.0 185.0 199.0 207.0 183.0 183.0 187.0 184.0 182.0 171.0 02:57:42 INFO - PID 1644 | 02:57:42 INFO - PID 1644 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.80 Median:68.00 stddev:0.63 (0.9%) stddev-sans-first:0.67 02:57:42 INFO - PID 1644 | Values: 68.0 68.0 67.0 68.0 67.0 67.0 68.0 69.0 68.0 68.0 02:57:42 INFO - PID 1644 | -------- Summary: end -------- 02:57:42 INFO - PID 1644 | 02:57:43 INFO - TEST-INFO | 1644: exit 0 02:57:43 INFO - TEST-OK | kraken | took 60105ms 02:57:43 INFO - SUITE-END | took 1178s 02:57:43 INFO - Completed test suite (00:19:38) 02:57:43 INFO - javascript benchmark 02:57:43 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7665.5157486088765, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2758.793090518211, 3484.1812174139072, 3497.178163131662, 3543.309054734327, 3589.1206654369685, 6142.570020098595, 6153.200083989075, 6166.209134015473, 6196.0396138598535, 6247.250329960411, 6115.41903519166, 6168.599041062328, 6170.290416602092, 6217.720202590883, 6217.906731399033, 4902.779374928128, 5338.599605029619, 5382.3541175059045, 5424.349078110613, 5439.891202175954, 4841.7821198045995, 5454.318210223722, 5455.318085239345, 5489.835304940858, 5522.502974732273, 4039.9192016159805, 5328.6802791832615, 5365.517103460668, 5368.812091576815, 5378.542823859977, 5356.571474282087, 5373.24774531562, 5377.4084850666395, 5386.703731294777, 5391.406945236021, 5307.044731948247, 5351.759170837283, 5364.6512976656395, 5365.543928766059, 5376.354837419534, 5046.26829109777, 6114.768109639994, 6127.505970454377, 6143.849652621591, 6144.907826382608, 6389.105525226472, 6410.551261411703, 6414.134091897581, 6425.389587989181, 6477.967610161966, 6692.598444093322, 6693.297203793629, 6701.43037841784, 6711.798646040626, 6718.764843230463, 6276.717547710318, 6279.623222606659, 6292.370762923717, 6293.150424692608, 6293.181886354791, 6560.212774466999, 6837.179538455417, 6856.862862742717, 6865.794026179123, 6872.62200578963, 6321.589096708699, 6429.38920802523, 6474.546781725328, 6509.739610415625, 6511.251206111263, 6703.832404189934, 6706.865862682816, 6710.597364158166, 6713.832154196184, 6724.697388617524, 5713.171590119493, 6583.0, 6584.5061620378665, 6603.867922641521, 6609.6364699941005, 6365.554411191265, 6446.677666116675, 6534.9673251634385, 6572.671366431659, 6615.140031795836, 5160.92258616121, 5197.792088316501, 5242.97378513105, 5244.475552444725, 5254.816081437131, 4671.649626278043, 4675.13510000651, 4680.742559159211, 4685.297205419146, 4704.1767690654, 6108.22065524731, 6129.570930034944, 6153.384661533811, 6253.624782513064, 6280.026595877646, 6698.5980841149685, 6719.731210751515, 6728.798136055926, 6733.427658648971, 6738.124043874363, 4720.95279047212, 5427.9185812212845, 6296.464800491918, 6305.527085468517, 6330.936690633127, 5705.942940570624, 5827.184194212814, 5842.503387212135, 5857.121431785266, 5860.267466566679, 6726.630035348005, 6744.224414192235, 6811.89782153278, 6869.347411995853, 6885.414739747178, 6476.255230648536, 6579.638119903451, 6590.80227593173, 6693.364130407604, 6734.225564060196, 6647.60114393138, 6730.394264516217, 6759.357861003196, 6770.119884414995, 6777.457803375817, 6414.711337989708, 6435.903461447989, 6438.259600146043, 6439.517036222208, 6440.903386449113, 6642.667866606747, 6726.461883049442, 6740.88775352081, 6743.089682892747, 6746.494012949147, 6324.177856878577, 6485.708143133476, 6514.2834288229205, 6515.990021546668, 6525.673716314261, 6274.749010039547, 6325.55721099537, 6392.520560957853, 6481.189851268591, 6495.285518593046, 6260.28006779208, 6332.651704156316, 6339.2392912850755, 6346.682665866781, 6359.90460143089, 6462.676866156768, 6483.708233129426, 6487.091807147098, 6487.740490380332, 6488.448481879093, 6205.875882482415, 6281.214848143982, 6287.088372186099, 6311.147995020613, 6319.0], "value": 6011.173880577061, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [755.8223817402909, 775.2480094308521, 789.4513313247294, 1092.388262572959, 1108.3793075877504, 21663.35009949702, 25303.49393012141, 25994.0, 31540.8960686376, 34596.82701586495, 35878.820605897, 36222.63777362232, 36399.454008189896, 36413.81793091037, 36423.0, 7484.363829074535, 7611.124720657126, 8251.876221856677, 8255.711050113247, 8278.627461764205, 5643.238162848024, 5965.38934487688, 8099.635516401747, 8137.30832879206, 8259.587020648974, 8247.711330103448, 8301.958490207571, 8310.792230194233, 8315.667373305061, 8348.248657620812, 8218.876716849252, 8282.254597086292, 8283.213094755989, 8283.668653253862, 8293.377996650215, 5828.679422631753, 5916.822495325104, 7958.243966823143, 8161.469504482249, 8239.423240373177, 36146.91559253226, 36510.63489365125, 36535.81732091349, 36547.451788223196, 36559.4516082259, 3077.322988942429, 3537.2748586539697, 3537.6462353764673, 3537.911552211215, 3545.7163426925786, 29356.0, 29357.412851742847, 29444.8527757362, 29469.70530294691, 29481.70518294811, 3068.125584208512, 3147.401993621205, 3169.9841500792577, 3186.410514054884, 3189.984050079735, 208286.0, 283158.5842070797, 283171.0, 284217.0, 285554.0, 47271.76364118226, 47498.762506187246, 47583.10045043519, 47593.762031189624, 47739.5226047742, 49686.75156624194, 49775.25337119946, 49783.00433991372, 49856.750716246184, 49911.75044124756, 13930.930345348208, 14013.719725605635, 14021.158730476203, 14027.78958315626, 14030.579082627537, 11398.658040258806, 11417.31496110236, 11419.543218271176, 11430.885691143149, 11448.771024579462, 9884.703458896245, 9901.0, 9914.058164474363, 11601.941990290163, 11725.589604363806, 5116.488351164928, 5141.331626888481, 5155.61332900034, 5180.119379705459, 5187.0, 953.4994128082812, 1002.4636819301638, 1037.2894567221476, 1049.234059136819, 1054.7468607534136, 23053.76946230516, 24355.025798968196, 27845.303867403072, 27998.079407586454, 30336.393272134876, 1090.5583238788304, 1090.6019302954364, 1116.2242241641982, 1133.387970495923, 1144.6165534545853, 10479.790404191874, 11821.940890295493, 12389.690257743629, 12448.688782780502, 12534.310612916195, 318311.0, 320046.0, 320135.0, 320703.0, 321589.0, 42013.0, 50640.0, 50652.74673626608, 50687.746561266955, 50699.746501267255, 1474.292339676961, 1485.0569888121122, 1524.3597688970438, 1526.5649289952532, 1536.4468791235147, 23276.95253713553, 23294.3011709649, 23325.0, 23407.765922340557, 23497.17759878396, 36300.09249768777, 36342.63657363498, 36402.27195456129, 36461.0, 36544.63455365413, 279237.0, 280174.0, 280283.0, 280395.0, 280825.0, 30708.00795896519, 31304.686953131088, 31329.8433507831, 31428.0, 31468.2132946669, 30627.387452251274, 31285.843570782, 34631.0, 34670.133246668025, 41778.0, 27461.03886363968, 28513.28716782097, 29184.0, 29320.413591728473, 29600.703992959796, 28327.575086374134, 29159.979400720873, 29267.0, 29330.853345733136, 29465.0, 33221.66778332186, 34158.82920585381, 34306.48540271848, 34321.0, 34399.484007739404, 28760.56859147156, 29649.110526684235, 29741.702582973892, 29891.0, 30018.849905750332, 22019.0, 22797.0, 23016.654750178426, 23039.76960230376, 23203.0], "value": 16532.40972573644, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [421.20887912172657, 426.72476252816915, 430.39099673961346, 441.097954682674, 449.9280115181572, 21170.25904093357, 21490.677639835416, 21861.453463663416, 22802.54394912101, 23007.654885176693, 21147.259845905395, 22458.98934547945, 23267.0, 23503.76496235037, 23583.76416235837, 5489.298317521561, 5780.364159942403, 6066.150738896558, 6279.120923070774, 6295.244570651528, 6076.6961651917445, 6225.688715564226, 6257.843553911143, 6262.436380725734, 6270.31026587075, 5866.413358664142, 5949.9107513387335, 6027.879442411149, 6213.782517611885, 6230.5638605297645, 6058.061000544901, 6075.210222671025, 6086.5435092368025, 6089.299730530957, 6093.78671746489, 5343.65266257692, 5352.4915133062295, 5404.756785944642, 5433.945660543423, 5438.483344082306, 27281.9528387249, 27420.725792742218, 27469.86265068662, 27685.446291074266, 27766.0, 2214.402111429921, 2272.4318920269766, 2274.4996100857784, 2277.555876604063, 2278.475950531387, 17870.91064544669, 17983.820161798478, 18039.819601803945, 18107.91352518853, 18283.542911427186, 2085.8331333493434, 2110.6095372356162, 2158.3093410108772, 2158.6222411078156, 2174.5759576882515, 136082.0, 136493.0, 137216.31391842978, 137490.31254843762, 139083.0, 11021.614243501439, 11740.823887641693, 12010.879891201152, 12413.93793031029, 12523.185992910432, 16280.837191628169, 16283.511494655178, 16294.940828846087, 16303.266353014144, 16331.83668163327, 8603.741887743377, 8789.033206347296, 8796.34027447944, 8902.065283145177, 8925.821483570293, 8355.289800366916, 8383.664653413794, 8527.360447966428, 8540.103289154673, 8563.486190828571, 6355.332690067568, 7756.4182686298755, 7815.25755053269, 7817.843643127106, 7826.1391246962785, 2081.9479513012298, 2087.2277257414853, 2090.1221486975505, 2097.674860396641, 2119.319061387346, 888.5512816027865, 970.2141265574766, 984.3552473130111, 1004.598160735697, 1031.138998935897, 19044.238230470903, 19136.9474678894, 22109.557808844056, 22122.44693882666, 22153.778462215494, 1010.7416266747946, 1014.4167103915278, 1043.8068957242783, 1049.8582691336571, 1050.6322787024633, 9568.890110714046, 10968.287061340987, 11030.566026416514, 11377.37424441647, 11386.08911287095, 544370.0, 552906.0, 557025.0, 558211.0, 560857.0, 129792.0, 135792.32103839616, 135801.0, 137489.0, 140970.0, 1171.8066519024435, 1185.6383802940013, 1213.7087099096154, 1215.1251099208555, 1217.0507004536476, 18549.814501855348, 18668.719969200745, 18729.81270187281, 18881.035102432295, 18965.905170474613, 48939.14851920805, 55472.890542189736, 56421.153682695614, 58898.411015889295, 60065.69967150136, 79791.80312295437, 82555.0, 83479.58260208661, 83931.58034210034, 84678.0, 8745.0, 8824.249938755022, 9015.323850711091, 9037.09629037091, 9165.450072995642, 13822.861771382157, 13886.09740366873, 14199.432022718975, 14287.285635717964, 14309.856901430852, 11753.059755219732, 12044.397780111134, 12089.159047623625, 12175.086868484723, 12250.75498490043, 11419.31484110956, 11419.657410277372, 11545.48045337945, 11560.710982225512, 11796.29222246668, 11963.940180299043, 12249.0, 12260.386980650754, 12319.507219711111, 12387.69030774238, 8351.916480835114, 8589.828203436022, 8945.329100317373, 9052.0, 9197.77005574866, 11710.648680539598, 11894.286342819458, 12078.51685932588, 12088.818667720167, 12123.81814272769], "value": 10459.145338855995, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1090.7927493776187, 1244.415124891301, 1303.9869601303988, 1448.9420423183074, 1453.3968403112701, 9775.36460130092, 11150.107991360692, 11827.586034488795, 11851.822222666668, 11854.288742675435, 11375.0, 11485.25345852519, 11509.712257193554, 11511.30932144072, 11524.884751152485, 2487.6517287579754, 2533.6959564852245, 2538.6318983747346, 2541.136013755323, 2541.809364297685, 2047.651899177136, 2124.4476436126643, 2201.152556265833, 2555.9872200639065, 2562.3466016165853, 2512.7110382306087, 2539.1113110411393, 2540.4157043879827, 2544.6564713763682, 2547.1085120207963, 2482.8262021658493, 2519.2694118705554, 2519.307190522621, 2522.445062086338, 2522.5207210630015, 2426.7451917548756, 2440.804735621146, 2447.9388015299583, 2451.63225516174, 2459.532688789124, 11620.6513804586, 11735.23720958135, 11749.177557570973, 11763.705907352385, 11765.0, 6508.869822603521, 6740.022696708998, 6758.087658166134, 6763.357481039294, 6769.52613317068, 3386.8306584670668, 4076.6331030207425, 4178.477690288714, 4233.576642335773, 4276.166147601219, 1252.5678640868887, 1276.7510335484585, 1279.5777393460228, 1295.598364507006, 1321.590307004822, 16391.754123688155, 16507.91746041262, 16514.33942642293, 16544.83455165457, 16579.253933572898, 5593.272874526286, 5858.970705146447, 6100.664463454464, 6113.816585502442, 6119.051547010132, 13749.793753093712, 13857.03000789935, 13863.098898571763, 13885.514007009706, 13928.442862285396, 6811.9659401702675, 6819.931800681929, 6823.658817059227, 6824.488163387766, 6827.897581536281, 5872.619934315432, 6235.189425374672, 6249.0938813871735, 6252.249730032425, 6258.593191442633, 3573.3032058748295, 3808.5810560838286, 4240.893977650583, 4263.211305908417, 4666.066786642685, 1452.2158034661377, 1456.5484699743113, 1464.8974571780086, 1484.6288427893028, 1486.4351546412295, 821.9068638710501, 827.420805436197, 836.180543067797, 845.6152450634946, 877.236803980541, 5469.890602187934, 6344.206974128234, 6434.581752186186, 6533.967330163414, 6883.036374907517, 927.5640448988964, 942.0814705661925, 954.8997355277735, 979.3242662562809, 981.7545613596601, 3800.25894950482, 3807.238552289498, 3873.2059927715313, 3914.0, 3926.5680775114715, 14333.56999290002, 14982.775258371134, 14992.70014599702, 15035.473758418622, 15070.397184112513, 14856.18290993984, 14886.181260030802, 14895.255237238094, 14907.329170187368, 14951.775723364159, 6624.867502650017, 8109.229623185788, 8434.957825210835, 8635.654573816977, 8647.654093836301, 6524.445422139172, 6595.175603049619, 6685.832854178684, 6710.932890671226, 6747.055412242192, 11228.4385780709, 13138.868611313765, 13319.800202996768, 13343.132696374705, 13646.181229126285, 13591.388387522784, 13860.792088118485, 13968.4412623498, 13983.44066237339, 14107.929460352632, 8515.233628973423, 9156.267498599962, 9284.535773211177, 9308.022657621123, 9325.720228393158, 9661.082197191256, 9935.801283974424, 9939.701808945743, 10569.101626361846, 10671.679849604525, 8085.272325490734, 8975.237104846161, 9034.367594268339, 9037.593308301011, 9242.491662958602, 8825.02924678272, 8838.0, 8904.0, 8923.0, 8923.73228803137, 8227.54748488815, 8261.586920654063, 8264.586770661563, 8269.0, 8276.958615206886, 5048.697078175322, 5955.196048533393, 6054.39456054391, 6056.242969628796, 6142.04798256271, 5989.161517387656, 6071.908921366271, 6107.633541987495, 6208.410201030895, 6362.586431881913], "value": 5377.376009455539, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [375.63563343556757, 389.0, 397.9661728753056, 398.39841838823384, 399.1697269679068, 5951.31559870615, 6006.519478441725, 6007.609505382153, 6044.455998960093, 6049.0, 5208.838332092187, 5579.944200557993, 5620.819627878151, 5876.002159265849, 5904.409559044094, 4256.97871510642, 4306.676999225055, 4308.246056940038, 4310.547392523787, 4359.427937516864, 4316.82732690692, 4324.351347297915, 4331.870043898688, 4338.980339620187, 4341.50072741634, 3954.703397245218, 4079.9592004079877, 4296.613304802567, 4304.397384366192, 4320.092780516095, 4274.807633656477, 4275.978620106911, 4284.807818175448, 4288.228118938589, 4292.978535107336, 3101.565780790691, 3692.5015122958325, 3711.6659500645073, 3722.8138593070507, 3733.178700685845, 5545.585875601736, 5628.789810190829, 5740.7703691852275, 5791.160281759121, 5810.883782324372, 4405.493368262659, 5059.494050594911, 5070.6957582545165, 5075.365579302587, 5108.591312694975, 4776.4268287805335, 5582.888342233173, 5660.971695141498, 5693.743781529841, 6354.140636713498, 3232.9816107926163, 3973.145773658677, 3988.6809055275507, 4198.685098617585, 4212.283911735012, 5900.734466948955, 5962.165296858487, 6505.21937367519, 6531.281559028503, 6617.503687223478, 4112.485939257593, 4113.025747641149, 4146.647534959502, 4147.921540399518, 4267.25323068468, 3704.333220020368, 3975.582563830814, 4299.484061912591, 4311.417958575615, 4333.544977777351, 4701.260533602589, 4705.71772690245, 5049.293098966147, 5073.822416215415, 5091.847244582668, 5465.945340546544, 6198.690065496708, 6241.065269766387, 6267.0, 6281.560290779693, 1246.6260121963555, 1446.616646588655, 1474.388128926505, 1475.7196132734746, 1480.195030417353, 815.9918400816034, 955.7276176289724, 960.6013504395597, 968.5109019945023, 977.8533220017055, 5232.686038837682, 5886.23478947734, 5897.557683173779, 5907.616004959664, 6011.458968692838, 5428.348598168245, 5495.945040549544, 5526.585506086979, 6413.662239089165, 6435.839104022343, 4624.95375046252, 5602.523785478198, 5639.971800141055, 5722.427757224327, 5955.40445955401, 3339.766216364831, 3389.7627166098137, 3391.406503861814, 3391.6269210386836, 3404.268082362304, 6537.705803238866, 6607.423104989058, 6645.169353830771, 6682.398584127452, 6728.226253980855, 6639.568428052257, 6639.933600663931, 6642.767503137464, 6650.96674516634, 6672.83317917056, 5986.820395388146, 6079.3008803988105, 6083.0542063726125, 6652.234992975869, 6677.833054173684, 5568.023351710084, 6425.389587989133, 6454.128692626436, 6482.70827812759, 6492.772752953624, 6220.813375598739, 6328.968355158379, 6368.617882927039, 6369.522285828674, 6390.93609063922, 6232.844178895564, 6606.8348291293105, 6627.3372662733345, 6655.667216639052, 6657.933420665731, 4260.488741351058, 4273.63674087706, 4277.165952639206, 4279.3096726793365, 4303.526612072732, 5819.592628515963, 5890.410958904075, 5897.351291358033, 6089.934261504308, 6413.807585772434, 5241.318628578413, 6218.782342617987, 6229.2524897012645, 6252.406021428139, 6278.968605156945, 4605.723656580616, 6215.34738852414, 6222.875542489034, 6242.750289988349, 6250.0, 6150.7847225346895, 6156.26124865019, 6160.014397696347, 6193.907091393543, 6235.906461402992, 5006.624503162205, 6228.781992630236, 6311.27420346666, 6324.494040476843, 6354.745810167541, 4596.494385617512, 5645.322561292671, 5715.199872018004, 6046.425589568984, 6155.907661384993], "value": 4519.116153095685, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [816.7958010497375, 837.284122075625, 840.6385254340631, 846.3187134356842, 861.7199410191689, 14912.850871491282, 14956.700865982673, 15117.92441037796, 15122.319495622696, 16618.0, 14974.475893343735, 15739.291731872065, 15898.284577194025, 15905.0, 15915.84084159158, 932.8041111366604, 948.4735971535795, 955.3742298794266, 955.7610597350663, 958.4488918871676, 978.9916386122305, 990.1484723138095, 990.732502224399, 992.0773680477154, 992.5235886774352, 906.931980101495, 1037.486444210117, 1048.6015314180638, 1049.0506091986765, 1049.3861091261622, 1035.30634474902, 1041.7760181561, 1043.4626167523732, 1045.095991966943, 1047.649037572414, 741.8924255982852, 742.1131747561666, 744.6946751831723, 752.1801236652021, 775.0970119810414, 16562.917185414117, 16830.747538786927, 17016.31934722622, 17418.390356337466, 17658.205380757896, 16630.75053874193, 16859.157042147843, 17050.74423883643, 17122.914385427994, 17237.224324905284, 13749.931250343685, 13944.721105577933, 14047.929760351133, 14122.01145919786, 14187.077839940372, 3089.552014957841, 3518.700910422617, 3521.0317162780184, 3525.2773181497732, 3526.647335266453, 91748.0, 129339.35330323382, 130423.34788326093, 130818.03772943413, 132703.0, 8765.561721913879, 10921.563137474572, 11131.276467029616, 11422.25755325901, 11424.942875285571, 17842.10789460522, 21715.0, 22293.777062229496, 22535.661965070536, 22650.320490385315, 6790.796276111724, 7740.993670822757, 7928.326092282221, 7931.405144614177, 7935.603219838985, 3500.7024402926036, 3559.875404360887, 3562.6437356264164, 3563.3051554946537, 3563.322968635951, 4241.87274381769, 4319.157764236007, 4354.847580334673, 4355.891102722458, 4363.040131171138, 927.4157280912982, 957.5684351893824, 963.720521048902, 966.6713317471953, 967.1923943507251, 637.3212528656966, 645.2321737132891, 648.8377905523619, 652.9771457998947, 680.3808534233922, 10527.368357898551, 11239.494222759995, 11357.602483913024, 11506.482208300478, 11515.71210719722, 542.5117394345122, 554.3237250554317, 637.1239545624766, 640.2477089419913, 666.027599704437, 4953.331300274417, 5029.245613158056, 5036.370453693288, 5092.6435149539175, 5233.685978841282, 90072.0, 103243.0, 103287.0, 103495.4825225869, 106008.15885775998, 90533.54733226291, 91945.54027229821, 92123.0, 94220.28898555045, 94780.57829132568, 51725.965480690174, 70070.64964675144, 70389.64805175942, 71436.64281678663, 71961.0, 10214.948925255327, 10377.688669339932, 10439.060484556123, 10454.529546170592, 10648.094911932572, 33886.0, 41705.58294417139, 41791.79104104581, 42307.1538569233, 43480.91297717582, 54710.0, 55207.0, 55324.44675553354, 55934.72032639811, 56626.43373566212, 14067.718645626825, 14339.498117565836, 14343.641408964442, 14351.138931664134, 14351.354189061309, 15363.38546458174, 15423.460178893685, 16265.0, 16423.42518011864, 17519.82480175182, 14198.3610737515, 16507.9174604131, 17305.39431119905, 17425.82574174242, 17705.91147044308, 16600.169991500617, 16983.0, 16985.065821379943, 17004.744928826323, 17248.396306129227, 13372.0, 14524.0, 16303.592410189365, 17072.317107315568, 17217.74173387375, 11780.882191177978, 11819.704507387383, 12183.90344868966, 12213.816792747939, 12235.95994340491, 12500.312482813533, 13250.271235082164, 13264.403101860633, 13548.187108773505, 13843.4462621494], "value": 8031.5928990499915, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1447.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 161.0, 147.0, 112.0, 110.0, 110.0, 111.0, 110.0, 111.0, 111.0], "value": 120.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 109.0, 108.0, 149.0, 112.0, 107.0, 117.0, 137.0, 140.0, 129.0], "value": 125.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 184.0, 185.0, 187.0, 186.0, 185.0, 183.0, 186.0, 181.0, 185.0], "value": 184.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 57.0, 60.0, 85.0, 59.0, 57.0, 60.0, 57.0, 86.0, 56.0], "value": 65.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 69.0, 85.0, 68.0, 71.0, 76.0, 104.0, 100.0, 95.0, 89.0], "value": 84.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 93.0, 90.0, 93.0, 94.0, 94.0, 86.0, 129.0, 89.0], "value": 95.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 96.0, 97.0, 96.0, 96.0, 96.0, 97.0, 97.0, 97.0, 96.0], "value": 96.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 86.0, 87.0, 86.0, 85.0, 85.0, 85.0, 87.0, 88.0], "value": 86.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [48.0, 47.0, 48.0, 48.0, 47.0, 48.0, 50.0, 48.0, 48.0, 49.0], "value": 48.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 59.0, 60.0, 61.0, 54.0, 57.0, 74.0, 66.0, 58.0, 59.0], "value": 60.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 82.0, 97.0, 81.0, 82.0, 81.0, 81.0, 81.0, 81.0], "value": 83.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 128.0, 141.0, 147.0, 145.0, 138.0, 137.0, 136.0, 176.0, 149.0], "value": 144.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 185.0, 199.0, 207.0, 183.0, 183.0, 187.0, 184.0, 182.0, 171.0], "value": 186.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 67.0, 68.0, 67.0, 67.0, 68.0, 69.0, 68.0, 68.0], "value": 67.8, "unit": "score"}]}]} 02:57:43 INFO - Return code: 0 02:57:43 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:57:43 INFO - # TBPL SUCCESS # 02:57:43 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:57:43 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:57:43 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:57:43 INFO - 2017-05-04 02:57:43.504 screenresolution[1649:9820] starting screenresolution argv=screenresolution get 02:57:43 INFO - 2017-05-04 02:57:43.507 screenresolution[1649:9820] Display 0: 1600x1200x32@60 02:57:43 INFO - 2017-05-04 02:57:43.514 screenresolution[1650:9823] starting screenresolution argv=screenresolution list 02:57:43 INFO - Available Modes on Display 0 02:57:43 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:57:43 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:57:43 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:57:43 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:57:43 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:57:43 INFO - Intel Iris: 02:57:43 INFO - Chipset Model: Intel Iris 02:57:43 INFO - Type: GPU 02:57:43 INFO - Bus: Built-In 02:57:43 INFO - VRAM (Dynamic, Max): 1536 MB 02:57:43 INFO - Vendor: Intel (0x8086) 02:57:43 INFO - Device ID: 0x0a2e 02:57:43 INFO - Revision ID: 0x0009 02:57:43 INFO - Displays: 02:57:43 INFO - Display: 02:57:43 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:57:43 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:57:43 INFO - Display Serial Number: 1600x1200 60 02:57:43 INFO - Main Display: Yes 02:57:43 INFO - Mirror: Off 02:57:43 INFO - Online: Yes 02:57:43 INFO - Rotation: Supported 02:57:43 INFO - Return code: 0 02:57:43 INFO - Running post-action listener: _resource_record_post_action 02:57:43 INFO - [mozharness: 2017-05-04 09:57:43.766216Z] Finished run-tests step (success) 02:57:43 INFO - Running post-run listener: _resource_record_post_run 02:57:43 INFO - Total resource usage - Wall time: 1199s; CPU: 54.0%; Read bytes: 39609856; Write bytes: 505849856; Read time: 553; Write time: 1617 02:57:43 INFO - TinderboxPrint: CPU usage
53.7% 02:57:43 INFO - TinderboxPrint: I/O read bytes / time
39,609,856 / 553 02:57:43 INFO - TinderboxPrint: I/O write bytes / time
505,849,856 / 1,617 02:57:43 INFO - TinderboxPrint: CPU idle
2,223.2 (46.3%) 02:57:43 INFO - TinderboxPrint: CPU system
965.1 (20.1%) 02:57:43 INFO - TinderboxPrint: CPU user
1,609.3 (33.5%) 02:57:43 INFO - TinderboxPrint: Swap in / out
246,964,224 / 0 02:57:43 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 205765120; Write bytes: 206403584; Read time: 16158; Write time: 434 02:57:43 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 37471744; Write bytes: 292372480; Read time: 522; Write time: 1150 02:57:44 INFO - Running post-run listener: _upload_blobber_files 02:57:44 INFO - Blob upload gear active. 02:57:44 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:57:44 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 02:57:44 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'] 02:57:44 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 02:57:44 INFO - (blobuploader) - INFO - Open directory for files ... 02:57:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 02:57:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:57:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:57:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 02:57:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:57:45 INFO - (blobuploader) - INFO - Done attempting. 02:57:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 02:57:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:57:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:57:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 02:57:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:57:46 INFO - (blobuploader) - INFO - Done attempting. 02:57:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 02:57:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:57:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:57:47 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 02:57:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:57:47 INFO - (blobuploader) - INFO - Done attempting. 02:57:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:57:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:57:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:57:49 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:57:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:57:49 INFO - (blobuploader) - INFO - Done attempting. 02:57:49 INFO - (blobuploader) - INFO - Iteration through files over. 02:57:49 INFO - Return code: 0 02:57:49 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:57:49 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:57:49 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1c90e927f5e5b86d5b1384a586d1436ec31741ad2b50c61513257a050a6c60e25fe43a0b9dd10003a0ecfcbbdec41d8dc51ae5d478478ed6501a2d6c7fc91f52", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d98e5dbec1db61d1bc1abdb542e7232f4867c6ee57e981716b3c3bf99fe991f6a2c013649ef0a4b9cef9a0a2e6fa8828e9dde9395b87893599cd875c2232fe00", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/221b3c047004ddb869ea201e615f7b47249c7a10c6c03c35e255b74e11c0346274fb3548334c244c1dd3a6f0b1fbb3800c0d3a76279fa6dce1b179f3edc9bfbe", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/70e83f665d03b122d3705ad7cb9cee7a3bd954f13737d725d10d33db4186cdbc26b84950443a91946925e8a8e101f60f2dc9396d41effa570b806e5ebd3c95de"} 02:57:49 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:57:49 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:57:49 INFO - Contents: 02:57:49 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1c90e927f5e5b86d5b1384a586d1436ec31741ad2b50c61513257a050a6c60e25fe43a0b9dd10003a0ecfcbbdec41d8dc51ae5d478478ed6501a2d6c7fc91f52", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d98e5dbec1db61d1bc1abdb542e7232f4867c6ee57e981716b3c3bf99fe991f6a2c013649ef0a4b9cef9a0a2e6fa8828e9dde9395b87893599cd875c2232fe00", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/221b3c047004ddb869ea201e615f7b47249c7a10c6c03c35e255b74e11c0346274fb3548334c244c1dd3a6f0b1fbb3800c0d3a76279fa6dce1b179f3edc9bfbe", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/70e83f665d03b122d3705ad7cb9cee7a3bd954f13737d725d10d33db4186cdbc26b84950443a91946925e8a8e101f60f2dc9396d41effa570b806e5ebd3c95de"} 02:57:49 INFO - Running post-run listener: copy_logs_to_upload_dir 02:57:49 INFO - Copying logs to upload dir... 02:57:49 INFO - mkdir: /builds/slave/test/build/upload/logs 02:57:49 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.587655 ========= master_lag: 1.19 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2017-05-04 02:57:50.961416) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-04 02:57:50.962620) ========= 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.Rg2KtSitaG/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.MuFujmHIqF/Listeners TMPDIR=/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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/1c90e927f5e5b86d5b1384a586d1436ec31741ad2b50c61513257a050a6c60e25fe43a0b9dd10003a0ecfcbbdec41d8dc51ae5d478478ed6501a2d6c7fc91f52", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d98e5dbec1db61d1bc1abdb542e7232f4867c6ee57e981716b3c3bf99fe991f6a2c013649ef0a4b9cef9a0a2e6fa8828e9dde9395b87893599cd875c2232fe00", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/221b3c047004ddb869ea201e615f7b47249c7a10c6c03c35e255b74e11c0346274fb3548334c244c1dd3a6f0b1fbb3800c0d3a76279fa6dce1b179f3edc9bfbe", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/70e83f665d03b122d3705ad7cb9cee7a3bd954f13737d725d10d33db4186cdbc26b84950443a91946925e8a8e101f60f2dc9396d41effa570b806e5ebd3c95de"} build_url:https://queue.taskcluster.net/v1/task/XNyrCkSHRFW3Q7EjWPSctw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011109 build_url: 'https://queue.taskcluster.net/v1/task/XNyrCkSHRFW3Q7EjWPSctw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1c90e927f5e5b86d5b1384a586d1436ec31741ad2b50c61513257a050a6c60e25fe43a0b9dd10003a0ecfcbbdec41d8dc51ae5d478478ed6501a2d6c7fc91f52", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d98e5dbec1db61d1bc1abdb542e7232f4867c6ee57e981716b3c3bf99fe991f6a2c013649ef0a4b9cef9a0a2e6fa8828e9dde9395b87893599cd875c2232fe00", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/221b3c047004ddb869ea201e615f7b47249c7a10c6c03c35e255b74e11c0346274fb3548334c244c1dd3a6f0b1fbb3800c0d3a76279fa6dce1b179f3edc9bfbe", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/70e83f665d03b122d3705ad7cb9cee7a3bd954f13737d725d10d33db4186cdbc26b84950443a91946925e8a8e101f60f2dc9396d41effa570b806e5ebd3c95de"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-04 02:57:50.998782) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-04 02:57:51.001196) ========= 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.Rg2KtSitaG/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.MuFujmHIqF/Listeners TMPDIR=/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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.005158 ========= master_lag: 0.66 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-04 02:57:51.669473) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-04 02:57:51.669791) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-04 02:57:51.674062) ========= ========= Total master_lag: 1.95 =========