builder: cedar_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0178 starttime: 1481648114.34 results: success (0) buildid: 20161213070203 builduid: dec8f402f9aa421f85ff02eac44b5285 revision: 6f4475a780411b76f6f51328666722b6d81f80ff ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-13 08:55:14.340717) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-13 08:55:14.341203) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-13 08:55:14.341533) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-13 08:55:14.369581) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-13 08:55:14.369978) ========= 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.Co6HtmUsxP/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.aeM7bW4KHu/Listeners TMPDIR=/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-12-13 08:55:14-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.37M=0.001s 2016-12-13 08:55:14 (8.37 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.091952 ========= master_lag: 0.36 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-13 08:55:14.817675) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-13 08:55:14.818099) ========= 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.Co6HtmUsxP/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.aeM7bW4KHu/Listeners TMPDIR=/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/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.019925 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-13 08:55:14.861002) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-13 08:55:14.861373) ========= bash -c 'python archiver_client.py mozharness --repo projects/cedar --rev 6f4475a780411b76f6f51328666722b6d81f80ff --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/cedar --rev 6f4475a780411b76f6f51328666722b6d81f80ff --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Co6HtmUsxP/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.aeM7bW4KHu/Listeners TMPDIR=/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-12-13 08:55:14,927 truncating revision to first 12 chars 2016-12-13 08:55:14,927 Setting DEBUG logging. 2016-12-13 08:55:14,927 attempt 1/10 2016-12-13 08:55:14,927 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/cedar/6f4475a78041?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-13 08:55:15,586 unpacking tar archive at: cedar-6f4475a78041/testing/mozharness/ program finished with exit code 0 elapsedTime=0.912362 ========= master_lag: 0.17 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-13 08:55:15.946614) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-13 08:55:15.949976) ========= script_repo_revision: 6f4475a780411b76f6f51328666722b6d81f80ff ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-13 08:55:15.950365) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-13 08:55:15.950634) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-13 08:55:15.972700) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2016-12-13 08:55:15.973111) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Cedar --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Cedar 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', 'Cedar', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Cedar'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Co6HtmUsxP/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.aeM7bW4KHu/Listeners TMPDIR=/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/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 08:55:16 INFO - MultiFileLogger online at 20161213 08:55:16 in /builds/slave/test 08:55:16 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Cedar --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Cedar 08:55:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:55:16 INFO - {'append_to_log': False, 08:55:16 INFO - 'base_work_dir': '/builds/slave/test', 08:55:16 INFO - 'blob_upload_branch': 'Cedar', 08:55:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:55:16 INFO - 'branch': 'Cedar', 08:55:16 INFO - 'buildbot_json_path': 'buildprops.json', 08:55:16 INFO - 'config_files': ('talos/mac_config.py',), 08:55:16 INFO - 'default_actions': ('clobber', 08:55:16 INFO - 'read-buildbot-config', 08:55:16 INFO - 'download-and-extract', 08:55:16 INFO - 'populate-webroot', 08:55:16 INFO - 'create-virtualenv', 08:55:16 INFO - 'install', 08:55:16 INFO - 'run-tests'), 08:55:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:55:16 INFO - 'download_minidump_stackwalk': True, 08:55:16 INFO - 'download_symbols': 'ondemand', 08:55:16 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:55:16 INFO - 'tooltool.py': '/tools/tooltool.py', 08:55:16 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:55:16 INFO - '/tools/misc-python/virtualenv.py')}, 08:55:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:55:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:55:16 INFO - 'installer_path': 'installer.exe', 08:55:16 INFO - 'log_level': 'info', 08:55:16 INFO - 'log_name': 'talos', 08:55:16 INFO - 'log_to_console': True, 08:55:16 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:55:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:55:16 INFO - 'opt_config_files': (), 08:55:16 INFO - 'pip_index': False, 08:55:16 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:55:16 INFO - 'cmd': ('bash', 08:55:16 INFO - '-c', 08:55:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:55:16 INFO - 'enabled': True, 08:55:16 INFO - 'halt_on_failure': False, 08:55:16 INFO - 'name': 'check_screen_resolution'},), 08:55:16 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:55:16 INFO - 'cmd': ('bash', 08:55:16 INFO - '-c', 08:55:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:55:16 INFO - 'enabled': True, 08:55:16 INFO - 'halt_on_failure': False, 08:55:16 INFO - 'name': 'check_screen_resolution'},), 08:55:16 INFO - 'run_cmd_checks_enabled': True, 08:55:16 INFO - 'sps_profile': False, 08:55:16 INFO - 'sps_profile_interval': 0, 08:55:16 INFO - 'suite': 'dromaeojs-e10s', 08:55:16 INFO - 'system_bits': '32', 08:55:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:55:16 INFO - 'title': 't-yosemite-r7-0178', 08:55:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:55:16 INFO - 'use_talos_json': True, 08:55:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:55:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:55:16 INFO - 'work_dir': 'build'} 08:55:16 INFO - [mozharness: 2016-12-13 16:55:16.165473Z] Running clobber step. 08:55:16 INFO - Running pre-action listener: _resource_record_pre_action 08:55:16 INFO - Running main action method: clobber 08:55:16 INFO - rmtree: /builds/slave/test/build 08:55:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:55:16 INFO - Running post-action listener: _resource_record_post_action 08:55:16 INFO - [mozharness: 2016-12-13 16:55:16.323435Z] Finished clobber step (success) 08:55:16 INFO - [mozharness: 2016-12-13 16:55:16.323561Z] Running read-buildbot-config step. 08:55:16 INFO - Running pre-action listener: _resource_record_pre_action 08:55:16 INFO - Running main action method: read_buildbot_config 08:55:16 INFO - Using buildbot properties: 08:55:16 INFO - { 08:55:16 INFO - "project": "", 08:55:16 INFO - "product": "firefox", 08:55:16 INFO - "script_repo_revision": "default", 08:55:16 INFO - "builddir": "cedar_yosemite_r7_test-dromaeojs-e10s", 08:55:16 INFO - "repository": "", 08:55:16 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 cedar talos dromaeojs-e10s", 08:55:16 INFO - "buildid": "20161213070203", 08:55:16 INFO - "pgo_build": "False", 08:55:16 INFO - "basedir": "/builds/slave/test", 08:55:16 INFO - "buildnumber": 0, 08:55:16 INFO - "slavename": "t-yosemite-r7-0178", 08:55:16 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 08:55:16 INFO - "platform": "yosemite_r7", 08:55:16 INFO - "scheduler": "tests-cedar-macosx64-talos", 08:55:16 INFO - "branch": "cedar", 08:55:16 INFO - "repo_path": "projects/cedar", 08:55:16 INFO - "revision": "6f4475a780411b76f6f51328666722b6d81f80ff", 08:55:16 INFO - "stage_platform": "macosx64", 08:55:16 INFO - "builduid": "dec8f402f9aa421f85ff02eac44b5285", 08:55:16 INFO - "slavebuilddir": "test" 08:55:16 INFO - } 08:55:16 INFO - Found installer url https://queue.taskcluster.net/v1/task/F9BEDpgTQA6owFWEPmgLnw/artifacts/public/build/firefox-53.0a1.en-US.mac64.dmg. 08:55:16 INFO - Running post-action listener: _resource_record_post_action 08:55:16 INFO - [mozharness: 2016-12-13 16:55:16.326802Z] Finished read-buildbot-config step (success) 08:55:16 INFO - [mozharness: 2016-12-13 16:55:16.326886Z] Running download-and-extract step. 08:55:16 INFO - Running pre-action listener: _resource_record_pre_action 08:55:16 INFO - Running main action method: download_and_extract 08:55:16 INFO - mkdir: /builds/slave/test/build/tests 08:55:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:55:16 INFO - trying https://queue.taskcluster.net/v1/task/F9BEDpgTQA6owFWEPmgLnw/artifacts/public/build/firefox-53.0a1.en-US.mac64.test_packages.json 08:55:16 INFO - Downloading https://queue.taskcluster.net/v1/task/F9BEDpgTQA6owFWEPmgLnw/artifacts/public/build/firefox-53.0a1.en-US.mac64.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac64.test_packages.json 08:55:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/F9BEDpgTQA6owFWEPmgLnw/artifacts/public/build/firefox-53.0a1.en-US.mac64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac64.test_packages.json'}, attempt #1 08:55:17 INFO - Downloaded 1235 bytes. 08:55:17 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac64.test_packages.json 08:55:17 INFO - Using the following test package requirements: 08:55:17 INFO - {u'common': [u'firefox-53.0a1.en-US.mac64.common.tests.zip'], 08:55:17 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac64.common.tests.zip', 08:55:17 INFO - u'firefox-53.0a1.en-US.mac64.cppunittest.tests.zip'], 08:55:17 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac64.common.tests.zip', 08:55:17 INFO - u'firefox-53.0a1.en-US.mac64.gtest.tests.zip'], 08:55:17 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac64.common.tests.zip', 08:55:17 INFO - u'jsshell-mac64.zip'], 08:55:17 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac64.common.tests.zip', 08:55:17 INFO - u'firefox-53.0a1.en-US.mac64.mochitest.tests.zip'], 08:55:17 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac64.common.tests.zip'], 08:55:17 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac64.common.tests.zip', 08:55:17 INFO - u'firefox-53.0a1.en-US.mac64.reftest.tests.zip'], 08:55:17 INFO - u'talos': [u'firefox-53.0a1.en-US.mac64.common.tests.zip', 08:55:17 INFO - u'firefox-53.0a1.en-US.mac64.talos.tests.zip'], 08:55:17 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac64.common.tests.zip', 08:55:17 INFO - u'firefox-53.0a1.en-US.mac64.web-platform.tests.zip'], 08:55:17 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac64.common.tests.zip', 08:55:17 INFO - u'firefox-53.0a1.en-US.mac64.xpcshell.tests.zip']} 08:55:17 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac64.common.tests.zip'] for test suite category: common 08:55:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/F9BEDpgTQA6owFWEPmgLnw/artifacts/public/build/firefox-53.0a1.en-US.mac64.common.tests.zip 08:55:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/F9BEDpgTQA6owFWEPmgLnw/artifacts/public/build/firefox-53.0a1.en-US.mac64.common.tests.zip'}, attempt #1 08:55:17 INFO - Fetch https://queue.taskcluster.net/v1/task/F9BEDpgTQA6owFWEPmgLnw/artifacts/public/build/firefox-53.0a1.en-US.mac64.common.tests.zip into memory 08:55:18 INFO - Expected file size: 16276100 08:55:18 INFO - Obtained file size: 16276100 08:55:25 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac64.common.tests.zip', u'firefox-53.0a1.en-US.mac64.talos.tests.zip'] for test suite category: talos 08:55:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/F9BEDpgTQA6owFWEPmgLnw/artifacts/public/build/firefox-53.0a1.en-US.mac64.common.tests.zip 08:55:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/F9BEDpgTQA6owFWEPmgLnw/artifacts/public/build/firefox-53.0a1.en-US.mac64.common.tests.zip'}, attempt #1 08:55:25 INFO - Fetch https://queue.taskcluster.net/v1/task/F9BEDpgTQA6owFWEPmgLnw/artifacts/public/build/firefox-53.0a1.en-US.mac64.common.tests.zip into memory 08:55:26 INFO - Expected file size: 16276100 08:55:26 INFO - Obtained file size: 16276100 08:55:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/F9BEDpgTQA6owFWEPmgLnw/artifacts/public/build/firefox-53.0a1.en-US.mac64.talos.tests.zip 08:55:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/F9BEDpgTQA6owFWEPmgLnw/artifacts/public/build/firefox-53.0a1.en-US.mac64.talos.tests.zip'}, attempt #1 08:55:32 INFO - Fetch https://queue.taskcluster.net/v1/task/F9BEDpgTQA6owFWEPmgLnw/artifacts/public/build/firefox-53.0a1.en-US.mac64.talos.tests.zip into memory 08:55:33 INFO - Expected file size: 14409734 08:55:33 INFO - Obtained file size: 14409734 08:55:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:55:33 INFO - trying https://queue.taskcluster.net/v1/task/F9BEDpgTQA6owFWEPmgLnw/artifacts/public/build/firefox-53.0a1.en-US.mac64.dmg 08:55:33 INFO - Downloading https://queue.taskcluster.net/v1/task/F9BEDpgTQA6owFWEPmgLnw/artifacts/public/build/firefox-53.0a1.en-US.mac64.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac64.dmg 08:55:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/F9BEDpgTQA6owFWEPmgLnw/artifacts/public/build/firefox-53.0a1.en-US.mac64.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac64.dmg'}, attempt #1 08:55:35 INFO - Downloaded 69664162 bytes. 08:55:35 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/F9BEDpgTQA6owFWEPmgLnw/artifacts/public/build/firefox-53.0a1.en-US.mac64.dmg 08:55:35 INFO - mkdir: /builds/slave/test/properties 08:55:35 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:55:35 INFO - Writing to file /builds/slave/test/properties/build_url 08:55:35 INFO - Contents: 08:55:35 INFO - build_url:https://queue.taskcluster.net/v1/task/F9BEDpgTQA6owFWEPmgLnw/artifacts/public/build/firefox-53.0a1.en-US.mac64.dmg 08:55:36 INFO - Running post-action listener: _resource_record_post_action 08:55:36 INFO - Running post-action listener: set_extra_try_arguments 08:55:36 INFO - [mozharness: 2016-12-13 16:55:36.255350Z] Finished download-and-extract step (success) 08:55:36 INFO - [mozharness: 2016-12-13 16:55:36.255485Z] Running populate-webroot step. 08:55:36 INFO - Running pre-action listener: _resource_record_pre_action 08:55:36 INFO - Running main action method: populate_webroot 08:55:36 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:55:36 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:55:36 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:55:36 INFO - '--tppagecycles', 08:55:36 INFO - '7'], 08:55:36 INFO - 'tests': ['tsvgm']}}, 08:55:36 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:55:36 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:55:36 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:55:36 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:55:36 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:55:36 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:55:36 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:55:36 INFO - 'talos_options': ['--disable-e10s'], 08:55:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:55:36 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:55:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:55:36 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:55:36 INFO - 'talos_options': ['--disable-e10s'], 08:55:36 INFO - 'tests': ['damp', 'tps']}, 08:55:36 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:55:36 INFO - 'tests': ['damp', 'tps']}, 08:55:36 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:55:36 INFO - 'tests': ['dromaeo_dom']}, 08:55:36 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:55:36 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:55:36 INFO - 'tests': ['basic_compositor_video']}, 08:55:36 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:55:36 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:55:36 INFO - 'tests': ['a11yr', 08:55:36 INFO - 'ts_paint', 08:55:36 INFO - 'tpaint', 08:55:36 INFO - 'sessionrestore', 08:55:36 INFO - 'sessionrestore_no_auto_restore', 08:55:36 INFO - 'tabpaint']}, 08:55:36 INFO - 'other-e10s': {'tests': ['a11yr', 08:55:36 INFO - 'ts_paint', 08:55:36 INFO - 'tpaint', 08:55:36 INFO - 'sessionrestore', 08:55:36 INFO - 'sessionrestore_no_auto_restore', 08:55:36 INFO - 'tabpaint']}, 08:55:36 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:55:36 INFO - 'ts_paint', 08:55:36 INFO - 'tpaint', 08:55:36 INFO - 'sessionrestore', 08:55:36 INFO - 'sessionrestore_no_auto_restore', 08:55:36 INFO - 'tabpaint']}, 08:55:36 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:55:36 INFO - 'ts_paint', 08:55:36 INFO - 'tpaint', 08:55:36 INFO - 'sessionrestore', 08:55:36 INFO - 'sessionrestore_no_auto_restore', 08:55:36 INFO - 'tabpaint']}, 08:55:36 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:55:36 INFO - 'tests': ['a11yr', 08:55:36 INFO - 'ts_paint', 08:55:36 INFO - 'tpaint', 08:55:36 INFO - 'sessionrestore', 08:55:36 INFO - 'sessionrestore_no_auto_restore', 08:55:36 INFO - 'tabpaint']}, 08:55:36 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:55:36 INFO - 'tests': ['a11yr', 08:55:36 INFO - 'ts_paint', 08:55:36 INFO - 'tpaint', 08:55:36 INFO - 'sessionrestore', 08:55:36 INFO - 'sessionrestore_no_auto_restore', 08:55:36 INFO - 'tabpaint']}, 08:55:36 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:55:36 INFO - 'tests': ['tsvgx', 08:55:36 INFO - 'tsvgr_opacity', 08:55:36 INFO - 'tart', 08:55:36 INFO - 'tscrollx', 08:55:36 INFO - 'cart', 08:55:36 INFO - 'tsvg_static']}, 08:55:36 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:55:36 INFO - 'tsvgr_opacity', 08:55:36 INFO - 'tart', 08:55:36 INFO - 'tscrollx', 08:55:36 INFO - 'cart', 08:55:36 INFO - 'tsvg_static']}, 08:55:36 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:55:36 INFO - 'talos_options': ['--disable-e10s'], 08:55:36 INFO - 'tests': ['tp5o']}, 08:55:36 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:55:36 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:55:36 INFO - 'talos_options': ['--disable-e10s', 08:55:36 INFO - '--xperf_path', 08:55:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:55:36 INFO - 'tests': ['tp5n']}, 08:55:36 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:55:36 INFO - 'talos_options': ['--xperf_path', 08:55:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:55:36 INFO - 'tests': ['tp5n']}}, 08:55:36 INFO - 'talos.zip': {'path': '', 08:55:36 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:55:36 INFO - Running post-action listener: _resource_record_post_action 08:55:36 INFO - [mozharness: 2016-12-13 16:55:36.273460Z] Finished populate-webroot step (success) 08:55:36 INFO - [mozharness: 2016-12-13 16:55:36.273541Z] Running create-virtualenv step. 08:55:36 INFO - Running pre-action listener: _resource_record_pre_action 08:55:36 INFO - Running main action method: create_virtualenv 08:55:36 INFO - Creating virtualenv /builds/slave/test/build/venv 08:55:36 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 08:55:36 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:55:36 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:55:36 INFO - Using real prefix '/tools/python27' 08:55:36 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:55:37 INFO - Installing distribute.............................................................................................................................................................................................done. 08:55:39 INFO - Installing pip.................done. 08:55:39 INFO - Return code: 0 08:55:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:55:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:55:40 INFO - Reading from file tmpfile_stdout 08:55:40 INFO - Output received: 08:55:40 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) 08:55:40 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:55:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:55:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:55:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:55:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:55:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:55:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:55:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f5173f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f48d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd61bd79be0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f32cd30>, '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 0x10f193db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f324b20>, '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.Co6HtmUsxP/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.aeM7bW4KHu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/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 08:55:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:55:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:55:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Co6HtmUsxP/Render', 08:55:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:55:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:55:40 INFO - 'HOME': '/Users/cltbld', 08:55:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:55:40 INFO - 'LOGNAME': 'cltbld', 08:55:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:55:40 INFO - 'MOZ_NO_REMOTE': '1', 08:55:40 INFO - 'NO_EM_RESTART': '1', 08:55:40 INFO - 'PAGER': '/bin/cat', 08:55:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:55:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:55:40 INFO - 'PWD': '/builds/slave/test', 08:55:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:55:40 INFO - 'SHELL': '/bin/bash', 08:55:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aeM7bW4KHu/Listeners', 08:55:40 INFO - 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', 08:55:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:55:40 INFO - 'USER': 'cltbld', 08:55:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:55:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:55:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:55:40 INFO - 'XPC_FLAGS': '0x0', 08:55:40 INFO - 'XPC_SERVICE_NAME': '0', 08:55:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:55:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:55:40 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 08:55:40 INFO - Cleaning up... 08:55:40 INFO - Return code: 0 08:55:40 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:55:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:55:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:55:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:55:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:55:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:55:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:55:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f5173f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f48d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd61bd79be0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f32cd30>, '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 0x10f193db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f324b20>, '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.Co6HtmUsxP/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.aeM7bW4KHu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/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 08:55:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:55:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:55:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Co6HtmUsxP/Render', 08:55:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:55:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:55:40 INFO - 'HOME': '/Users/cltbld', 08:55:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:55:40 INFO - 'LOGNAME': 'cltbld', 08:55:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:55:40 INFO - 'MOZ_NO_REMOTE': '1', 08:55:40 INFO - 'NO_EM_RESTART': '1', 08:55:40 INFO - 'PAGER': '/bin/cat', 08:55:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:55:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:55:40 INFO - 'PWD': '/builds/slave/test', 08:55:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:55:40 INFO - 'SHELL': '/bin/bash', 08:55:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aeM7bW4KHu/Listeners', 08:55:40 INFO - 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', 08:55:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:55:40 INFO - 'USER': 'cltbld', 08:55:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:55:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:55:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:55:40 INFO - 'XPC_FLAGS': '0x0', 08:55:40 INFO - 'XPC_SERVICE_NAME': '0', 08:55:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:55:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:55:40 INFO - Downloading/unpacking psutil>=3.1.1 08:55: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 08:55: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 08:55: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 08:55: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 08:55: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 08:55: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 08:55:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:55:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:55:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:55:44 INFO - Installing collected packages: psutil 08:55:44 INFO - Running setup.py install for psutil 08:55:44 INFO - building 'psutil._psutil_osx' extension 08:55:44 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 08:55:44 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 08:55:44 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 08:55:44 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 08:55:44 INFO - building 'psutil._psutil_posix' extension 08:55:44 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 08:55:45 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:55:45 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:55:45 INFO - ^ 08:55:45 INFO - 1 warning generated. 08:55:45 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 08:55:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:55:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:55:45 INFO - Successfully installed psutil 08:55:45 INFO - Cleaning up... 08:55:45 INFO - Return code: 0 08:55:45 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:55:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:55:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:55:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:55:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:55:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:55:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:55:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f5173f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f48d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd61bd79be0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f32cd30>, '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 0x10f193db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f324b20>, '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.Co6HtmUsxP/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.aeM7bW4KHu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/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 08:55:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:55:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 08:55:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Co6HtmUsxP/Render', 08:55:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:55:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:55:45 INFO - 'HOME': '/Users/cltbld', 08:55:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:55:45 INFO - 'LOGNAME': 'cltbld', 08:55:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:55:45 INFO - 'MOZ_NO_REMOTE': '1', 08:55:45 INFO - 'NO_EM_RESTART': '1', 08:55:45 INFO - 'PAGER': '/bin/cat', 08:55:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:55:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:55:45 INFO - 'PWD': '/builds/slave/test', 08:55:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:55:45 INFO - 'SHELL': '/bin/bash', 08:55:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aeM7bW4KHu/Listeners', 08:55:45 INFO - 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', 08:55:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:55:45 INFO - 'USER': 'cltbld', 08:55:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:55:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:55:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:55:45 INFO - 'XPC_FLAGS': '0x0', 08:55:45 INFO - 'XPC_SERVICE_NAME': '0', 08:55:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:55:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:55:45 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:55:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:55:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:55:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:55:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:55:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:55:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:55:48 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:55:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:55:48 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:55:48 INFO - Installing collected packages: mozsystemmonitor 08:55:48 INFO - Running setup.py install for mozsystemmonitor 08:55:48 INFO - Successfully installed mozsystemmonitor 08:55:48 INFO - Cleaning up... 08:55:48 INFO - Return code: 0 08:55:48 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:55:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:55:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:55:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:55:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:55:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:55:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:55:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f5173f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f48d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd61bd79be0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f32cd30>, '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 0x10f193db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f324b20>, '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.Co6HtmUsxP/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.aeM7bW4KHu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/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 08:55:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:55:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:55:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Co6HtmUsxP/Render', 08:55:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:55:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:55:48 INFO - 'HOME': '/Users/cltbld', 08:55:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:55:48 INFO - 'LOGNAME': 'cltbld', 08:55:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:55:48 INFO - 'MOZ_NO_REMOTE': '1', 08:55:48 INFO - 'NO_EM_RESTART': '1', 08:55:48 INFO - 'PAGER': '/bin/cat', 08:55:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:55:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:55:48 INFO - 'PWD': '/builds/slave/test', 08:55:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:55:48 INFO - 'SHELL': '/bin/bash', 08:55:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aeM7bW4KHu/Listeners', 08:55:48 INFO - 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', 08:55:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:55:48 INFO - 'USER': 'cltbld', 08:55:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:55:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:55:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:55:48 INFO - 'XPC_FLAGS': '0x0', 08:55:48 INFO - 'XPC_SERVICE_NAME': '0', 08:55:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:55:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:55:48 INFO - Downloading/unpacking jsonschema==2.5.1 08:55: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 08:55: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 08:55: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 08:55: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 08:55: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 08:55: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 08:55:51 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:55:51 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:55:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:55:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:55:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:55:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:55:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:55:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:55:51 INFO - Downloading functools32-3.2.3-2.tar.gz 08:55:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:55:51 INFO - warning: no files found matching '*.txt' 08:55:51 INFO - no previously-included directories found matching 'build' 08:55:51 INFO - no previously-included directories found matching 'dist' 08:55:51 INFO - no previously-included directories found matching '.git*' 08:55:51 INFO - Installing collected packages: jsonschema, functools32 08:55:51 INFO - Running setup.py install for functools32 08:55:51 INFO - warning: no files found matching '*.txt' 08:55:51 INFO - no previously-included directories found matching 'build' 08:55:51 INFO - no previously-included directories found matching 'dist' 08:55:51 INFO - no previously-included directories found matching '.git*' 08:55:51 INFO - Successfully installed jsonschema functools32 08:55:51 INFO - Cleaning up... 08:55:52 INFO - Return code: 0 08:55:52 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:55: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')]} 08:55:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:55:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:55: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')]} 08:55:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:55:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:55: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', '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 0x10f5173f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f48d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd61bd79be0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f32cd30>, '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 0x10f193db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f324b20>, '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.Co6HtmUsxP/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.aeM7bW4KHu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/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 08:55: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:55: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 functools32==3.2.3-2 08:55:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Co6HtmUsxP/Render', 08:55:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:55:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:55:52 INFO - 'HOME': '/Users/cltbld', 08:55:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:55:52 INFO - 'LOGNAME': 'cltbld', 08:55:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:55:52 INFO - 'MOZ_NO_REMOTE': '1', 08:55:52 INFO - 'NO_EM_RESTART': '1', 08:55:52 INFO - 'PAGER': '/bin/cat', 08:55:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:55:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:55:52 INFO - 'PWD': '/builds/slave/test', 08:55:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:55:52 INFO - 'SHELL': '/bin/bash', 08:55:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aeM7bW4KHu/Listeners', 08:55:52 INFO - 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', 08:55:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:55:52 INFO - 'USER': 'cltbld', 08:55:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:55:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:55:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:55:52 INFO - 'XPC_FLAGS': '0x0', 08:55:52 INFO - 'XPC_SERVICE_NAME': '0', 08:55:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:55:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:55:52 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:55:52 INFO - Cleaning up... 08:55:52 INFO - Return code: 0 08:55:52 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:55: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')]} 08:55:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:55:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:55: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')]} 08:55:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:55:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:55:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f5173f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f48d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd61bd79be0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f32cd30>, '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 0x10f193db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f324b20>, '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.Co6HtmUsxP/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.aeM7bW4KHu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/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 08:55:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:55:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:55:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Co6HtmUsxP/Render', 08:55:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:55:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:55:52 INFO - 'HOME': '/Users/cltbld', 08:55:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:55:52 INFO - 'LOGNAME': 'cltbld', 08:55:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:55:52 INFO - 'MOZ_NO_REMOTE': '1', 08:55:52 INFO - 'NO_EM_RESTART': '1', 08:55:52 INFO - 'PAGER': '/bin/cat', 08:55:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:55:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:55:52 INFO - 'PWD': '/builds/slave/test', 08:55:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:55:52 INFO - 'SHELL': '/bin/bash', 08:55:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aeM7bW4KHu/Listeners', 08:55:52 INFO - 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', 08:55:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:55:52 INFO - 'USER': 'cltbld', 08:55:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:55:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:55:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:55:52 INFO - 'XPC_FLAGS': '0x0', 08:55:52 INFO - 'XPC_SERVICE_NAME': '0', 08:55:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:55:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:55:52 INFO - Downloading/unpacking blobuploader==1.2.4 08:55:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:55:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:55:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:55:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:55:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:55:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:55:55 INFO - Downloading blobuploader-1.2.4.tar.gz 08:55:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:55:55 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:55:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:55:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:55:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:55:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:55:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:55:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:55:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:55:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:55:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:55:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:55:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:55:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:55:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:55:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:55:56 INFO - Downloading docopt-0.6.1.tar.gz 08:55:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:55:56 INFO - Installing collected packages: blobuploader, requests, docopt 08:55:56 INFO - Running setup.py install for blobuploader 08:55:56 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:55:56 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:55:56 INFO - Running setup.py install for requests 08:55:57 INFO - Running setup.py install for docopt 08:55:57 INFO - Successfully installed blobuploader requests docopt 08:55:57 INFO - Cleaning up... 08:55:57 INFO - Return code: 0 08:55:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:55:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:55:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:55:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:55:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:55:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:55:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:55:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f5173f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f48d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd61bd79be0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f32cd30>, '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 0x10f193db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f324b20>, '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.Co6HtmUsxP/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.aeM7bW4KHu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/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 08:55:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:55:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:55:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Co6HtmUsxP/Render', 08:55:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:55:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:55:57 INFO - 'HOME': '/Users/cltbld', 08:55:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:55:57 INFO - 'LOGNAME': 'cltbld', 08:55:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:55:57 INFO - 'MOZ_NO_REMOTE': '1', 08:55:57 INFO - 'NO_EM_RESTART': '1', 08:55:57 INFO - 'PAGER': '/bin/cat', 08:55:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:55:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:55:57 INFO - 'PWD': '/builds/slave/test', 08:55:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:55:57 INFO - 'SHELL': '/bin/bash', 08:55:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aeM7bW4KHu/Listeners', 08:55:57 INFO - 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', 08:55:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:55:57 INFO - 'USER': 'cltbld', 08:55:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:55:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:55:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:55:57 INFO - 'XPC_FLAGS': '0x0', 08:55:57 INFO - 'XPC_SERVICE_NAME': '0', 08:55:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:55:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:55:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:55:57 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-tGtfaD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:55:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:55:57 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-Kn1KST-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:55:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:55:57 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-CuVUYi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:55:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:55:57 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-tez4mZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:55:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:55:57 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-Asov50-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:55:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:55:58 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-UphFF6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:55:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:55:58 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-AX1R15-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:55:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:55:58 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-am9KrH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:55:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:55:58 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-Lj6PRD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:55:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:55:58 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-4WGMDj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:55:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:55:58 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-Df5oCj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:55:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:55:58 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-gzfnlI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:55:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:55:58 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-TmsFx_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:55:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:55:58 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-u4ka__-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:55:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:55:59 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-zplf5t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:55:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:55:59 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-Qj10ae-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:55:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:55:59 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-iZWZMj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:55:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:55:59 INFO - Running setup.py install for manifestparser 08:55:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:55:59 INFO - Running setup.py install for mozcrash 08:55:59 INFO - Running setup.py install for mozdebug 08:55:59 INFO - Running setup.py install for mozdevice 08:55:59 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:55:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:55:59 INFO - Running setup.py install for mozfile 08:56:00 INFO - Running setup.py install for mozhttpd 08:56:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:56:00 INFO - Running setup.py install for mozinfo 08:56:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:56:00 INFO - Running setup.py install for mozInstall 08:56:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:56:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:56:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:56:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:56:00 INFO - Running setup.py install for mozleak 08:56:00 INFO - Running setup.py install for mozlog 08:56:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:56:00 INFO - Running setup.py install for moznetwork 08:56:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:56:00 INFO - Running setup.py install for mozprocess 08:56:01 INFO - Running setup.py install for mozprofile 08:56:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:56:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:56:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:56:01 INFO - Running setup.py install for mozrunner 08:56:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:56:01 INFO - Running setup.py install for mozscreenshot 08:56:01 INFO - Running setup.py install for moztest 08:56:01 INFO - Running setup.py install for mozversion 08:56:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:56:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:56:01 INFO - Cleaning up... 08:56:01 INFO - Return code: 0 08:56:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:56:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:56:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:56:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:56:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:56:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:56:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:56:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f5173f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f48d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd61bd79be0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f32cd30>, '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 0x10f193db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f324b20>, '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.Co6HtmUsxP/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.aeM7bW4KHu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/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 08:56:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:56:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:56:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Co6HtmUsxP/Render', 08:56:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:56:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:56:01 INFO - 'HOME': '/Users/cltbld', 08:56:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:56:01 INFO - 'LOGNAME': 'cltbld', 08:56:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:56:01 INFO - 'MOZ_NO_REMOTE': '1', 08:56:01 INFO - 'NO_EM_RESTART': '1', 08:56:01 INFO - 'PAGER': '/bin/cat', 08:56:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:56:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:56:01 INFO - 'PWD': '/builds/slave/test', 08:56:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:56:01 INFO - 'SHELL': '/bin/bash', 08:56:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aeM7bW4KHu/Listeners', 08:56:01 INFO - 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', 08:56:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:56:01 INFO - 'USER': 'cltbld', 08:56:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:56:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:56:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:56:01 INFO - 'XPC_FLAGS': '0x0', 08:56:01 INFO - 'XPC_SERVICE_NAME': '0', 08:56:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:56:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:56:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:56:02 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-Xfz9F3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:56:02 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 08:56:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:56:02 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-ktc7JJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:56:02 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)) 08:56:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:56:02 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-6eaHLB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:56:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:56:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:56:02 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-jwlEdC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:56:02 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)) 08:56:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:56:02 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-m_5YFG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:56:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 08:56:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:56:02 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-Nw8jmd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:56:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 08:56:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:56:02 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-WJ5zs4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:56:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 08:56:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:56:02 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-03NbbR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:56:02 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)) 08:56:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:56:02 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-ubV316-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:56:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 08:56:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:56:03 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-izKi79-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:56:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:56:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:56:03 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-94XPwo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:56:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 08:56:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:56:03 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-eeYopy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:56:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 08:56:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:56:03 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-KPGh8a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:56:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 08:56:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:56:03 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-YARWKY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:56:03 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)) 08:56:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:56:03 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-khRuj7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:56:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 08:56:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:56:03 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-5bfjPS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:56:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 08:56:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:56:03 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-rYbEUe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:56:03 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)) 08:56:03 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)) 08:56:03 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)) 08:56:03 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)) 08:56:03 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)) 08:56:03 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)) 08:56:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:56:03 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 08:56:03 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 08:56:03 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 08:56:03 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 08:56:03 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 08:56:03 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 08:56:06 INFO - Downloading blessings-1.6.tar.gz 08:56:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:56:06 INFO - Installing collected packages: blessings 08:56:06 INFO - Running setup.py install for blessings 08:56:06 INFO - Successfully installed blessings 08:56:06 INFO - Cleaning up... 08:56:06 INFO - Return code: 0 08:56:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:56:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:56:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:56:07 INFO - Reading from file tmpfile_stdout 08:56:07 INFO - Current package versions: 08:56:07 INFO - blessings == 1.6 08:56:07 INFO - blobuploader == 1.2.4 08:56:07 INFO - docopt == 0.6.1 08:56:07 INFO - functools32 == 3.2.3-2 08:56:07 INFO - jsonschema == 2.5.1 08:56:07 INFO - manifestparser == 1.1 08:56:07 INFO - mozInstall == 1.12 08:56:07 INFO - mozcrash == 1.0 08:56:07 INFO - mozdebug == 0.1 08:56:07 INFO - mozdevice == 0.49 08:56:07 INFO - mozfile == 1.2 08:56:07 INFO - mozhttpd == 0.7 08:56:07 INFO - mozinfo == 0.9 08:56:07 INFO - mozleak == 0.1 08:56:07 INFO - mozlog == 3.4 08:56:07 INFO - moznetwork == 0.27 08:56:07 INFO - mozprocess == 0.23 08:56:07 INFO - mozprofile == 0.28 08:56:07 INFO - mozrunner == 6.13 08:56:07 INFO - mozscreenshot == 0.1 08:56:07 INFO - mozsystemmonitor == 0.3 08:56:07 INFO - moztest == 0.7 08:56:07 INFO - mozversion == 1.4 08:56:07 INFO - psutil == 3.1.1 08:56:07 INFO - requests == 1.2.3 08:56:07 INFO - wsgiref == 0.1.2 08:56:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:56:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:56:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:56:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:56:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:56:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:56:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:56:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f5173f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f48d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd61bd79be0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f32cd30>, '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 0x10f193db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f324b20>, '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.Co6HtmUsxP/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.aeM7bW4KHu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/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 08:56:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 08:56:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:56:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Co6HtmUsxP/Render', 08:56:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:56:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:56:07 INFO - 'HOME': '/Users/cltbld', 08:56:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:56:07 INFO - 'LOGNAME': 'cltbld', 08:56:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:56:07 INFO - 'MOZ_NO_REMOTE': '1', 08:56:07 INFO - 'NO_EM_RESTART': '1', 08:56:07 INFO - 'PAGER': '/bin/cat', 08:56:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:56:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:56:07 INFO - 'PWD': '/builds/slave/test', 08:56:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:56:07 INFO - 'SHELL': '/bin/bash', 08:56:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aeM7bW4KHu/Listeners', 08:56:07 INFO - 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', 08:56:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:56:07 INFO - 'USER': 'cltbld', 08:56:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:56:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:56:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:56:07 INFO - 'XPC_FLAGS': '0x0', 08:56:07 INFO - 'XPC_SERVICE_NAME': '0', 08:56:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:56:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:56:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:56:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 08:56:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 08:56:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:56:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 08:56:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 08:56:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 08:56:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 08:56:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 08:56:07 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:56:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:56:07 INFO - Cleaning up... 08:56:07 INFO - Return code: 0 08:56:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:56:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:56:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:56:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:56:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:56:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:56:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:56:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f5173f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f48d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd61bd79be0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f32cd30>, '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 0x10f193db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f324b20>, '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.Co6HtmUsxP/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.aeM7bW4KHu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/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 08:56:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:56:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:56:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Co6HtmUsxP/Render', 08:56:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:56:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:56:07 INFO - 'HOME': '/Users/cltbld', 08:56:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:56:07 INFO - 'LOGNAME': 'cltbld', 08:56:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:56:07 INFO - 'MOZ_NO_REMOTE': '1', 08:56:07 INFO - 'NO_EM_RESTART': '1', 08:56:07 INFO - 'PAGER': '/bin/cat', 08:56:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:56:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:56:07 INFO - 'PWD': '/builds/slave/test', 08:56:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:56:07 INFO - 'SHELL': '/bin/bash', 08:56:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aeM7bW4KHu/Listeners', 08:56:07 INFO - 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', 08:56:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:56:07 INFO - 'USER': 'cltbld', 08:56:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:56:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:56:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:56:07 INFO - 'XPC_FLAGS': '0x0', 08:56:07 INFO - 'XPC_SERVICE_NAME': '0', 08:56:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:56:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:56:07 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:56:07 INFO - Cleaning up... 08:56:07 INFO - Return code: 0 08:56:07 INFO - Running post-action listener: _resource_record_post_action 08:56:07 INFO - Running post-action listener: _start_resource_monitoring 08:56:07 INFO - Starting resource monitoring. 08:56:07 INFO - [mozharness: 2016-12-13 16:56:07.707059Z] Finished create-virtualenv step (success) 08:56:07 INFO - [mozharness: 2016-12-13 16:56:07.708055Z] Running install step. 08:56:07 INFO - Running pre-action listener: _resource_record_pre_action 08:56:07 INFO - Running main action method: install 08:56:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:56:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:56:07 INFO - Reading from file tmpfile_stdout 08:56:07 INFO - Detecting whether we're running mozinstall >=1.0... 08:56:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:56:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:56:08 INFO - Reading from file tmpfile_stdout 08:56:08 INFO - Output received: 08:56:08 INFO - Usage: mozinstall [options] installer 08:56:08 INFO - Options: 08:56:08 INFO - -h, --help show this help message and exit 08:56:08 INFO - -d DEST, --destination=DEST 08:56:08 INFO - Directory to install application into. [default: 08:56:08 INFO - "/builds/slave/test"] 08:56:08 INFO - --app=APP Application being installed. [default: firefox] 08:56:08 INFO - mkdir: /builds/slave/test/build/application 08:56:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac64.dmg', '--destination', '/builds/slave/test/build/application'] 08:56:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac64.dmg --destination /builds/slave/test/build/application 08:56:29 INFO - Reading from file tmpfile_stdout 08:56:29 INFO - Output received: 08:56:29 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:56:29 INFO - Running post-action listener: _resource_record_post_action 08:56:29 INFO - [mozharness: 2016-12-13 16:56:29.752310Z] Finished install step (success) 08:56:29 INFO - [mozharness: 2016-12-13 16:56:29.752443Z] Running run-tests step. 08:56:29 INFO - Running pre-action listener: _resource_record_pre_action 08:56:29 INFO - Running main action method: run_tests 08:56:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:56:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:56:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:56:29 INFO - 2016-12-13 08:56:29.767 screenresolution[2146:18899] starting screenresolution argv=screenresolution get 08:56:29 INFO - 2016-12-13 08:56:29.769 screenresolution[2146:18899] Display 0: 1600x1200x32@60 08:56:29 INFO - 2016-12-13 08:56:29.775 screenresolution[2147:18901] starting screenresolution argv=screenresolution list 08:56:29 INFO - Available Modes on Display 0 08:56:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:56:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:56:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:56:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:56:30 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:56:30 INFO - Intel Iris: 08:56:30 INFO - Chipset Model: Intel Iris 08:56:30 INFO - Type: GPU 08:56:30 INFO - Bus: Built-In 08:56:30 INFO - VRAM (Dynamic, Max): 1536 MB 08:56:30 INFO - Vendor: Intel (0x8086) 08:56:30 INFO - Device ID: 0x0a2e 08:56:30 INFO - Revision ID: 0x0009 08:56:30 INFO - Displays: 08:56:30 INFO - Display: 08:56:30 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:56:30 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:56:30 INFO - Display Serial Number: 1600x1200 60 08:56:30 INFO - Main Display: Yes 08:56:30 INFO - Mirror: Off 08:56:30 INFO - Online: Yes 08:56:30 INFO - Rotation: Supported 08:56:30 INFO - Return code: 0 08:56:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:56:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:56:30 INFO - Python 2.7.3 08:56:30 INFO - Return code: 0 08:56:30 INFO - grabbing minidump binary from tooltool 08:56:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:56:30 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f32cd30>, '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 0x10f193db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f324b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:56:30 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 08:56:30 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 08:56:30 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 08:56:30 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:56:30 INFO - Return code: 0 08:56:30 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:56:30 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:56:30 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:56:30 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:56:30 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:56:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Cedar', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/F9BEDpgTQA6owFWEPmgLnw/artifacts/public/build/firefox-53.0a1.en-US.mac64.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0178', '--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 08:56:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Cedar --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/F9BEDpgTQA6owFWEPmgLnw/artifacts/public/build/firefox-53.0a1.en-US.mac64.crashreporter-symbols.zip --title t-yosemite-r7-0178 --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 08:56:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Co6HtmUsxP/Render', 08:56:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:56:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:56:30 INFO - 'HOME': '/Users/cltbld', 08:56:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:56:30 INFO - 'LOGNAME': 'cltbld', 08:56:30 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:56:30 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:56:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:56:30 INFO - 'MOZ_NO_REMOTE': '1', 08:56:30 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:56:30 INFO - 'NO_EM_RESTART': '1', 08:56:30 INFO - 'PAGER': '/bin/cat', 08:56:30 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:56:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:56:30 INFO - 'PWD': '/builds/slave/test', 08:56:30 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:56:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:56:30 INFO - 'SHELL': '/bin/bash', 08:56:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aeM7bW4KHu/Listeners', 08:56:30 INFO - 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', 08:56:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:56:30 INFO - 'USER': 'cltbld', 08:56:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:56:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:56:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:56:30 INFO - 'XPC_FLAGS': '0x0', 08:56:30 INFO - 'XPC_SERVICE_NAME': '0', 08:56:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:56:30 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Cedar', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/F9BEDpgTQA6owFWEPmgLnw/artifacts/public/build/firefox-53.0a1.en-US.mac64.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0178', '--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 08:56:30 INFO - mozversion application_buildid: 20161213070203 08:56:30 INFO - mozversion application_changeset: 6f4475a780411b76f6f51328666722b6d81f80ff 08:56:30 INFO - mozversion application_display_name: Nightly 08:56:30 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:56:30 INFO - mozversion application_name: Firefox 08:56:30 INFO - mozversion application_remotingname: firefox 08:56:30 INFO - mozversion application_repository: https://hg.mozilla.org/projects/cedar 08:56:30 INFO - mozversion application_vendor: Mozilla 08:56:30 INFO - mozversion application_version: 53.0a1 08:56:30 INFO - mozversion platform_buildid: 20161213070203 08:56:30 INFO - mozversion platform_changeset: 6f4475a780411b76f6f51328666722b6d81f80ff 08:56:30 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/cedar 08:56:30 INFO - mozversion platform_version: 53.0a1 08:56:30 INFO - using testdate: 1481648190 08:56:30 INFO - actual date: 1481648190 08:56:30 INFO - starting webserver on 'localhost:57158' 08:56:30 INFO - SUITE-START | Running 2 tests 08:56:30 INFO - TEST-START | dromaeo_css 08:56:30 INFO - operating with platform_type : mac_ 08:56:30 INFO - Initialising browser for dromaeo_css test... 08:56:30 INFO - TEST-INFO | started process 2157 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/tmpjgaM3H/profile http://localhost:57158/getInfo.html) 08:56:33 INFO - PROCESS | 2157 | 2016-12-13 08:56:33.159 plugin-container[2159:19055] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d3f, name = 'com.apple.tsm.portname' 08:56:33 INFO - PROCESS | 2157 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:56:33 INFO - PROCESS | 2157 | 2016-12-13 08:56:33.161 plugin-container[2159:19055] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c07, name = 'com.apple.CFPasteboardClient' 08:56:33 INFO - PROCESS | 2157 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:56:33 INFO - PROCESS | 2157 | 2016-12-13 08:56:33.161 plugin-container[2159:19055] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:56:33 INFO - PROCESS | 2157 | __metrics Screen width/height:1600/1200 08:56:33 INFO - PROCESS | 2157 | colorDepth:24 08:56:33 INFO - PROCESS | 2157 | Browser inner width/height: 1024/689 08:56:33 INFO - PROCESS | 2157 | __metrics 08:56:33 ERROR - PROCESS | 2157 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3212: TypeError: this.tabs is undefined 08:56:34 INFO - TEST-INFO | 2157: exit 0 08:56:34 INFO - Browser initialized. 08:56:34 INFO - Running cycle 1/1 for dromaeo_css test... 08:56:34 INFO - TEST-INFO | started process 2160 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/tmpjgaM3H/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:56:36 INFO - PROCESS | 2160 | 2016-12-13 08:56:36.609 plugin-container[2161:19178] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x862b, name = 'com.apple.tsm.portname' 08:56:36 INFO - PROCESS | 2160 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:56:36 INFO - PROCESS | 2160 | 2016-12-13 08:56:36.610 plugin-container[2161:19178] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f03, name = 'com.apple.CFPasteboardClient' 08:56:36 INFO - PROCESS | 2160 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:56:36 INFO - PROCESS | 2160 | 2016-12-13 08:56:36.610 plugin-container[2161:19178] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:56:46 INFO - PROCESS | 2160 | RSS: Main: 236761088 08:56:46 INFO - PROCESS | 2160 | 08:57:35 INFO - PROCESS | 2160 | 1481648255918 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 08:59:48 INFO - PROCESS | 2160 | Cycle 1(1): loaded http://localhost:57158/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57158/tests/dromaeo/cssquery-ext.html) 08:59:51 INFO - PROCESS | 2160 | RSS: Main: 257544192 08:59:51 INFO - PROCESS | 2160 | 09:02:53 INFO - PROCESS | 2160 | Cycle 1(1): loaded http://localhost:57158/tests/dromaeo/cssquery-ext.html (next: http://localhost:57158/tests/dromaeo/cssquery-jquery.html) 09:02:54 INFO - PROCESS | 2160 | RSS: Main: 267407360 09:02:54 INFO - PROCESS | 2160 | 09:05:56 INFO - PROCESS | 2160 | Cycle 1(1): loaded http://localhost:57158/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57158/tests/dromaeo/cssquery-mootools.html) 09:05:57 INFO - PROCESS | 2160 | RSS: Main: 272633856 09:05:57 INFO - PROCESS | 2160 | 09:08:58 INFO - PROCESS | 2160 | Cycle 1(1): loaded http://localhost:57158/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57158/tests/dromaeo/cssquery-prototype.html) 09:08:59 INFO - PROCESS | 2160 | RSS: Main: 271433728 09:08:59 INFO - PROCESS | 2160 | 09:12:02 INFO - PROCESS | 2160 | Cycle 1(1): loaded http://localhost:57158/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57158/tests/dromaeo/cssquery-yui.html) 09:12:03 INFO - PROCESS | 2160 | RSS: Main: 276189184 09:12:03 INFO - PROCESS | 2160 | 09:15:05 INFO - PROCESS | 2160 | Cycle 1(1): loaded http://localhost:57158/tests/dromaeo/cssquery-yui.html (next: http://localhost:57158/tests/dromaeo/cssquery-dojo.html) 09:15:06 INFO - PROCESS | 2160 | RSS: Main: 278986752 09:15:06 INFO - PROCESS | 2160 | 09:15:06 INFO - PROCESS | 2160 | __start_tp_report 09:15:06 INFO - PROCESS | 2160 | _x_x_mozilla_page_load 09:15:06 INFO - PROCESS | 2160 | _x_x_mozilla_page_load_details 09:15:06 INFO - PROCESS | 2160 | |i|pagename|runs| 09:15:06 INFO - PROCESS | 2160 | |0;dojo.html;3410.403179443597;3425.5375524304213;3425.9037108125403;3435.484677298405;3443.9655603443966;5795.101759227321;5799.304083509985;5864.149698293745;5871.970640146804;5889.7349619267125;5643.153526970957;5705.914411283824;5770.567207459437;5772;5774.711264436772;5175.40482844473;5238.8690282742855;5238.895222095556;5280.894382112356;5336.199570064502;5334.786608535654;5338.599605029619;5352.304200453955;5361.276227709268;5375.978564072833;5329.813456529022;5371.7582708778;5389.9191512127345;5392.379876314235;5405.7567409466665;5321.494458026481;5345.251664766955;5368.087425137716;5386.803524895078;5436.646617969859;4419.867403977886;4973.726445045521;5243.475652434764;5260.500252476008;5351.411344752113;5344.492273234076;5634.323881134249;5761.769529218826;5796.739146738365;5806.983777838861;6227.314995350508;6232.34560371159;6298.2756982947085;6307.779227727007;6313.179286692701;6204.47261982732;6216.129741836146;6232.9376706232815;6242.469390101847;6256.812295631138;5923.881522369528;5932.288125424977;5932.940670593239;5948.40515948406;5974.327188387215;6305.064429127922;6337.271213810379;6349.650769207646;6363.459105975951;6380.872382552416;6106.847328816815;6127.250613123855;6131.570790044743;6166.753329866755;6224.190855188796;4981.601471882241;5113.71874546896;5555.944440555623;6204.162438070803;6262.749490020439;5173;6223.3465486123305;6240.656763878031;6305.8108256752375;6323.114763933053;4522.321651752198;6150.630962142286;6262;6293.402126797947;6308;3980.9251502094853;4887.755612219375;4899.657024008286;4920.6309526785635;4980.352554167935;3745.8688945886765;4409.603135717801;4428.800703968329;4485.506594274627;4533.252013417749;5842.912356314659;5875.20684707559;5879.911801322984;5908.084246941645;5935.673537955454;6177.938220617826;6220.968895155495;6283.214598175228;6292.968535157295;6305.716242769086;5919.9936010878255;5946.929552680473;6003;6006.699665016732;6047.485963693048;5365.22204280385;5391.083515802323;5402.6758394496455;5414.864628384243;5456.017916774939;6178.351273116258;6231.314555398993;6252.030935205051;6284.780032698834;6289.465395441439;6286.8428289291305;6294.496440284858;6297.937020629918;6298.244210694746;6315;6253;6280.7487700491465;6292.402221788922;6298.338674439118;6301.928672125658;6140.258857967814;6170.50635949114;6182.856171608267;6209.658468784261;6267.655278959701;6264.154339164337;6264.812055638338;6269.435750782452;6275.497960163085;6295.307516173126;6019.337872833898;6157.599756015845;6165.660888651168;6184.443400094013;6237.781677641261;6052.909206361996;6115.388461153849;6118.449339559461;6200.038993955944;6218;5292;5762.913556296575;6043.12374705674;6052.788152414644;6059.878802424015;6109.374301065143;6132.110769108063;6133.723982420713;6134.957077261001;6158.876822463615;6054.515638748801;6102.176206212104;6102.816915492543;6132.263729246367;6135.53983451234 09:15:06 INFO - PROCESS | 2160 | |1;ext.html;470.6658272626435;481.6050838312583;486.69095124595884;488.05317683693636;490.8380234522606;23757.94172486018;30370.696293037065;37312.626873731286;40103.59896401035;40202.59797402025;40628.79685601568;41152;41189.794051029705;41229.79385103078;41276.58723412765;8452.830943381128;8699.260562852165;9200.217981471566;9455.858162127573;9461.763455913588;11189.608363707272;11486;11537.134714896374;11626.593069242579;11628.953394194521;11406.258593191458;11442.370669613169;11467.025302849226;11471.254368466065;11512.539498420054;11346.546138154505;11376.431178441046;11410.31538107712;11478.368689722061;11487.023602993755;8839;9384.296177786626;10049.995000499965;10359.378437293712;11195.608153714662;39140.217195655925;39449.40825887614;39453;39513.61702340433;39545.80227098875;2790.4140130572605;2918.54762511811;2953.9852300738576;3091.536269559584;3283.2448536836423;30014.69985300141;30153.547696784564;30453.323031662832;30593.388132237455;30716.2320941976;2635.8154929154966;3203.519472079207;3213.694699003591;3215.389076075538;3269.640339562646;423669;515595;517193;519730;520235.9999999962;58315.708421457624;58643;58700.11949820756;58715;58933;62173.689131554056;63010.68494657498;63061.68469157625;63247;63507;14199.438061813193;15585.298661560257;15802.841971580367;15821;15845.52463426099;11205.887941120647;11416.600418985461;12255.264684118982;12351.38243087842;12476.937615312047;16888.240029198714;17004.064776437415;17061.317547297967;17063.658726825393;17172.828271717124;6156.799424112255;6884.277150899184;6952.443804495628;7038.401735852408;7116.786496405116;841.5413599588117;952.9332946693803;1018.6520071235725;1036.828923227674;1126.1272513801869;29437;29668.40663186724;30035.549466758017;30537.69462305393;30600.92896748603;965.4448692002127;1058.317385286486;1079.0935614084208;1092.3664274720643;1142.628645690154;14652.706945861022;14739.410423583151;15610.275495937567;15669.529914102595;15929.283182256826;180119.09940450214;616937;624264;627464.8626756929;629363;53380.99999999922;53585;58516;58573;58750.82498350095;1138.686861113184;1268.9936550317188;1312.2454588611586;1380.42712274405;1394.0520446096752;29605.5559166617;29846.701532985262;30044.849775751856;30268.24329391783;30295.185383562668;49882.25176622426;50565;50602.746986264836;50650.746746266035;50801.49198507968;535944;539203.3039834775;546035;547788;548242;33014.50478242876;35785.821070894475;39954;40216;40357.79821100993;43714;46140.7692961533;46302.536974629824;46366.76816615895;47089.52910470851;31363.686363136076;38169;38542.42186367151;39095.2180956385;39369;36949;37943.43084853785;38182;38412.61587384202;39217.80391098026;32947.835260823544;36320.81839590785;37927.43108853314;37934.24131517299;37998.810005949796;27426.725732743216;32283.51574726334;34038.14904627404;34283.828580856934;34857.77997770167;25060.373490662878;25399.492010160062;25535.361615959006;25611.74388256168;26037.869810650827 09:15:06 INFO - PROCESS | 2160 | |2;jquery.html;368.5945459994005;374.27764414679666;374.5262243262273;384.4924699396797;399.357035173371;18364.265429382824;20630.1747930083;20692.379228623147;20752.584948301042;20937.895310523425;20090.296839610615;20469.385918422435;20557.794422055777;20729.585408291827;20989.271394718664;4341.734599755687;4650.953490465103;5180.611454140945;5241.977814326208;5319.361676598814;5252.054630166568;5530.640508366943;5667.4615911488345;5875.38308477608;5883.205767221434;5570.916436253459;5727.627704199234;5788.27646544182;5876.471117599415;5899.970500147493;5335.719427337411;5698.544116470673;5770.942290577124;5772.538196944234;5772.942270577282;3948.091938854051;4782.306565547976;5037;5100.566451851597;5125.666831655931;25274.368140796443;25283.494330113477;25303.36741581457;25636.35909102269;25811.35471613206;2182.3998400439846;2217.3569664797187;2330.8135349172026;2632.7498887605648;2839.3895312507702;14888.702225955529;15196.924015379853;15523;15644.904856660038;15759;1346.5556366399064;1482.9184394858278;1604.8174482703514;1742.3901634427975;1781.8930864148192;100520.49739751255;119811;119928.40035799853;120226.59546809102;122486;10519.368837869599;10696.411697356565;10838.078763305208;11219.551217951353;11278.199228951624;13739.793903091462;13782.241976691177;13915.373808178656;13952.930235348758;13965;7319.63401829917;8266.462679925906;8415.53714545706;8446.282066024334;8569.785755356166;8377.413581049268;8405.70580029696;8435.536045517432;8558.95720521406;8573.571321434029;5630.690312032795;6402.48780097591;6713.597184168965;6723.159605049369;6744.932550674431;1818.4999125240665;1858.7211918212106;1858.925642974293;1859.1540848913712;1871.2982631513182;869.8477766390856;923.7228831350702;947.4788866123743;954.2842867849113;964.1130160252584;20861.165553377694;21898.562028759334;22146.335609931728;22168.667469987962;22218;929.177677755178;937.6718148647918;950.9476978766096;952.8713623660717;973.8733964584559;8761.25529330014;9185.954070229605;11709.473073711704;11856.525738970344;11970.760584788255;559400;564543;569997;577826;579271;113378.29932551019;120291;120318.3984080074;129372;144213;430.184799804369;456.64609927306606;457.89239528710726;458.9535858243144;459.779305933154;10863.837042444211;10985.725356866142;11475.254108483256;11496.655100347003;11548.59579914699;41618.79190604028;52494.16270430516;52544;52944;56284.43715562792;72901.54196916138;81226.3754724914;81554;82409;83751.5812420934;8130.918690813016;8173.79565510867;8241.381896357918;8521.659133634585;8541.914580854113;10513.474326283502;11951;12708.745825083632;13065.216087034809;13111.67220819449;8965.820683586162;10180.52382404562;11171.162162837658;11252.26860254081;11480.368579727861;9274.2842574124;10743.623973160902;11173.329600224013;11180.72048198769;11440.370779606868;11524.59663911759;11549.942250288696;11733.530658773554;11862.466189021343;11926.940365298466;6459.773907913201;7790.805229869298;8311.709090181814;8311.875321870057;8364.121767214356;10599.62901298451;10757.784844302914;11077.889221108007;11570.421478926188;11627.069834413058 09:15:06 INFO - PROCESS | 2160 | |3;mootools.html;1383.9792403113954;1506.7965824613677;1706.15545305074;1823.8996855172977;1872.2136702584912;7474.103107627092;7641.197674244194;7938.960305198473;8315.584220788967;8354.498730076191;6744.595324280546;7812.843743125134;8114.350851931846;8210.343172546196;8225.547594882279;2294.139697613399;2323;2332.0555175154004;2333.4516388648653;2342.2153578551206;2329.8252631052655;2344.22640528626;2362.5747365474294;2376.5484557933937;2377.560151371993;2302.5164715409783;2308.192132753531;2328.5692146952783;2329.615613423783;2342.2856028911174;1903.9619207615908;2234.726206062545;2287.165184707578;2290.473191166041;2314.6643736658175;1910.722945172956;2294.736105347873;2296.73587537432;2333.871637059961;2333.9883300583388;8268.958655206745;8324.583770811436;8331.66673333066;8341.582920853933;8346;5023;5184.611154163453;5197.402298735656;5222.086134926372;5222.164453687392;3288.917777055569;3374.3588718143474;3578.499010138583;3580.2481478889204;3583.2654305867495;836.7196989008689;1021.5300961557692;1035.6685860524635;1061.9362838229654;1066.8399740038979;10597.046265836034;10600.94699526505;10632.043116119508;10731.302465339728;10749.032587067202;4473.4408198975125;4606.4338125036975;4620.607248383925;4675.0883577702025;4711.986922811612;9235;9351.859722104175;9372.453545165108;9380.390274632127;9389.061093890556;5185.948140518546;5195.844124676266;5204.76578553966;5208.8437346879655;5219.42586315505;4239.40648309237;4242.215190189825;4721.693089949143;4746.216874215785;4765.356676848581;3205.599300087489;3757.9060523487133;3804.6766024887643;3843.9807800961376;3893.980530097331;1247.1830950727287;1254.8479357901242;1257.7107265328843;1262.993685031569;1269.3526301586257;788.4047544104164;890.1899271662755;909.3816204980544;933.0534173081479;943.2831048403262;5356.276902618097;5744.798932037359;5780.884382312414;5809.937472197394;5830.416958304136;906.9274458043341;913.2100732866023;927.9350445468888;938.3103418987039;968.2883080935507;3734.8319325630414;3795.4876091727815;3828.578856325802;3898.317794385971;3910.3156947534067;9527.809443811086;9911.900880991097;9923.851142232872;9965.40207587547;9967.40195588267;9846.803063938682;9906.356086854332;9908.306418550776;9937.403755774532;9968.850467242997;7252.782416527512;7643.350315223268;7678.654460549289;7692.461527693008;7805.960970195113;5744.339400968942;5768.990426675231;5859.44335288147;5859.736311866061;5935.643861368331;8845.292376609985;9148.085191480799;9160.58777355034;9178.495182765013;9326.906730932604;9249.491277979776;9306.255499559884;9346.252299815862;9380.765480863032;9457.439522478713;5468.425815289337;5739.512141468022;6565.737370505126;7104.076470058859;7275.690375732207;7789.805254868674;7960.800979975315;7996.200379961957;7999.920000800151;8025.518468891885;5964.582479226413;6653.933460665331;7104.040954471287;7163.7492687755675;7242.96378518104;5425.13197888336;5929.584929054925;5936.554758393051;6110.877782444415;6956.5478243913985;6352.523560732871;6536.0522724205675;6659.567128136656;6668.83327916806;6677.332266773478;4804.759762011955;4816.325714400057;4816.566509014206;4877.6829506081995;4989.55094041538;4886;4924.261360795909;4966.255061740768;4971.453140154508;4997.925031124463 09:15:06 INFO - PROCESS | 2160 | |4;prototype.html;350.19105865450797;357.8783213707339;362.6572888620255;369.5749887629227;373.85980257403475;4767.354118464251;5145.6398052136365;5604.9719751401235;5637.548996080314;5644.322681278247;4499.6400287976885;5237.266782650422;5273.024490469266;5289.8576076688905;5488.506034456898;3351.514030465587;4247.978760106195;4263.4883813942215;4279.379489973952;4331.870043898688;3669.5779985301765;3891.241207964434;4182.519010313822;4293.270144075499;4300.892477688051;4077.4903137107863;4083.530394004683;4091.652209562176;4123.525794533637;4140.60664236897;3384.796912185252;3835.7725527831103;3968.67049538405;4092.3016947966407;4108.958910410887;3279.852406641683;3525.4183059795105;3538.4161613333767;3542;3559.394902866519;5147.7683504242395;5195;5348.572114230852;5389.487998640123;5409.810656626999;3928.6267804558806;4330.631896288819;4670.6497012724185;4699.130660827724;4724.267738500538;5669.773209071674;5754.6547207167705;5761.164631128463;5773.39379365169;5788.450097240755;3249.610046794376;3252.869885204589;3286.277019055804;3869.787161706105;3898.4152377143373;4995.975020124913;5047.848564543069;5749.8275051748515;5870.414987953229;6047.304559975615;3184.474561697294;3404.097914052778;3937.9606203938165;3951.8221680024467;4012.8996775080273;3373.763836531419;3569.660882216185;3743.3636281832155;4024.0684471453865;4033.878983630496;4643.326717625925;4646.883827904329;4669.042846216517;4694.2723877799;4746.454157771831;4867.440244371872;5383.115909431667;5844.357120716718;5917.497012753965;6022.81414416976;1066.9519871605796;1228.8203324808285;1267.0497127154633;1284.3064745037761;1295.7861952777876;790.6916302641974;860.2687715441824;875.3718084363079;889.3863234368265;890.2788741119714;4817.8313759018265;5179.61152913533;5231.738413079331;5237.8428647140645;5287.034723957032;4459.375687403766;4546.977265113654;5194.532492075731;5461.453854614586;5621.718914054362;5128.205128205134;5153.315379503673;5166.741662916839;5169.741512924339;5206.71363075027;2576.948461030769;2614.307208589725;2673;2816.5634326679397;2975.3751712140693;4836.516348365206;5150.510701483353;5503.9174412383845;5767.798127065533;5859.773286151235;5263.263123169345;5293.761780719877;5755.309362876398;5799.710014499258;5830.41695830422;5330.253764472899;5463.535599474089;5517.69652669099;5774.48029677331;5790.363060063305;4677.438707355139;4695.131400690883;5329.253904453457;5363.039188750312;5676.545876329967;4791.353167322367;5021.945391467832;5052.747362631927;5390.520360633996;5628.808310858023;4652.487372034093;5298.417174110922;5585.408158674758;5636.802711905064;5778.335491418541;3225.451673215512;3234.64418913924;3236.741060715184;3725.7019438444318;3771.4719939207985;4890.510948905081;5121.7695203715175;5613.607047506635;5642.689652069012;5706.42935706426;4554.51040252152;5060.456560749007;5362.70505122222;5395.113614216375;5405.945940540544;3928.9017774555864;4533.750643714628;4535.095259990746;5221.608379371638;5467.87908478753;5356.410794812489;5376.59675524345;5439.211314359475;5533.557315414838;5542.224088627515;4355.586219309161;4451.844185453494;4572.6570507212755;4661.953380466152;5825.300963884361;5042.949570504248;5487.588430867621;5699.606165410454;5708.971455142698;5739.081746920473 09:15:06 INFO - PROCESS | 2160 | |5;yui.html;701.933316334948;712.213004629884;738.7672883041838;775.4106878772131;822.5681517203469;14950.626234344147;15380;15489.84510154898;15530.922345388271;15554.066755994649;13653.317334133304;14078.507252246174;14293.14241145532;14429.639259018504;14463.276836158202;715.3490323805338;885.9689910853122;889.3463304471209;889.3552174673375;889.3552174673375;669.6919417068154;707.9504434689574;725.8729722298604;746.5296862976345;877.9517126558037;921.4287141971987;939.2157548447021;957.0238356875982;957.0812020460367;957.5212393803098;867.0159369116099;914.6295750221171;939.5537119868077;942.7784470649426;943.2548286853412;691.5193940211554;698.068079114383;702.9859402811915;703.9049728286667;704.5138854190643;14993.32530036151;15148.394064237416;15182.848171518253;15230.238488075553;15665.530034098996;14519.20144392058;14778.408863645549;14927.925360373238;15052.473163439226;15245.61885952849;11692.415379231004;11761.117916156323;11852.940735296268;11896.702582435422;12320.5071797128;3664.670179683841;3743.9812800936093;3757.229767897602;3758.2107757370986;3760.1163726524246;116756;118334.40832795866;121583;122545.38727306307;123702;9889.950550247202;10495.212859035602;11162.441877906072;11253.774924501628;11263.211575189658;23029.769702303096;23804.285871423886;23827.642585361235;23833.642495362583;23923.7607623925;7759.612019399007;7772.611369431505;7910.7231246906085;8008;8139.674413023531;3582.802945838004;4277.230098582285;4334.491113500421;4411.470623525198;4567.794449249792;3581.8388172531777;3661.469086982426;3689.612590677994;3703.203811180609;3708.7589306694977;637.7449020391788;639.3510586754448;639.8880195965687;646.3149061994301;651.8109748172976;576.2882839692976;641.9935800642028;655.1859762320844;656.6946369938001;664.5514277862424;8877.911220887709;9084.22784063362;9330.626774928927;9458.905410945803;9564.330496865152;623.8707938631093;667.1160712056544;671.6742379945722;673.8483841135803;681.5433659448181;4380.737155770664;4601.74690392025;4621.1219868224925;4621.838235661736;4701.059788042405;68630;77290.61354693191;79709;80416;81866.59066704629;66730.33269667339;67205.66397168081;67396.6630166846;73838;75128.62435687786;54591;63732.36267637357;64777.02834457487;71158;73270.900936486;7652.80867978305;7690.307872291297;8452.492850428995;9001.729948101567;9576.900377422133;46781;46906;48162;48583.75708121437;49476.25785613291;61552;64654;65548.67225663841;66365;67458.66270668812;12926.030547708771;13395.0623456361;13448.394822232827;13535.59393218205;13710.862891370958;14543.78184327257;14669.853301466848;15068.397264109313;15106.093634381972;15852.44516441965;11250;13620.250886201355;14026.438942442595;14138.646533836736;14597.489087881873;13657.521986730417;13752.862471375158;13879;13890.30548472552;14168.362423690753;12973.870261297643;13303.534376296782;13447.058705890493;13638.93180534091;13830.654233643836;10846.67459976202;11110.833337499782;11128.22102452853;11182.720431989266;11928.582499612472;10375.533101010322;11260.549578016788;11549.249298795552;11585.536578536765;11619.593314233962 09:15:06 INFO - PROCESS | 2160 | __end_tp_report 09:15:06 INFO - PROCESS | 2160 | __start_cc_report 09:15:06 INFO - PROCESS | 2160 | _x_x_mozilla_cycle_collect,375 09:15:06 INFO - PROCESS | 2160 | __end_cc_report 09:15:06 INFO - PROCESS | 2160 | __startTimestamp1481649306004__endTimestamp 09:15:06 INFO - PROCESS | 2160 | 09:15:06 INFO - PROCESS | 2160 | ------- Summary: start ------- 09:15:06 INFO - PROCESS | 2160 | Number of tests: 6 09:15:06 INFO - PROCESS | 2160 | 09:15:06 INFO - PROCESS | 2160 | [#0] dojo.html Cycles:165 Average:5753.82 Median:6043.12 stddev:668.58 (11.1%) stddev-sans-first:644.86 09:15:06 INFO - PROCESS | 2160 | Values: 3410.4 3425.5 3425.9 3435.5 3444.0 5795.1 5799.3 5864.1 5872.0 5889.7 5643.2 5705.9 5770.6 5772.0 5774.7 5175.4 5238.9 5238.9 5280.9 5336.2 5334.8 5338.6 5352.3 5361.3 5376.0 5329.8 5371.8 5389.9 5392.4 5405.8 5321.5 5345.3 5368.1 5386.8 5436.6 4419.9 4973.7 5243.5 5260.5 5351.4 5344.5 5634.3 5761.8 5796.7 5807.0 6227.3 6232.3 6298.3 6307.8 6313.2 6204.5 6216.1 6232.9 6242.5 6256.8 5923.9 5932.3 5932.9 5948.4 5974.3 6305.1 6337.3 6349.7 6363.5 6380.9 6106.8 6127.3 6131.6 6166.8 6224.2 4981.6 5113.7 5555.9 6204.2 6262.7 5173.0 6223.3 6240.7 6305.8 6323.1 4522.3 6150.6 6262.0 6293.4 6308.0 3980.9 4887.8 4899.7 4920.6 4980.4 3745.9 4409.6 4428.8 4485.5 4533.3 5842.9 5875.2 5879.9 5908.1 5935.7 6177.9 6221.0 6283.2 6293.0 6305.7 5920.0 5946.9 6003.0 6006.7 6047.5 5365.2 5391.1 5402.7 5414.9 5456.0 6178.4 6231.3 6252.0 6284.8 6289.5 6286.8 6294.5 6297.9 6298.2 6315.0 6253.0 6280.7 6292.4 6298.3 6301.9 6140.3 6170.5 6182.9 6209.7 6267.7 6264.2 6264.8 6269.4 6275.5 6295.3 6019.3 6157.6 6165.7 6184.4 6237.8 6052.9 6115.4 6118.4 6200.0 6218.0 5292.0 5762.9 6043.1 6052.8 6059.9 6109.4 6132.1 6133.7 6135.0 6158.9 6054.5 6102.2 6102.8 6132.3 6135.5 09:15:06 INFO - PROCESS | 2160 | 09:15:06 INFO - PROCESS | 2160 | [#1] ext.html Cycles:180 Average:66563.96 Median:35321.80 stddev:143428.05 (406.1%) stddev-sans-first:143744.56 09:15:06 INFO - PROCESS | 2160 | Values: 470.7 481.6 486.7 488.1 490.8 23757.9 30370.7 37312.6 40103.6 40202.6 40628.8 41152.0 41189.8 41229.8 41276.6 8452.8 8699.3 9200.2 9455.9 9461.8 11189.6 11486.0 11537.1 11626.6 11629.0 11406.3 11442.4 11467.0 11471.3 11512.5 11346.5 11376.4 11410.3 11478.4 11487.0 8839.0 9384.3 10050.0 10359.4 11195.6 39140.2 39449.4 39453.0 39513.6 39545.8 2790.4 2918.5 2954.0 3091.5 3283.2 30014.7 30153.5 30453.3 30593.4 30716.2 2635.8 3203.5 3213.7 3215.4 3269.6 423669.0 515595.0 517193.0 519730.0 520236.0 58315.7 58643.0 58700.1 58715.0 58933.0 62173.7 63010.7 63061.7 63247.0 63507.0 14199.4 15585.3 15802.8 15821.0 15845.5 11205.9 11416.6 12255.3 12351.4 12476.9 16888.2 17004.1 17061.3 17063.7 17172.8 6156.8 6884.3 6952.4 7038.4 7116.8 841.5 952.9 1018.7 1036.8 1126.1 29437.0 29668.4 30035.5 30537.7 30600.9 965.4 1058.3 1079.1 1092.4 1142.6 14652.7 14739.4 15610.3 15669.5 15929.3 180119.1 616937.0 624264.0 627464.9 629363.0 53381.0 53585.0 58516.0 58573.0 58750.8 1138.7 1269.0 1312.2 1380.4 1394.1 29605.6 29846.7 30044.8 30268.2 30295.2 49882.3 50565.0 50602.7 50650.7 50801.5 535944.0 539203.3 546035.0 547788.0 548242.0 33014.5 35785.8 39954.0 40216.0 40357.8 43714.0 46140.8 46302.5 46366.8 47089.5 31363.7 38169.0 38542.4 39095.2 39369.0 36949.0 37943.4 38182.0 38412.6 39217.8 32947.8 36320.8 37927.4 37934.2 37998.8 27426.7 32283.5 34038.1 34283.8 34857.8 25060.4 25399.5 25535.4 25611.7 26037.9 09:15:06 INFO - PROCESS | 2160 | 09:15:06 INFO - PROCESS | 2160 | [#2] jquery.html Cycles:180 Average:34038.45 Median:1603.99 stddev:95425.72 (5949.3%) stddev-sans-first:95659.93 09:15:06 INFO - PROCESS | 2160 | Values: 368.6 374.3 374.5 384.5 399.4 18364.3 20630.2 20692.4 20752.6 20937.9 20090.3 20469.4 20557.8 20729.6 20989.3 4341.7 4651.0 5180.6 5242.0 5319.4 5252.1 5530.6 5667.5 5875.4 5883.2 5570.9 5727.6 5788.3 5876.5 5900.0 5335.7 5698.5 5770.9 5772.5 5772.9 3948.1 4782.3 5037.0 5100.6 5125.7 25274.4 25283.5 25303.4 25636.4 25811.4 2182.4 2217.4 2330.8 2632.7 2839.4 14888.7 15196.9 15523.0 15644.9 15759.0 1346.6 1482.9 1604.8 1742.4 1781.9 100520.5 119811.0 119928.4 120226.6 122486.0 10519.4 10696.4 10838.1 11219.6 11278.2 13739.8 13782.2 13915.4 13952.9 13965.0 7319.6 8266.5 8415.5 8446.3 8569.8 8377.4 8405.7 8435.5 8559.0 8573.6 5630.7 6402.5 6713.6 6723.2 6744.9 1818.5 1858.7 1858.9 1859.2 1871.3 869.8 923.7 947.5 954.3 964.1 20861.2 21898.6 22146.3 22168.7 22218.0 929.2 937.7 950.9 952.9 973.9 8761.3 9186.0 11709.5 11856.5 11970.8 559400.0 564543.0 569997.0 577826.0 579271.0 113378.3 120291.0 120318.4 129372.0 144213.0 430.2 456.6 457.9 459.0 459.8 10863.8 10985.7 11475.3 11496.7 11548.6 41618.8 52494.2 52544.0 52944.0 56284.4 72901.5 81226.4 81554.0 82409.0 83751.6 8130.9 8173.8 8241.4 8521.7 8541.9 10513.5 11951.0 12708.7 13065.2 13111.7 8965.8 10180.5 11171.2 11252.3 11480.4 9274.3 10743.6 11173.3 11180.7 11440.4 11524.6 11549.9 11733.5 11862.5 11926.9 6459.8 7790.8 8311.7 8311.9 8364.1 10599.6 10757.8 11077.9 11570.4 11627.1 09:15:06 INFO - PROCESS | 2160 | 09:15:06 INFO - PROCESS | 2160 | [#3] mootools.html Cycles:180 Average:5358.72 Median:5446.78 stddev:2906.67 (53.4%) stddev-sans-first:2899.47 09:15:06 INFO - PROCESS | 2160 | Values: 1384.0 1506.8 1706.2 1823.9 1872.2 7474.1 7641.2 7939.0 8315.6 8354.5 6744.6 7812.8 8114.4 8210.3 8225.5 2294.1 2323.0 2332.1 2333.5 2342.2 2329.8 2344.2 2362.6 2376.5 2377.6 2302.5 2308.2 2328.6 2329.6 2342.3 1904.0 2234.7 2287.2 2290.5 2314.7 1910.7 2294.7 2296.7 2333.9 2334.0 8269.0 8324.6 8331.7 8341.6 8346.0 5023.0 5184.6 5197.4 5222.1 5222.2 3288.9 3374.4 3578.5 3580.2 3583.3 836.7 1021.5 1035.7 1061.9 1066.8 10597.0 10600.9 10632.0 10731.3 10749.0 4473.4 4606.4 4620.6 4675.1 4712.0 9235.0 9351.9 9372.5 9380.4 9389.1 5185.9 5195.8 5204.8 5208.8 5219.4 4239.4 4242.2 4721.7 4746.2 4765.4 3205.6 3757.9 3804.7 3844.0 3894.0 1247.2 1254.8 1257.7 1263.0 1269.4 788.4 890.2 909.4 933.1 943.3 5356.3 5744.8 5780.9 5809.9 5830.4 906.9 913.2 927.9 938.3 968.3 3734.8 3795.5 3828.6 3898.3 3910.3 9527.8 9911.9 9923.9 9965.4 9967.4 9846.8 9906.4 9908.3 9937.4 9968.9 7252.8 7643.4 7678.7 7692.5 7806.0 5744.3 5769.0 5859.4 5859.7 5935.6 8845.3 9148.1 9160.6 9178.5 9326.9 9249.5 9306.3 9346.3 9380.8 9457.4 5468.4 5739.5 6565.7 7104.1 7275.7 7789.8 7960.8 7996.2 7999.9 8025.5 5964.6 6653.9 7104.0 7163.7 7243.0 5425.1 5929.6 5936.6 6110.9 6956.5 6352.5 6536.1 6659.6 6668.8 6677.3 4804.8 4816.3 4816.6 4877.7 4989.6 4886.0 4924.3 4966.3 4971.5 4997.9 09:15:06 INFO - PROCESS | 2160 | 09:15:06 INFO - PROCESS | 2160 | [#4] prototype.html Cycles:180 Average:4438.94 Median:5008.96 stddev:1373.38 (27.4%) stddev-sans-first:1342.51 09:15:06 INFO - PROCESS | 2160 | Values: 350.2 357.9 362.7 369.6 373.9 4767.4 5145.6 5605.0 5637.5 5644.3 4499.6 5237.3 5273.0 5289.9 5488.5 3351.5 4248.0 4263.5 4279.4 4331.9 3669.6 3891.2 4182.5 4293.3 4300.9 4077.5 4083.5 4091.7 4123.5 4140.6 3384.8 3835.8 3968.7 4092.3 4109.0 3279.9 3525.4 3538.4 3542.0 3559.4 5147.8 5195.0 5348.6 5389.5 5409.8 3928.6 4330.6 4670.6 4699.1 4724.3 5669.8 5754.7 5761.2 5773.4 5788.5 3249.6 3252.9 3286.3 3869.8 3898.4 4996.0 5047.8 5749.8 5870.4 6047.3 3184.5 3404.1 3938.0 3951.8 4012.9 3373.8 3569.7 3743.4 4024.1 4033.9 4643.3 4646.9 4669.0 4694.3 4746.5 4867.4 5383.1 5844.4 5917.5 6022.8 1067.0 1228.8 1267.0 1284.3 1295.8 790.7 860.3 875.4 889.4 890.3 4817.8 5179.6 5231.7 5237.8 5287.0 4459.4 4547.0 5194.5 5461.5 5621.7 5128.2 5153.3 5166.7 5169.7 5206.7 2576.9 2614.3 2673.0 2816.6 2975.4 4836.5 5150.5 5503.9 5767.8 5859.8 5263.3 5293.8 5755.3 5799.7 5830.4 5330.3 5463.5 5517.7 5774.5 5790.4 4677.4 4695.1 5329.3 5363.0 5676.5 4791.4 5021.9 5052.7 5390.5 5628.8 4652.5 5298.4 5585.4 5636.8 5778.3 3225.5 3234.6 3236.7 3725.7 3771.5 4890.5 5121.8 5613.6 5642.7 5706.4 4554.5 5060.5 5362.7 5395.1 5405.9 3928.9 4533.8 4535.1 5221.6 5467.9 5356.4 5376.6 5439.2 5533.6 5542.2 4355.6 4451.8 4572.7 4662.0 5825.3 5042.9 5487.6 5699.6 5709.0 5739.1 09:15:06 INFO - PROCESS | 2160 | 09:15:06 INFO - PROCESS | 2160 | [#5] yui.html Cycles:180 Average:19263.72 Median:4611.43 stddev:27081.25 (587.3%) stddev-sans-first:27121.36 09:15:06 INFO - PROCESS | 2160 | Values: 701.9 712.2 738.8 775.4 822.6 14950.6 15380.0 15489.8 15530.9 15554.1 13653.3 14078.5 14293.1 14429.6 14463.3 715.3 886.0 889.3 889.4 889.4 669.7 708.0 725.9 746.5 878.0 921.4 939.2 957.0 957.1 957.5 867.0 914.6 939.6 942.8 943.3 691.5 698.1 703.0 703.9 704.5 14993.3 15148.4 15182.8 15230.2 15665.5 14519.2 14778.4 14927.9 15052.5 15245.6 11692.4 11761.1 11852.9 11896.7 12320.5 3664.7 3744.0 3757.2 3758.2 3760.1 116756.0 118334.4 121583.0 122545.4 123702.0 9890.0 10495.2 11162.4 11253.8 11263.2 23029.8 23804.3 23827.6 23833.6 23923.8 7759.6 7772.6 7910.7 8008.0 8139.7 3582.8 4277.2 4334.5 4411.5 4567.8 3581.8 3661.5 3689.6 3703.2 3708.8 637.7 639.4 639.9 646.3 651.8 576.3 642.0 655.2 656.7 664.6 8877.9 9084.2 9330.6 9458.9 9564.3 623.9 667.1 671.7 673.8 681.5 4380.7 4601.7 4621.1 4621.8 4701.1 68630.0 77290.6 79709.0 80416.0 81866.6 66730.3 67205.7 67396.7 73838.0 75128.6 54591.0 63732.4 64777.0 71158.0 73270.9 7652.8 7690.3 8452.5 9001.7 9576.9 46781.0 46906.0 48162.0 48583.8 49476.3 61552.0 64654.0 65548.7 66365.0 67458.7 12926.0 13395.1 13448.4 13535.6 13710.9 14543.8 14669.9 15068.4 15106.1 15852.4 11250.0 13620.3 14026.4 14138.6 14597.5 13657.5 13752.9 13879.0 13890.3 14168.4 12973.9 13303.5 13447.1 13638.9 13830.7 10846.7 11110.8 11128.2 11182.7 11928.6 10375.5 11260.5 11549.2 11585.5 11619.6 09:15:06 INFO - PROCESS | 2160 | -------- Summary: end -------- 09:15:06 INFO - PROCESS | 2160 | 09:15:06 INFO - TEST-INFO | 2160: exit 0 09:15:06 INFO - TEST-OK | dromaeo_css | took 1116350ms 09:15:06 INFO - TEST-START | kraken 09:15:06 INFO - operating with platform_type : mac_ 09:15:06 INFO - Initialising browser for kraken test... 09:15:06 INFO - TEST-INFO | started process 2165 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/tmpLJIInG/profile http://localhost:57158/getInfo.html) 09:15:09 INFO - PROCESS | 2165 | 2016-12-13 09:15:09.426 plugin-container[2166:21246] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x444b, name = 'com.apple.tsm.portname' 09:15:09 INFO - PROCESS | 2165 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:15:09 INFO - PROCESS | 2165 | 2016-12-13 09:15:09.429 plugin-container[2166:21246] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c17, name = 'com.apple.CFPasteboardClient' 09:15:09 INFO - PROCESS | 2165 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:15:09 INFO - PROCESS | 2165 | 2016-12-13 09:15:09.429 plugin-container[2166:21246] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:15:09 INFO - PROCESS | 2165 | __metrics Screen width/height:1600/1200 09:15:09 INFO - PROCESS | 2165 | colorDepth:24 09:15:09 INFO - PROCESS | 2165 | Browser inner width/height: 1024/689 09:15:09 INFO - PROCESS | 2165 | __metrics 09:15:09 ERROR - PROCESS | 2165 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3212: TypeError: this.tabs is undefined 09:15:10 INFO - TEST-INFO | 2165: exit 0 09:15:10 INFO - Browser initialized. 09:15:10 INFO - Running cycle 1/1 for kraken test... 09:15:10 INFO - TEST-INFO | started process 2167 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/tmpLJIInG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:15:12 INFO - PROCESS | 2167 | 2016-12-13 09:15:12.716 plugin-container[2168:21372] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883b, name = 'com.apple.tsm.portname' 09:15:12 INFO - PROCESS | 2167 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:15:12 INFO - PROCESS | 2167 | 2016-12-13 09:15:12.716 plugin-container[2168:21372] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0b, name = 'com.apple.CFPasteboardClient' 09:15:12 INFO - PROCESS | 2167 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:15:12 INFO - PROCESS | 2167 | 2016-12-13 09:15:12.716 plugin-container[2168:21372] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:15:22 INFO - PROCESS | 2167 | RSS: Main: 237236224 09:15:22 INFO - PROCESS | 2167 | 09:15:25 INFO - PROCESS | 2167 | Cycle 1(1): loaded http://localhost:57158/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57158/tests/kraken/driver.html?testName=audio-beat-detection) 09:15:26 INFO - PROCESS | 2167 | RSS: Main: 249503744 09:15:26 INFO - PROCESS | 2167 | 09:15:28 INFO - PROCESS | 2167 | Cycle 1(1): loaded http://localhost:57158/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57158/tests/kraken/driver.html?testName=audio-dft) 09:15:29 INFO - PROCESS | 2167 | RSS: Main: 257126400 09:15:29 INFO - PROCESS | 2167 | 09:15:32 INFO - PROCESS | 2167 | Cycle 1(1): loaded http://localhost:57158/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57158/tests/kraken/driver.html?testName=audio-fft) 09:15:33 INFO - PROCESS | 2167 | RSS: Main: 259682304 09:15:33 INFO - PROCESS | 2167 | 09:15:35 INFO - PROCESS | 2167 | Cycle 1(1): loaded http://localhost:57158/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57158/tests/kraken/driver.html?testName=audio-oscillator) 09:15:36 INFO - PROCESS | 2167 | RSS: Main: 260972544 09:15:36 INFO - PROCESS | 2167 | 09:15:37 INFO - PROCESS | 2167 | Cycle 1(1): loaded http://localhost:57158/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57158/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:15:38 INFO - PROCESS | 2167 | RSS: Main: 261226496 09:15:38 INFO - PROCESS | 2167 | 09:15:41 INFO - PROCESS | 2167 | Cycle 1(1): loaded http://localhost:57158/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57158/tests/kraken/driver.html?testName=imaging-darkroom) 09:15:42 INFO - PROCESS | 2167 | RSS: Main: 268943360 09:15:42 INFO - PROCESS | 2167 | 09:15:45 INFO - PROCESS | 2167 | Cycle 1(1): loaded http://localhost:57158/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57158/tests/kraken/driver.html?testName=imaging-desaturate) 09:15:46 INFO - PROCESS | 2167 | RSS: Main: 267886592 09:15:46 INFO - PROCESS | 2167 | 09:15:49 INFO - PROCESS | 2167 | Cycle 1(1): loaded http://localhost:57158/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57158/tests/kraken/driver.html?testName=json-parse-financial) 09:15:49 INFO - PROCESS | 2167 | RSS: Main: 267923456 09:15:49 INFO - PROCESS | 2167 | 09:15:51 INFO - PROCESS | 2167 | Cycle 1(1): loaded http://localhost:57158/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57158/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:15:52 INFO - PROCESS | 2167 | RSS: Main: 267112448 09:15:52 INFO - PROCESS | 2167 | 09:15:54 INFO - PROCESS | 2167 | Cycle 1(1): loaded http://localhost:57158/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57158/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:15:54 INFO - PROCESS | 2167 | RSS: Main: 267030528 09:15:54 INFO - PROCESS | 2167 | 09:15:57 INFO - PROCESS | 2167 | Cycle 1(1): loaded http://localhost:57158/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57158/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:15:57 INFO - PROCESS | 2167 | RSS: Main: 267091968 09:15:57 INFO - PROCESS | 2167 | 09:16:00 INFO - PROCESS | 2167 | Cycle 1(1): loaded http://localhost:57158/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57158/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:16:01 INFO - PROCESS | 2167 | RSS: Main: 267341824 09:16:01 INFO - PROCESS | 2167 | 09:16:04 INFO - PROCESS | 2167 | Cycle 1(1): loaded http://localhost:57158/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57158/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:16:05 INFO - PROCESS | 2167 | RSS: Main: 267210752 09:16:05 INFO - PROCESS | 2167 | 09:16:07 INFO - PROCESS | 2167 | Cycle 1(1): loaded http://localhost:57158/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57158/tests/kraken/driver.html?testName=ai-astar) 09:16:07 INFO - PROCESS | 2167 | RSS: Main: 268255232 09:16:07 INFO - PROCESS | 2167 | 09:16:07 INFO - PROCESS | 2167 | __start_tp_report 09:16:07 INFO - PROCESS | 2167 | _x_x_mozilla_page_load 09:16:07 INFO - PROCESS | 2167 | _x_x_mozilla_page_load_details 09:16:07 INFO - PROCESS | 2167 | |i|pagename|runs| 09:16:07 INFO - PROCESS | 2167 | |0;ai-astar;114;117;117;117;124;167;118;121;119;157 09:16:07 INFO - PROCESS | 2167 | |1;audio-beat-detection;187;154;134;157;118;120;121;111;177;153 09:16:07 INFO - PROCESS | 2167 | |2;audio-dft;191;209;210;212;198;197;201;195;200;196 09:16:07 INFO - PROCESS | 2167 | |3;audio-fft;80;61;60;87;61;63;75;87;64;80 09:16:07 INFO - PROCESS | 2167 | |4;audio-oscillator;96;73;83;72;71;71;85;71;70;86 09:16:07 INFO - PROCESS | 2167 | |5;imaging-gaussian-blur;109;113;111;113;111;114;131;170;110;112 09:16:07 INFO - PROCESS | 2167 | |6;imaging-darkroom;119;133;130;128;115;102;95;89;95;125 09:16:07 INFO - PROCESS | 2167 | |7;imaging-desaturate;94;137;105;141;117;143;146;117;93;91 09:16:07 INFO - PROCESS | 2167 | |8;json-parse-financial;59;58;57;58;57;60;59;58;58;58 09:16:07 INFO - PROCESS | 2167 | |9;json-stringify-tinderbox;69;77;77;86;74;72;73;74;77;113 09:16:07 INFO - PROCESS | 2167 | |10;stanford-crypto-aes;104;111;112;92;94;92;94;89;90;92 09:16:07 INFO - PROCESS | 2167 | |11;stanford-crypto-ccm;188;163;159;147;148;150;181;156;240;164 09:16:07 INFO - PROCESS | 2167 | |12;stanford-crypto-pbkdf2;224;245;191;196;157;218;243;195;180;176 09:16:07 INFO - PROCESS | 2167 | |13;stanford-crypto-sha256-iterative;70;74;68;92;70;69;67;70;69;66 09:16:07 INFO - PROCESS | 2167 | __end_tp_report 09:16:07 INFO - PROCESS | 2167 | __start_cc_report 09:16:07 INFO - PROCESS | 2167 | _x_x_mozilla_cycle_collect,941 09:16:07 INFO - PROCESS | 2167 | __end_cc_report 09:16:07 INFO - PROCESS | 2167 | __startTimestamp1481649367456__endTimestamp 09:16:07 INFO - PROCESS | 2167 | 09:16:07 INFO - PROCESS | 2167 | ------- Summary: start ------- 09:16:07 INFO - PROCESS | 2167 | Number of tests: 14 09:16:07 INFO - PROCESS | 2167 | 09:16:07 INFO - PROCESS | 2167 | [#0] ai-astar Cycles:10 Average:127.10 Median:120.00 stddev:18.73 (15.6%) stddev-sans-first:19.26 09:16:07 INFO - PROCESS | 2167 | Values: 114.0 117.0 117.0 117.0 124.0 167.0 118.0 121.0 119.0 157.0 09:16:07 INFO - PROCESS | 2167 | 09:16:07 INFO - PROCESS | 2167 | [#1] audio-beat-detection Cycles:10 Average:143.20 Median:153.50 stddev:26.36 (17.2%) stddev-sans-first:22.69 09:16:07 INFO - PROCESS | 2167 | Values: 187.0 154.0 134.0 157.0 118.0 120.0 121.0 111.0 177.0 153.0 09:16:07 INFO - PROCESS | 2167 | 09:16:07 INFO - PROCESS | 2167 | [#2] audio-dft Cycles:10 Average:200.90 Median:200.50 stddev:7.09 (3.5%) stddev-sans-first:6.56 09:16:07 INFO - PROCESS | 2167 | Values: 191.0 209.0 210.0 212.0 198.0 197.0 201.0 195.0 200.0 196.0 09:16:07 INFO - PROCESS | 2167 | 09:16:07 INFO - PROCESS | 2167 | [#3] audio-fft Cycles:10 Average:71.80 Median:77.50 stddev:11.14 (14.4%) stddev-sans-first:11.42 09:16:07 INFO - PROCESS | 2167 | Values: 80.0 61.0 60.0 87.0 61.0 63.0 75.0 87.0 64.0 80.0 09:16:07 INFO - PROCESS | 2167 | 09:16:07 INFO - PROCESS | 2167 | [#4] audio-oscillator Cycles:10 Average:77.80 Median:78.00 stddev:9.03 (11.6%) stddev-sans-first:6.76 09:16:07 INFO - PROCESS | 2167 | Values: 96.0 73.0 83.0 72.0 71.0 71.0 85.0 71.0 70.0 86.0 09:16:07 INFO - PROCESS | 2167 | 09:16:07 INFO - PROCESS | 2167 | [#5] imaging-gaussian-blur Cycles:10 Average:119.40 Median:113.00 stddev:18.85 (16.7%) stddev-sans-first:19.62 09:16:07 INFO - PROCESS | 2167 | Values: 109.0 113.0 111.0 113.0 111.0 114.0 131.0 170.0 110.0 112.0 09:16:07 INFO - PROCESS | 2167 | 09:16:07 INFO - PROCESS | 2167 | [#6] imaging-darkroom Cycles:10 Average:113.10 Median:131.50 stddev:16.48 (12.5%) stddev-sans-first:17.34 09:16:07 INFO - PROCESS | 2167 | Values: 119.0 133.0 130.0 128.0 115.0 102.0 95.0 89.0 95.0 125.0 09:16:07 INFO - PROCESS | 2167 | 09:16:07 INFO - PROCESS | 2167 | [#7] imaging-desaturate Cycles:10 Average:118.40 Median:144.50 stddev:22.11 (15.3%) stddev-sans-first:21.61 09:16:07 INFO - PROCESS | 2167 | Values: 94.0 137.0 105.0 141.0 117.0 143.0 146.0 117.0 93.0 91.0 09:16:07 INFO - PROCESS | 2167 | 09:16:07 INFO - PROCESS | 2167 | [#8] json-parse-financial Cycles:10 Average:58.20 Median:58.00 stddev:0.92 (1.6%) stddev-sans-first:0.93 09:16:07 INFO - PROCESS | 2167 | Values: 59.0 58.0 57.0 58.0 57.0 60.0 59.0 58.0 58.0 58.0 09:16:07 INFO - PROCESS | 2167 | 09:16:07 INFO - PROCESS | 2167 | [#9] json-stringify-tinderbox Cycles:10 Average:79.20 Median:75.50 stddev:12.70 (16.8%) stddev-sans-first:12.92 09:16:07 INFO - PROCESS | 2167 | Values: 69.0 77.0 77.0 86.0 74.0 72.0 73.0 74.0 77.0 113.0 09:16:07 INFO - PROCESS | 2167 | 09:16:07 INFO - PROCESS | 2167 | [#10] stanford-crypto-aes Cycles:10 Average:97.00 Median:92.00 stddev:8.67 (9.4%) stddev-sans-first:8.81 09:16:07 INFO - PROCESS | 2167 | Values: 104.0 111.0 112.0 92.0 94.0 92.0 94.0 89.0 90.0 92.0 09:16:07 INFO - PROCESS | 2167 | 09:16:07 INFO - PROCESS | 2167 | [#11] stanford-crypto-ccm Cycles:10 Average:169.60 Median:163.50 stddev:28.20 (17.2%) stddev-sans-first:29.12 09:16:07 INFO - PROCESS | 2167 | Values: 188.0 163.0 159.0 147.0 148.0 150.0 181.0 156.0 240.0 164.0 09:16:07 INFO - PROCESS | 2167 | 09:16:07 INFO - PROCESS | 2167 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:202.50 Median:207.00 stddev:29.17 (14.1%) stddev-sans-first:29.88 09:16:07 INFO - PROCESS | 2167 | Values: 224.0 245.0 191.0 196.0 157.0 218.0 243.0 195.0 180.0 176.0 09:16:07 INFO - PROCESS | 2167 | 09:16:07 INFO - PROCESS | 2167 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.50 Median:70.00 stddev:7.52 (10.7%) stddev-sans-first:7.95 09:16:07 INFO - PROCESS | 2167 | Values: 70.0 74.0 68.0 92.0 70.0 69.0 67.0 70.0 69.0 66.0 09:16:07 INFO - PROCESS | 2167 | -------- Summary: end -------- 09:16:07 INFO - PROCESS | 2167 | 09:16:08 INFO - TEST-INFO | 2167: exit 0 09:16:08 INFO - TEST-OK | kraken | took 61627ms 09:16:08 INFO - SUITE-END | took 1177s 09:16:08 INFO - Completed test suite (00:19:38) 09:16:08 INFO - javascript benchmark 09:16:08 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7165.483201750611, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3410.403179443597, 3425.5375524304213, 3425.9037108125403, 3435.484677298405, 3443.9655603443966, 5795.101759227321, 5799.304083509985, 5864.149698293745, 5871.970640146804, 5889.7349619267125, 5643.153526970957, 5705.914411283824, 5770.567207459437, 5772.0, 5774.711264436772, 5175.40482844473, 5238.8690282742855, 5238.895222095556, 5280.894382112356, 5336.199570064502, 5334.786608535654, 5338.599605029619, 5352.304200453955, 5361.276227709268, 5375.978564072833, 5329.813456529022, 5371.7582708778, 5389.9191512127345, 5392.379876314235, 5405.7567409466665, 5321.494458026481, 5345.251664766955, 5368.087425137716, 5386.803524895078, 5436.646617969859, 4419.867403977886, 4973.726445045521, 5243.475652434764, 5260.500252476008, 5351.411344752113, 5344.492273234076, 5634.323881134249, 5761.769529218826, 5796.739146738365, 5806.983777838861, 6227.314995350508, 6232.34560371159, 6298.2756982947085, 6307.779227727007, 6313.179286692701, 6204.47261982732, 6216.129741836146, 6232.9376706232815, 6242.469390101847, 6256.812295631138, 5923.881522369528, 5932.288125424977, 5932.940670593239, 5948.40515948406, 5974.327188387215, 6305.064429127922, 6337.271213810379, 6349.650769207646, 6363.459105975951, 6380.872382552416, 6106.847328816815, 6127.250613123855, 6131.570790044743, 6166.753329866755, 6224.190855188796, 4981.601471882241, 5113.71874546896, 5555.944440555623, 6204.162438070803, 6262.749490020439, 5173.0, 6223.3465486123305, 6240.656763878031, 6305.8108256752375, 6323.114763933053, 4522.321651752198, 6150.630962142286, 6262.0, 6293.402126797947, 6308.0, 3980.9251502094853, 4887.755612219375, 4899.657024008286, 4920.6309526785635, 4980.352554167935, 3745.8688945886765, 4409.603135717801, 4428.800703968329, 4485.506594274627, 4533.252013417749, 5842.912356314659, 5875.20684707559, 5879.911801322984, 5908.084246941645, 5935.673537955454, 6177.938220617826, 6220.968895155495, 6283.214598175228, 6292.968535157295, 6305.716242769086, 5919.9936010878255, 5946.929552680473, 6003.0, 6006.699665016732, 6047.485963693048, 5365.22204280385, 5391.083515802323, 5402.6758394496455, 5414.864628384243, 5456.017916774939, 6178.351273116258, 6231.314555398993, 6252.030935205051, 6284.780032698834, 6289.465395441439, 6286.8428289291305, 6294.496440284858, 6297.937020629918, 6298.244210694746, 6315.0, 6253.0, 6280.7487700491465, 6292.402221788922, 6298.338674439118, 6301.928672125658, 6140.258857967814, 6170.50635949114, 6182.856171608267, 6209.658468784261, 6267.655278959701, 6264.154339164337, 6264.812055638338, 6269.435750782452, 6275.497960163085, 6295.307516173126, 6019.337872833898, 6157.599756015845, 6165.660888651168, 6184.443400094013, 6237.781677641261, 6052.909206361996, 6115.388461153849, 6118.449339559461, 6200.038993955944, 6218.0, 5292.0, 5762.913556296575, 6043.12374705674, 6052.788152414644, 6059.878802424015, 6109.374301065143, 6132.110769108063, 6133.723982420713, 6134.957077261001, 6158.876822463615, 6054.515638748801, 6102.176206212104, 6102.816915492543, 6132.263729246367, 6135.53983451234], "value": 5712.3763233525915, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [470.6658272626435, 481.6050838312583, 486.69095124595884, 488.05317683693636, 490.8380234522606, 23757.94172486018, 30370.696293037065, 37312.626873731286, 40103.59896401035, 40202.59797402025, 40628.79685601568, 41152.0, 41189.794051029705, 41229.79385103078, 41276.58723412765, 8452.830943381128, 8699.260562852165, 9200.217981471566, 9455.858162127573, 9461.763455913588, 11189.608363707272, 11486.0, 11537.134714896374, 11626.593069242579, 11628.953394194521, 11406.258593191458, 11442.370669613169, 11467.025302849226, 11471.254368466065, 11512.539498420054, 11346.546138154505, 11376.431178441046, 11410.31538107712, 11478.368689722061, 11487.023602993755, 8839.0, 9384.296177786626, 10049.995000499965, 10359.378437293712, 11195.608153714662, 39140.217195655925, 39449.40825887614, 39453.0, 39513.61702340433, 39545.80227098875, 2790.4140130572605, 2918.54762511811, 2953.9852300738576, 3091.536269559584, 3283.2448536836423, 30014.69985300141, 30153.547696784564, 30453.323031662832, 30593.388132237455, 30716.2320941976, 2635.8154929154966, 3203.519472079207, 3213.694699003591, 3215.389076075538, 3269.640339562646, 423669.0, 515595.0, 517193.0, 519730.0, 520235.9999999962, 58315.708421457624, 58643.0, 58700.11949820756, 58715.0, 58933.0, 62173.689131554056, 63010.68494657498, 63061.68469157625, 63247.0, 63507.0, 14199.438061813193, 15585.298661560257, 15802.841971580367, 15821.0, 15845.52463426099, 11205.887941120647, 11416.600418985461, 12255.264684118982, 12351.38243087842, 12476.937615312047, 16888.240029198714, 17004.064776437415, 17061.317547297967, 17063.658726825393, 17172.828271717124, 6156.799424112255, 6884.277150899184, 6952.443804495628, 7038.401735852408, 7116.786496405116, 841.5413599588117, 952.9332946693803, 1018.6520071235725, 1036.828923227674, 1126.1272513801869, 29437.0, 29668.40663186724, 30035.549466758017, 30537.69462305393, 30600.92896748603, 965.4448692002127, 1058.317385286486, 1079.0935614084208, 1092.3664274720643, 1142.628645690154, 14652.706945861022, 14739.410423583151, 15610.275495937567, 15669.529914102595, 15929.283182256826, 180119.09940450214, 616937.0, 624264.0, 627464.8626756929, 629363.0, 53380.99999999922, 53585.0, 58516.0, 58573.0, 58750.82498350095, 1138.686861113184, 1268.9936550317188, 1312.2454588611586, 1380.42712274405, 1394.0520446096752, 29605.5559166617, 29846.701532985262, 30044.849775751856, 30268.24329391783, 30295.185383562668, 49882.25176622426, 50565.0, 50602.746986264836, 50650.746746266035, 50801.49198507968, 535944.0, 539203.3039834775, 546035.0, 547788.0, 548242.0, 33014.50478242876, 35785.821070894475, 39954.0, 40216.0, 40357.79821100993, 43714.0, 46140.7692961533, 46302.536974629824, 46366.76816615895, 47089.52910470851, 31363.686363136076, 38169.0, 38542.42186367151, 39095.2180956385, 39369.0, 36949.0, 37943.43084853785, 38182.0, 38412.61587384202, 39217.80391098026, 32947.835260823544, 36320.81839590785, 37927.43108853314, 37934.24131517299, 37998.810005949796, 27426.725732743216, 32283.51574726334, 34038.14904627404, 34283.828580856934, 34857.77997770167, 25060.373490662878, 25399.492010160062, 25535.361615959006, 25611.74388256168, 26037.869810650827], "value": 19816.899840113612, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [368.5945459994005, 374.27764414679666, 374.5262243262273, 384.4924699396797, 399.357035173371, 18364.265429382824, 20630.1747930083, 20692.379228623147, 20752.584948301042, 20937.895310523425, 20090.296839610615, 20469.385918422435, 20557.794422055777, 20729.585408291827, 20989.271394718664, 4341.734599755687, 4650.953490465103, 5180.611454140945, 5241.977814326208, 5319.361676598814, 5252.054630166568, 5530.640508366943, 5667.4615911488345, 5875.38308477608, 5883.205767221434, 5570.916436253459, 5727.627704199234, 5788.27646544182, 5876.471117599415, 5899.970500147493, 5335.719427337411, 5698.544116470673, 5770.942290577124, 5772.538196944234, 5772.942270577282, 3948.091938854051, 4782.306565547976, 5037.0, 5100.566451851597, 5125.666831655931, 25274.368140796443, 25283.494330113477, 25303.36741581457, 25636.35909102269, 25811.35471613206, 2182.3998400439846, 2217.3569664797187, 2330.8135349172026, 2632.7498887605648, 2839.3895312507702, 14888.702225955529, 15196.924015379853, 15523.0, 15644.904856660038, 15759.0, 1346.5556366399064, 1482.9184394858278, 1604.8174482703514, 1742.3901634427975, 1781.8930864148192, 100520.49739751255, 119811.0, 119928.40035799853, 120226.59546809102, 122486.0, 10519.368837869599, 10696.411697356565, 10838.078763305208, 11219.551217951353, 11278.199228951624, 13739.793903091462, 13782.241976691177, 13915.373808178656, 13952.930235348758, 13965.0, 7319.63401829917, 8266.462679925906, 8415.53714545706, 8446.282066024334, 8569.785755356166, 8377.413581049268, 8405.70580029696, 8435.536045517432, 8558.95720521406, 8573.571321434029, 5630.690312032795, 6402.48780097591, 6713.597184168965, 6723.159605049369, 6744.932550674431, 1818.4999125240665, 1858.7211918212106, 1858.925642974293, 1859.1540848913712, 1871.2982631513182, 869.8477766390856, 923.7228831350702, 947.4788866123743, 954.2842867849113, 964.1130160252584, 20861.165553377694, 21898.562028759334, 22146.335609931728, 22168.667469987962, 22218.0, 929.177677755178, 937.6718148647918, 950.9476978766096, 952.8713623660717, 973.8733964584559, 8761.25529330014, 9185.954070229605, 11709.473073711704, 11856.525738970344, 11970.760584788255, 559400.0, 564543.0, 569997.0, 577826.0, 579271.0, 113378.29932551019, 120291.0, 120318.3984080074, 129372.0, 144213.0, 430.184799804369, 456.64609927306606, 457.89239528710726, 458.9535858243144, 459.779305933154, 10863.837042444211, 10985.725356866142, 11475.254108483256, 11496.655100347003, 11548.59579914699, 41618.79190604028, 52494.16270430516, 52544.0, 52944.0, 56284.43715562792, 72901.54196916138, 81226.3754724914, 81554.0, 82409.0, 83751.5812420934, 8130.918690813016, 8173.79565510867, 8241.381896357918, 8521.659133634585, 8541.914580854113, 10513.474326283502, 11951.0, 12708.745825083632, 13065.216087034809, 13111.67220819449, 8965.820683586162, 10180.52382404562, 11171.162162837658, 11252.26860254081, 11480.368579727861, 9274.2842574124, 10743.623973160902, 11173.329600224013, 11180.72048198769, 11440.370779606868, 11524.59663911759, 11549.942250288696, 11733.530658773554, 11862.466189021343, 11926.940365298466, 6459.773907913201, 7790.805229869298, 8311.709090181814, 8311.875321870057, 8364.121767214356, 10599.62901298451, 10757.784844302914, 11077.889221108007, 11570.421478926188, 11627.069834413058], "value": 9239.039183181583, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1383.9792403113954, 1506.7965824613677, 1706.15545305074, 1823.8996855172977, 1872.2136702584912, 7474.103107627092, 7641.197674244194, 7938.960305198473, 8315.584220788967, 8354.498730076191, 6744.595324280546, 7812.843743125134, 8114.350851931846, 8210.343172546196, 8225.547594882279, 2294.139697613399, 2323.0, 2332.0555175154004, 2333.4516388648653, 2342.2153578551206, 2329.8252631052655, 2344.22640528626, 2362.5747365474294, 2376.5484557933937, 2377.560151371993, 2302.5164715409783, 2308.192132753531, 2328.5692146952783, 2329.615613423783, 2342.2856028911174, 1903.9619207615908, 2234.726206062545, 2287.165184707578, 2290.473191166041, 2314.6643736658175, 1910.722945172956, 2294.736105347873, 2296.73587537432, 2333.871637059961, 2333.9883300583388, 8268.958655206745, 8324.583770811436, 8331.66673333066, 8341.582920853933, 8346.0, 5023.0, 5184.611154163453, 5197.402298735656, 5222.086134926372, 5222.164453687392, 3288.917777055569, 3374.3588718143474, 3578.499010138583, 3580.2481478889204, 3583.2654305867495, 836.7196989008689, 1021.5300961557692, 1035.6685860524635, 1061.9362838229654, 1066.8399740038979, 10597.046265836034, 10600.94699526505, 10632.043116119508, 10731.302465339728, 10749.032587067202, 4473.4408198975125, 4606.4338125036975, 4620.607248383925, 4675.0883577702025, 4711.986922811612, 9235.0, 9351.859722104175, 9372.453545165108, 9380.390274632127, 9389.061093890556, 5185.948140518546, 5195.844124676266, 5204.76578553966, 5208.8437346879655, 5219.42586315505, 4239.40648309237, 4242.215190189825, 4721.693089949143, 4746.216874215785, 4765.356676848581, 3205.599300087489, 3757.9060523487133, 3804.6766024887643, 3843.9807800961376, 3893.980530097331, 1247.1830950727287, 1254.8479357901242, 1257.7107265328843, 1262.993685031569, 1269.3526301586257, 788.4047544104164, 890.1899271662755, 909.3816204980544, 933.0534173081479, 943.2831048403262, 5356.276902618097, 5744.798932037359, 5780.884382312414, 5809.937472197394, 5830.416958304136, 906.9274458043341, 913.2100732866023, 927.9350445468888, 938.3103418987039, 968.2883080935507, 3734.8319325630414, 3795.4876091727815, 3828.578856325802, 3898.317794385971, 3910.3156947534067, 9527.809443811086, 9911.900880991097, 9923.851142232872, 9965.40207587547, 9967.40195588267, 9846.803063938682, 9906.356086854332, 9908.306418550776, 9937.403755774532, 9968.850467242997, 7252.782416527512, 7643.350315223268, 7678.654460549289, 7692.461527693008, 7805.960970195113, 5744.339400968942, 5768.990426675231, 5859.44335288147, 5859.736311866061, 5935.643861368331, 8845.292376609985, 9148.085191480799, 9160.58777355034, 9178.495182765013, 9326.906730932604, 9249.491277979776, 9306.255499559884, 9346.252299815862, 9380.765480863032, 9457.439522478713, 5468.425815289337, 5739.512141468022, 6565.737370505126, 7104.076470058859, 7275.690375732207, 7789.805254868674, 7960.800979975315, 7996.200379961957, 7999.920000800151, 8025.518468891885, 5964.582479226413, 6653.933460665331, 7104.040954471287, 7163.7492687755675, 7242.96378518104, 5425.13197888336, 5929.584929054925, 5936.554758393051, 6110.877782444415, 6956.5478243913985, 6352.523560732871, 6536.0522724205675, 6659.567128136656, 6668.83327916806, 6677.332266773478, 4804.759762011955, 4816.325714400057, 4816.566509014206, 4877.6829506081995, 4989.55094041538, 4886.0, 4924.261360795909, 4966.255061740768, 4971.453140154508, 4997.925031124463], "value": 4367.448927292782, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [350.19105865450797, 357.8783213707339, 362.6572888620255, 369.5749887629227, 373.85980257403475, 4767.354118464251, 5145.6398052136365, 5604.9719751401235, 5637.548996080314, 5644.322681278247, 4499.6400287976885, 5237.266782650422, 5273.024490469266, 5289.8576076688905, 5488.506034456898, 3351.514030465587, 4247.978760106195, 4263.4883813942215, 4279.379489973952, 4331.870043898688, 3669.5779985301765, 3891.241207964434, 4182.519010313822, 4293.270144075499, 4300.892477688051, 4077.4903137107863, 4083.530394004683, 4091.652209562176, 4123.525794533637, 4140.60664236897, 3384.796912185252, 3835.7725527831103, 3968.67049538405, 4092.3016947966407, 4108.958910410887, 3279.852406641683, 3525.4183059795105, 3538.4161613333767, 3542.0, 3559.394902866519, 5147.7683504242395, 5195.0, 5348.572114230852, 5389.487998640123, 5409.810656626999, 3928.6267804558806, 4330.631896288819, 4670.6497012724185, 4699.130660827724, 4724.267738500538, 5669.773209071674, 5754.6547207167705, 5761.164631128463, 5773.39379365169, 5788.450097240755, 3249.610046794376, 3252.869885204589, 3286.277019055804, 3869.787161706105, 3898.4152377143373, 4995.975020124913, 5047.848564543069, 5749.8275051748515, 5870.414987953229, 6047.304559975615, 3184.474561697294, 3404.097914052778, 3937.9606203938165, 3951.8221680024467, 4012.8996775080273, 3373.763836531419, 3569.660882216185, 3743.3636281832155, 4024.0684471453865, 4033.878983630496, 4643.326717625925, 4646.883827904329, 4669.042846216517, 4694.2723877799, 4746.454157771831, 4867.440244371872, 5383.115909431667, 5844.357120716718, 5917.497012753965, 6022.81414416976, 1066.9519871605796, 1228.8203324808285, 1267.0497127154633, 1284.3064745037761, 1295.7861952777876, 790.6916302641974, 860.2687715441824, 875.3718084363079, 889.3863234368265, 890.2788741119714, 4817.8313759018265, 5179.61152913533, 5231.738413079331, 5237.8428647140645, 5287.034723957032, 4459.375687403766, 4546.977265113654, 5194.532492075731, 5461.453854614586, 5621.718914054362, 5128.205128205134, 5153.315379503673, 5166.741662916839, 5169.741512924339, 5206.71363075027, 2576.948461030769, 2614.307208589725, 2673.0, 2816.5634326679397, 2975.3751712140693, 4836.516348365206, 5150.510701483353, 5503.9174412383845, 5767.798127065533, 5859.773286151235, 5263.263123169345, 5293.761780719877, 5755.309362876398, 5799.710014499258, 5830.41695830422, 5330.253764472899, 5463.535599474089, 5517.69652669099, 5774.48029677331, 5790.363060063305, 4677.438707355139, 4695.131400690883, 5329.253904453457, 5363.039188750312, 5676.545876329967, 4791.353167322367, 5021.945391467832, 5052.747362631927, 5390.520360633996, 5628.808310858023, 4652.487372034093, 5298.417174110922, 5585.408158674758, 5636.802711905064, 5778.335491418541, 3225.451673215512, 3234.64418913924, 3236.741060715184, 3725.7019438444318, 3771.4719939207985, 4890.510948905081, 5121.7695203715175, 5613.607047506635, 5642.689652069012, 5706.42935706426, 4554.51040252152, 5060.456560749007, 5362.70505122222, 5395.113614216375, 5405.945940540544, 3928.9017774555864, 4533.750643714628, 4535.095259990746, 5221.608379371638, 5467.87908478753, 5356.410794812489, 5376.59675524345, 5439.211314359475, 5533.557315414838, 5542.224088627515, 4355.586219309161, 4451.844185453494, 4572.6570507212755, 4661.953380466152, 5825.300963884361, 5042.949570504248, 5487.588430867621, 5699.606165410454, 5708.971455142698, 5739.081746920473], "value": 4017.470469209604, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [701.933316334948, 712.213004629884, 738.7672883041838, 775.4106878772131, 822.5681517203469, 14950.626234344147, 15380.0, 15489.84510154898, 15530.922345388271, 15554.066755994649, 13653.317334133304, 14078.507252246174, 14293.14241145532, 14429.639259018504, 14463.276836158202, 715.3490323805338, 885.9689910853122, 889.3463304471209, 889.3552174673375, 889.3552174673375, 669.6919417068154, 707.9504434689574, 725.8729722298604, 746.5296862976345, 877.9517126558037, 921.4287141971987, 939.2157548447021, 957.0238356875982, 957.0812020460367, 957.5212393803098, 867.0159369116099, 914.6295750221171, 939.5537119868077, 942.7784470649426, 943.2548286853412, 691.5193940211554, 698.068079114383, 702.9859402811915, 703.9049728286667, 704.5138854190643, 14993.32530036151, 15148.394064237416, 15182.848171518253, 15230.238488075553, 15665.530034098996, 14519.20144392058, 14778.408863645549, 14927.925360373238, 15052.473163439226, 15245.61885952849, 11692.415379231004, 11761.117916156323, 11852.940735296268, 11896.702582435422, 12320.5071797128, 3664.670179683841, 3743.9812800936093, 3757.229767897602, 3758.2107757370986, 3760.1163726524246, 116756.0, 118334.40832795866, 121583.0, 122545.38727306307, 123702.0, 9889.950550247202, 10495.212859035602, 11162.441877906072, 11253.774924501628, 11263.211575189658, 23029.769702303096, 23804.285871423886, 23827.642585361235, 23833.642495362583, 23923.7607623925, 7759.612019399007, 7772.611369431505, 7910.7231246906085, 8008.0, 8139.674413023531, 3582.802945838004, 4277.230098582285, 4334.491113500421, 4411.470623525198, 4567.794449249792, 3581.8388172531777, 3661.469086982426, 3689.612590677994, 3703.203811180609, 3708.7589306694977, 637.7449020391788, 639.3510586754448, 639.8880195965687, 646.3149061994301, 651.8109748172976, 576.2882839692976, 641.9935800642028, 655.1859762320844, 656.6946369938001, 664.5514277862424, 8877.911220887709, 9084.22784063362, 9330.626774928927, 9458.905410945803, 9564.330496865152, 623.8707938631093, 667.1160712056544, 671.6742379945722, 673.8483841135803, 681.5433659448181, 4380.737155770664, 4601.74690392025, 4621.1219868224925, 4621.838235661736, 4701.059788042405, 68630.0, 77290.61354693191, 79709.0, 80416.0, 81866.59066704629, 66730.33269667339, 67205.66397168081, 67396.6630166846, 73838.0, 75128.62435687786, 54591.0, 63732.36267637357, 64777.02834457487, 71158.0, 73270.900936486, 7652.80867978305, 7690.307872291297, 8452.492850428995, 9001.729948101567, 9576.900377422133, 46781.0, 46906.0, 48162.0, 48583.75708121437, 49476.25785613291, 61552.0, 64654.0, 65548.67225663841, 66365.0, 67458.66270668812, 12926.030547708771, 13395.0623456361, 13448.394822232827, 13535.59393218205, 13710.862891370958, 14543.78184327257, 14669.853301466848, 15068.397264109313, 15106.093634381972, 15852.44516441965, 11250.0, 13620.250886201355, 14026.438942442595, 14138.646533836736, 14597.489087881873, 13657.521986730417, 13752.862471375158, 13879.0, 13890.30548472552, 14168.362423690753, 12973.870261297643, 13303.534376296782, 13447.058705890493, 13638.93180534091, 13830.654233643836, 10846.67459976202, 11110.833337499782, 11128.22102452853, 11182.720431989266, 11928.582499612472, 10375.533101010322, 11260.549578016788, 11549.249298795552, 11585.536578536765, 11619.593314233962], "value": 7375.0560259518315, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1649.6999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 117.0, 117.0, 117.0, 124.0, 167.0, 118.0, 121.0, 119.0, 157.0], "value": 127.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 154.0, 134.0, 157.0, 118.0, 120.0, 121.0, 111.0, 177.0, 153.0], "value": 143.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [191.0, 209.0, 210.0, 212.0, 198.0, 197.0, 201.0, 195.0, 200.0, 196.0], "value": 200.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 61.0, 60.0, 87.0, 61.0, 63.0, 75.0, 87.0, 64.0, 80.0], "value": 71.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 73.0, 83.0, 72.0, 71.0, 71.0, 85.0, 71.0, 70.0, 86.0], "value": 77.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 113.0, 111.0, 113.0, 111.0, 114.0, 131.0, 170.0, 110.0, 112.0], "value": 119.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 133.0, 130.0, 128.0, 115.0, 102.0, 95.0, 89.0, 95.0, 125.0], "value": 113.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 137.0, 105.0, 141.0, 117.0, 143.0, 146.0, 117.0, 93.0, 91.0], "value": 118.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 58.0, 57.0, 58.0, 57.0, 60.0, 59.0, 58.0, 58.0, 58.0], "value": 58.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 77.0, 77.0, 86.0, 74.0, 72.0, 73.0, 74.0, 77.0, 113.0], "value": 79.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 111.0, 112.0, 92.0, 94.0, 92.0, 94.0, 89.0, 90.0, 92.0], "value": 97.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [188.0, 163.0, 159.0, 147.0, 148.0, 150.0, 181.0, 156.0, 240.0, 164.0], "value": 169.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [224.0, 245.0, 191.0, 196.0, 157.0, 218.0, 243.0, 195.0, 180.0, 176.0], "value": 202.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 74.0, 68.0, 92.0, 70.0, 69.0, 67.0, 70.0, 69.0, 66.0], "value": 71.5, "unit": "score"}]}]} 09:16:08 INFO - Return code: 0 09:16:08 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:16:08 ERROR - # TBPL SUCCESS # 09:16:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:16:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:16:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:16:08 INFO - 2016-12-13 09:16:08.793 screenresolution[2172:21759] starting screenresolution argv=screenresolution get 09:16:08 INFO - 2016-12-13 09:16:08.796 screenresolution[2172:21759] Display 0: 1600x1200x32@60 09:16:08 INFO - 2016-12-13 09:16:08.802 screenresolution[2173:21762] starting screenresolution argv=screenresolution list 09:16:08 INFO - Available Modes on Display 0 09:16:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:16:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:16:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:16:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:16:09 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:16:09 INFO - Intel Iris: 09:16:09 INFO - Chipset Model: Intel Iris 09:16:09 INFO - Type: GPU 09:16:09 INFO - Bus: Built-In 09:16:09 INFO - VRAM (Dynamic, Max): 1536 MB 09:16:09 INFO - Vendor: Intel (0x8086) 09:16:09 INFO - Device ID: 0x0a2e 09:16:09 INFO - Revision ID: 0x0009 09:16:09 INFO - Displays: 09:16:09 INFO - Display: 09:16:09 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:16:09 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:16:09 INFO - Display Serial Number: 1600x1200 60 09:16:09 INFO - Main Display: Yes 09:16:09 INFO - Mirror: Off 09:16:09 INFO - Online: Yes 09:16:09 INFO - Rotation: Supported 09:16:09 INFO - Return code: 0 09:16:09 INFO - Running post-action listener: _resource_record_post_action 09:16:09 INFO - [mozharness: 2016-12-13 17:16:09.042087Z] Finished run-tests step (success) 09:16:09 INFO - Running post-run listener: _resource_record_post_run 09:16:09 INFO - Total resource usage - Wall time: 1200s; CPU: 55.0%; Read bytes: 114688; Write bytes: 552817664; Read time: 4; Write time: 1738 09:16:09 INFO - TinderboxPrint: CPU usage
54.7% 09:16:09 INFO - TinderboxPrint: I/O read bytes / time
114,688 / 4 09:16:09 INFO - TinderboxPrint: I/O write bytes / time
552,817,664 / 1,738 09:16:09 INFO - TinderboxPrint: CPU idle
2,175.4 (45.3%) 09:16:09 INFO - TinderboxPrint: CPU system
969.6 (20.2%) 09:16:09 INFO - TinderboxPrint: CPU user
1,656.7 (34.5%) 09:16:09 INFO - TinderboxPrint: Swap in / out
249,647,104 / 0 09:16:09 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 250282496; Write bytes: 253298688; Read time: 18193; Write time: 553 09:16:09 INFO - run-tests - Wall time: 1179s; CPU: 55.0%; Read bytes: 114688; Write bytes: 298023936; Read time: 4; Write time: 1178 09:16:09 INFO - Running post-run listener: _upload_blobber_files 09:16:09 INFO - Blob upload gear active. 09:16:09 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:16:09 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 09:16:09 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', 'Cedar', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:16:09 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 Cedar -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:16:09 INFO - (blobuploader) - INFO - Open directory for files ... 09:16:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:16:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:16:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:16:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:16:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:16:10 INFO - (blobuploader) - INFO - Done attempting. 09:16:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:16:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:16:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:16:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:16:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:16:11 INFO - (blobuploader) - INFO - Done attempting. 09:16:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:16:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:16:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:16:14 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:16:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:16:14 INFO - (blobuploader) - INFO - Done attempting. 09:16:14 INFO - (blobuploader) - INFO - Iteration through files over. 09:16:14 INFO - Return code: 0 09:16:14 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:16:14 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:16:14 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/81008389abfb18fc0b6daade633f56b23941300bb407b65145e0d7760766e87cd4907780eccc859696c4c9bca025a676165a8a9049a2f985f66ef61e18cda0b4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/195fd3c39b6404d93aee01b12aba5209aba0c798256955a5cae50f8a04e0df38f81a9c88dcd555443eadd64d01eecc3a2e994ef2fb19d93ef62b4143a31200b0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/f01b735b5fd282be1a600208f2d2b583fb6af9c8542fb639a01bd32c7704158e22c057a9bec166e5bc43e8383bac132b4b7b188314ae8d99dabe58866f5abeeb"} 09:16:14 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:16:14 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:16:14 INFO - Contents: 09:16:14 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/81008389abfb18fc0b6daade633f56b23941300bb407b65145e0d7760766e87cd4907780eccc859696c4c9bca025a676165a8a9049a2f985f66ef61e18cda0b4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/195fd3c39b6404d93aee01b12aba5209aba0c798256955a5cae50f8a04e0df38f81a9c88dcd555443eadd64d01eecc3a2e994ef2fb19d93ef62b4143a31200b0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/f01b735b5fd282be1a600208f2d2b583fb6af9c8542fb639a01bd32c7704158e22c057a9bec166e5bc43e8383bac132b4b7b188314ae8d99dabe58866f5abeeb"} 09:16:14 INFO - Running post-run listener: copy_logs_to_upload_dir 09:16:14 INFO - Copying logs to upload dir... 09:16:14 INFO - mkdir: /builds/slave/test/build/upload/logs 09:16:14 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1258.263335 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2016-12-13 09:16:14.284858) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-13 09:16:14.292139) ========= 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.Co6HtmUsxP/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.aeM7bW4KHu/Listeners TMPDIR=/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/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/Cedar/sha512/81008389abfb18fc0b6daade633f56b23941300bb407b65145e0d7760766e87cd4907780eccc859696c4c9bca025a676165a8a9049a2f985f66ef61e18cda0b4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/195fd3c39b6404d93aee01b12aba5209aba0c798256955a5cae50f8a04e0df38f81a9c88dcd555443eadd64d01eecc3a2e994ef2fb19d93ef62b4143a31200b0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/f01b735b5fd282be1a600208f2d2b583fb6af9c8542fb639a01bd32c7704158e22c057a9bec166e5bc43e8383bac132b4b7b188314ae8d99dabe58866f5abeeb"} build_url:https://queue.taskcluster.net/v1/task/F9BEDpgTQA6owFWEPmgLnw/artifacts/public/build/firefox-53.0a1.en-US.mac64.dmg program finished with exit code 0 elapsedTime=0.011177 build_url: 'https://queue.taskcluster.net/v1/task/F9BEDpgTQA6owFWEPmgLnw/artifacts/public/build/firefox-53.0a1.en-US.mac64.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/81008389abfb18fc0b6daade633f56b23941300bb407b65145e0d7760766e87cd4907780eccc859696c4c9bca025a676165a8a9049a2f985f66ef61e18cda0b4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/195fd3c39b6404d93aee01b12aba5209aba0c798256955a5cae50f8a04e0df38f81a9c88dcd555443eadd64d01eecc3a2e994ef2fb19d93ef62b4143a31200b0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/f01b735b5fd282be1a600208f2d2b583fb6af9c8542fb639a01bd32c7704158e22c057a9bec166e5bc43e8383bac132b4b7b188314ae8d99dabe58866f5abeeb"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-13 09:16:14.329194) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-13 09:16:14.329536) ========= 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.Co6HtmUsxP/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.aeM7bW4KHu/Listeners TMPDIR=/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/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.005275 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-13 09:16:14.378635) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-13 09:16:14.382002) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-13 09:16:14.382479) ========= ========= Total master_lag: 0.67 =========