builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0133 starttime: 1486332895.33 results: success (0) buildid: 20170205125708 builduid: 3ec68f53e8174fe4a95e59c32c47f674 revision: 6c998914bad21a65c7fe2ee7e8c1c7f5269c750d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-05 14:14:55.332049) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-05 14:14:55.332486) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-05 14:14:55.332759) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-05 14:14:55.356480) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-05 14:14:55.356756) ========= 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.9tx6zCukH5/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.EUA7otxdoc/Listeners TMPDIR=/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-02-05 14:14:55-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 142M=0s 2017-02-05 14:14:55 (142 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.120791 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-05 14:14:55.500543) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-05 14:14:55.500847) ========= 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.9tx6zCukH5/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.EUA7otxdoc/Listeners TMPDIR=/var/folders/b5/152b2m257t95w2vfwktssysw00000w/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.020675 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-05 14:14:55.551655) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-05 14:14:55.551971) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 6c998914bad21a65c7fe2ee7e8c1c7f5269c750d --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 6c998914bad21a65c7fe2ee7e8c1c7f5269c750d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.9tx6zCukH5/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.EUA7otxdoc/Listeners TMPDIR=/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-02-05 14:14:55,641 truncating revision to first 12 chars 2017-02-05 14:14:55,641 Setting DEBUG logging. 2017-02-05 14:14:55,641 attempt 1/10 2017-02-05 14:14:55,641 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/6c998914bad2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-05 14:14:56,862 unpacking tar archive at: mozilla-central-6c998914bad2/testing/mozharness/ program finished with exit code 0 elapsedTime=1.476424 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-05 14:14:57.057425) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-05 14:14:57.057774) ========= script_repo_revision: 6c998914bad21a65c7fe2ee7e8c1c7f5269c750d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-05 14:14:57.058150) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-05 14:14:57.058429) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-05 14:14:57.077767) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2017-02-05 14:14:57.078032) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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.9tx6zCukH5/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.EUA7otxdoc/Listeners TMPDIR=/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 14:14:57 INFO - MultiFileLogger online at 20170205 14:14:57 in /builds/slave/test 14:14:57 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 14:14:57 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:14:57 INFO - {'append_to_log': False, 14:14:57 INFO - 'base_work_dir': '/builds/slave/test', 14:14:57 INFO - 'blob_upload_branch': 'Firefox', 14:14:57 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:14:57 INFO - 'branch': 'Firefox', 14:14:57 INFO - 'buildbot_json_path': 'buildprops.json', 14:14:57 INFO - 'config_files': ('talos/mac_config.py',), 14:14:57 INFO - 'default_actions': ('clobber', 14:14:57 INFO - 'read-buildbot-config', 14:14:57 INFO - 'download-and-extract', 14:14:57 INFO - 'populate-webroot', 14:14:57 INFO - 'create-virtualenv', 14:14:57 INFO - 'install', 14:14:57 INFO - 'run-tests'), 14:14:57 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:14:57 INFO - 'download_minidump_stackwalk': True, 14:14:57 INFO - 'download_symbols': 'ondemand', 14:14:57 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:14:57 INFO - 'tooltool.py': '/tools/tooltool.py', 14:14:57 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:14:57 INFO - '/tools/misc-python/virtualenv.py')}, 14:14:57 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:14:57 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:14:57 INFO - 'installer_path': 'installer.exe', 14:14:57 INFO - 'log_level': 'info', 14:14:57 INFO - 'log_name': 'talos', 14:14:57 INFO - 'log_to_console': True, 14:14:57 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 14:14:57 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 14:14:57 INFO - 'opt_config_files': (), 14:14:57 INFO - 'pip_index': False, 14:14:57 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:14:57 INFO - 'cmd': ('bash', 14:14:57 INFO - '-c', 14:14:57 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:14:57 INFO - 'enabled': True, 14:14:57 INFO - 'halt_on_failure': False, 14:14:57 INFO - 'name': 'check_screen_resolution'},), 14:14:57 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:14:57 INFO - 'cmd': ('bash', 14:14:57 INFO - '-c', 14:14:57 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:14:57 INFO - 'enabled': True, 14:14:57 INFO - 'halt_on_failure': False, 14:14:57 INFO - 'name': 'check_screen_resolution'},), 14:14:57 INFO - 'run_cmd_checks_enabled': True, 14:14:57 INFO - 'sps_profile': False, 14:14:57 INFO - 'sps_profile_interval': 0, 14:14:57 INFO - 'suite': 'dromaeojs-e10s', 14:14:57 INFO - 'system_bits': '32', 14:14:57 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:14:57 INFO - 'title': 't-yosemite-r7-0133', 14:14:57 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:14:57 INFO - 'use_talos_json': True, 14:14:57 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:14:57 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:14:57 INFO - 'work_dir': 'build'} 14:14:57 INFO - [mozharness: 2017-02-05 22:14:57.289818Z] Running clobber step. 14:14:57 INFO - Running pre-action listener: _resource_record_pre_action 14:14:57 INFO - Running main action method: clobber 14:14:57 INFO - rmtree: /builds/slave/test/build 14:14:57 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:14:59 INFO - Running post-action listener: _resource_record_post_action 14:14:59 INFO - [mozharness: 2017-02-05 22:14:59.210596Z] Finished clobber step (success) 14:14:59 INFO - [mozharness: 2017-02-05 22:14:59.210728Z] Running read-buildbot-config step. 14:14:59 INFO - Running pre-action listener: _resource_record_pre_action 14:14:59 INFO - Running main action method: read_buildbot_config 14:14:59 INFO - Using buildbot properties: 14:14:59 INFO - { 14:14:59 INFO - "project": "", 14:14:59 INFO - "product": "firefox", 14:14:59 INFO - "script_repo_revision": "production", 14:14:59 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 14:14:59 INFO - "repository": "", 14:14:59 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 14:14:59 INFO - "buildid": "20170205125708", 14:14:59 INFO - "pgo_build": "False", 14:14:59 INFO - "basedir": "/builds/slave/test", 14:14:59 INFO - "buildnumber": 49, 14:14:59 INFO - "slavename": "t-yosemite-r7-0133", 14:14:59 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 14:14:59 INFO - "platform": "yosemite_r7", 14:14:59 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 14:14:59 INFO - "branch": "mozilla-central", 14:14:59 INFO - "repo_path": "mozilla-central", 14:14:59 INFO - "revision": "6c998914bad21a65c7fe2ee7e8c1c7f5269c750d", 14:14:59 INFO - "stage_platform": "macosx64", 14:14:59 INFO - "builduid": "3ec68f53e8174fe4a95e59c32c47f674", 14:14:59 INFO - "slavebuilddir": "test" 14:14:59 INFO - } 14:14:59 INFO - Found installer url https://queue.taskcluster.net/v1/task/GmTt9U59QleKKFMwB6xn6Q/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg. 14:14:59 INFO - Running post-action listener: _resource_record_post_action 14:14:59 INFO - [mozharness: 2017-02-05 22:14:59.214170Z] Finished read-buildbot-config step (success) 14:14:59 INFO - [mozharness: 2017-02-05 22:14:59.214290Z] Running download-and-extract step. 14:14:59 INFO - Running pre-action listener: _resource_record_pre_action 14:14:59 INFO - Running main action method: download_and_extract 14:14:59 INFO - mkdir: /builds/slave/test/build/tests 14:14:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:14:59 INFO - trying https://queue.taskcluster.net/v1/task/GmTt9U59QleKKFMwB6xn6Q/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json 14:14:59 INFO - Downloading https://queue.taskcluster.net/v1/task/GmTt9U59QleKKFMwB6xn6Q/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 14:14:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GmTt9U59QleKKFMwB6xn6Q/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json'}, attempt #1 14:15:00 INFO - Downloaded 1199 bytes. 14:15:00 INFO - Reading from file /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 14:15:00 INFO - Using the following test package requirements: 14:15:00 INFO - {u'common': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 14:15:00 INFO - u'cppunittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 14:15:00 INFO - u'firefox-54.0a1.en-US.mac.cppunittest.tests.zip'], 14:15:00 INFO - u'gtest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 14:15:00 INFO - u'firefox-54.0a1.en-US.mac.gtest.tests.zip'], 14:15:00 INFO - u'jittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 14:15:00 INFO - u'jsshell-mac.zip'], 14:15:00 INFO - u'mochitest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 14:15:00 INFO - u'firefox-54.0a1.en-US.mac.mochitest.tests.zip'], 14:15:00 INFO - u'mozbase': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 14:15:00 INFO - u'reftest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 14:15:00 INFO - u'firefox-54.0a1.en-US.mac.reftest.tests.zip'], 14:15:00 INFO - u'talos': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 14:15:00 INFO - u'firefox-54.0a1.en-US.mac.talos.tests.zip'], 14:15:00 INFO - u'web-platform': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 14:15:00 INFO - u'firefox-54.0a1.en-US.mac.web-platform.tests.zip'], 14:15:00 INFO - u'xpcshell': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 14:15:00 INFO - u'firefox-54.0a1.en-US.mac.xpcshell.tests.zip']} 14:15:00 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip'] for test suite category: common 14:15:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GmTt9U59QleKKFMwB6xn6Q/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 14:15:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GmTt9U59QleKKFMwB6xn6Q/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:15:00 INFO - Fetch https://queue.taskcluster.net/v1/task/GmTt9U59QleKKFMwB6xn6Q/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 14:15:01 INFO - Expected file size: 34851075 14:15:01 INFO - Obtained file size: 34851075 14:15:08 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip', u'firefox-54.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 14:15:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GmTt9U59QleKKFMwB6xn6Q/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 14:15:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GmTt9U59QleKKFMwB6xn6Q/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:15:08 INFO - Fetch https://queue.taskcluster.net/v1/task/GmTt9U59QleKKFMwB6xn6Q/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 14:15:09 INFO - Expected file size: 34851075 14:15:09 INFO - Obtained file size: 34851075 14:15:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GmTt9U59QleKKFMwB6xn6Q/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip 14:15:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GmTt9U59QleKKFMwB6xn6Q/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip'}, attempt #1 14:15:16 INFO - Fetch https://queue.taskcluster.net/v1/task/GmTt9U59QleKKFMwB6xn6Q/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip into memory 14:15:17 INFO - Expected file size: 14403454 14:15:17 INFO - Obtained file size: 14403454 14:15:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:15:17 INFO - trying https://queue.taskcluster.net/v1/task/GmTt9U59QleKKFMwB6xn6Q/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 14:15:17 INFO - Downloading https://queue.taskcluster.net/v1/task/GmTt9U59QleKKFMwB6xn6Q/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg 14:15:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GmTt9U59QleKKFMwB6xn6Q/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg'}, attempt #1 14:15:19 INFO - Downloaded 70288893 bytes. 14:15:19 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GmTt9U59QleKKFMwB6xn6Q/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 14:15:19 INFO - mkdir: /builds/slave/test/properties 14:15:19 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:15:19 INFO - Writing to file /builds/slave/test/properties/build_url 14:15:19 INFO - Contents: 14:15:19 INFO - build_url:https://queue.taskcluster.net/v1/task/GmTt9U59QleKKFMwB6xn6Q/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 14:15:20 INFO - Running post-action listener: _resource_record_post_action 14:15:20 INFO - Running post-action listener: set_extra_try_arguments 14:15:20 INFO - [mozharness: 2017-02-05 22:15:20.154440Z] Finished download-and-extract step (success) 14:15:20 INFO - [mozharness: 2017-02-05 22:15:20.154588Z] Running populate-webroot step. 14:15:20 INFO - Running pre-action listener: _resource_record_pre_action 14:15:20 INFO - Running main action method: populate_webroot 14:15:20 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:15:20 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:15:20 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:15:20 INFO - '--tppagecycles', 14:15:20 INFO - '7'], 14:15:20 INFO - 'tests': ['tsvgm']}}, 14:15:20 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:15:20 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:15:20 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:15:20 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:15:20 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:15:20 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:15:20 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 14:15:20 INFO - 'talos_options': ['--disable-e10s'], 14:15:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:15:20 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 14:15:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:15:20 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 14:15:20 INFO - 'talos_options': ['--disable-e10s'], 14:15:20 INFO - 'tests': ['damp', 'tps']}, 14:15:20 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 14:15:20 INFO - 'tests': ['damp', 'tps']}, 14:15:20 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:15:20 INFO - 'tests': ['dromaeo_dom']}, 14:15:20 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:15:20 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:15:20 INFO - 'tests': ['basic_compositor_video']}, 14:15:20 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:15:20 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:15:20 INFO - 'tests': ['a11yr', 14:15:20 INFO - 'ts_paint', 14:15:20 INFO - 'tpaint', 14:15:20 INFO - 'sessionrestore', 14:15:20 INFO - 'sessionrestore_no_auto_restore', 14:15:20 INFO - 'tabpaint']}, 14:15:20 INFO - 'other-e10s': {'tests': ['a11yr', 14:15:20 INFO - 'ts_paint', 14:15:20 INFO - 'tpaint', 14:15:20 INFO - 'sessionrestore', 14:15:20 INFO - 'sessionrestore_no_auto_restore', 14:15:20 INFO - 'tabpaint']}, 14:15:20 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:15:20 INFO - 'ts_paint', 14:15:20 INFO - 'tpaint', 14:15:20 INFO - 'sessionrestore', 14:15:20 INFO - 'sessionrestore_no_auto_restore', 14:15:20 INFO - 'tabpaint']}, 14:15:20 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:15:20 INFO - 'ts_paint', 14:15:20 INFO - 'tpaint', 14:15:20 INFO - 'sessionrestore', 14:15:20 INFO - 'sessionrestore_no_auto_restore', 14:15:20 INFO - 'tabpaint']}, 14:15:20 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:15:20 INFO - 'tests': ['a11yr', 14:15:20 INFO - 'ts_paint', 14:15:20 INFO - 'tpaint', 14:15:20 INFO - 'sessionrestore', 14:15:20 INFO - 'sessionrestore_no_auto_restore', 14:15:20 INFO - 'tabpaint']}, 14:15:20 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:15:20 INFO - 'tests': ['a11yr', 14:15:20 INFO - 'ts_paint', 14:15:20 INFO - 'tpaint', 14:15:20 INFO - 'sessionrestore', 14:15:20 INFO - 'sessionrestore_no_auto_restore', 14:15:20 INFO - 'tabpaint']}, 14:15:20 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:15:20 INFO - 'tests': ['tsvgx', 14:15:20 INFO - 'tsvgr_opacity', 14:15:20 INFO - 'tart', 14:15:20 INFO - 'tscrollx', 14:15:20 INFO - 'cart', 14:15:20 INFO - 'tsvg_static']}, 14:15:20 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:15:20 INFO - 'tsvgr_opacity', 14:15:20 INFO - 'tart', 14:15:20 INFO - 'tscrollx', 14:15:20 INFO - 'cart', 14:15:20 INFO - 'tsvg_static']}, 14:15:20 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 14:15:20 INFO - 'talos_options': ['--disable-e10s'], 14:15:20 INFO - 'tests': ['tp5o']}, 14:15:20 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 14:15:20 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 14:15:20 INFO - 'talos_options': ['--disable-e10s', 14:15:20 INFO - '--xperf_path', 14:15:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:15:20 INFO - 'tests': ['tp5n']}, 14:15:20 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 14:15:20 INFO - 'talos_options': ['--xperf_path', 14:15:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:15:20 INFO - 'tests': ['tp5n']}}, 14:15:20 INFO - 'talos.zip': {'path': '', 14:15:20 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:15:20 INFO - Running post-action listener: _resource_record_post_action 14:15:20 INFO - [mozharness: 2017-02-05 22:15:20.173294Z] Finished populate-webroot step (success) 14:15:20 INFO - [mozharness: 2017-02-05 22:15:20.173429Z] Running create-virtualenv step. 14:15:20 INFO - Running pre-action listener: _resource_record_pre_action 14:15:20 INFO - Running main action method: create_virtualenv 14:15:20 INFO - Creating virtualenv /builds/slave/test/build/venv 14:15:20 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 14:15:20 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:15:20 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:15:20 INFO - Using real prefix '/tools/python27' 14:15:20 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:15:21 INFO - Installing distribute.............................................................................................................................................................................................done. 14:15:23 INFO - Installing pip.................done. 14:15:23 INFO - Return code: 0 14:15:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 14:15:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 14:15:24 INFO - Reading from file tmpfile_stdout 14:15:24 INFO - Output received: 14:15:24 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 14:15:24 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:15:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:15:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:15:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:15:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:15:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:15:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:15:24 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 0x108640b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108643768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8af95781e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10841ed30>, '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 0x108345db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108413be8>, '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.9tx6zCukH5/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.EUA7otxdoc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:15:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 14:15:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 14:15:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9tx6zCukH5/Render', 14:15:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:15:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:15:24 INFO - 'HOME': '/Users/cltbld', 14:15:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:15:24 INFO - 'LOGNAME': 'cltbld', 14:15:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:15:24 INFO - 'MOZ_NO_REMOTE': '1', 14:15:24 INFO - 'NO_EM_RESTART': '1', 14:15:24 INFO - 'PAGER': '/bin/cat', 14:15:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:15:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:15:24 INFO - 'PWD': '/builds/slave/test', 14:15:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:15:24 INFO - 'SHELL': '/bin/bash', 14:15:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EUA7otxdoc/Listeners', 14:15:24 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 14:15:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:15:24 INFO - 'USER': 'cltbld', 14:15:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:15:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:15:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:15:24 INFO - 'XPC_FLAGS': '0x0', 14:15:24 INFO - 'XPC_SERVICE_NAME': '0', 14:15:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:15:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:15:24 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 14:15:24 INFO - Cleaning up... 14:15:24 INFO - Return code: 0 14:15:24 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:15:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:15:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:15:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:15:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:15:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:15:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:15:24 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 0x108640b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108643768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8af95781e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10841ed30>, '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 0x108345db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108413be8>, '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.9tx6zCukH5/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.EUA7otxdoc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:15:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 14:15:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 14:15:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9tx6zCukH5/Render', 14:15:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:15:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:15:24 INFO - 'HOME': '/Users/cltbld', 14:15:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:15:24 INFO - 'LOGNAME': 'cltbld', 14:15:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:15:24 INFO - 'MOZ_NO_REMOTE': '1', 14:15:24 INFO - 'NO_EM_RESTART': '1', 14:15:24 INFO - 'PAGER': '/bin/cat', 14:15:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:15:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:15:24 INFO - 'PWD': '/builds/slave/test', 14:15:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:15:24 INFO - 'SHELL': '/bin/bash', 14:15:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EUA7otxdoc/Listeners', 14:15:24 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 14:15:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:15:24 INFO - 'USER': 'cltbld', 14:15:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:15:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:15:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:15:24 INFO - 'XPC_FLAGS': '0x0', 14:15:24 INFO - 'XPC_SERVICE_NAME': '0', 14:15:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:15:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:15:24 INFO - Downloading/unpacking psutil>=3.1.1 14:15:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:15:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:15:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:15:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:15:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:15:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:15:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:15:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:15:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:15:28 INFO - Installing collected packages: psutil 14:15:28 INFO - Running setup.py install for psutil 14:15:28 INFO - building 'psutil._psutil_osx' extension 14:15:28 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 14:15:28 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 14:15:28 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 14:15:28 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 14:15:28 INFO - building 'psutil._psutil_posix' extension 14:15:28 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 14:15:28 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 14:15:28 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 14:15:28 INFO - ^ 14:15:28 INFO - 1 warning generated. 14:15:28 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 14:15:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:15:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:15:29 INFO - Successfully installed psutil 14:15:29 INFO - Cleaning up... 14:15:29 INFO - Return code: 0 14:15:29 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:15: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')]} 14:15:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:15:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:15: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')]} 14:15:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:15:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:15: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108640b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108643768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8af95781e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10841ed30>, '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 0x108345db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108413be8>, '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.9tx6zCukH5/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.EUA7otxdoc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:15: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 14:15: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 mozsystemmonitor==0.3 14:15:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9tx6zCukH5/Render', 14:15:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:15:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:15:29 INFO - 'HOME': '/Users/cltbld', 14:15:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:15:29 INFO - 'LOGNAME': 'cltbld', 14:15:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:15:29 INFO - 'MOZ_NO_REMOTE': '1', 14:15:29 INFO - 'NO_EM_RESTART': '1', 14:15:29 INFO - 'PAGER': '/bin/cat', 14:15:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:15:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:15:29 INFO - 'PWD': '/builds/slave/test', 14:15:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:15:29 INFO - 'SHELL': '/bin/bash', 14:15:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EUA7otxdoc/Listeners', 14:15:29 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 14:15:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:15:29 INFO - 'USER': 'cltbld', 14:15:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:15:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:15:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:15:29 INFO - 'XPC_FLAGS': '0x0', 14:15:29 INFO - 'XPC_SERVICE_NAME': '0', 14:15:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:15:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:15:29 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:15:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:15:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:15:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:15:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:15:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:15:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:15:32 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:15:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:15:32 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:15:32 INFO - Installing collected packages: mozsystemmonitor 14:15:32 INFO - Running setup.py install for mozsystemmonitor 14:15:32 INFO - Successfully installed mozsystemmonitor 14:15:32 INFO - Cleaning up... 14:15:32 INFO - Return code: 0 14:15:32 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:15:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:15:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:15:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:15:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:15:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:15:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:15:32 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 0x108640b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108643768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8af95781e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10841ed30>, '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 0x108345db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108413be8>, '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.9tx6zCukH5/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.EUA7otxdoc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:15:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 14:15:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 14:15:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9tx6zCukH5/Render', 14:15:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:15:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:15:32 INFO - 'HOME': '/Users/cltbld', 14:15:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:15:32 INFO - 'LOGNAME': 'cltbld', 14:15:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:15:32 INFO - 'MOZ_NO_REMOTE': '1', 14:15:32 INFO - 'NO_EM_RESTART': '1', 14:15:32 INFO - 'PAGER': '/bin/cat', 14:15:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:15:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:15:32 INFO - 'PWD': '/builds/slave/test', 14:15:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:15:32 INFO - 'SHELL': '/bin/bash', 14:15:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EUA7otxdoc/Listeners', 14:15:32 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 14:15:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:15:32 INFO - 'USER': 'cltbld', 14:15:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:15:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:15:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:15:32 INFO - 'XPC_FLAGS': '0x0', 14:15:32 INFO - 'XPC_SERVICE_NAME': '0', 14:15:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:15:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:15:32 INFO - Downloading/unpacking jsonschema==2.5.1 14:15: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 14:15: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 14:15: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 14:15: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 14:15: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 14:15: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 14:15:35 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:15:35 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:15:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:15:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:15:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:15:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:15:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:15:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:15:35 INFO - Downloading functools32-3.2.3-2.tar.gz 14:15:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:15:35 INFO - warning: no files found matching '*.txt' 14:15:35 INFO - no previously-included directories found matching 'build' 14:15:35 INFO - no previously-included directories found matching 'dist' 14:15:35 INFO - no previously-included directories found matching '.git*' 14:15:35 INFO - Installing collected packages: jsonschema, functools32 14:15:35 INFO - Running setup.py install for functools32 14:15:36 INFO - warning: no files found matching '*.txt' 14:15:36 INFO - no previously-included directories found matching 'build' 14:15:36 INFO - no previously-included directories found matching 'dist' 14:15:36 INFO - no previously-included directories found matching '.git*' 14:15:36 INFO - Successfully installed jsonschema functools32 14:15:36 INFO - Cleaning up... 14:15:36 INFO - Return code: 0 14:15:36 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:15: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')]} 14:15:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:15:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:15: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')]} 14:15:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:15:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:15:36 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 0x108640b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108643768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8af95781e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10841ed30>, '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 0x108345db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108413be8>, '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.9tx6zCukH5/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.EUA7otxdoc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:15:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 14:15:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 14:15:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9tx6zCukH5/Render', 14:15:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:15:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:15:36 INFO - 'HOME': '/Users/cltbld', 14:15:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:15:36 INFO - 'LOGNAME': 'cltbld', 14:15:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:15:36 INFO - 'MOZ_NO_REMOTE': '1', 14:15:36 INFO - 'NO_EM_RESTART': '1', 14:15:36 INFO - 'PAGER': '/bin/cat', 14:15:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:15:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:15:36 INFO - 'PWD': '/builds/slave/test', 14:15:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:15:36 INFO - 'SHELL': '/bin/bash', 14:15:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EUA7otxdoc/Listeners', 14:15:36 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 14:15:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:15:36 INFO - 'USER': 'cltbld', 14:15:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:15:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:15:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:15:36 INFO - 'XPC_FLAGS': '0x0', 14:15:36 INFO - 'XPC_SERVICE_NAME': '0', 14:15:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:15:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:15:36 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:15:36 INFO - Cleaning up... 14:15:36 INFO - Return code: 0 14:15:36 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:15: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')]} 14:15:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:15:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:15: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')]} 14:15:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:15:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:15:36 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 0x108640b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108643768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8af95781e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10841ed30>, '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 0x108345db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108413be8>, '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.9tx6zCukH5/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.EUA7otxdoc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:15:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 14:15:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 14:15:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9tx6zCukH5/Render', 14:15:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:15:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:15:36 INFO - 'HOME': '/Users/cltbld', 14:15:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:15:36 INFO - 'LOGNAME': 'cltbld', 14:15:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:15:36 INFO - 'MOZ_NO_REMOTE': '1', 14:15:36 INFO - 'NO_EM_RESTART': '1', 14:15:36 INFO - 'PAGER': '/bin/cat', 14:15:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:15:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:15:36 INFO - 'PWD': '/builds/slave/test', 14:15:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:15:36 INFO - 'SHELL': '/bin/bash', 14:15:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EUA7otxdoc/Listeners', 14:15:36 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 14:15:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:15:36 INFO - 'USER': 'cltbld', 14:15:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:15:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:15:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:15:36 INFO - 'XPC_FLAGS': '0x0', 14:15:36 INFO - 'XPC_SERVICE_NAME': '0', 14:15:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:15:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:15:36 INFO - Downloading/unpacking blobuploader==1.2.4 14:15:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:15:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:15:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:15:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:15:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:15:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:15:39 INFO - Downloading blobuploader-1.2.4.tar.gz 14:15:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:15:39 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:15:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:15:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:15:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:15:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:15:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:15:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:15:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:15:40 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:15:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:15:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:15:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:15:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:15:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:15:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:15:40 INFO - Downloading docopt-0.6.1.tar.gz 14:15:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:15:40 INFO - Installing collected packages: blobuploader, requests, docopt 14:15:40 INFO - Running setup.py install for blobuploader 14:15:40 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:15:40 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:15:40 INFO - Running setup.py install for requests 14:15:41 INFO - Running setup.py install for docopt 14:15:41 INFO - Successfully installed blobuploader requests docopt 14:15:41 INFO - Cleaning up... 14:15:41 INFO - Return code: 0 14:15:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:15:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:15:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:15:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:15:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:15:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:15:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:15:41 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 0x108640b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108643768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8af95781e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10841ed30>, '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 0x108345db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108413be8>, '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.9tx6zCukH5/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.EUA7otxdoc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:15:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 14:15:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:15:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9tx6zCukH5/Render', 14:15:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:15:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:15:41 INFO - 'HOME': '/Users/cltbld', 14:15:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:15:41 INFO - 'LOGNAME': 'cltbld', 14:15:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:15:41 INFO - 'MOZ_NO_REMOTE': '1', 14:15:41 INFO - 'NO_EM_RESTART': '1', 14:15:41 INFO - 'PAGER': '/bin/cat', 14:15:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:15:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:15:41 INFO - 'PWD': '/builds/slave/test', 14:15:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:15:41 INFO - 'SHELL': '/bin/bash', 14:15:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EUA7otxdoc/Listeners', 14:15:41 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 14:15:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:15:41 INFO - 'USER': 'cltbld', 14:15:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:15:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:15:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:15:41 INFO - 'XPC_FLAGS': '0x0', 14:15:41 INFO - 'XPC_SERVICE_NAME': '0', 14:15:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:15:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:15:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:15:41 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-TXDC0X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:15:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:15:41 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-sm1K0E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:15:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:15:42 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-Kzp_iL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:15:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:15:42 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-1K0n6G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:15:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:15:42 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-TidTxb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:15:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:15:42 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-wW0cGL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:15:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:15:42 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-3DTibA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:15:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:15:42 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-az1ogc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:15:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:15:42 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-jrQ3Zb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:15:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:15:42 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-xLXWZy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:15:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:15:42 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-WaINOp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:15:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:15:42 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-3iwKdr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:15:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:15:43 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-xw_cq9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:15:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:15:43 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-OOday_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:15:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:15:43 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-dWLQKv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:15:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:15:43 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-RmOZHY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:15:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:15:43 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-h3hcNn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:15:43 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:15:43 INFO - Running setup.py install for manifestparser 14:15:43 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:15:43 INFO - Running setup.py install for mozcrash 14:15:43 INFO - Running setup.py install for mozdebug 14:15:44 INFO - Running setup.py install for mozdevice 14:15:44 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:15:44 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:15:44 INFO - Running setup.py install for mozfile 14:15:44 INFO - Running setup.py install for mozhttpd 14:15:44 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:15:44 INFO - Running setup.py install for mozinfo 14:15:44 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:15:44 INFO - Running setup.py install for mozInstall 14:15:44 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:15:44 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:15:44 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:15:44 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:15:44 INFO - Running setup.py install for mozleak 14:15:44 INFO - Running setup.py install for mozlog 14:15:45 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:15:45 INFO - Running setup.py install for moznetwork 14:15:45 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:15:45 INFO - Running setup.py install for mozprocess 14:15:45 INFO - Running setup.py install for mozprofile 14:15:45 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:15:45 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:15:45 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:15:45 INFO - Running setup.py install for mozrunner 14:15:45 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:15:45 INFO - Running setup.py install for mozscreenshot 14:15:45 INFO - Running setup.py install for moztest 14:15:46 INFO - Running setup.py install for mozversion 14:15:46 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:15:46 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:15:46 INFO - Cleaning up... 14:15:46 INFO - Return code: 0 14:15:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:15:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:15:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:15:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:15:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:15:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:15:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:15:46 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 0x108640b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108643768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8af95781e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10841ed30>, '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 0x108345db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108413be8>, '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.9tx6zCukH5/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.EUA7otxdoc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:15:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 14:15:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:15:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9tx6zCukH5/Render', 14:15:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:15:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:15:46 INFO - 'HOME': '/Users/cltbld', 14:15:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:15:46 INFO - 'LOGNAME': 'cltbld', 14:15:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:15:46 INFO - 'MOZ_NO_REMOTE': '1', 14:15:46 INFO - 'NO_EM_RESTART': '1', 14:15:46 INFO - 'PAGER': '/bin/cat', 14:15:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:15:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:15:46 INFO - 'PWD': '/builds/slave/test', 14:15:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:15:46 INFO - 'SHELL': '/bin/bash', 14:15:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EUA7otxdoc/Listeners', 14:15:46 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 14:15:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:15:46 INFO - 'USER': 'cltbld', 14:15:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:15:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:15:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:15:46 INFO - 'XPC_FLAGS': '0x0', 14:15:46 INFO - 'XPC_SERVICE_NAME': '0', 14:15:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:15:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:15:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:15:46 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-9cDS9n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:15:46 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 14:15:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:15:46 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-KYhkmh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:15:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:15:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:15:46 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-ZsGbRY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:15:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 14:15:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:15:46 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-rss9xg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:15:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:15:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:15:46 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-RLCRib-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:15:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 14:15:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:15:47 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-rQz2IE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:15:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 14:15:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:15:47 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-xMPNWL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:15:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 14:15:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:15:47 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-RTpH2k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:15:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 14:15:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:15:47 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-OFZEQH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:15:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 14:15:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:15:47 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-CBnVN4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:15:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:15:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:15:47 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-w2xvBc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:15:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 14:15:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:15:47 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-vwtaGD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:15:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 14:15:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:15:47 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-opIhMn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:15:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 14:15:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:15:47 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-szk9J1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:15:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 14:15:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:15:47 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-0ByXQG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:15:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 14:15:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:15:48 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-ikOKRX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:15:48 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)) 14:15:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:15:48 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-UCwvoI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:15:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 14:15:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:15:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:15:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 14:15:48 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:15:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:15:48 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:15:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:15:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:15:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:15:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:15:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:15:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:15:50 INFO - Downloading blessings-1.6.tar.gz 14:15:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:15:50 INFO - Installing collected packages: blessings 14:15:50 INFO - Running setup.py install for blessings 14:15:51 INFO - Successfully installed blessings 14:15:51 INFO - Cleaning up... 14:15:51 INFO - Return code: 0 14:15:51 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:15:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:15:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:15:51 INFO - Reading from file tmpfile_stdout 14:15:51 INFO - Current package versions: 14:15:51 INFO - blessings == 1.6 14:15:51 INFO - blobuploader == 1.2.4 14:15:51 INFO - docopt == 0.6.1 14:15:51 INFO - functools32 == 3.2.3-2 14:15:51 INFO - jsonschema == 2.5.1 14:15:51 INFO - manifestparser == 1.1 14:15:51 INFO - mozInstall == 1.12 14:15:51 INFO - mozcrash == 1.0 14:15:51 INFO - mozdebug == 0.1 14:15:51 INFO - mozdevice == 0.49 14:15:51 INFO - mozfile == 1.2 14:15:51 INFO - mozhttpd == 0.7 14:15:51 INFO - mozinfo == 0.9 14:15:51 INFO - mozleak == 0.1 14:15:51 INFO - mozlog == 3.4 14:15:51 INFO - moznetwork == 0.27 14:15:51 INFO - mozprocess == 0.24 14:15:51 INFO - mozprofile == 0.28 14:15:51 INFO - mozrunner == 6.13 14:15:51 INFO - mozscreenshot == 0.1 14:15:51 INFO - mozsystemmonitor == 0.3 14:15:51 INFO - moztest == 0.8 14:15:51 INFO - mozversion == 1.4 14:15:51 INFO - psutil == 3.1.1 14:15:51 INFO - requests == 1.2.3 14:15:51 INFO - wsgiref == 0.1.2 14:15:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:15:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:15:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:15:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:15:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:15:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:15:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:15:52 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 0x108640b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108643768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8af95781e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10841ed30>, '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 0x108345db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108413be8>, '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.9tx6zCukH5/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.EUA7otxdoc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:15:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 14:15:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:15:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9tx6zCukH5/Render', 14:15:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:15:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:15:52 INFO - 'HOME': '/Users/cltbld', 14:15:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:15:52 INFO - 'LOGNAME': 'cltbld', 14:15:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:15:52 INFO - 'MOZ_NO_REMOTE': '1', 14:15:52 INFO - 'NO_EM_RESTART': '1', 14:15:52 INFO - 'PAGER': '/bin/cat', 14:15:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:15:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:15:52 INFO - 'PWD': '/builds/slave/test', 14:15:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:15:52 INFO - 'SHELL': '/bin/bash', 14:15:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EUA7otxdoc/Listeners', 14:15:52 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 14:15:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:15:52 INFO - 'USER': 'cltbld', 14:15:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:15:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:15:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:15:52 INFO - 'XPC_FLAGS': '0x0', 14:15:52 INFO - 'XPC_SERVICE_NAME': '0', 14:15:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:15:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:15:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 14:15:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 14:15:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 14:15:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 14:15:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 14:15:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 14:15:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 14:15:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 14:15:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 14:15:52 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 14:15:52 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 14:15:52 INFO - Cleaning up... 14:15:52 INFO - Return code: 0 14:15:52 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:15:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:15:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:15:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:15:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:15:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:15:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:15:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108640b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108643768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8af95781e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10841ed30>, '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 0x108345db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108413be8>, '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.9tx6zCukH5/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.EUA7otxdoc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:15:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 14:15:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 14:15:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9tx6zCukH5/Render', 14:15:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:15:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:15:52 INFO - 'HOME': '/Users/cltbld', 14:15:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:15:52 INFO - 'LOGNAME': 'cltbld', 14:15:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:15:52 INFO - 'MOZ_NO_REMOTE': '1', 14:15:52 INFO - 'NO_EM_RESTART': '1', 14:15:52 INFO - 'PAGER': '/bin/cat', 14:15:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:15:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:15:52 INFO - 'PWD': '/builds/slave/test', 14:15:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:15:52 INFO - 'SHELL': '/bin/bash', 14:15:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EUA7otxdoc/Listeners', 14:15:52 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 14:15:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:15:52 INFO - 'USER': 'cltbld', 14:15:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:15:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:15:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:15:52 INFO - 'XPC_FLAGS': '0x0', 14:15:52 INFO - 'XPC_SERVICE_NAME': '0', 14:15:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:15:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:15:53 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:15:53 INFO - Cleaning up... 14:15:53 INFO - Return code: 0 14:15:53 INFO - Running post-action listener: _resource_record_post_action 14:15:53 INFO - Running post-action listener: _start_resource_monitoring 14:15:53 INFO - Starting resource monitoring. 14:15:53 INFO - [mozharness: 2017-02-05 22:15:53.227951Z] Finished create-virtualenv step (success) 14:15:53 INFO - [mozharness: 2017-02-05 22:15:53.228879Z] Running install step. 14:15:53 INFO - Running pre-action listener: _resource_record_pre_action 14:15:53 INFO - Running main action method: install 14:15:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:15:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:15:53 INFO - Reading from file tmpfile_stdout 14:15:53 INFO - Detecting whether we're running mozinstall >=1.0... 14:15:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:15:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:15:53 INFO - Reading from file tmpfile_stdout 14:15:53 INFO - Output received: 14:15:53 INFO - Usage: mozinstall [options] installer 14:15:53 INFO - Options: 14:15:53 INFO - -h, --help show this help message and exit 14:15:53 INFO - -d DEST, --destination=DEST 14:15:53 INFO - Directory to install application into. [default: 14:15:53 INFO - "/builds/slave/test"] 14:15:53 INFO - --app=APP Application being installed. [default: firefox] 14:15:53 INFO - mkdir: /builds/slave/test/build/application 14:15:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 14:15:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 14:16:15 INFO - Reading from file tmpfile_stdout 14:16:15 INFO - Output received: 14:16:15 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 14:16:15 INFO - Running post-action listener: _resource_record_post_action 14:16:15 INFO - [mozharness: 2017-02-05 22:16:15.363334Z] Finished install step (success) 14:16:15 INFO - [mozharness: 2017-02-05 22:16:15.363470Z] Running run-tests step. 14:16:15 INFO - Running pre-action listener: _resource_record_pre_action 14:16:15 INFO - Running main action method: run_tests 14:16:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:16:15 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:16:15 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:16:15 INFO - 2017-02-05 14:16:15.376 screenresolution[2154:15814] starting screenresolution argv=screenresolution get 14:16:15 INFO - 2017-02-05 14:16:15.379 screenresolution[2154:15814] Display 0: 1600x1200x32@60 14:16:15 INFO - 2017-02-05 14:16:15.384 screenresolution[2155:15816] starting screenresolution argv=screenresolution list 14:16:15 INFO - Available Modes on Display 0 14:16:15 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:16:15 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:16:15 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:16:15 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:16:15 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:16:15 INFO - Intel Iris: 14:16:15 INFO - Chipset Model: Intel Iris 14:16:15 INFO - Type: GPU 14:16:15 INFO - Bus: Built-In 14:16:15 INFO - VRAM (Dynamic, Max): 1536 MB 14:16:15 INFO - Vendor: Intel (0x8086) 14:16:15 INFO - Device ID: 0x0a2e 14:16:15 INFO - Revision ID: 0x0009 14:16:15 INFO - Displays: 14:16:15 INFO - Display: 14:16:15 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:16:15 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:16:15 INFO - Display Serial Number: 1600x1200 60 14:16:15 INFO - Main Display: Yes 14:16:15 INFO - Mirror: Off 14:16:15 INFO - Online: Yes 14:16:15 INFO - Rotation: Supported 14:16:15 INFO - Return code: 0 14:16:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:16:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:16:15 INFO - Python 2.7.3 14:16:15 INFO - Return code: 0 14:16:15 INFO - grabbing minidump binary from tooltool 14:16:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:16:15 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10841ed30>, '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 0x108345db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108413be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:16:15 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 14:16:15 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 14:16:15 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 14:16:15 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:16:15 INFO - Return code: 0 14:16:15 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:16:15 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:16:15 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:16:15 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:16:15 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:16:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GmTt9U59QleKKFMwB6xn6Q/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0133', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 14:16:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/GmTt9U59QleKKFMwB6xn6Q/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0133 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 14:16:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9tx6zCukH5/Render', 14:16:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:16:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:16:15 INFO - 'HOME': '/Users/cltbld', 14:16:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:16:15 INFO - 'LOGNAME': 'cltbld', 14:16:15 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:16:15 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:16:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:16:15 INFO - 'MOZ_NO_REMOTE': '1', 14:16:15 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:16:15 INFO - 'NO_EM_RESTART': '1', 14:16:15 INFO - 'PAGER': '/bin/cat', 14:16:15 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:16:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:16:15 INFO - 'PWD': '/builds/slave/test', 14:16:15 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:16:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:16:15 INFO - 'SHELL': '/bin/bash', 14:16:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EUA7otxdoc/Listeners', 14:16:15 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 14:16:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:16:15 INFO - 'USER': 'cltbld', 14:16:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:16:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:16:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:16:15 INFO - 'XPC_FLAGS': '0x0', 14:16:15 INFO - 'XPC_SERVICE_NAME': '0', 14:16:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:16:15 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GmTt9U59QleKKFMwB6xn6Q/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0133', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 14:16:15 INFO - mozversion application_buildid: 20170205125708 14:16:15 INFO - mozversion application_changeset: 6c998914bad21a65c7fe2ee7e8c1c7f5269c750d 14:16:15 INFO - mozversion application_display_name: Nightly 14:16:15 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:16:15 INFO - mozversion application_name: Firefox 14:16:15 INFO - mozversion application_remotingname: firefox 14:16:15 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 14:16:15 INFO - mozversion application_vendor: Mozilla 14:16:15 INFO - mozversion application_version: 54.0a1 14:16:15 INFO - mozversion platform_buildid: 20170205125708 14:16:15 INFO - mozversion platform_changeset: 6c998914bad21a65c7fe2ee7e8c1c7f5269c750d 14:16:15 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 14:16:15 INFO - mozversion platform_version: 54.0a1 14:16:15 INFO - using testdate: 1486332975 14:16:15 INFO - actual date: 1486332975 14:16:15 INFO - starting webserver on 'localhost:52696' 14:16:15 INFO - SUITE-START | Running 2 tests 14:16:15 INFO - TEST-START | dromaeo_css 14:16:15 INFO - operating with platform_type : mac_ 14:16:15 INFO - Initialising browser for dromaeo_css test... 14:16:15 INFO - TEST-INFO | started process 2165 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/tmpQKuXBp/profile http://localhost:52696/getInfo.html) 14:16:18 INFO - PROCESS | 2165 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 14:16:18 INFO - PROCESS | 2165 | 2017-02-05 14:16:18.820 plugin-container[2167:15945] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f43, name = 'com.apple.tsm.portname' 14:16:18 INFO - PROCESS | 2165 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:16:18 INFO - PROCESS | 2165 | 2017-02-05 14:16:18.823 plugin-container[2167:15945] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4d07, name = 'com.apple.CFPasteboardClient' 14:16:18 INFO - PROCESS | 2165 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:16:18 INFO - PROCESS | 2165 | 2017-02-05 14:16:18.823 plugin-container[2167:15945] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:16:18 INFO - PROCESS | 2165 | __metrics Screen width/height:1600/1200 14:16:18 INFO - PROCESS | 2165 | colorDepth:24 14:16:18 INFO - PROCESS | 2165 | Browser inner width/height: 1024/689 14:16:18 INFO - PROCESS | 2165 | __metrics 14:16:18 ERROR - PROCESS | 2165 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3283: TypeError: this.tabs is undefined 14:16:18 INFO - PROCESS | 2165 | 1486332978988 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < forceQuit@jar:file:///var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/tmpQKuXBp/profile/extensions/talos-powers@TalosPowersService.js:238:7 < whenDone@jar:file:///var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/tmpQKuXBp/profile/extensions/talos-powers@TalosPowersService.js:223:9 < promise callback*forceQuit@jar:file:///var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/tmpQKuXBp/profile/extensions/talos-powers@TalosPowersService.js:225:7 < receiveMessage@jar:file:///var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/tmpQKuXBp/profile/extensions/talos-powers@TalosPowersService.js:55:9 14:16:19 INFO - TEST-INFO | 2165: exit 0 14:16:19 INFO - Browser initialized. 14:16:19 INFO - Running cycle 1/1 for dromaeo_css test... 14:16:19 INFO - TEST-INFO | started process 2168 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/tmpQKuXBp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:16:22 INFO - PROCESS | 2168 | 2017-02-05 14:16:22.319 plugin-container[2169:16067] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8743, name = 'com.apple.tsm.portname' 14:16:22 INFO - PROCESS | 2168 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:16:22 INFO - PROCESS | 2168 | 2017-02-05 14:16:22.319 plugin-container[2169:16067] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 14:16:22 INFO - PROCESS | 2168 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:16:22 INFO - PROCESS | 2168 | 2017-02-05 14:16:22.319 plugin-container[2169:16067] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:16:31 INFO - PROCESS | 2168 | RSS: Main: 234049536 14:16:31 INFO - PROCESS | 2168 | 14:19:34 INFO - PROCESS | 2168 | Cycle 1(1): loaded http://localhost:52696/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52696/tests/dromaeo/cssquery-ext.html) 14:19:36 INFO - PROCESS | 2168 | RSS: Main: 259411968 14:19:36 INFO - PROCESS | 2168 | 14:21:20 INFO - PROCESS | 2168 | ************************* 14:21:20 INFO - PROCESS | 2168 | A coding exception was thrown and uncaught in a Task. 14:21:20 INFO - PROCESS | 2168 | 14:21:20 ERROR - PROCESS | 2168 | Full message: TypeError: NetworkError when attempting to fetch resource. 14:21:20 INFO - PROCESS | 2168 | Full stack: 14:21:20 INFO - PROCESS | 2168 | ************************* 14:22:38 INFO - PROCESS | 2168 | Cycle 1(1): loaded http://localhost:52696/tests/dromaeo/cssquery-ext.html (next: http://localhost:52696/tests/dromaeo/cssquery-jquery.html) 14:22:39 INFO - PROCESS | 2168 | RSS: Main: 267005952 14:22:39 INFO - PROCESS | 2168 | 14:25:41 INFO - PROCESS | 2168 | Cycle 1(1): loaded http://localhost:52696/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52696/tests/dromaeo/cssquery-mootools.html) 14:25:42 INFO - PROCESS | 2168 | RSS: Main: 277311488 14:25:42 INFO - PROCESS | 2168 | 14:28:43 INFO - PROCESS | 2168 | Cycle 1(1): loaded http://localhost:52696/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52696/tests/dromaeo/cssquery-prototype.html) 14:28:44 INFO - PROCESS | 2168 | RSS: Main: 270512128 14:28:44 INFO - PROCESS | 2168 | 14:31:47 INFO - PROCESS | 2168 | Cycle 1(1): loaded http://localhost:52696/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52696/tests/dromaeo/cssquery-yui.html) 14:31:48 INFO - PROCESS | 2168 | RSS: Main: 275431424 14:31:48 INFO - PROCESS | 2168 | 14:34:50 INFO - PROCESS | 2168 | Cycle 1(1): loaded http://localhost:52696/tests/dromaeo/cssquery-yui.html (next: http://localhost:52696/tests/dromaeo/cssquery-dojo.html) 14:34:51 INFO - PROCESS | 2168 | RSS: Main: 277504000 14:34:51 INFO - PROCESS | 2168 | 14:34:51 INFO - PROCESS | 2168 | __start_tp_report 14:34:51 INFO - PROCESS | 2168 | _x_x_mozilla_page_load 14:34:51 INFO - PROCESS | 2168 | _x_x_mozilla_page_load_details 14:34:51 INFO - PROCESS | 2168 | |i|pagename|runs| 14:34:51 INFO - PROCESS | 2168 | |0;dojo.html;3257.5928008998876;3265.167382317509;3269.1663625775423;3294.2752594429226;3297.8515966781492;5429;5437.646552974059;5507.9449205507935;5520.585956053292;5538.307711536058;5340.732963351827;5448.7548060337285;5518.641288316267;5541.778328866851;5585.1063829787145;4967.925481117786;4972.751362431882;5026.29631851541;5049.621278404116;5060.873478163038;4960.032793605248;5025.824096156635;5032.396112466509;5146.125158723007;5155.432902380735;4981.476944920767;5067.265246539268;5071.2139618359215;5078.873028174288;5102.795888164487;4976.2286845539;5063.443021267658;5077.466865979056;5081.618878584084;5095.694258344512;4990.975045124787;4995.9500404996215;5031.06925218832;5058.291839142523;5062.696238225718;4540.205464043812;5386.380566234855;5416.647917885325;5463.098588732854;5508.504234618864;6003.729832157564;6085.300190478063;6115.727150604525;6118.101457354478;6119.265688117454;6120.571559990803;6170.321264660884;6172.660503672296;6174.783882564088;6176.351483094255;5128.794848206067;5499.560035197174;5697.9715101424645;5701.173329867146;5715.257016587817;4901.828436004723;5862.267216597926;6207.720652570645;6207.906881396782;6231.688415579203;5743.942560574341;5993.250843644544;6008.3390827009;6026.0659597762415;6027.156198132265;5904.291485021825;6040.244969378828;6091.817245482643;6119.776756597957;6144.279601711528;6105.702364394958;6108.433136212329;6113.4154954094565;6113.749968913926;6120.050983828535;5813.8255852323655;5887.5289976802605;6023.488003519662;6066.150738896558;6068.423499767603;4861.80552777892;4871.29366241893;4891.364122664031;4896.045271172087;4904.288878112654;4335.653147748172;4350.58669426404;4354.934675979863;4363.956360436418;4393.29707246839;5438.102713052381;5487.314085739283;5562.02664533705;5594.9369619772115;5605.607607467519;6166.81499555014;6187.102870083812;6196.721147548371;6199.442050215503;6203.069539569101;5621.915671264935;5626.74679639409;5633.971830140905;5645.802396916089;5652.3217213934595;4526.072155208174;5168.664036837594;5186.766595503212;5314;5321.840344789585;5075.5178258065425;6120.204373431426;6221.2845522763655;6231.532635052389;6248.312685604489;6099.634021958696;6100.054491553817;6136.32500424944;6155.138280640804;6179.969100154471;5035.723035232951;6071.696415179312;6086.969565152146;6116.847078823065;6223.439890409885;5980.641161530322;6035.064564992434;6035.728392222272;6077.574569780073;6129.417705317987;6074.149619053424;6086.269647642312;6091.360407157184;6125.295591006913;6131.938680613137;5611.663300202001;5733.483986441241;5801.76792928295;5930.881382372242;6020.608660437058;5094.286799848099;5944.138099975566;5956.8808623828145;5960.8807823844145;5985.102234664835;4603.608693261111;5710.143478478261;5772.826815195551;5789.1895134680335;5804.303483581997;5656.123300888369;5783.929972954848;5875.764969401176;5893.557983151195;5914.792982245601;5723.112917497794;5804.593678442638;5808.709564521672;5822.359540450631;5850.414958504116 14:34:51 INFO - PROCESS | 2168 | |1;ext.html;461.81989024280534;487.4053654541458;498.9925151122734;503.7305041802636;537.6344086021505;30980;33370.66629333703;39315.0171245719;43120.353194702104;43168.56831431685;42420.575794242046;42780;42844.785776071076;42880.356794648105;42887.57112428875;9248.676296329613;9433.198178154866;9445.81108377832;9499.145076943074;10806.837897431546;10860.185486088536;11060.17048721345;11099.55601775928;11105.666829995112;11129.554817807319;11086.390248536327;11101.111911047137;11118.054965327958;11138.443077846116;11139.610113646024;9363.625454981793;11068.114550835913;11071.335719856834;11082.778344433147;11094.278871873343;10801.243912926098;10874.238803283773;10878.401687907162;10882.29265097766;10902.890971090346;36582.26835463276;36848.26303473942;36901;36903.261934761154;37172.07069823249;3855.101362578932;3856.0359910022494;3886.591907849663;3898.649121579043;3906.5898080701686;27093.729062709317;27186;27212;27407.45185096287;27750.849242138334;3092.721655051056;3116.688331166888;3128.857966842109;3141.623005239363;3183.4428974929297;444832;445762;445952;447155.76422118006;450490.74754626345;46282.537174628495;47493.52506474891;54989.450105499236;55219.171712424344;55369.72315138399;55487.722561387745;56487.43512564822;56837.863242734915;59623.40376596178;59626.70186649126;13920.443182272798;14205.928970355082;14244.572662820132;14251.50119745804;14275.85724142766;10801.24391292602;10903;10904.291221070605;10935.015848573667;10939.960703733133;13761;15714.528564143095;15901.920490397473;17415.3033878646;17645.558861028578;6993.510454268152;7490.325870671557;7723.497972631761;7851.528908265523;7914.445988780845;824.6577670266892;834.9415540912078;851.9318454523623;858.0004294996385;882.1222883231097;26404.86797566038;28393.29016774556;29886.103416897095;30169.245768855515;30588;1012.336919317848;1041.5261056219554;1061.6974162363845;1064.9733756656146;1164.4818055965013;15383.769243461356;15438.38246470151;15463.762898968056;15529.86589199724;15821.841781582038;521024;564337;564709;564881;565230;44800;58983.41016589779;59478.10782838261;59636.403635963085;59822.70088649529;1319.8812106910425;1354.5326862232514;1405.915645061279;1409.6969151632447;1410.2032351721246;28958.7104128956;29059.564106537997;29128.417431651673;29594.408111837212;29877.402451951275;47400.52599473961;49876.25185622291;49970;50114;50170.24744628907;444490;446288;446316;446598;447451;23634.29097127089;24680.629790553514;24781.504369912862;25463.10879119296;26051.739482605688;41148.971275718344;41261.1747765049;41738.79130604328;41988.95027624334;42340;34076;34556.65443345534;34753.82623086868;35466.29067418585;35681.28637427289;28539.714602854536;33663.99008029763;34283.828580856934;34593.827030865694;35065.123371915906;33769.49345759865;33873.830630846685;33986.15034624154;34142.31715365629;34361.140971475914;29084.418311634072;30842.691573083983;30938.0718578443;30972.845135774176;31010.379792404477;23051.19320823763;23085;23383.766162338838;23559.528809423373;23704.525909482058 14:34:51 INFO - PROCESS | 2168 | |2;jquery.html;317.94600898023054;367.17202707893705;385.78781670081446;397.59643961379226;412.1859327827541;19324.059211699656;19850.503737406572;20377.184912603516;20807.27174548891;21056.157753689873;20677.689834652494;20918.372448826558;21367.67948480774;21373.465663358424;21711;5745.109508026242;5745.281839770039;5996.550258730613;5999.580029397944;6085.691576311092;5627.352854421732;5911.674857882815;5992.790252341147;6047.969760151215;6059.848503787396;5991.340952495222;6008.3390827009;6015.187949626788;6020.45815876571;6034.939650603482;5022.773975171126;5763.884722305552;5866.706664666771;5932.080527518242;5994.8501287467725;4409.625181859546;5103.744812759384;5421.1597202433295;5755.0504166812425;5775.682337471438;9554.761130971712;13378.598642040753;13607.59177224675;13735.656608584766;13756.380962856696;2517.5971844504793;2550.6939167300043;2637.5384307746067;2653.548896687568;2690.6636670416196;9906.40561566301;9982.201423886072;10132.898671013269;10296.639617613422;10399.116075133623;1408.6196726883786;1455.86169313915;1780.4213630570116;1790.1228398084975;1794.8310455114345;107183.85632287388;124200.36270152921;128504;137682;141446.99999999898;11634.825477617842;11743.530258789553;11881.40592970348;11923.880761192451;12067.517299308105;15466.845331546541;15483.84516154824;15523.844761552466;15526.767098493532;15539.145347006022;7770.650320735581;7937.722179723682;7975.84048319042;7999.480033797785;8091.838163236703;7376.262373762581;7389.298016688406;7421.332080112816;7466.178720340758;7522.855986044061;4736.478987311393;4812.302216178635;4820.590249828733;4883.731394773322;4894.828680996148;1983.1274239334646;2025.858189926691;2028.8275496582955;2043.3257025181654;2044.9182032718525;1001.6744558018673;1009.9394036357842;1014.8376259798397;1017.7404761785697;1041.6510468992822;20426.591468170554;21172.894135529223;21498.892505537373;21634.89182554077;21761.782382176294;1011.9898801011896;1014.4775440648073;1019.4749703902496;1041.3075304922143;1053.6312290698195;10902;11196.104311655048;11235.719107022227;11235.71910702239;11256.493457794255;620231;661085;674021;684143;685537;144974;148948;152140.239298805;153238;153470;819.5410570080774;991.2862738828032;1008.5713571731984;1036.2538971940191;1042.7966546523355;18525.72211416857;19578.706319404937;20074.59850803005;20114.698279525524;20240.660291051812;58134.4186558129;58929;59209.40790592039;59411.51471213254;59565;80913;86044;87273;87845;87863.56068219618;8422.494650321001;8468.322534197372;8564.229219370287;8573.642248381744;8579.442336248125;9390.906090939003;12395.380230988594;12936.935315323362;13410.463581457023;13431.462741490623;11196.776064478501;11375.772484550098;11641.59254426091;11672.824907626562;11851.992580630744;10909.34543927367;11275.718107047389;11575.363355015219;11697.23967942081;11698.005669517846;11469.426528673699;11472.713182170179;11788.823167652663;11963.401829908644;12002.519899204284;8239.340852731886;8455.915440845512;8516.403851730567;8528.872066918877;8717.738467845975;8584;11252.831207532057;11336.433178341214;11532.94233528827;11585.957263846294 14:34:51 INFO - PROCESS | 2168 | |3;mootools.html;1226.2274766896853;1247.3139773124778;1352.7835546312592;1396.6578188343858;1417;8168.91831081689;8356.623951922164;8634.4387614805;8643.135686431353;8664.74005779826;8461;8678.305735541157;8696.21734043936;8696.826063478742;8718.302535797136;2286.576983258094;2307.9884600577057;2318.9536209275802;2326.9534609307802;2337.602607556711;2292.701948746669;2326.4300246439648;2331.6968794056834;2332.3119679694532;2332.346942856003;2269.5687819314276;2273.1702928431087;2295.1966811615885;2295.529416469628;2296.0700916128994;2182.6289530779804;2242.4057624729458;2262.8981695823645;2265.6714776357335;2268.965965510519;1973.7236786849853;2052.374025922093;2262.4343914021497;2274.863508189514;2300.459392042869;6968.268331825187;8183.549904755236;8513.53175575343;8535.146485351477;8552.615132318999;3992.301347264217;4830.9275360869615;5246.029484545333;5272.762725677354;5326;3077.7842752113015;3423.4351332030187;3441.483777433405;3521.154922818505;3522.8943131705832;1197.144042009967;1201.9579314723942;1232.2360136715295;1236.418883124939;1257.1200159888115;10773.515191816387;11026;11120.666380008613;11159.553617855276;11193.776124477547;4434.55654434554;4506.256467682796;4738.407699037621;4776.522347765195;4783.425988881357;8044.758657240292;9595.184409325286;9725.367851089657;9727.124558789743;9744.658936937172;4199;5051.368578927634;5054.469280725544;5060.974695126501;5066.82266120684;4667.346571479996;4753.691010084333;4760;4803.255495398219;4822.180229361017;3361.0925050236788;3810.0855794609647;3839.788811615388;3929.44987701722;4007.2586571484367;1265.1080987903506;1286.7555164518712;1289.5035411366691;1298.6298904812083;1299.9805002924963;723.1177962885376;729.8430837369991;754.5925200391837;754.9660265288076;779.0534500581821;5034.697918124924;5562.221289019541;5576.414476479992;5593.916091258634;5696.0031994400815;569.9202111704365;572.627791935237;596.6002778138658;601.2244204975533;603.2218438214654;3605.9973429493243;3737.588865224823;3757.755745876509;3822.655960963471;3830.9233815323537;9806.754831129136;10208.43853588045;10208.540615672313;10215.591376345012;10226.744331391776;10073.748156296004;10110.797784044278;10150.847737283946;10152.796944061078;10171.745706357253;6529.542931994714;7606.353459955966;7646.273604007611;7834.451588388872;7905.446618736633;5449.700266485382;5493.7802487900035;5554.555635549227;5680.460356266148;5709.200711900255;8951.41815781972;9078.909210907806;9081.364304498622;9224.631014759334;9311.627534898798;9158.450492970444;9251.491167985825;9359.485228312507;9360.672376466791;9404.482753448352;7193.4604904631315;7303.233160518282;7305.525140865827;7311.634418279171;7313.524620899624;7850.842983140419;7860.253275938777;7868.606569671608;7936.325412340016;7987.880181797394;7126.821829454305;7144.035555200189;7154.534955227893;7236.38490728294;7243.384312333511;6126.326104128631;6814.182298124257;6917.861642767217;7006.6846991886505;7053.929460705533;6304.621722696653;6399.64801935898;6400.39196276353;6413.583117097561;6415.743370265137;4816.735079570657;4821.614270858253;4977.253411988231;5026.045051440215;5168.224766285088;3876.903077423087;4991.5258050485145;5076.898462030665;5094.490550944876;5118.360204974379 14:34:51 INFO - PROCESS | 2168 | |4;prototype.html;314.2411077248445;349.2351749668226;363.92539529396475;365.78784305103034;372.3130823630401;4718.646101542381;5027.09512287788;5237.921431178531;5395;5434.375046869611;3967.4048892666115;4572.017016341486;4767.547083027115;4791.706239315383;5154.458781827911;3139.9952015355098;3803.50554427924;3956.6636835868985;4114.156597897424;4156.646685031776;3831.942520862189;3931.702538162414;4049.2711311963835;4141.62725354718;4150.481189851269;3685.649863262999;3981.7212795104356;4002.8398864045403;4010.5788892166192;4018.1963607278517;3616.6021737608844;3690.907727306812;3926.6466018058227;3993.4209539616736;4010.9999999999854;3484.1986343140966;3490.581130264359;3497.2306092659574;3502.4571191465366;3510.9473357899396;4978.415540810611;5050.217216331474;5138.634194602541;5172.58619310458;5206.713630750307;4309.078738348088;4311.417958575584;4595.724256544618;4623.352730617717;4625.7687115644085;5549.805756798533;5578.721063946787;5600.831975040755;5621.747021384048;5621.8594535136535;3615.969448707132;3824.6749026332795;3849.3071247175776;3862.6909847212155;3908.511436070491;4926.605871530269;5425.724954635657;5575.243798203553;5728.513076388512;5837.118316319685;3705.4997575326984;3857.903552411156;3861.2431343739095;3873.12854607711;3885.1452680410266;3917.275304068756;3934.232824599233;3941.5664276929515;3959.604039596075;4061.1268577256033;3809.7142714296538;4523.615492683159;4553.4991150973365;4581.518940511265;4587.958358530489;3870.2521209941256;4608.354830323758;5090;5257.004676445928;5591.916121258185;1152.9538818447168;1237.6782036670531;1250.8686587908321;1253.3921048291572;1253.6928452529007;804.951702897828;822.9917700823034;833.8123922117475;843.7342237195264;879.6569337958201;4226.788660566959;4250.192463431938;4949.109160351171;4974.9354018082095;4992.925106123411;5034.651956448994;5324.5740340772645;5484.314460692413;5499.587530935196;5525.392206857243;4698.671093023456;4942.912559236962;4955.331030310934;4991.3511243538105;5082.517160869711;2479.701996344857;2483.255023492945;2687.4625074985083;2731.0714357118277;2875.7699384049224;4758.547937945889;5057.418396884332;5675.318961724537;5711.771529138871;5754.338251101093;4955.76990354342;5249.632525723163;5365.678059316453;5660.943390566042;5723.570732195102;5421.728913554385;5629.268195134525;5652.3217213934595;5661.179129026268;5701.71491425427;4879.853604391874;5491.670499770026;5502.779888804403;5526.982651332315;5536.280283563111;4556.6582506312825;5065.924011139762;5164.431912489698;5323.057084164175;5487.75305111263;4621.353010578455;4705.388299521041;4787.832425865078;5672.064109421899;5697.7720891165545;3838.788866612363;3844.9807750961068;3850.4994350735346;3861.1505468796818;3885.8057097145597;5230.801500222249;5493;5604.8879022418505;5715.799417224482;5784.132380142844;5232.5552328052545;5513.448655134454;5529.806456774155;5530.889382212414;5608.439156084359;4438.289873620205;4806.375171227718;5562.527185189304;5583.659921618701;5599.384067752462;4676.415448068991;4796.472388037383;5314.3091398117995;5431.293931788842;5471.987682278632;4193.496780386251;5009.699418034929;5301.867453313698;5389.27244821944;5510.858810477989;4897.534734200245;5080.593552515864;5440.836774896759;5454.672719636835;5501.889962200814 14:34:51 INFO - PROCESS | 2168 | |5;yui.html;622.8660837919846;698.6751160710269;772.2084863015408;826.5330088499998;835.9874601880973;13884.79172812406;13943.233122178277;13970.650733731662;14791.999999999987;15010.249487525622;13496.257705826176;13571.185728856275;13830.308484575782;13842.238676872768;13847.723045539084;851.9233269005788;857.8198578298565;889.5374405309235;893.0622846011694;896.0680891872445;868.1578868497547;881.9294456443469;883.7878909061811;886.9423487473326;887.5917078144062;802.173761026141;932.9860052099224;936.5925822267302;939.7979434421597;939.7979434421597;932.8927173375047;932.9533523323875;933.9719808405691;934.7476181431043;937.7889974755693;692.4875592061888;692.5256199503306;695.2144077192739;696.053367420308;696.2793508718452;14682.339294731762;14930;14932.402703891832;14950.177740224284;15040.32318545668;12370.072244581603;13301.467941282337;13657.180569165783;13665.658358541017;13680.384382702803;12222.205556638879;12620.116591838574;12653.936730316382;12886.80669789954;12969.41637626309;4320.611144996966;4429.778511074465;4456.77716114193;4537.818487260505;4560.087982403533;96521.5173924133;96886.0311396884;98124.50937745337;98249.50875245649;100910.49544752303;10645.28651198903;10753.569857205624;10758.300710453796;10842.891571084347;11095.778084438427;20470.795292047187;20471.692924606145;20476.795232047785;20491.59016819685;20525.486862828548;7426.777196684108;7481.438892083115;7485.925140748523;7567.962160189164;7624.771256862303;3544.03886881774;4106.856260030884;4200.285951388271;4213.073123912734;4225.176090662353;3438.8796392126155;3498.6851183393615;3498.9475157872653;3519.9296014079573;3535.116220944764;668.0547025958215;668.5525836563882;698.8707089188516;736.2343163110305;746;607.3804719186405;672.9629870357178;709.5742554467381;709.7444919828857;716.9390601798801;7946.131958824327;8775.605097770616;9132.63010548423;9606.847178338645;9948.90216780192;645.8901986662279;650.9381608747161;685.8902575587882;690.1717938473852;695.2699665351462;3619.7104231661406;3836.405357169643;4389.868303950887;4580.679352445297;4604.286335617984;61936.38063619306;74928.50142997218;88644.55677721699;91423;92858;50097.74951125221;58098.12852807211;70305.2969470309;83620;85527.14472855316;58646;65696.67151664307;72877.27122728704;76749.84875226876;78010;8404.36967227448;10086.495675216356;10582.417967011888;10595.47022648849;10613.41626210535;42508.574914250465;47668;48352.274715878586;51046;52070;65029.349706504225;67014;67507.66246168934;68222.3177768216;69940.6502967482;10215.489225538544;12471.750564988832;12726.109172358216;12727.363631818187;12862.549810756578;14331.426742930584;14501.274936252934;14546.636334091732;14676.950259571062;14687.265636717908;13341.791096552335;13401.798973015217;13416.530421435204;13730.382132804247;13748.312584370942;13421.561338289963;13423.664408389868;13513.594592162252;13553.932230338785;13720.93139534296;13138.343082846011;13235.867641323464;13367.732645346845;13756.862431375559;13793.379297931819;8392.706255281037;10696.839547406951;11010;11121.721956951142;11289.37908415045;8043.115257321573;9151.588178531849;10358.792824143626;10368.637097701545;10444.268901177149 14:34:51 INFO - PROCESS | 2168 | __end_tp_report 14:34:51 INFO - PROCESS | 2168 | __start_cc_report 14:34:51 INFO - PROCESS | 2168 | _x_x_mozilla_cycle_collect,408 14:34:51 INFO - PROCESS | 2168 | __end_cc_report 14:34:51 INFO - PROCESS | 2168 | __startTimestamp1486334091024__endTimestamp 14:34:51 INFO - PROCESS | 2168 | 14:34:51 INFO - PROCESS | 2168 | ------- Summary: start ------- 14:34:51 INFO - PROCESS | 2168 | Number of tests: 6 14:34:51 INFO - PROCESS | 2168 | 14:34:51 INFO - PROCESS | 2168 | [#0] dojo.html Cycles:165 Average:5551.01 Median:5715.26 stddev:642.66 (11.2%) stddev-sans-first:618.94 14:34:51 INFO - PROCESS | 2168 | Values: 3257.6 3265.2 3269.2 3294.3 3297.9 5429.0 5437.6 5507.9 5520.6 5538.3 5340.7 5448.8 5518.6 5541.8 5585.1 4967.9 4972.8 5026.3 5049.6 5060.9 4960.0 5025.8 5032.4 5146.1 5155.4 4981.5 5067.3 5071.2 5078.9 5102.8 4976.2 5063.4 5077.5 5081.6 5095.7 4991.0 4996.0 5031.1 5058.3 5062.7 4540.2 5386.4 5416.6 5463.1 5508.5 6003.7 6085.3 6115.7 6118.1 6119.3 6120.6 6170.3 6172.7 6174.8 6176.4 5128.8 5499.6 5698.0 5701.2 5715.3 4901.8 5862.3 6207.7 6207.9 6231.7 5743.9 5993.3 6008.3 6026.1 6027.2 5904.3 6040.2 6091.8 6119.8 6144.3 6105.7 6108.4 6113.4 6113.7 6120.1 5813.8 5887.5 6023.5 6066.2 6068.4 4861.8 4871.3 4891.4 4896.0 4904.3 4335.7 4350.6 4354.9 4364.0 4393.3 5438.1 5487.3 5562.0 5594.9 5605.6 6166.8 6187.1 6196.7 6199.4 6203.1 5621.9 5626.7 5634.0 5645.8 5652.3 4526.1 5168.7 5186.8 5314.0 5321.8 5075.5 6120.2 6221.3 6231.5 6248.3 6099.6 6100.1 6136.3 6155.1 6180.0 5035.7 6071.7 6087.0 6116.8 6223.4 5980.6 6035.1 6035.7 6077.6 6129.4 6074.1 6086.3 6091.4 6125.3 6131.9 5611.7 5733.5 5801.8 5930.9 6020.6 5094.3 5944.1 5956.9 5960.9 5985.1 4603.6 5710.1 5772.8 5789.2 5804.3 5656.1 5783.9 5875.8 5893.6 5914.8 5723.1 5804.6 5808.7 5822.4 5850.4 14:34:51 INFO - PROCESS | 2168 | 14:34:51 INFO - PROCESS | 2168 | [#1] ext.html Cycles:180 Average:61646.56 Median:33929.99 stddev:129362.14 (381.3%) stddev-sans-first:129643.46 14:34:51 INFO - PROCESS | 2168 | Values: 461.8 487.4 499.0 503.7 537.6 30980.0 33370.7 39315.0 43120.4 43168.6 42420.6 42780.0 42844.8 42880.4 42887.6 9248.7 9433.2 9445.8 9499.1 10806.8 10860.2 11060.2 11099.6 11105.7 11129.6 11086.4 11101.1 11118.1 11138.4 11139.6 9363.6 11068.1 11071.3 11082.8 11094.3 10801.2 10874.2 10878.4 10882.3 10902.9 36582.3 36848.3 36901.0 36903.3 37172.1 3855.1 3856.0 3886.6 3898.6 3906.6 27093.7 27186.0 27212.0 27407.5 27750.8 3092.7 3116.7 3128.9 3141.6 3183.4 444832.0 445762.0 445952.0 447155.8 450490.7 46282.5 47493.5 54989.5 55219.2 55369.7 55487.7 56487.4 56837.9 59623.4 59626.7 13920.4 14205.9 14244.6 14251.5 14275.9 10801.2 10903.0 10904.3 10935.0 10940.0 13761.0 15714.5 15901.9 17415.3 17645.6 6993.5 7490.3 7723.5 7851.5 7914.4 824.7 834.9 851.9 858.0 882.1 26404.9 28393.3 29886.1 30169.2 30588.0 1012.3 1041.5 1061.7 1065.0 1164.5 15383.8 15438.4 15463.8 15529.9 15821.8 521024.0 564337.0 564709.0 564881.0 565230.0 44800.0 58983.4 59478.1 59636.4 59822.7 1319.9 1354.5 1405.9 1409.7 1410.2 28958.7 29059.6 29128.4 29594.4 29877.4 47400.5 49876.3 49970.0 50114.0 50170.2 444490.0 446288.0 446316.0 446598.0 447451.0 23634.3 24680.6 24781.5 25463.1 26051.7 41149.0 41261.2 41738.8 41989.0 42340.0 34076.0 34556.7 34753.8 35466.3 35681.3 28539.7 33664.0 34283.8 34593.8 35065.1 33769.5 33873.8 33986.2 34142.3 34361.1 29084.4 30842.7 30938.1 30972.8 31010.4 23051.2 23085.0 23383.8 23559.5 23704.5 14:34:51 INFO - PROCESS | 2168 | 14:34:51 INFO - PROCESS | 2168 | [#2] jquery.html Cycles:180 Average:37945.60 Median:21114.53 stddev:111445.66 (527.8%) stddev-sans-first:111722.48 14:34:51 INFO - PROCESS | 2168 | Values: 317.9 367.2 385.8 397.6 412.2 19324.1 19850.5 20377.2 20807.3 21056.2 20677.7 20918.4 21367.7 21373.5 21711.0 5745.1 5745.3 5996.6 5999.6 6085.7 5627.4 5911.7 5992.8 6048.0 6059.8 5991.3 6008.3 6015.2 6020.5 6034.9 5022.8 5763.9 5866.7 5932.1 5994.9 4409.6 5103.7 5421.2 5755.1 5775.7 9554.8 13378.6 13607.6 13735.7 13756.4 2517.6 2550.7 2637.5 2653.5 2690.7 9906.4 9982.2 10132.9 10296.6 10399.1 1408.6 1455.9 1780.4 1790.1 1794.8 107183.9 124200.4 128504.0 137682.0 141447.0 11634.8 11743.5 11881.4 11923.9 12067.5 15466.8 15483.8 15523.8 15526.8 15539.1 7770.7 7937.7 7975.8 7999.5 8091.8 7376.3 7389.3 7421.3 7466.2 7522.9 4736.5 4812.3 4820.6 4883.7 4894.8 1983.1 2025.9 2028.8 2043.3 2044.9 1001.7 1009.9 1014.8 1017.7 1041.7 20426.6 21172.9 21498.9 21634.9 21761.8 1012.0 1014.5 1019.5 1041.3 1053.6 10902.0 11196.1 11235.7 11235.7 11256.5 620231.0 661085.0 674021.0 684143.0 685537.0 144974.0 148948.0 152140.2 153238.0 153470.0 819.5 991.3 1008.6 1036.3 1042.8 18525.7 19578.7 20074.6 20114.7 20240.7 58134.4 58929.0 59209.4 59411.5 59565.0 80913.0 86044.0 87273.0 87845.0 87863.6 8422.5 8468.3 8564.2 8573.6 8579.4 9390.9 12395.4 12936.9 13410.5 13431.5 11196.8 11375.8 11641.6 11672.8 11852.0 10909.3 11275.7 11575.4 11697.2 11698.0 11469.4 11472.7 11788.8 11963.4 12002.5 8239.3 8455.9 8516.4 8528.9 8717.7 8584.0 11252.8 11336.4 11532.9 11586.0 14:34:51 INFO - PROCESS | 2168 | 14:34:51 INFO - PROCESS | 2168 | [#3] mootools.html Cycles:180 Average:5416.98 Median:5057.72 stddev:3026.36 (59.8%) stddev-sans-first:3018.46 14:34:51 INFO - PROCESS | 2168 | Values: 1226.2 1247.3 1352.8 1396.7 1417.0 8168.9 8356.6 8634.4 8643.1 8664.7 8461.0 8678.3 8696.2 8696.8 8718.3 2286.6 2308.0 2319.0 2327.0 2337.6 2292.7 2326.4 2331.7 2332.3 2332.3 2269.6 2273.2 2295.2 2295.5 2296.1 2182.6 2242.4 2262.9 2265.7 2269.0 1973.7 2052.4 2262.4 2274.9 2300.5 6968.3 8183.5 8513.5 8535.1 8552.6 3992.3 4830.9 5246.0 5272.8 5326.0 3077.8 3423.4 3441.5 3521.2 3522.9 1197.1 1202.0 1232.2 1236.4 1257.1 10773.5 11026.0 11120.7 11159.6 11193.8 4434.6 4506.3 4738.4 4776.5 4783.4 8044.8 9595.2 9725.4 9727.1 9744.7 4199.0 5051.4 5054.5 5061.0 5066.8 4667.3 4753.7 4760.0 4803.3 4822.2 3361.1 3810.1 3839.8 3929.4 4007.3 1265.1 1286.8 1289.5 1298.6 1300.0 723.1 729.8 754.6 755.0 779.1 5034.7 5562.2 5576.4 5593.9 5696.0 569.9 572.6 596.6 601.2 603.2 3606.0 3737.6 3757.8 3822.7 3830.9 9806.8 10208.4 10208.5 10215.6 10226.7 10073.7 10110.8 10150.8 10152.8 10171.7 6529.5 7606.4 7646.3 7834.5 7905.4 5449.7 5493.8 5554.6 5680.5 5709.2 8951.4 9078.9 9081.4 9224.6 9311.6 9158.5 9251.5 9359.5 9360.7 9404.5 7193.5 7303.2 7305.5 7311.6 7313.5 7850.8 7860.3 7868.6 7936.3 7987.9 7126.8 7144.0 7154.5 7236.4 7243.4 6126.3 6814.2 6917.9 7006.7 7053.9 6304.6 6399.6 6400.4 6413.6 6415.7 4816.7 4821.6 4977.3 5026.0 5168.2 3876.9 4991.5 5076.9 5094.5 5118.4 14:34:51 INFO - PROCESS | 2168 | 14:34:51 INFO - PROCESS | 2168 | [#4] prototype.html Cycles:180 Average:4394.72 Median:4888.69 stddev:1330.48 (27.2%) stddev-sans-first:1298.49 14:34:51 INFO - PROCESS | 2168 | Values: 314.2 349.2 363.9 365.8 372.3 4718.6 5027.1 5237.9 5395.0 5434.4 3967.4 4572.0 4767.5 4791.7 5154.5 3140.0 3803.5 3956.7 4114.2 4156.6 3831.9 3931.7 4049.3 4141.6 4150.5 3685.6 3981.7 4002.8 4010.6 4018.2 3616.6 3690.9 3926.6 3993.4 4011.0 3484.2 3490.6 3497.2 3502.5 3510.9 4978.4 5050.2 5138.6 5172.6 5206.7 4309.1 4311.4 4595.7 4623.4 4625.8 5549.8 5578.7 5600.8 5621.7 5621.9 3616.0 3824.7 3849.3 3862.7 3908.5 4926.6 5425.7 5575.2 5728.5 5837.1 3705.5 3857.9 3861.2 3873.1 3885.1 3917.3 3934.2 3941.6 3959.6 4061.1 3809.7 4523.6 4553.5 4581.5 4588.0 3870.3 4608.4 5090.0 5257.0 5591.9 1153.0 1237.7 1250.9 1253.4 1253.7 805.0 823.0 833.8 843.7 879.7 4226.8 4250.2 4949.1 4974.9 4992.9 5034.7 5324.6 5484.3 5499.6 5525.4 4698.7 4942.9 4955.3 4991.4 5082.5 2479.7 2483.3 2687.5 2731.1 2875.8 4758.5 5057.4 5675.3 5711.8 5754.3 4955.8 5249.6 5365.7 5660.9 5723.6 5421.7 5629.3 5652.3 5661.2 5701.7 4879.9 5491.7 5502.8 5527.0 5536.3 4556.7 5065.9 5164.4 5323.1 5487.8 4621.4 4705.4 4787.8 5672.1 5697.8 3838.8 3845.0 3850.5 3861.2 3885.8 5230.8 5493.0 5604.9 5715.8 5784.1 5232.6 5513.4 5529.8 5530.9 5608.4 4438.3 4806.4 5562.5 5583.7 5599.4 4676.4 4796.5 5314.3 5431.3 5472.0 4193.5 5009.7 5301.9 5389.3 5510.9 4897.5 5080.6 5440.8 5454.7 5501.9 14:34:51 INFO - PROCESS | 2168 | 14:34:51 INFO - PROCESS | 2168 | [#5] yui.html Cycles:180 Average:18710.50 Median:4570.38 stddev:25759.32 (563.6%) stddev-sans-first:25795.77 14:34:51 INFO - PROCESS | 2168 | Values: 622.9 698.7 772.2 826.5 836.0 13884.8 13943.2 13970.7 14792.0 15010.2 13496.3 13571.2 13830.3 13842.2 13847.7 851.9 857.8 889.5 893.1 896.1 868.2 881.9 883.8 886.9 887.6 802.2 933.0 936.6 939.8 939.8 932.9 933.0 934.0 934.7 937.8 692.5 692.5 695.2 696.1 696.3 14682.3 14930.0 14932.4 14950.2 15040.3 12370.1 13301.5 13657.2 13665.7 13680.4 12222.2 12620.1 12653.9 12886.8 12969.4 4320.6 4429.8 4456.8 4537.8 4560.1 96521.5 96886.0 98124.5 98249.5 100910.5 10645.3 10753.6 10758.3 10842.9 11095.8 20470.8 20471.7 20476.8 20491.6 20525.5 7426.8 7481.4 7485.9 7568.0 7624.8 3544.0 4106.9 4200.3 4213.1 4225.2 3438.9 3498.7 3498.9 3519.9 3535.1 668.1 668.6 698.9 736.2 746.0 607.4 673.0 709.6 709.7 716.9 7946.1 8775.6 9132.6 9606.8 9948.9 645.9 650.9 685.9 690.2 695.3 3619.7 3836.4 4389.9 4580.7 4604.3 61936.4 74928.5 88644.6 91423.0 92858.0 50097.7 58098.1 70305.3 83620.0 85527.1 58646.0 65696.7 72877.3 76749.8 78010.0 8404.4 10086.5 10582.4 10595.5 10613.4 42508.6 47668.0 48352.3 51046.0 52070.0 65029.3 67014.0 67507.7 68222.3 69940.7 10215.5 12471.8 12726.1 12727.4 12862.5 14331.4 14501.3 14546.6 14677.0 14687.3 13341.8 13401.8 13416.5 13730.4 13748.3 13421.6 13423.7 13513.6 13553.9 13720.9 13138.3 13235.9 13367.7 13756.9 13793.4 8392.7 10696.8 11010.0 11121.7 11289.4 8043.1 9151.6 10358.8 10368.6 10444.3 14:34:51 INFO - PROCESS | 2168 | -------- Summary: end -------- 14:34:51 INFO - PROCESS | 2168 | 14:34:51 INFO - TEST-INFO | 2168: exit 0 14:34:51 INFO - TEST-OK | dromaeo_css | took 1115757ms 14:34:51 INFO - TEST-START | kraken 14:34:51 INFO - operating with platform_type : mac_ 14:34:51 INFO - Initialising browser for kraken test... 14:34:51 INFO - TEST-INFO | started process 2176 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/tmpmuUJj3/profile http://localhost:52696/getInfo.html) 14:34:54 INFO - PROCESS | 2176 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 14:34:54 INFO - PROCESS | 2176 | 2017-02-05 14:34:54.646 plugin-container[2177:18153] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c43, name = 'com.apple.tsm.portname' 14:34:54 INFO - PROCESS | 2176 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:34:54 INFO - PROCESS | 2176 | 2017-02-05 14:34:54.649 plugin-container[2177:18153] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x440f, name = 'com.apple.CFPasteboardClient' 14:34:54 INFO - PROCESS | 2176 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:34:54 INFO - PROCESS | 2176 | 2017-02-05 14:34:54.649 plugin-container[2177:18153] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:34:54 INFO - PROCESS | 2176 | __metrics Screen width/height:1600/1200 14:34:54 INFO - PROCESS | 2176 | colorDepth:24 14:34:54 INFO - PROCESS | 2176 | Browser inner width/height: 1024/689 14:34:54 INFO - PROCESS | 2176 | __metrics 14:34:54 ERROR - PROCESS | 2176 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3283: TypeError: this.tabs is undefined 14:34:54 INFO - PROCESS | 2176 | 1486334094804 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < forceQuit@jar:file:///var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/tmpmuUJj3/profile/extensions/talos-powers@TalosPowersService.js:238:7 < whenDone@jar:file:///var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/tmpmuUJj3/profile/extensions/talos-powers@TalosPowersService.js:223:9 < promise callback*forceQuit@jar:file:///var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/tmpmuUJj3/profile/extensions/talos-powers@TalosPowersService.js:225:7 < receiveMessage@jar:file:///var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/tmpmuUJj3/profile/extensions/talos-powers@TalosPowersService.js:55:9 14:34:55 INFO - TEST-INFO | 2176: exit 0 14:34:55 INFO - Browser initialized. 14:34:55 INFO - Running cycle 1/1 for kraken test... 14:34:55 INFO - TEST-INFO | started process 2178 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/tmpmuUJj3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:34:58 INFO - PROCESS | 2178 | 2017-02-05 14:34:58.077 plugin-container[2179:18267] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 14:34:58 INFO - PROCESS | 2178 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:34:58 INFO - PROCESS | 2178 | 2017-02-05 14:34:58.077 plugin-container[2179:18267] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 14:34:58 INFO - PROCESS | 2178 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:34:58 INFO - PROCESS | 2178 | 2017-02-05 14:34:58.077 plugin-container[2179:18267] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:35:07 INFO - PROCESS | 2178 | RSS: Main: 233373696 14:35:07 INFO - PROCESS | 2178 | 14:35:10 INFO - PROCESS | 2178 | Cycle 1(1): loaded http://localhost:52696/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52696/tests/kraken/driver.html?testName=audio-beat-detection) 14:35:11 INFO - PROCESS | 2178 | RSS: Main: 247009280 14:35:11 INFO - PROCESS | 2178 | 14:35:13 INFO - PROCESS | 2178 | Cycle 1(1): loaded http://localhost:52696/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52696/tests/kraken/driver.html?testName=audio-dft) 14:35:14 INFO - PROCESS | 2178 | RSS: Main: 254906368 14:35:14 INFO - PROCESS | 2178 | 14:35:17 INFO - PROCESS | 2178 | Cycle 1(1): loaded http://localhost:52696/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52696/tests/kraken/driver.html?testName=audio-fft) 14:35:18 INFO - PROCESS | 2178 | RSS: Main: 257327104 14:35:18 INFO - PROCESS | 2178 | 14:35:20 INFO - PROCESS | 2178 | Cycle 1(1): loaded http://localhost:52696/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52696/tests/kraken/driver.html?testName=audio-oscillator) 14:35:21 INFO - PROCESS | 2178 | RSS: Main: 259780608 14:35:21 INFO - PROCESS | 2178 | 14:35:23 INFO - PROCESS | 2178 | Cycle 1(1): loaded http://localhost:52696/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52696/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:35:23 INFO - PROCESS | 2178 | RSS: Main: 260485120 14:35:23 INFO - PROCESS | 2178 | 14:35:26 INFO - PROCESS | 2178 | Cycle 1(1): loaded http://localhost:52696/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52696/tests/kraken/driver.html?testName=imaging-darkroom) 14:35:27 INFO - PROCESS | 2178 | RSS: Main: 266018816 14:35:27 INFO - PROCESS | 2178 | 14:35:30 INFO - PROCESS | 2178 | Cycle 1(1): loaded http://localhost:52696/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52696/tests/kraken/driver.html?testName=imaging-desaturate) 14:35:31 INFO - PROCESS | 2178 | RSS: Main: 265351168 14:35:31 INFO - PROCESS | 2178 | 14:35:34 INFO - PROCESS | 2178 | Cycle 1(1): loaded http://localhost:52696/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52696/tests/kraken/driver.html?testName=json-parse-financial) 14:35:35 INFO - PROCESS | 2178 | RSS: Main: 265449472 14:35:35 INFO - PROCESS | 2178 | 14:35:36 INFO - PROCESS | 2178 | Cycle 1(1): loaded http://localhost:52696/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52696/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:35:37 INFO - PROCESS | 2178 | RSS: Main: 264683520 14:35:37 INFO - PROCESS | 2178 | 14:35:39 INFO - PROCESS | 2178 | Cycle 1(1): loaded http://localhost:52696/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52696/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:35:40 INFO - PROCESS | 2178 | RSS: Main: 264683520 14:35:40 INFO - PROCESS | 2178 | 14:35:42 INFO - PROCESS | 2178 | Cycle 1(1): loaded http://localhost:52696/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52696/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:35:43 INFO - PROCESS | 2178 | RSS: Main: 264781824 14:35:43 INFO - PROCESS | 2178 | 14:35:46 INFO - PROCESS | 2178 | Cycle 1(1): loaded http://localhost:52696/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52696/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:35:47 INFO - PROCESS | 2178 | RSS: Main: 265068544 14:35:47 INFO - PROCESS | 2178 | 14:35:50 INFO - PROCESS | 2178 | Cycle 1(1): loaded http://localhost:52696/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52696/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:35:50 INFO - PROCESS | 2178 | RSS: Main: 265105408 14:35:50 INFO - PROCESS | 2178 | 14:35:52 INFO - PROCESS | 2178 | Cycle 1(1): loaded http://localhost:52696/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52696/tests/kraken/driver.html?testName=ai-astar) 14:35:52 INFO - PROCESS | 2178 | RSS: Main: 267165696 14:35:52 INFO - PROCESS | 2178 | 14:35:52 INFO - PROCESS | 2178 | __start_tp_report 14:35:52 INFO - PROCESS | 2178 | _x_x_mozilla_page_load 14:35:52 INFO - PROCESS | 2178 | _x_x_mozilla_page_load_details 14:35:52 INFO - PROCESS | 2178 | |i|pagename|runs| 14:35:52 INFO - PROCESS | 2178 | |0;ai-astar;114;120;117;116;117;117;117;117;116;119 14:35:52 INFO - PROCESS | 2178 | |1;audio-beat-detection;147;115;113;150;111;116;117;149;123;141 14:35:52 INFO - PROCESS | 2178 | |2;audio-dft;198;199;205;214;205;216;195;198;193;222 14:35:52 INFO - PROCESS | 2178 | |3;audio-fft;83;59;61;87;59;69;71;59;73;61 14:35:52 INFO - PROCESS | 2178 | |4;audio-oscillator;115;82;73;77;101;104;119;102;71;86 14:35:52 INFO - PROCESS | 2178 | |5;imaging-gaussian-blur;78;118;136;102;88;84;113;131;89;85 14:35:52 INFO - PROCESS | 2178 | |6;imaging-darkroom;96;99;100;139;106;154;107;114;146;100 14:35:52 INFO - PROCESS | 2178 | |7;imaging-desaturate;101;131;91;119;112;91;96;93;94;146 14:35:52 INFO - PROCESS | 2178 | |8;json-parse-financial;64;75;99;102;101;63;64;65;65;64 14:35:52 INFO - PROCESS | 2178 | |9;json-stringify-tinderbox;56;102;65;90;122;113;80;69;75;72 14:35:52 INFO - PROCESS | 2178 | |10;stanford-crypto-aes;87;90;92;90;87;90;93;89;90;89 14:35:52 INFO - PROCESS | 2178 | |11;stanford-crypto-ccm;141;163;155;139;151;150;142;145;208;159 14:35:52 INFO - PROCESS | 2178 | |12;stanford-crypto-pbkdf2;173;184;215;182;169;159;162;216;239;176 14:35:52 INFO - PROCESS | 2178 | |13;stanford-crypto-sha256-iterative;69;81;89;93;91;88;67;68;66;68 14:35:52 INFO - PROCESS | 2178 | __end_tp_report 14:35:52 INFO - PROCESS | 2178 | __start_cc_report 14:35:52 INFO - PROCESS | 2178 | _x_x_mozilla_cycle_collect,990 14:35:52 INFO - PROCESS | 2178 | __end_cc_report 14:35:52 INFO - PROCESS | 2178 | __startTimestamp1486334152768__endTimestamp 14:35:52 INFO - PROCESS | 2178 | 14:35:52 INFO - PROCESS | 2178 | ------- Summary: start ------- 14:35:52 INFO - PROCESS | 2178 | Number of tests: 14 14:35:52 INFO - PROCESS | 2178 | 14:35:52 INFO - PROCESS | 2178 | [#0] ai-astar Cycles:10 Average:117.00 Median:117.00 stddev:1.63 (1.4%) stddev-sans-first:1.32 14:35:52 INFO - PROCESS | 2178 | Values: 114.0 120.0 117.0 116.0 117.0 117.0 117.0 117.0 116.0 119.0 14:35:52 INFO - PROCESS | 2178 | 14:35:52 INFO - PROCESS | 2178 | [#1] audio-beat-detection Cycles:10 Average:128.20 Median:132.00 stddev:16.42 (12.4%) stddev-sans-first:15.95 14:35:52 INFO - PROCESS | 2178 | Values: 147.0 115.0 113.0 150.0 111.0 116.0 117.0 149.0 123.0 141.0 14:35:52 INFO - PROCESS | 2178 | 14:35:52 INFO - PROCESS | 2178 | [#2] audio-dft Cycles:10 Average:204.50 Median:205.00 stddev:9.81 (4.8%) stddev-sans-first:10.12 14:35:52 INFO - PROCESS | 2178 | Values: 198.0 199.0 205.0 214.0 205.0 216.0 195.0 198.0 193.0 222.0 14:35:52 INFO - PROCESS | 2178 | 14:35:52 INFO - PROCESS | 2178 | [#3] audio-fft Cycles:10 Average:68.20 Median:70.00 stddev:10.34 (14.8%) stddev-sans-first:9.48 14:35:52 INFO - PROCESS | 2178 | Values: 83.0 59.0 61.0 87.0 59.0 69.0 71.0 59.0 73.0 61.0 14:35:52 INFO - PROCESS | 2178 | 14:35:52 INFO - PROCESS | 2178 | [#4] audio-oscillator Cycles:10 Average:93.00 Median:72.00 stddev:17.44 (24.2%) stddev-sans-first:16.58 14:35:52 INFO - PROCESS | 2178 | Values: 115.0 82.0 73.0 77.0 101.0 104.0 119.0 102.0 71.0 86.0 14:35:52 INFO - PROCESS | 2178 | 14:35:52 INFO - PROCESS | 2178 | [#5] imaging-gaussian-blur Cycles:10 Average:102.40 Median:81.00 stddev:20.89 (25.8%) stddev-sans-first:20.20 14:35:52 INFO - PROCESS | 2178 | Values: 78.0 118.0 136.0 102.0 88.0 84.0 113.0 131.0 89.0 85.0 14:35:52 INFO - PROCESS | 2178 | 14:35:52 INFO - PROCESS | 2178 | [#6] imaging-darkroom Cycles:10 Average:116.10 Median:142.50 stddev:21.75 (15.3%) stddev-sans-first:21.82 14:35:52 INFO - PROCESS | 2178 | Values: 96.0 99.0 100.0 139.0 106.0 154.0 107.0 114.0 146.0 100.0 14:35:52 INFO - PROCESS | 2178 | 14:35:52 INFO - PROCESS | 2178 | [#7] imaging-desaturate Cycles:10 Average:107.40 Median:91.00 stddev:19.14 (21.0%) stddev-sans-first:20.16 14:35:52 INFO - PROCESS | 2178 | Values: 101.0 131.0 91.0 119.0 112.0 91.0 96.0 93.0 94.0 146.0 14:35:52 INFO - PROCESS | 2178 | 14:35:52 INFO - PROCESS | 2178 | [#8] json-parse-financial Cycles:10 Average:76.20 Median:64.50 stddev:17.24 (26.7%) stddev-sans-first:17.71 14:35:52 INFO - PROCESS | 2178 | Values: 64.0 75.0 99.0 102.0 101.0 63.0 64.0 65.0 65.0 64.0 14:35:52 INFO - PROCESS | 2178 | 14:35:52 INFO - PROCESS | 2178 | [#9] json-stringify-tinderbox Cycles:10 Average:84.40 Median:70.50 stddev:21.74 (30.8%) stddev-sans-first:20.49 14:35:52 INFO - PROCESS | 2178 | Values: 56.0 102.0 65.0 90.0 122.0 113.0 80.0 69.0 75.0 72.0 14:35:52 INFO - PROCESS | 2178 | 14:35:52 INFO - PROCESS | 2178 | [#10] stanford-crypto-aes Cycles:10 Average:89.70 Median:90.00 stddev:1.89 (2.1%) stddev-sans-first:1.73 14:35:52 INFO - PROCESS | 2178 | Values: 87.0 90.0 92.0 90.0 87.0 90.0 93.0 89.0 90.0 89.0 14:35:52 INFO - PROCESS | 2178 | 14:35:52 INFO - PROCESS | 2178 | [#11] stanford-crypto-ccm Cycles:10 Average:155.30 Median:153.00 stddev:20.14 (13.2%) stddev-sans-first:20.68 14:35:52 INFO - PROCESS | 2178 | Values: 141.0 163.0 155.0 139.0 151.0 150.0 142.0 145.0 208.0 159.0 14:35:52 INFO - PROCESS | 2178 | 14:35:52 INFO - PROCESS | 2178 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:187.50 Median:183.00 stddev:26.69 (14.6%) stddev-sans-first:27.79 14:35:52 INFO - PROCESS | 2178 | Values: 173.0 184.0 215.0 182.0 169.0 159.0 162.0 216.0 239.0 176.0 14:35:52 INFO - PROCESS | 2178 | 14:35:52 INFO - PROCESS | 2178 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:78.00 Median:84.50 stddev:11.40 (13.5%) stddev-sans-first:11.62 14:35:52 INFO - PROCESS | 2178 | Values: 69.0 81.0 89.0 93.0 91.0 88.0 67.0 68.0 66.0 68.0 14:35:52 INFO - PROCESS | 2178 | -------- Summary: end -------- 14:35:52 INFO - PROCESS | 2178 | 14:35:53 INFO - TEST-INFO | 2178: exit 0 14:35:53 INFO - TEST-OK | kraken | took 61974ms 14:35:53 INFO - SUITE-END | took 1177s 14:35:53 INFO - Completed test suite (00:19:37) 14:35:53 INFO - javascript benchmark 14:35:53 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7095.644445948762, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3257.5928008998876, 3265.167382317509, 3269.1663625775423, 3294.2752594429226, 3297.8515966781492, 5429.0, 5437.646552974059, 5507.9449205507935, 5520.585956053292, 5538.307711536058, 5340.732963351827, 5448.7548060337285, 5518.641288316267, 5541.778328866851, 5585.1063829787145, 4967.925481117786, 4972.751362431882, 5026.29631851541, 5049.621278404116, 5060.873478163038, 4960.032793605248, 5025.824096156635, 5032.396112466509, 5146.125158723007, 5155.432902380735, 4981.476944920767, 5067.265246539268, 5071.2139618359215, 5078.873028174288, 5102.795888164487, 4976.2286845539, 5063.443021267658, 5077.466865979056, 5081.618878584084, 5095.694258344512, 4990.975045124787, 4995.9500404996215, 5031.06925218832, 5058.291839142523, 5062.696238225718, 4540.205464043812, 5386.380566234855, 5416.647917885325, 5463.098588732854, 5508.504234618864, 6003.729832157564, 6085.300190478063, 6115.727150604525, 6118.101457354478, 6119.265688117454, 6120.571559990803, 6170.321264660884, 6172.660503672296, 6174.783882564088, 6176.351483094255, 5128.794848206067, 5499.560035197174, 5697.9715101424645, 5701.173329867146, 5715.257016587817, 4901.828436004723, 5862.267216597926, 6207.720652570645, 6207.906881396782, 6231.688415579203, 5743.942560574341, 5993.250843644544, 6008.3390827009, 6026.0659597762415, 6027.156198132265, 5904.291485021825, 6040.244969378828, 6091.817245482643, 6119.776756597957, 6144.279601711528, 6105.702364394958, 6108.433136212329, 6113.4154954094565, 6113.749968913926, 6120.050983828535, 5813.8255852323655, 5887.5289976802605, 6023.488003519662, 6066.150738896558, 6068.423499767603, 4861.80552777892, 4871.29366241893, 4891.364122664031, 4896.045271172087, 4904.288878112654, 4335.653147748172, 4350.58669426404, 4354.934675979863, 4363.956360436418, 4393.29707246839, 5438.102713052381, 5487.314085739283, 5562.02664533705, 5594.9369619772115, 5605.607607467519, 6166.81499555014, 6187.102870083812, 6196.721147548371, 6199.442050215503, 6203.069539569101, 5621.915671264935, 5626.74679639409, 5633.971830140905, 5645.802396916089, 5652.3217213934595, 4526.072155208174, 5168.664036837594, 5186.766595503212, 5314.0, 5321.840344789585, 5075.5178258065425, 6120.204373431426, 6221.2845522763655, 6231.532635052389, 6248.312685604489, 6099.634021958696, 6100.054491553817, 6136.32500424944, 6155.138280640804, 6179.969100154471, 5035.723035232951, 6071.696415179312, 6086.969565152146, 6116.847078823065, 6223.439890409885, 5980.641161530322, 6035.064564992434, 6035.728392222272, 6077.574569780073, 6129.417705317987, 6074.149619053424, 6086.269647642312, 6091.360407157184, 6125.295591006913, 6131.938680613137, 5611.663300202001, 5733.483986441241, 5801.76792928295, 5930.881382372242, 6020.608660437058, 5094.286799848099, 5944.138099975566, 5956.8808623828145, 5960.8807823844145, 5985.102234664835, 4603.608693261111, 5710.143478478261, 5772.826815195551, 5789.1895134680335, 5804.303483581997, 5656.123300888369, 5783.929972954848, 5875.764969401176, 5893.557983151195, 5914.792982245601, 5723.112917497794, 5804.593678442638, 5808.709564521672, 5822.359540450631, 5850.414958504116], "value": 5511.779490118251, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [461.81989024280534, 487.4053654541458, 498.9925151122734, 503.7305041802636, 537.6344086021505, 30980.0, 33370.66629333703, 39315.0171245719, 43120.353194702104, 43168.56831431685, 42420.575794242046, 42780.0, 42844.785776071076, 42880.356794648105, 42887.57112428875, 9248.676296329613, 9433.198178154866, 9445.81108377832, 9499.145076943074, 10806.837897431546, 10860.185486088536, 11060.17048721345, 11099.55601775928, 11105.666829995112, 11129.554817807319, 11086.390248536327, 11101.111911047137, 11118.054965327958, 11138.443077846116, 11139.610113646024, 9363.625454981793, 11068.114550835913, 11071.335719856834, 11082.778344433147, 11094.278871873343, 10801.243912926098, 10874.238803283773, 10878.401687907162, 10882.29265097766, 10902.890971090346, 36582.26835463276, 36848.26303473942, 36901.0, 36903.261934761154, 37172.07069823249, 3855.101362578932, 3856.0359910022494, 3886.591907849663, 3898.649121579043, 3906.5898080701686, 27093.729062709317, 27186.0, 27212.0, 27407.45185096287, 27750.849242138334, 3092.721655051056, 3116.688331166888, 3128.857966842109, 3141.623005239363, 3183.4428974929297, 444832.0, 445762.0, 445952.0, 447155.76422118006, 450490.74754626345, 46282.537174628495, 47493.52506474891, 54989.450105499236, 55219.171712424344, 55369.72315138399, 55487.722561387745, 56487.43512564822, 56837.863242734915, 59623.40376596178, 59626.70186649126, 13920.443182272798, 14205.928970355082, 14244.572662820132, 14251.50119745804, 14275.85724142766, 10801.24391292602, 10903.0, 10904.291221070605, 10935.015848573667, 10939.960703733133, 13761.0, 15714.528564143095, 15901.920490397473, 17415.3033878646, 17645.558861028578, 6993.510454268152, 7490.325870671557, 7723.497972631761, 7851.528908265523, 7914.445988780845, 824.6577670266892, 834.9415540912078, 851.9318454523623, 858.0004294996385, 882.1222883231097, 26404.86797566038, 28393.29016774556, 29886.103416897095, 30169.245768855515, 30588.0, 1012.336919317848, 1041.5261056219554, 1061.6974162363845, 1064.9733756656146, 1164.4818055965013, 15383.769243461356, 15438.38246470151, 15463.762898968056, 15529.86589199724, 15821.841781582038, 521024.0, 564337.0, 564709.0, 564881.0, 565230.0, 44800.0, 58983.41016589779, 59478.10782838261, 59636.403635963085, 59822.70088649529, 1319.8812106910425, 1354.5326862232514, 1405.915645061279, 1409.6969151632447, 1410.2032351721246, 28958.7104128956, 29059.564106537997, 29128.417431651673, 29594.408111837212, 29877.402451951275, 47400.52599473961, 49876.25185622291, 49970.0, 50114.0, 50170.24744628907, 444490.0, 446288.0, 446316.0, 446598.0, 447451.0, 23634.29097127089, 24680.629790553514, 24781.504369912862, 25463.10879119296, 26051.739482605688, 41148.971275718344, 41261.1747765049, 41738.79130604328, 41988.95027624334, 42340.0, 34076.0, 34556.65443345534, 34753.82623086868, 35466.29067418585, 35681.28637427289, 28539.714602854536, 33663.99008029763, 34283.828580856934, 34593.827030865694, 35065.123371915906, 33769.49345759865, 33873.830630846685, 33986.15034624154, 34142.31715365629, 34361.140971475914, 29084.418311634072, 30842.691573083983, 30938.0718578443, 30972.845135774176, 31010.379792404477, 23051.19320823763, 23085.0, 23383.766162338838, 23559.528809423373, 23704.525909482058], "value": 19043.917940043488, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [317.94600898023054, 367.17202707893705, 385.78781670081446, 397.59643961379226, 412.1859327827541, 19324.059211699656, 19850.503737406572, 20377.184912603516, 20807.27174548891, 21056.157753689873, 20677.689834652494, 20918.372448826558, 21367.67948480774, 21373.465663358424, 21711.0, 5745.109508026242, 5745.281839770039, 5996.550258730613, 5999.580029397944, 6085.691576311092, 5627.352854421732, 5911.674857882815, 5992.790252341147, 6047.969760151215, 6059.848503787396, 5991.340952495222, 6008.3390827009, 6015.187949626788, 6020.45815876571, 6034.939650603482, 5022.773975171126, 5763.884722305552, 5866.706664666771, 5932.080527518242, 5994.8501287467725, 4409.625181859546, 5103.744812759384, 5421.1597202433295, 5755.0504166812425, 5775.682337471438, 9554.761130971712, 13378.598642040753, 13607.59177224675, 13735.656608584766, 13756.380962856696, 2517.5971844504793, 2550.6939167300043, 2637.5384307746067, 2653.548896687568, 2690.6636670416196, 9906.40561566301, 9982.201423886072, 10132.898671013269, 10296.639617613422, 10399.116075133623, 1408.6196726883786, 1455.86169313915, 1780.4213630570116, 1790.1228398084975, 1794.8310455114345, 107183.85632287388, 124200.36270152921, 128504.0, 137682.0, 141446.99999999898, 11634.825477617842, 11743.530258789553, 11881.40592970348, 11923.880761192451, 12067.517299308105, 15466.845331546541, 15483.84516154824, 15523.844761552466, 15526.767098493532, 15539.145347006022, 7770.650320735581, 7937.722179723682, 7975.84048319042, 7999.480033797785, 8091.838163236703, 7376.262373762581, 7389.298016688406, 7421.332080112816, 7466.178720340758, 7522.855986044061, 4736.478987311393, 4812.302216178635, 4820.590249828733, 4883.731394773322, 4894.828680996148, 1983.1274239334646, 2025.858189926691, 2028.8275496582955, 2043.3257025181654, 2044.9182032718525, 1001.6744558018673, 1009.9394036357842, 1014.8376259798397, 1017.7404761785697, 1041.6510468992822, 20426.591468170554, 21172.894135529223, 21498.892505537373, 21634.89182554077, 21761.782382176294, 1011.9898801011896, 1014.4775440648073, 1019.4749703902496, 1041.3075304922143, 1053.6312290698195, 10902.0, 11196.104311655048, 11235.719107022227, 11235.71910702239, 11256.493457794255, 620231.0, 661085.0, 674021.0, 684143.0, 685537.0, 144974.0, 148948.0, 152140.239298805, 153238.0, 153470.0, 819.5410570080774, 991.2862738828032, 1008.5713571731984, 1036.2538971940191, 1042.7966546523355, 18525.72211416857, 19578.706319404937, 20074.59850803005, 20114.698279525524, 20240.660291051812, 58134.4186558129, 58929.0, 59209.40790592039, 59411.51471213254, 59565.0, 80913.0, 86044.0, 87273.0, 87845.0, 87863.56068219618, 8422.494650321001, 8468.322534197372, 8564.229219370287, 8573.642248381744, 8579.442336248125, 9390.906090939003, 12395.380230988594, 12936.935315323362, 13410.463581457023, 13431.462741490623, 11196.776064478501, 11375.772484550098, 11641.59254426091, 11672.824907626562, 11851.992580630744, 10909.34543927367, 11275.718107047389, 11575.363355015219, 11697.23967942081, 11698.005669517846, 11469.426528673699, 11472.713182170179, 11788.823167652663, 11963.401829908644, 12002.519899204284, 8239.340852731886, 8455.915440845512, 8516.403851730567, 8528.872066918877, 8717.738467845975, 8584.0, 11252.831207532057, 11336.433178341214, 11532.94233528827, 11585.957263846294], "value": 9660.323039793479, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1226.2274766896853, 1247.3139773124778, 1352.7835546312592, 1396.6578188343858, 1417.0, 8168.91831081689, 8356.623951922164, 8634.4387614805, 8643.135686431353, 8664.74005779826, 8461.0, 8678.305735541157, 8696.21734043936, 8696.826063478742, 8718.302535797136, 2286.576983258094, 2307.9884600577057, 2318.9536209275802, 2326.9534609307802, 2337.602607556711, 2292.701948746669, 2326.4300246439648, 2331.6968794056834, 2332.3119679694532, 2332.346942856003, 2269.5687819314276, 2273.1702928431087, 2295.1966811615885, 2295.529416469628, 2296.0700916128994, 2182.6289530779804, 2242.4057624729458, 2262.8981695823645, 2265.6714776357335, 2268.965965510519, 1973.7236786849853, 2052.374025922093, 2262.4343914021497, 2274.863508189514, 2300.459392042869, 6968.268331825187, 8183.549904755236, 8513.53175575343, 8535.146485351477, 8552.615132318999, 3992.301347264217, 4830.9275360869615, 5246.029484545333, 5272.762725677354, 5326.0, 3077.7842752113015, 3423.4351332030187, 3441.483777433405, 3521.154922818505, 3522.8943131705832, 1197.144042009967, 1201.9579314723942, 1232.2360136715295, 1236.418883124939, 1257.1200159888115, 10773.515191816387, 11026.0, 11120.666380008613, 11159.553617855276, 11193.776124477547, 4434.55654434554, 4506.256467682796, 4738.407699037621, 4776.522347765195, 4783.425988881357, 8044.758657240292, 9595.184409325286, 9725.367851089657, 9727.124558789743, 9744.658936937172, 4199.0, 5051.368578927634, 5054.469280725544, 5060.974695126501, 5066.82266120684, 4667.346571479996, 4753.691010084333, 4760.0, 4803.255495398219, 4822.180229361017, 3361.0925050236788, 3810.0855794609647, 3839.788811615388, 3929.44987701722, 4007.2586571484367, 1265.1080987903506, 1286.7555164518712, 1289.5035411366691, 1298.6298904812083, 1299.9805002924963, 723.1177962885376, 729.8430837369991, 754.5925200391837, 754.9660265288076, 779.0534500581821, 5034.697918124924, 5562.221289019541, 5576.414476479992, 5593.916091258634, 5696.0031994400815, 569.9202111704365, 572.627791935237, 596.6002778138658, 601.2244204975533, 603.2218438214654, 3605.9973429493243, 3737.588865224823, 3757.755745876509, 3822.655960963471, 3830.9233815323537, 9806.754831129136, 10208.43853588045, 10208.540615672313, 10215.591376345012, 10226.744331391776, 10073.748156296004, 10110.797784044278, 10150.847737283946, 10152.796944061078, 10171.745706357253, 6529.542931994714, 7606.353459955966, 7646.273604007611, 7834.451588388872, 7905.446618736633, 5449.700266485382, 5493.7802487900035, 5554.555635549227, 5680.460356266148, 5709.200711900255, 8951.41815781972, 9078.909210907806, 9081.364304498622, 9224.631014759334, 9311.627534898798, 9158.450492970444, 9251.491167985825, 9359.485228312507, 9360.672376466791, 9404.482753448352, 7193.4604904631315, 7303.233160518282, 7305.525140865827, 7311.634418279171, 7313.524620899624, 7850.842983140419, 7860.253275938777, 7868.606569671608, 7936.325412340016, 7987.880181797394, 7126.821829454305, 7144.035555200189, 7154.534955227893, 7236.38490728294, 7243.384312333511, 6126.326104128631, 6814.182298124257, 6917.861642767217, 7006.6846991886505, 7053.929460705533, 6304.621722696653, 6399.64801935898, 6400.39196276353, 6413.583117097561, 6415.743370265137, 4816.735079570657, 4821.614270858253, 4977.253411988231, 5026.045051440215, 5168.224766285088, 3876.903077423087, 4991.5258050485145, 5076.898462030665, 5094.490550944876, 5118.360204974379], "value": 4321.6034049287755, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [314.2411077248445, 349.2351749668226, 363.92539529396475, 365.78784305103034, 372.3130823630401, 4718.646101542381, 5027.09512287788, 5237.921431178531, 5395.0, 5434.375046869611, 3967.4048892666115, 4572.017016341486, 4767.547083027115, 4791.706239315383, 5154.458781827911, 3139.9952015355098, 3803.50554427924, 3956.6636835868985, 4114.156597897424, 4156.646685031776, 3831.942520862189, 3931.702538162414, 4049.2711311963835, 4141.62725354718, 4150.481189851269, 3685.649863262999, 3981.7212795104356, 4002.8398864045403, 4010.5788892166192, 4018.1963607278517, 3616.6021737608844, 3690.907727306812, 3926.6466018058227, 3993.4209539616736, 4010.9999999999854, 3484.1986343140966, 3490.581130264359, 3497.2306092659574, 3502.4571191465366, 3510.9473357899396, 4978.415540810611, 5050.217216331474, 5138.634194602541, 5172.58619310458, 5206.713630750307, 4309.078738348088, 4311.417958575584, 4595.724256544618, 4623.352730617717, 4625.7687115644085, 5549.805756798533, 5578.721063946787, 5600.831975040755, 5621.747021384048, 5621.8594535136535, 3615.969448707132, 3824.6749026332795, 3849.3071247175776, 3862.6909847212155, 3908.511436070491, 4926.605871530269, 5425.724954635657, 5575.243798203553, 5728.513076388512, 5837.118316319685, 3705.4997575326984, 3857.903552411156, 3861.2431343739095, 3873.12854607711, 3885.1452680410266, 3917.275304068756, 3934.232824599233, 3941.5664276929515, 3959.604039596075, 4061.1268577256033, 3809.7142714296538, 4523.615492683159, 4553.4991150973365, 4581.518940511265, 4587.958358530489, 3870.2521209941256, 4608.354830323758, 5090.0, 5257.004676445928, 5591.916121258185, 1152.9538818447168, 1237.6782036670531, 1250.8686587908321, 1253.3921048291572, 1253.6928452529007, 804.951702897828, 822.9917700823034, 833.8123922117475, 843.7342237195264, 879.6569337958201, 4226.788660566959, 4250.192463431938, 4949.109160351171, 4974.9354018082095, 4992.925106123411, 5034.651956448994, 5324.5740340772645, 5484.314460692413, 5499.587530935196, 5525.392206857243, 4698.671093023456, 4942.912559236962, 4955.331030310934, 4991.3511243538105, 5082.517160869711, 2479.701996344857, 2483.255023492945, 2687.4625074985083, 2731.0714357118277, 2875.7699384049224, 4758.547937945889, 5057.418396884332, 5675.318961724537, 5711.771529138871, 5754.338251101093, 4955.76990354342, 5249.632525723163, 5365.678059316453, 5660.943390566042, 5723.570732195102, 5421.728913554385, 5629.268195134525, 5652.3217213934595, 5661.179129026268, 5701.71491425427, 4879.853604391874, 5491.670499770026, 5502.779888804403, 5526.982651332315, 5536.280283563111, 4556.6582506312825, 5065.924011139762, 5164.431912489698, 5323.057084164175, 5487.75305111263, 4621.353010578455, 4705.388299521041, 4787.832425865078, 5672.064109421899, 5697.7720891165545, 3838.788866612363, 3844.9807750961068, 3850.4994350735346, 3861.1505468796818, 3885.8057097145597, 5230.801500222249, 5493.0, 5604.8879022418505, 5715.799417224482, 5784.132380142844, 5232.5552328052545, 5513.448655134454, 5529.806456774155, 5530.889382212414, 5608.439156084359, 4438.289873620205, 4806.375171227718, 5562.527185189304, 5583.659921618701, 5599.384067752462, 4676.415448068991, 4796.472388037383, 5314.3091398117995, 5431.293931788842, 5471.987682278632, 4193.496780386251, 5009.699418034929, 5301.867453313698, 5389.27244821944, 5510.858810477989, 4897.534734200245, 5080.593552515864, 5440.836774896759, 5454.672719636835, 5501.889962200814], "value": 3981.842736251113, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [622.8660837919846, 698.6751160710269, 772.2084863015408, 826.5330088499998, 835.9874601880973, 13884.79172812406, 13943.233122178277, 13970.650733731662, 14791.999999999987, 15010.249487525622, 13496.257705826176, 13571.185728856275, 13830.308484575782, 13842.238676872768, 13847.723045539084, 851.9233269005788, 857.8198578298565, 889.5374405309235, 893.0622846011694, 896.0680891872445, 868.1578868497547, 881.9294456443469, 883.7878909061811, 886.9423487473326, 887.5917078144062, 802.173761026141, 932.9860052099224, 936.5925822267302, 939.7979434421597, 939.7979434421597, 932.8927173375047, 932.9533523323875, 933.9719808405691, 934.7476181431043, 937.7889974755693, 692.4875592061888, 692.5256199503306, 695.2144077192739, 696.053367420308, 696.2793508718452, 14682.339294731762, 14930.0, 14932.402703891832, 14950.177740224284, 15040.32318545668, 12370.072244581603, 13301.467941282337, 13657.180569165783, 13665.658358541017, 13680.384382702803, 12222.205556638879, 12620.116591838574, 12653.936730316382, 12886.80669789954, 12969.41637626309, 4320.611144996966, 4429.778511074465, 4456.77716114193, 4537.818487260505, 4560.087982403533, 96521.5173924133, 96886.0311396884, 98124.50937745337, 98249.50875245649, 100910.49544752303, 10645.28651198903, 10753.569857205624, 10758.300710453796, 10842.891571084347, 11095.778084438427, 20470.795292047187, 20471.692924606145, 20476.795232047785, 20491.59016819685, 20525.486862828548, 7426.777196684108, 7481.438892083115, 7485.925140748523, 7567.962160189164, 7624.771256862303, 3544.03886881774, 4106.856260030884, 4200.285951388271, 4213.073123912734, 4225.176090662353, 3438.8796392126155, 3498.6851183393615, 3498.9475157872653, 3519.9296014079573, 3535.116220944764, 668.0547025958215, 668.5525836563882, 698.8707089188516, 736.2343163110305, 746.0, 607.3804719186405, 672.9629870357178, 709.5742554467381, 709.7444919828857, 716.9390601798801, 7946.131958824327, 8775.605097770616, 9132.63010548423, 9606.847178338645, 9948.90216780192, 645.8901986662279, 650.9381608747161, 685.8902575587882, 690.1717938473852, 695.2699665351462, 3619.7104231661406, 3836.405357169643, 4389.868303950887, 4580.679352445297, 4604.286335617984, 61936.38063619306, 74928.50142997218, 88644.55677721699, 91423.0, 92858.0, 50097.74951125221, 58098.12852807211, 70305.2969470309, 83620.0, 85527.14472855316, 58646.0, 65696.67151664307, 72877.27122728704, 76749.84875226876, 78010.0, 8404.36967227448, 10086.495675216356, 10582.417967011888, 10595.47022648849, 10613.41626210535, 42508.574914250465, 47668.0, 48352.274715878586, 51046.0, 52070.0, 65029.349706504225, 67014.0, 67507.66246168934, 68222.3177768216, 69940.6502967482, 10215.489225538544, 12471.750564988832, 12726.109172358216, 12727.363631818187, 12862.549810756578, 14331.426742930584, 14501.274936252934, 14546.636334091732, 14676.950259571062, 14687.265636717908, 13341.791096552335, 13401.798973015217, 13416.530421435204, 13730.382132804247, 13748.312584370942, 13421.561338289963, 13423.664408389868, 13513.594592162252, 13553.932230338785, 13720.93139534296, 13138.343082846011, 13235.867641323464, 13367.732645346845, 13756.862431375559, 13793.379297931819, 8392.706255281037, 10696.839547406951, 11010.0, 11121.721956951142, 11289.37908415045, 8043.115257321573, 9151.588178531849, 10358.792824143626, 10368.637097701545, 10444.268901177149], "value": 7313.647485996985, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1607.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 120.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 116.0, 119.0], "value": 117.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 115.0, 113.0, 150.0, 111.0, 116.0, 117.0, 149.0, 123.0, 141.0], "value": 128.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 199.0, 205.0, 214.0, 205.0, 216.0, 195.0, 198.0, 193.0, 222.0], "value": 204.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 59.0, 61.0, 87.0, 59.0, 69.0, 71.0, 59.0, 73.0, 61.0], "value": 68.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 82.0, 73.0, 77.0, 101.0, 104.0, 119.0, 102.0, 71.0, 86.0], "value": 93.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 118.0, 136.0, 102.0, 88.0, 84.0, 113.0, 131.0, 89.0, 85.0], "value": 102.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 99.0, 100.0, 139.0, 106.0, 154.0, 107.0, 114.0, 146.0, 100.0], "value": 116.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 131.0, 91.0, 119.0, 112.0, 91.0, 96.0, 93.0, 94.0, 146.0], "value": 107.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 75.0, 99.0, 102.0, 101.0, 63.0, 64.0, 65.0, 65.0, 64.0], "value": 76.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 102.0, 65.0, 90.0, 122.0, 113.0, 80.0, 69.0, 75.0, 72.0], "value": 84.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 90.0, 92.0, 90.0, 87.0, 90.0, 93.0, 89.0, 90.0, 89.0], "value": 89.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 163.0, 155.0, 139.0, 151.0, 150.0, 142.0, 145.0, 208.0, 159.0], "value": 155.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 184.0, 215.0, 182.0, 169.0, 159.0, 162.0, 216.0, 239.0, 176.0], "value": 187.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 81.0, 89.0, 93.0, 91.0, 88.0, 67.0, 68.0, 66.0, 68.0], "value": 78.0, "unit": "score"}]}]} 14:35:54 INFO - Return code: 0 14:35:54 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:35:54 ERROR - # TBPL SUCCESS # 14:35:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:35:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:35:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:35:54 INFO - 2017-02-05 14:35:54.075 screenresolution[2183:18642] starting screenresolution argv=screenresolution get 14:35:54 INFO - 2017-02-05 14:35:54.077 screenresolution[2183:18642] Display 0: 1600x1200x32@60 14:35:54 INFO - 2017-02-05 14:35:54.084 screenresolution[2184:18645] starting screenresolution argv=screenresolution list 14:35:54 INFO - Available Modes on Display 0 14:35:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:35:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:35:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:35:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:35:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:35:54 INFO - Intel Iris: 14:35:54 INFO - Chipset Model: Intel Iris 14:35:54 INFO - Type: GPU 14:35:54 INFO - Bus: Built-In 14:35:54 INFO - VRAM (Dynamic, Max): 1536 MB 14:35:54 INFO - Vendor: Intel (0x8086) 14:35:54 INFO - Device ID: 0x0a2e 14:35:54 INFO - Revision ID: 0x0009 14:35:54 INFO - Displays: 14:35:54 INFO - Display: 14:35:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:35:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:35:54 INFO - Display Serial Number: 1600x1200 60 14:35:54 INFO - Main Display: Yes 14:35:54 INFO - Mirror: Off 14:35:54 INFO - Online: Yes 14:35:54 INFO - Rotation: Supported 14:35:54 INFO - Return code: 0 14:35:54 INFO - Running post-action listener: _resource_record_post_action 14:35:54 INFO - [mozharness: 2017-02-05 22:35:54.319297Z] Finished run-tests step (success) 14:35:54 INFO - Running post-run listener: _resource_record_post_run 14:35:54 INFO - Total resource usage - Wall time: 1200s; CPU: 54.0%; Read bytes: 1746944; Write bytes: 557258240; Read time: 22; Write time: 1640 14:35:54 INFO - TinderboxPrint: CPU usage
54.4% 14:35:54 INFO - TinderboxPrint: I/O read bytes / time
1,746,944 / 22 14:35:54 INFO - TinderboxPrint: I/O write bytes / time
557,258,240 / 1,640 14:35:54 INFO - TinderboxPrint: CPU idle
2,188.3 (45.6%) 14:35:54 INFO - TinderboxPrint: CPU system
964.2 (20.1%) 14:35:54 INFO - TinderboxPrint: CPU user
1,649.2 (34.3%) 14:35:54 INFO - TinderboxPrint: Swap in / out
254,148,608 / 0 14:35:54 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 252709376; Write bytes: 252780544; Read time: 18173; Write time: 474 14:35:54 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 1689088; Write bytes: 303109632; Read time: 21; Write time: 1158 14:35:54 INFO - Running post-run listener: _upload_blobber_files 14:35:54 INFO - Blob upload gear active. 14:35:54 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:35:54 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 14:35:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:35:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:35:55 INFO - (blobuploader) - INFO - Open directory for files ... 14:35:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 14:35:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:35:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:35:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 14:35:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:35:56 INFO - (blobuploader) - INFO - Done attempting. 14:35:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 14:35:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:35:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:35:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 14:35:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:35:58 INFO - (blobuploader) - INFO - Done attempting. 14:35:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 14:35:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:35:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:35:59 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 14:35:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:35:59 INFO - (blobuploader) - INFO - Done attempting. 14:35:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:35:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:35:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:36:01 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:36:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:36:01 INFO - (blobuploader) - INFO - Done attempting. 14:36:01 INFO - (blobuploader) - INFO - Iteration through files over. 14:36:01 INFO - Return code: 0 14:36:01 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:36:01 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:36:01 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cfb4c4a01bae9c9be60ce4f2decf8d4ff843f16ace1d11d95028e0d9711492c8c6cedc73f087e078ce332f9cdff65e64c1e7baa039370b752390ab852215439c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fe309fe673bd91e841933671e96d076a30cf6d4e36942d754c4eabf24812b6afaab6d4ae0d583ad7175ea3f646e08364d3d9c6d0ae35dca90cf8e7f4b4bf7aaf", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4a5640de49b0544ab6a3a47f9bd08e282ce172eece4376c64d0f87810a3e60a4cf479e6cec0aed3581a88cddb2b22cc147bf806356463ebb1a2288103ac1f038", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/867a0c210fbaa23f8148becf282ff1cfaef5a4c0eb4aae50cb3f160b431e948f22ba97bd3977d1109b186d7858a7863212972f7f40cab667dccc33d2e5c9042b"} 14:36:01 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:36:01 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:36:01 INFO - Contents: 14:36:01 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cfb4c4a01bae9c9be60ce4f2decf8d4ff843f16ace1d11d95028e0d9711492c8c6cedc73f087e078ce332f9cdff65e64c1e7baa039370b752390ab852215439c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fe309fe673bd91e841933671e96d076a30cf6d4e36942d754c4eabf24812b6afaab6d4ae0d583ad7175ea3f646e08364d3d9c6d0ae35dca90cf8e7f4b4bf7aaf", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4a5640de49b0544ab6a3a47f9bd08e282ce172eece4376c64d0f87810a3e60a4cf479e6cec0aed3581a88cddb2b22cc147bf806356463ebb1a2288103ac1f038", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/867a0c210fbaa23f8148becf282ff1cfaef5a4c0eb4aae50cb3f160b431e948f22ba97bd3977d1109b186d7858a7863212972f7f40cab667dccc33d2e5c9042b"} 14:36:01 INFO - Running post-run listener: copy_logs_to_upload_dir 14:36:01 INFO - Copying logs to upload dir... 14:36:01 INFO - mkdir: /builds/slave/test/build/upload/logs 14:36:01 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1264.296076 ========= master_lag: 0.18 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2017-02-05 14:36:01.555305) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-05 14:36:01.562680) ========= 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.9tx6zCukH5/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.EUA7otxdoc/Listeners TMPDIR=/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cfb4c4a01bae9c9be60ce4f2decf8d4ff843f16ace1d11d95028e0d9711492c8c6cedc73f087e078ce332f9cdff65e64c1e7baa039370b752390ab852215439c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fe309fe673bd91e841933671e96d076a30cf6d4e36942d754c4eabf24812b6afaab6d4ae0d583ad7175ea3f646e08364d3d9c6d0ae35dca90cf8e7f4b4bf7aaf", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4a5640de49b0544ab6a3a47f9bd08e282ce172eece4376c64d0f87810a3e60a4cf479e6cec0aed3581a88cddb2b22cc147bf806356463ebb1a2288103ac1f038", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/867a0c210fbaa23f8148becf282ff1cfaef5a4c0eb4aae50cb3f160b431e948f22ba97bd3977d1109b186d7858a7863212972f7f40cab667dccc33d2e5c9042b"} build_url:https://queue.taskcluster.net/v1/task/GmTt9U59QleKKFMwB6xn6Q/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011171 build_url: 'https://queue.taskcluster.net/v1/task/GmTt9U59QleKKFMwB6xn6Q/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cfb4c4a01bae9c9be60ce4f2decf8d4ff843f16ace1d11d95028e0d9711492c8c6cedc73f087e078ce332f9cdff65e64c1e7baa039370b752390ab852215439c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fe309fe673bd91e841933671e96d076a30cf6d4e36942d754c4eabf24812b6afaab6d4ae0d583ad7175ea3f646e08364d3d9c6d0ae35dca90cf8e7f4b4bf7aaf", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4a5640de49b0544ab6a3a47f9bd08e282ce172eece4376c64d0f87810a3e60a4cf479e6cec0aed3581a88cddb2b22cc147bf806356463ebb1a2288103ac1f038", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/867a0c210fbaa23f8148becf282ff1cfaef5a4c0eb4aae50cb3f160b431e948f22ba97bd3977d1109b186d7858a7863212972f7f40cab667dccc33d2e5c9042b"}' ========= master_lag: 0.89 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-05 14:36:02.460612) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 9 secs) (at 2017-02-05 14:36:02.460967) ========= 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.9tx6zCukH5/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.EUA7otxdoc/Listeners TMPDIR=/var/folders/b5/152b2m257t95w2vfwktssysw00000w/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.005183 ========= master_lag: 9.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 9 secs) (at 2017-02-05 14:36:11.486108) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-05 14:36:11.486474) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-05 14:36:11.502220) ========= ========= Total master_lag: 10.17 =========