builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0209 starttime: 1490929683.7 results: success (0) buildid: 20170330191006 builduid: 5be1e18753b44f98bc6e4a47ddb2b0d5 revision: 14b6ab023dec51a423a034aa8f307c56776d59c1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-30 20:08:03.696536) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-30 20:08:03.697064) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-30 20:08:03.697401) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-30 20:08:03.723625) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-30 20:08:03.724013) ========= 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.PMupApxgVF/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.DwotjDSIbz/Listeners TMPDIR=/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-03-30 20:08:03-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 63.1M=0s 2017-03-30 20:08:03 (63.1 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.097270 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-30 20:08:03.849095) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-30 20:08:03.849469) ========= 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.PMupApxgVF/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.DwotjDSIbz/Listeners TMPDIR=/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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.021338 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-30 20:08:03.903994) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-30 20:08:03.904813) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 14b6ab023dec51a423a034aa8f307c56776d59c1 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 14b6ab023dec51a423a034aa8f307c56776d59c1 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.PMupApxgVF/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.DwotjDSIbz/Listeners TMPDIR=/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-03-30 20:08:03,995 truncating revision to first 12 chars 2017-03-30 20:08:03,995 Setting DEBUG logging. 2017-03-30 20:08:03,995 attempt 1/10 2017-03-30 20:08:03,995 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/14b6ab023dec?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-30 20:08:04,904 unpacking tar archive at: mozilla-beta-14b6ab023dec/testing/mozharness/ program finished with exit code 0 elapsedTime=1.172748 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-30 20:08:05.105966) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-30 20:08:05.106404) ========= script_repo_revision: 14b6ab023dec51a423a034aa8f307c56776d59c1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-30 20:08:05.106858) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-30 20:08:05.107162) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-30 20:08:05.127467) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2017-03-30 20:08:05.127861) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.PMupApxgVF/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.DwotjDSIbz/Listeners TMPDIR=/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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 20:08:05 INFO - MultiFileLogger online at 20170330 20:08:05 in /builds/slave/test 20:08:05 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 20:08:05 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:08:05 INFO - {'append_to_log': False, 20:08:05 INFO - 'base_work_dir': '/builds/slave/test', 20:08:05 INFO - 'blob_upload_branch': 'Mozilla-Beta', 20:08:05 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:08:05 INFO - 'branch': 'Mozilla-Beta', 20:08:05 INFO - 'buildbot_json_path': 'buildprops.json', 20:08:05 INFO - 'config_files': ('talos/mac_config.py',), 20:08:05 INFO - 'default_actions': ('clobber', 20:08:05 INFO - 'read-buildbot-config', 20:08:05 INFO - 'download-and-extract', 20:08:05 INFO - 'populate-webroot', 20:08:05 INFO - 'create-virtualenv', 20:08:05 INFO - 'install', 20:08:05 INFO - 'run-tests'), 20:08:05 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:08:05 INFO - 'download_minidump_stackwalk': True, 20:08:05 INFO - 'download_symbols': 'ondemand', 20:08:05 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:08:05 INFO - 'tooltool.py': '/tools/tooltool.py', 20:08:05 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:08:05 INFO - '/tools/misc-python/virtualenv.py')}, 20:08:05 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:08:05 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:08:05 INFO - 'installer_path': 'installer.exe', 20:08:05 INFO - 'log_level': 'info', 20:08:05 INFO - 'log_name': 'talos', 20:08:05 INFO - 'log_to_console': True, 20:08:05 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 20:08:05 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 20:08:05 INFO - 'opt_config_files': (), 20:08:05 INFO - 'pip_index': False, 20:08:05 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:08:05 INFO - 'cmd': ('bash', 20:08:05 INFO - '-c', 20:08:05 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:08:05 INFO - 'enabled': True, 20:08:05 INFO - 'halt_on_failure': False, 20:08:05 INFO - 'name': 'check_screen_resolution'},), 20:08:05 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:08:05 INFO - 'cmd': ('bash', 20:08:05 INFO - '-c', 20:08:05 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:08:05 INFO - 'enabled': True, 20:08:05 INFO - 'halt_on_failure': False, 20:08:05 INFO - 'name': 'check_screen_resolution'},), 20:08:05 INFO - 'run_cmd_checks_enabled': True, 20:08:05 INFO - 'sps_profile': False, 20:08:05 INFO - 'sps_profile_interval': 0, 20:08:05 INFO - 'suite': 'dromaeojs-e10s', 20:08:05 INFO - 'system_bits': '32', 20:08:05 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:08:05 INFO - 'title': 't-yosemite-r7-0209', 20:08:05 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:08:05 INFO - 'use_talos_json': True, 20:08:05 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:08:05 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:08:05 INFO - 'work_dir': 'build'} 20:08:05 INFO - [mozharness: 2017-03-31 03:08:05.340199Z] Running clobber step. 20:08:05 INFO - Running pre-action listener: _resource_record_pre_action 20:08:05 INFO - Running main action method: clobber 20:08:05 INFO - rmtree: /builds/slave/test/build 20:08:05 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:08:07 INFO - Running post-action listener: _resource_record_post_action 20:08:07 INFO - [mozharness: 2017-03-31 03:08:07.283907Z] Finished clobber step (success) 20:08:07 INFO - [mozharness: 2017-03-31 03:08:07.284028Z] Running read-buildbot-config step. 20:08:07 INFO - Running pre-action listener: _resource_record_pre_action 20:08:07 INFO - Running main action method: read_buildbot_config 20:08:07 INFO - Using buildbot properties: 20:08:07 INFO - { 20:08:07 INFO - "project": "", 20:08:07 INFO - "product": "firefox", 20:08:07 INFO - "script_repo_revision": "production", 20:08:07 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 20:08:07 INFO - "repository": "", 20:08:07 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 20:08:07 INFO - "buildid": "20170330191006", 20:08:07 INFO - "pgo_build": "False", 20:08:07 INFO - "basedir": "/builds/slave/test", 20:08:07 INFO - "buildnumber": 19, 20:08:07 INFO - "slavename": "t-yosemite-r7-0209", 20:08:07 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 20:08:07 INFO - "platform": "yosemite_r7", 20:08:07 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 20:08:07 INFO - "branch": "mozilla-beta", 20:08:07 INFO - "repo_path": "releases/mozilla-beta", 20:08:07 INFO - "revision": "14b6ab023dec51a423a034aa8f307c56776d59c1", 20:08:07 INFO - "stage_platform": "macosx64", 20:08:07 INFO - "builduid": "5be1e18753b44f98bc6e4a47ddb2b0d5", 20:08:07 INFO - "slavebuilddir": "test" 20:08:07 INFO - } 20:08:07 INFO - Found installer url https://queue.taskcluster.net/v1/task/GiSEB9oaTUiJNkBULDebLQ/artifacts/public/build/firefox-53.0.en-US.mac.dmg. 20:08:07 INFO - Running post-action listener: _resource_record_post_action 20:08:07 INFO - [mozharness: 2017-03-31 03:08:07.287290Z] Finished read-buildbot-config step (success) 20:08:07 INFO - [mozharness: 2017-03-31 03:08:07.287400Z] Running download-and-extract step. 20:08:07 INFO - Running pre-action listener: _resource_record_pre_action 20:08:07 INFO - Running main action method: download_and_extract 20:08:07 INFO - mkdir: /builds/slave/test/build/tests 20:08: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')]} 20:08:07 INFO - trying https://queue.taskcluster.net/v1/task/GiSEB9oaTUiJNkBULDebLQ/artifacts/public/build/firefox-53.0.en-US.mac.test_packages.json 20:08:07 INFO - Downloading https://queue.taskcluster.net/v1/task/GiSEB9oaTUiJNkBULDebLQ/artifacts/public/build/firefox-53.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0.en-US.mac.test_packages.json 20:08:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GiSEB9oaTUiJNkBULDebLQ/artifacts/public/build/firefox-53.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0.en-US.mac.test_packages.json'}, attempt #1 20:08:08 INFO - Downloaded 1165 bytes. 20:08:08 INFO - Reading from file /builds/slave/test/build/firefox-53.0.en-US.mac.test_packages.json 20:08:08 INFO - Using the following test package requirements: 20:08:08 INFO - {u'common': [u'firefox-53.0.en-US.mac.common.tests.zip'], 20:08:08 INFO - u'cppunittest': [u'firefox-53.0.en-US.mac.common.tests.zip', 20:08:08 INFO - u'firefox-53.0.en-US.mac.cppunittest.tests.zip'], 20:08:08 INFO - u'gtest': [u'firefox-53.0.en-US.mac.common.tests.zip', 20:08:08 INFO - u'firefox-53.0.en-US.mac.gtest.tests.zip'], 20:08:08 INFO - u'jittest': [u'firefox-53.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 20:08:08 INFO - u'mochitest': [u'firefox-53.0.en-US.mac.common.tests.zip', 20:08:08 INFO - u'firefox-53.0.en-US.mac.mochitest.tests.zip'], 20:08:08 INFO - u'mozbase': [u'firefox-53.0.en-US.mac.common.tests.zip'], 20:08:08 INFO - u'reftest': [u'firefox-53.0.en-US.mac.common.tests.zip', 20:08:08 INFO - u'firefox-53.0.en-US.mac.reftest.tests.zip'], 20:08:08 INFO - u'talos': [u'firefox-53.0.en-US.mac.common.tests.zip', 20:08:08 INFO - u'firefox-53.0.en-US.mac.talos.tests.zip'], 20:08:08 INFO - u'web-platform': [u'firefox-53.0.en-US.mac.common.tests.zip', 20:08:08 INFO - u'firefox-53.0.en-US.mac.web-platform.tests.zip'], 20:08:08 INFO - u'xpcshell': [u'firefox-53.0.en-US.mac.common.tests.zip', 20:08:08 INFO - u'firefox-53.0.en-US.mac.xpcshell.tests.zip']} 20:08:08 INFO - Downloading packages: [u'firefox-53.0.en-US.mac.common.tests.zip'] for test suite category: common 20:08:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GiSEB9oaTUiJNkBULDebLQ/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip 20:08:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GiSEB9oaTUiJNkBULDebLQ/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip'}, attempt #1 20:08:08 INFO - Fetch https://queue.taskcluster.net/v1/task/GiSEB9oaTUiJNkBULDebLQ/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip into memory 20:08:09 INFO - Expected file size: 16108765 20:08:09 INFO - Obtained file size: 16108765 20:08:15 INFO - Downloading packages: [u'firefox-53.0.en-US.mac.common.tests.zip', u'firefox-53.0.en-US.mac.talos.tests.zip'] for test suite category: talos 20:08:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GiSEB9oaTUiJNkBULDebLQ/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip 20:08:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GiSEB9oaTUiJNkBULDebLQ/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip'}, attempt #1 20:08:15 INFO - Fetch https://queue.taskcluster.net/v1/task/GiSEB9oaTUiJNkBULDebLQ/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip into memory 20:08:16 INFO - Expected file size: 16108765 20:08:16 INFO - Obtained file size: 16108765 20:08:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GiSEB9oaTUiJNkBULDebLQ/artifacts/public/build/firefox-53.0.en-US.mac.talos.tests.zip 20:08:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GiSEB9oaTUiJNkBULDebLQ/artifacts/public/build/firefox-53.0.en-US.mac.talos.tests.zip'}, attempt #1 20:08:23 INFO - Fetch https://queue.taskcluster.net/v1/task/GiSEB9oaTUiJNkBULDebLQ/artifacts/public/build/firefox-53.0.en-US.mac.talos.tests.zip into memory 20:08:24 INFO - Expected file size: 14403663 20:08:24 INFO - Obtained file size: 14403663 20:08:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:08:24 INFO - trying https://queue.taskcluster.net/v1/task/GiSEB9oaTUiJNkBULDebLQ/artifacts/public/build/firefox-53.0.en-US.mac.dmg 20:08:24 INFO - Downloading https://queue.taskcluster.net/v1/task/GiSEB9oaTUiJNkBULDebLQ/artifacts/public/build/firefox-53.0.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0.en-US.mac.dmg 20:08:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GiSEB9oaTUiJNkBULDebLQ/artifacts/public/build/firefox-53.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0.en-US.mac.dmg'}, attempt #1 20:08:27 INFO - Downloaded 58692397 bytes. 20:08:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GiSEB9oaTUiJNkBULDebLQ/artifacts/public/build/firefox-53.0.en-US.mac.dmg 20:08:27 INFO - mkdir: /builds/slave/test/properties 20:08:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:08:27 INFO - Writing to file /builds/slave/test/properties/build_url 20:08:27 INFO - Contents: 20:08:27 INFO - build_url:https://queue.taskcluster.net/v1/task/GiSEB9oaTUiJNkBULDebLQ/artifacts/public/build/firefox-53.0.en-US.mac.dmg 20:08:28 INFO - Running post-action listener: _resource_record_post_action 20:08:28 INFO - Running post-action listener: set_extra_try_arguments 20:08:28 INFO - [mozharness: 2017-03-31 03:08:28.137002Z] Finished download-and-extract step (success) 20:08:28 INFO - [mozharness: 2017-03-31 03:08:28.137122Z] Running populate-webroot step. 20:08:28 INFO - Running pre-action listener: _resource_record_pre_action 20:08:28 INFO - Running main action method: populate_webroot 20:08:28 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:08:28 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:08:28 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:08:28 INFO - '--tppagecycles', 20:08:28 INFO - '7'], 20:08:28 INFO - 'tests': ['tsvgm']}}, 20:08:28 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:08:28 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:08:28 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:08:28 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:08:28 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:08:28 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:08:28 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 20:08:28 INFO - 'talos_options': ['--disable-e10s'], 20:08:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:08:28 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 20:08:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:08:28 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 20:08:28 INFO - 'talos_options': ['--disable-e10s'], 20:08:28 INFO - 'tests': ['damp', 'tps']}, 20:08:28 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 20:08:28 INFO - 'tests': ['damp', 'tps']}, 20:08:28 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:08:28 INFO - 'tests': ['dromaeo_dom']}, 20:08:28 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:08:28 INFO - 'g4': {'talos_options': ['--disable-e10s'], 20:08:28 INFO - 'tests': ['basic_compositor_video']}, 20:08:28 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 20:08:28 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:08:28 INFO - 'tests': ['a11yr', 20:08:28 INFO - 'ts_paint', 20:08:28 INFO - 'tpaint', 20:08:28 INFO - 'sessionrestore', 20:08:28 INFO - 'sessionrestore_no_auto_restore', 20:08:28 INFO - 'tabpaint']}, 20:08:28 INFO - 'other-e10s': {'tests': ['a11yr', 20:08:28 INFO - 'ts_paint', 20:08:28 INFO - 'tpaint', 20:08:28 INFO - 'sessionrestore', 20:08:28 INFO - 'sessionrestore_no_auto_restore', 20:08:28 INFO - 'tabpaint']}, 20:08:28 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:08:28 INFO - 'ts_paint', 20:08:28 INFO - 'tpaint', 20:08:28 INFO - 'sessionrestore', 20:08:28 INFO - 'sessionrestore_no_auto_restore', 20:08:28 INFO - 'tabpaint']}, 20:08:28 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:08:28 INFO - 'ts_paint', 20:08:28 INFO - 'tpaint', 20:08:28 INFO - 'sessionrestore', 20:08:28 INFO - 'sessionrestore_no_auto_restore', 20:08:28 INFO - 'tabpaint']}, 20:08:28 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:08:28 INFO - 'tests': ['a11yr', 20:08:28 INFO - 'ts_paint', 20:08:28 INFO - 'tpaint', 20:08:28 INFO - 'sessionrestore', 20:08:28 INFO - 'sessionrestore_no_auto_restore', 20:08:28 INFO - 'tabpaint']}, 20:08:28 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:08:28 INFO - 'tests': ['a11yr', 20:08:28 INFO - 'ts_paint', 20:08:28 INFO - 'tpaint', 20:08:28 INFO - 'sessionrestore', 20:08:28 INFO - 'sessionrestore_no_auto_restore', 20:08:28 INFO - 'tabpaint']}, 20:08:28 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:08:28 INFO - 'tests': ['tsvgx', 20:08:28 INFO - 'tsvgr_opacity', 20:08:28 INFO - 'tart', 20:08:28 INFO - 'tscrollx', 20:08:28 INFO - 'cart', 20:08:28 INFO - 'tsvg_static']}, 20:08:28 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:08:28 INFO - 'tsvgr_opacity', 20:08:28 INFO - 'tart', 20:08:28 INFO - 'tscrollx', 20:08:28 INFO - 'cart', 20:08:28 INFO - 'tsvg_static']}, 20:08:28 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 20:08:28 INFO - 'talos_options': ['--disable-e10s'], 20:08:28 INFO - 'tests': ['tp5o']}, 20:08:28 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 20:08:28 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 20:08:28 INFO - 'talos_options': ['--disable-e10s', 20:08:28 INFO - '--xperf_path', 20:08:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:08:28 INFO - 'tests': ['tp5n']}, 20:08:28 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 20:08:28 INFO - 'talos_options': ['--xperf_path', 20:08:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:08:28 INFO - 'tests': ['tp5n']}}, 20:08:28 INFO - 'talos.zip': {'path': '', 20:08:28 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:08:28 INFO - Running post-action listener: _resource_record_post_action 20:08:28 INFO - [mozharness: 2017-03-31 03:08:28.155720Z] Finished populate-webroot step (success) 20:08:28 INFO - [mozharness: 2017-03-31 03:08:28.155835Z] Running create-virtualenv step. 20:08:28 INFO - Running pre-action listener: _resource_record_pre_action 20:08:28 INFO - Running main action method: create_virtualenv 20:08:28 INFO - Creating virtualenv /builds/slave/test/build/venv 20:08:28 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 20:08:28 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:08:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:08:28 INFO - Using real prefix '/tools/python27' 20:08:28 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:08:28 INFO - Installing distribute.............................................................................................................................................................................................done. 20:08:31 INFO - Installing pip.................done. 20:08:31 INFO - Return code: 0 20:08:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 20:08:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 20:08:32 INFO - Reading from file tmpfile_stdout 20:08:32 INFO - Output received: 20:08:32 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) 20:08:32 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:08:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:08:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:08:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:08:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:08:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c68bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c68e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa71d8fd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c469d30>, '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 0x10c390db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c45ebe8>, '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.PMupApxgVF/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.DwotjDSIbz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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 20:08:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 20:08:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 20:08:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PMupApxgVF/Render', 20:08:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:08:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:08:32 INFO - 'HOME': '/Users/cltbld', 20:08:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:08:32 INFO - 'LOGNAME': 'cltbld', 20:08:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:08:32 INFO - 'MOZ_NO_REMOTE': '1', 20:08:32 INFO - 'NO_EM_RESTART': '1', 20:08:32 INFO - 'PAGER': '/bin/cat', 20:08:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:08:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:08:32 INFO - 'PWD': '/builds/slave/test', 20:08:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:08:32 INFO - 'SHELL': '/bin/bash', 20:08:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DwotjDSIbz/Listeners', 20:08:32 INFO - 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', 20:08:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:08:32 INFO - 'USER': 'cltbld', 20:08:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:08:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:08:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:08:32 INFO - 'XPC_FLAGS': '0x0', 20:08:32 INFO - 'XPC_SERVICE_NAME': '0', 20:08:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:08:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:08:32 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 20:08:32 INFO - Cleaning up... 20:08:32 INFO - Return code: 0 20:08:32 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:08:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:08:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:08:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:08:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:08:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10c68bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c68e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa71d8fd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c469d30>, '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 0x10c390db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c45ebe8>, '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.PMupApxgVF/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.DwotjDSIbz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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 20:08:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 20:08:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 20:08:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PMupApxgVF/Render', 20:08:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:08:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:08:32 INFO - 'HOME': '/Users/cltbld', 20:08:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:08:32 INFO - 'LOGNAME': 'cltbld', 20:08:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:08:32 INFO - 'MOZ_NO_REMOTE': '1', 20:08:32 INFO - 'NO_EM_RESTART': '1', 20:08:32 INFO - 'PAGER': '/bin/cat', 20:08:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:08:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:08:32 INFO - 'PWD': '/builds/slave/test', 20:08:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:08:32 INFO - 'SHELL': '/bin/bash', 20:08:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DwotjDSIbz/Listeners', 20:08:32 INFO - 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', 20:08:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:08:32 INFO - 'USER': 'cltbld', 20:08:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:08:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:08:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:08:32 INFO - 'XPC_FLAGS': '0x0', 20:08:32 INFO - 'XPC_SERVICE_NAME': '0', 20:08:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:08:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:08:32 INFO - Downloading/unpacking psutil>=3.1.1 20:08:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:08:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:08:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:08:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:08:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:08:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:08:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:08:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:08:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:08:36 INFO - Installing collected packages: psutil 20:08:36 INFO - Running setup.py install for psutil 20:08:36 INFO - building 'psutil._psutil_osx' extension 20:08:36 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 20:08:36 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 20:08:36 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 20:08:36 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 20:08:36 INFO - building 'psutil._psutil_posix' extension 20:08:36 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 20:08:36 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 20:08:36 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 20:08:36 INFO - ^ 20:08:36 INFO - 1 warning generated. 20:08:36 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 20:08:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:08:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:08:37 INFO - Successfully installed psutil 20:08:37 INFO - Cleaning up... 20:08:37 INFO - Return code: 0 20:08:37 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:08:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:08:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:08:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:08:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:08:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08:37 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 0x10c68bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c68e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa71d8fd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c469d30>, '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 0x10c390db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c45ebe8>, '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.PMupApxgVF/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.DwotjDSIbz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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 20:08:37 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 20:08:37 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 20:08:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PMupApxgVF/Render', 20:08:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:08:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:08:37 INFO - 'HOME': '/Users/cltbld', 20:08:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:08:37 INFO - 'LOGNAME': 'cltbld', 20:08:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:08:37 INFO - 'MOZ_NO_REMOTE': '1', 20:08:37 INFO - 'NO_EM_RESTART': '1', 20:08:37 INFO - 'PAGER': '/bin/cat', 20:08:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:08:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:08:37 INFO - 'PWD': '/builds/slave/test', 20:08:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:08:37 INFO - 'SHELL': '/bin/bash', 20:08:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DwotjDSIbz/Listeners', 20:08:37 INFO - 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', 20:08:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:08:37 INFO - 'USER': 'cltbld', 20:08:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:08:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:08:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:08:37 INFO - 'XPC_FLAGS': '0x0', 20:08:37 INFO - 'XPC_SERVICE_NAME': '0', 20:08:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:08:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:08:37 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:08:37 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 20:08:37 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 20:08:37 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 20:08:37 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 20:08:37 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 20:08:37 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 20:08:40 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:08:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:08:40 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:08:40 INFO - Installing collected packages: mozsystemmonitor 20:08:40 INFO - Running setup.py install for mozsystemmonitor 20:08:40 INFO - Successfully installed mozsystemmonitor 20:08:40 INFO - Cleaning up... 20:08:40 INFO - Return code: 0 20:08:40 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 20:08: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')]} 20:08:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:08:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08: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')]} 20:08:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:08:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08: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', '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 0x10c68bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c68e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa71d8fd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c469d30>, '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 0x10c390db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c45ebe8>, '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.PMupApxgVF/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.DwotjDSIbz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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 20:08: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 20:08: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 jsonschema==2.5.1 20:08:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PMupApxgVF/Render', 20:08:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:08:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:08:40 INFO - 'HOME': '/Users/cltbld', 20:08:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:08:40 INFO - 'LOGNAME': 'cltbld', 20:08:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:08:40 INFO - 'MOZ_NO_REMOTE': '1', 20:08:40 INFO - 'NO_EM_RESTART': '1', 20:08:40 INFO - 'PAGER': '/bin/cat', 20:08:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:08:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:08:40 INFO - 'PWD': '/builds/slave/test', 20:08:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:08:40 INFO - 'SHELL': '/bin/bash', 20:08:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DwotjDSIbz/Listeners', 20:08:40 INFO - 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', 20:08:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:08:40 INFO - 'USER': 'cltbld', 20:08:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:08:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:08:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:08:40 INFO - 'XPC_FLAGS': '0x0', 20:08:40 INFO - 'XPC_SERVICE_NAME': '0', 20:08:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:08:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:08:40 INFO - Downloading/unpacking jsonschema==2.5.1 20:08: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 20:08: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 20:08: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 20:08: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 20:08: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 20:08: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 20:08:43 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:08:43 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 20:08:43 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 20:08:43 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 20:08:43 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 20:08:43 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 20:08:43 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 20:08:43 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 20:08:43 INFO - Downloading functools32-3.2.3-2.tar.gz 20:08:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:08:43 INFO - warning: no files found matching '*.txt' 20:08:43 INFO - no previously-included directories found matching 'build' 20:08:43 INFO - no previously-included directories found matching 'dist' 20:08:43 INFO - no previously-included directories found matching '.git*' 20:08:43 INFO - Installing collected packages: jsonschema, functools32 20:08:43 INFO - Running setup.py install for functools32 20:08:44 INFO - warning: no files found matching '*.txt' 20:08:44 INFO - no previously-included directories found matching 'build' 20:08:44 INFO - no previously-included directories found matching 'dist' 20:08:44 INFO - no previously-included directories found matching '.git*' 20:08:44 INFO - Successfully installed jsonschema functools32 20:08:44 INFO - Cleaning up... 20:08:44 INFO - Return code: 0 20:08:44 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 20:08:44 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')]} 20:08:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:08:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08:44 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')]} 20:08:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:08:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08:44 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 0x10c68bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c68e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa71d8fd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c469d30>, '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 0x10c390db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c45ebe8>, '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.PMupApxgVF/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.DwotjDSIbz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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 20:08:44 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 20:08:44 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 20:08:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PMupApxgVF/Render', 20:08:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:08:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:08:44 INFO - 'HOME': '/Users/cltbld', 20:08:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:08:44 INFO - 'LOGNAME': 'cltbld', 20:08:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:08:44 INFO - 'MOZ_NO_REMOTE': '1', 20:08:44 INFO - 'NO_EM_RESTART': '1', 20:08:44 INFO - 'PAGER': '/bin/cat', 20:08:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:08:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:08:44 INFO - 'PWD': '/builds/slave/test', 20:08:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:08:44 INFO - 'SHELL': '/bin/bash', 20:08:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DwotjDSIbz/Listeners', 20:08:44 INFO - 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', 20:08:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:08:44 INFO - 'USER': 'cltbld', 20:08:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:08:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:08:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:08:44 INFO - 'XPC_FLAGS': '0x0', 20:08:44 INFO - 'XPC_SERVICE_NAME': '0', 20:08:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:08:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:08:44 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 20:08:44 INFO - Cleaning up... 20:08:44 INFO - Return code: 0 20:08:44 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:08:44 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')]} 20:08:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:08:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08:44 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')]} 20:08:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:08:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08:44 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 0x10c68bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c68e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa71d8fd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c469d30>, '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 0x10c390db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c45ebe8>, '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.PMupApxgVF/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.DwotjDSIbz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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 20:08:44 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 20:08:44 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 20:08:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PMupApxgVF/Render', 20:08:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:08:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:08:44 INFO - 'HOME': '/Users/cltbld', 20:08:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:08:44 INFO - 'LOGNAME': 'cltbld', 20:08:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:08:44 INFO - 'MOZ_NO_REMOTE': '1', 20:08:44 INFO - 'NO_EM_RESTART': '1', 20:08:44 INFO - 'PAGER': '/bin/cat', 20:08:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:08:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:08:44 INFO - 'PWD': '/builds/slave/test', 20:08:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:08:44 INFO - 'SHELL': '/bin/bash', 20:08:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DwotjDSIbz/Listeners', 20:08:44 INFO - 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', 20:08:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:08:44 INFO - 'USER': 'cltbld', 20:08:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:08:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:08:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:08:44 INFO - 'XPC_FLAGS': '0x0', 20:08:44 INFO - 'XPC_SERVICE_NAME': '0', 20:08:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:08:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:08:44 INFO - Downloading/unpacking blobuploader==1.2.4 20:08:44 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 20:08:44 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 20:08:44 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 20:08:44 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 20:08:44 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 20:08:44 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 20:08:47 INFO - Downloading blobuploader-1.2.4.tar.gz 20:08:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:08:47 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:08:47 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 20:08:47 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 20:08:47 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 20:08:47 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 20:08:47 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 20:08:47 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 20:08:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:08:48 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:08: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 20:08: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 20:08: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 20:08: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 20:08: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 20:08: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 20:08:48 INFO - Downloading docopt-0.6.1.tar.gz 20:08:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:08:48 INFO - Installing collected packages: blobuploader, requests, docopt 20:08:48 INFO - Running setup.py install for blobuploader 20:08:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:08:48 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:08:48 INFO - Running setup.py install for requests 20:08:49 INFO - Running setup.py install for docopt 20:08:49 INFO - Successfully installed blobuploader requests docopt 20:08:49 INFO - Cleaning up... 20:08:49 INFO - Return code: 0 20:08:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:08:49 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')]} 20:08:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:08:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08:49 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')]} 20:08:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:08:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08:49 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 0x10c68bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c68e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa71d8fd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c469d30>, '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 0x10c390db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c45ebe8>, '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.PMupApxgVF/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.DwotjDSIbz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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 20:08:49 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 20:08:49 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 20:08:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PMupApxgVF/Render', 20:08:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:08:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:08:49 INFO - 'HOME': '/Users/cltbld', 20:08:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:08:49 INFO - 'LOGNAME': 'cltbld', 20:08:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:08:49 INFO - 'MOZ_NO_REMOTE': '1', 20:08:49 INFO - 'NO_EM_RESTART': '1', 20:08:49 INFO - 'PAGER': '/bin/cat', 20:08:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:08:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:08:49 INFO - 'PWD': '/builds/slave/test', 20:08:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:08:49 INFO - 'SHELL': '/bin/bash', 20:08:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DwotjDSIbz/Listeners', 20:08:49 INFO - 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', 20:08:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:08:49 INFO - 'USER': 'cltbld', 20:08:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:08:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:08:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:08:49 INFO - 'XPC_FLAGS': '0x0', 20:08:49 INFO - 'XPC_SERVICE_NAME': '0', 20:08:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:08:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:08:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:08:49 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-TaT8jz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:08:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:08:49 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-3MqW6Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:08:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:08:49 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-EBBJLI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:08:50 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-05UGzK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:08:50 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-_90990-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:08:50 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-zSgHWQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:08:50 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-jkdZyr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:08:50 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-N_xhcs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:08:50 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-75ghxa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:08:50 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-vVqTSu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:08:50 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-wjeAKC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:08:50 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-z2BgqV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:08:51 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-ijaHR5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:08:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:08:51 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-kiyuW6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:08:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:08:51 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-0tW0Jy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:08:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:08:51 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-ibb879-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:08:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:08:51 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-hrECSn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:08:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:08:51 INFO - Running setup.py install for manifestparser 20:08:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:08:51 INFO - Running setup.py install for mozcrash 20:08:51 INFO - Running setup.py install for mozdebug 20:08:51 INFO - Running setup.py install for mozdevice 20:08:52 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:08:52 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:08:52 INFO - Running setup.py install for mozfile 20:08:52 INFO - Running setup.py install for mozhttpd 20:08:52 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:08:52 INFO - Running setup.py install for mozinfo 20:08:52 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:08:52 INFO - Running setup.py install for mozInstall 20:08:52 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:08:52 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:08:52 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:08:52 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:08:52 INFO - Running setup.py install for mozleak 20:08:52 INFO - Running setup.py install for mozlog 20:08:53 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:08:53 INFO - Running setup.py install for moznetwork 20:08:53 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:08:53 INFO - Running setup.py install for mozprocess 20:08:53 INFO - Running setup.py install for mozprofile 20:08:53 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:08:53 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:08:53 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:08:53 INFO - Running setup.py install for mozrunner 20:08:53 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:08:53 INFO - Running setup.py install for mozscreenshot 20:08:53 INFO - Running setup.py install for moztest 20:08:53 INFO - Running setup.py install for mozversion 20:08:54 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:08:54 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:08:54 INFO - Cleaning up... 20:08:54 INFO - Return code: 0 20:08:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:08:54 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')]} 20:08:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:08:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08:54 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')]} 20:08:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:08:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:08:55 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 0x10c68bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c68e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa71d8fd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c469d30>, '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 0x10c390db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c45ebe8>, '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.PMupApxgVF/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.DwotjDSIbz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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 20:08:55 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 20:08:55 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 20:08:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PMupApxgVF/Render', 20:08:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:08:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:08:55 INFO - 'HOME': '/Users/cltbld', 20:08:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:08:55 INFO - 'LOGNAME': 'cltbld', 20:08:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:08:55 INFO - 'MOZ_NO_REMOTE': '1', 20:08:55 INFO - 'NO_EM_RESTART': '1', 20:08:55 INFO - 'PAGER': '/bin/cat', 20:08:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:08:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:08:55 INFO - 'PWD': '/builds/slave/test', 20:08:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:08:55 INFO - 'SHELL': '/bin/bash', 20:08:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DwotjDSIbz/Listeners', 20:08:55 INFO - 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', 20:08:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:08:55 INFO - 'USER': 'cltbld', 20:08:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:08:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:08:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:08:55 INFO - 'XPC_FLAGS': '0x0', 20:08:55 INFO - 'XPC_SERVICE_NAME': '0', 20:08:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:08:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:08:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:08:55 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-dyOk9J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:08:55 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)) 20:08:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:08:55 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-MK9UyF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:08:55 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)) 20:08:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:08:55 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-GuisbU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:08:55 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)) 20:08:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:08:55 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-aiDf1u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:08:55 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)) 20:08:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:08:55 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-_eur82-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:08:55 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)) 20:08:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:08:55 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-Y5kdOH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:08:56 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)) 20:08:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:08:56 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-XUj0r7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:08:56 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)) 20:08:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:08:56 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-FHg2Hn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:08:56 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)) 20:08:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:08:56 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-iIolYl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:08:56 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)) 20:08:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:08:56 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-TgByU0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:08:56 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)) 20:08:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:08:56 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-02WanO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:08:56 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)) 20:08:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:08:56 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-Hbq8Kd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:08:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 20:08:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:08:56 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-Nq6PhO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:08:56 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)) 20:08:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:08:56 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-Zkacej-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:08:56 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)) 20:08:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:08:56 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-Bbtmkl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:08:56 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)) 20:08:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:08:56 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-UCFLy8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:08:57 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 20:08:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:08:57 INFO - Running setup.py (path:/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/pip-zo9JDx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:08:57 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)) 20:08:57 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)) 20:08:57 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)) 20:08:57 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)) 20:08:57 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)) 20:08:57 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)) 20:08:57 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:08:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:08:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:08:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:08:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:08:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:08:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:09:00 INFO - Downloading blessings-1.6.tar.gz 20:09:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:09:00 INFO - Installing collected packages: blessings 20:09:00 INFO - Running setup.py install for blessings 20:09:00 INFO - Successfully installed blessings 20:09:00 INFO - Cleaning up... 20:09:00 INFO - Return code: 0 20:09:00 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:09:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:09:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:09:01 INFO - Reading from file tmpfile_stdout 20:09:01 INFO - Current package versions: 20:09:01 INFO - blessings == 1.6 20:09:01 INFO - blobuploader == 1.2.4 20:09:01 INFO - docopt == 0.6.1 20:09:01 INFO - functools32 == 3.2.3-2 20:09:01 INFO - jsonschema == 2.5.1 20:09:01 INFO - manifestparser == 1.1 20:09:01 INFO - mozInstall == 1.12 20:09:01 INFO - mozcrash == 1.0 20:09:01 INFO - mozdebug == 0.1 20:09:01 INFO - mozdevice == 0.49 20:09:01 INFO - mozfile == 1.2 20:09:01 INFO - mozhttpd == 0.7 20:09:01 INFO - mozinfo == 0.9 20:09:01 INFO - mozleak == 0.1 20:09:01 INFO - mozlog == 3.4 20:09:01 INFO - moznetwork == 0.27 20:09:01 INFO - mozprocess == 0.24 20:09:01 INFO - mozprofile == 0.28 20:09:01 INFO - mozrunner == 6.13 20:09:01 INFO - mozscreenshot == 0.1 20:09:01 INFO - mozsystemmonitor == 0.3 20:09:01 INFO - moztest == 0.8 20:09:01 INFO - mozversion == 1.4 20:09:01 INFO - psutil == 3.1.1 20:09:01 INFO - requests == 1.2.3 20:09:01 INFO - wsgiref == 0.1.2 20:09:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:09: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')]} 20:09:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:09:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09: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')]} 20:09:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:09:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09:01 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 0x10c68bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c68e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa71d8fd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c469d30>, '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 0x10c390db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c45ebe8>, '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.PMupApxgVF/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.DwotjDSIbz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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 20:09:01 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 20:09:01 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 20:09:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PMupApxgVF/Render', 20:09:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:09:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:09:01 INFO - 'HOME': '/Users/cltbld', 20:09:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:09:01 INFO - 'LOGNAME': 'cltbld', 20:09:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:09:01 INFO - 'MOZ_NO_REMOTE': '1', 20:09:01 INFO - 'NO_EM_RESTART': '1', 20:09:01 INFO - 'PAGER': '/bin/cat', 20:09:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:09:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:09:01 INFO - 'PWD': '/builds/slave/test', 20:09:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:09:01 INFO - 'SHELL': '/bin/bash', 20:09:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DwotjDSIbz/Listeners', 20:09:01 INFO - 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', 20:09:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:09:01 INFO - 'USER': 'cltbld', 20:09:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:09:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:09:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:09:01 INFO - 'XPC_FLAGS': '0x0', 20:09:01 INFO - 'XPC_SERVICE_NAME': '0', 20:09:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:09:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:09:01 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)) 20:09:01 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)) 20:09:01 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)) 20:09:01 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)) 20:09:01 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)) 20:09:01 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)) 20:09:01 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)) 20:09:01 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)) 20:09:01 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)) 20:09:01 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)) 20:09:01 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)) 20:09:01 INFO - Cleaning up... 20:09:01 INFO - Return code: 0 20:09:01 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:09: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')]} 20:09:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:09:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09: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')]} 20:09:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:09:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:09:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c68bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c68e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa71d8fd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c469d30>, '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 0x10c390db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c45ebe8>, '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.PMupApxgVF/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.DwotjDSIbz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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 20:09:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 20:09:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 20:09:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PMupApxgVF/Render', 20:09:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:09:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:09:01 INFO - 'HOME': '/Users/cltbld', 20:09:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:09:01 INFO - 'LOGNAME': 'cltbld', 20:09:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:09:01 INFO - 'MOZ_NO_REMOTE': '1', 20:09:01 INFO - 'NO_EM_RESTART': '1', 20:09:01 INFO - 'PAGER': '/bin/cat', 20:09:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:09:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:09:01 INFO - 'PWD': '/builds/slave/test', 20:09:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:09:01 INFO - 'SHELL': '/bin/bash', 20:09:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DwotjDSIbz/Listeners', 20:09:01 INFO - 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', 20:09:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:09:01 INFO - 'USER': 'cltbld', 20:09:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:09:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:09:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:09:01 INFO - 'XPC_FLAGS': '0x0', 20:09:01 INFO - 'XPC_SERVICE_NAME': '0', 20:09:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:09:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:09:01 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 20:09:01 INFO - Cleaning up... 20:09:01 INFO - Return code: 0 20:09:01 INFO - Running post-action listener: _resource_record_post_action 20:09:01 INFO - Running post-action listener: _start_resource_monitoring 20:09:01 INFO - Starting resource monitoring. 20:09:01 INFO - [mozharness: 2017-03-31 03:09:01.580045Z] Finished create-virtualenv step (success) 20:09:01 INFO - [mozharness: 2017-03-31 03:09:01.581010Z] Running install step. 20:09:01 INFO - Running pre-action listener: _resource_record_pre_action 20:09:01 INFO - Running main action method: install 20:09:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:09:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:09:01 INFO - Reading from file tmpfile_stdout 20:09:01 INFO - Detecting whether we're running mozinstall >=1.0... 20:09:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:09:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:09:01 INFO - Reading from file tmpfile_stdout 20:09:01 INFO - Output received: 20:09:01 INFO - Usage: mozinstall [options] installer 20:09:01 INFO - Options: 20:09:01 INFO - -h, --help show this help message and exit 20:09:01 INFO - -d DEST, --destination=DEST 20:09:01 INFO - Directory to install application into. [default: 20:09:01 INFO - "/builds/slave/test"] 20:09:01 INFO - --app=APP Application being installed. [default: firefox] 20:09:01 INFO - mkdir: /builds/slave/test/build/application 20:09:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 20:09:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0.en-US.mac.dmg --destination /builds/slave/test/build/application 20:09:17 INFO - Reading from file tmpfile_stdout 20:09:17 INFO - Output received: 20:09:17 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 20:09:17 INFO - Running post-action listener: _resource_record_post_action 20:09:17 INFO - [mozharness: 2017-03-31 03:09:17.310478Z] Finished install step (success) 20:09:17 INFO - [mozharness: 2017-03-31 03:09:17.310614Z] Running run-tests step. 20:09:17 INFO - Running pre-action listener: _resource_record_pre_action 20:09:17 INFO - Running main action method: run_tests 20:09:17 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:09:17 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:09:17 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:09:17 INFO - 2017-03-30 20:09:17.325 screenresolution[1951:19067] starting screenresolution argv=screenresolution get 20:09:17 INFO - 2017-03-30 20:09:17.328 screenresolution[1951:19067] Display 0: 1600x1200x32@60 20:09:17 INFO - 2017-03-30 20:09:17.334 screenresolution[1952:19069] starting screenresolution argv=screenresolution list 20:09:17 INFO - Available Modes on Display 0 20:09:17 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:09:17 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:09:17 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:09:17 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:09:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:09:17 INFO - Intel Iris: 20:09:17 INFO - Chipset Model: Intel Iris 20:09:17 INFO - Type: GPU 20:09:17 INFO - Bus: Built-In 20:09:17 INFO - VRAM (Dynamic, Max): 1536 MB 20:09:17 INFO - Vendor: Intel (0x8086) 20:09:17 INFO - Device ID: 0x0a2e 20:09:17 INFO - Revision ID: 0x0009 20:09:17 INFO - Displays: 20:09:17 INFO - Display: 20:09:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:09:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:09:17 INFO - Display Serial Number: 1600x1200 60 20:09:17 INFO - Main Display: Yes 20:09:17 INFO - Mirror: Off 20:09:17 INFO - Online: Yes 20:09:17 INFO - Rotation: Supported 20:09:17 INFO - Return code: 0 20:09:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:09:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:09:17 INFO - Python 2.7.3 20:09:17 INFO - Return code: 0 20:09:17 INFO - grabbing minidump binary from tooltool 20:09:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:09:17 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 0x10c469d30>, '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 0x10c390db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c45ebe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:09:17 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 20:09:17 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 20:09:17 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 20:09:17 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:09:17 INFO - Return code: 0 20:09:17 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 20:09:17 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:09:17 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:09:17 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 20:09:17 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:09:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GiSEB9oaTUiJNkBULDebLQ/artifacts/public/build/firefox-53.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0209', '--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 20:09:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/GiSEB9oaTUiJNkBULDebLQ/artifacts/public/build/firefox-53.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0209 --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 20:09:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PMupApxgVF/Render', 20:09:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:09:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:09:17 INFO - 'HOME': '/Users/cltbld', 20:09:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:09:17 INFO - 'LOGNAME': 'cltbld', 20:09:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:09:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 20:09:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:09:17 INFO - 'MOZ_NO_REMOTE': '1', 20:09:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:09:17 INFO - 'NO_EM_RESTART': '1', 20:09:17 INFO - 'PAGER': '/bin/cat', 20:09:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:09:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:09:17 INFO - 'PWD': '/builds/slave/test', 20:09:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:09:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:09:17 INFO - 'SHELL': '/bin/bash', 20:09:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DwotjDSIbz/Listeners', 20:09:17 INFO - 'TMPDIR': '/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/', 20:09:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:09:17 INFO - 'USER': 'cltbld', 20:09:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:09:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:09:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:09:17 INFO - 'XPC_FLAGS': '0x0', 20:09:17 INFO - 'XPC_SERVICE_NAME': '0', 20:09:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:09:17 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GiSEB9oaTUiJNkBULDebLQ/artifacts/public/build/firefox-53.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0209', '--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 20:09:17 INFO - mozversion application_buildid: 20170330191006 20:09:17 INFO - mozversion application_changeset: 14b6ab023dec51a423a034aa8f307c56776d59c1 20:09:17 INFO - mozversion application_display_name: Firefox 20:09:17 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:09:17 INFO - mozversion application_name: Firefox 20:09:17 INFO - mozversion application_remotingname: firefox 20:09:17 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 20:09:17 INFO - mozversion application_vendor: Mozilla 20:09:17 INFO - mozversion application_version: 53.0 20:09:17 INFO - mozversion platform_buildid: 20170330191006 20:09:17 INFO - mozversion platform_changeset: 14b6ab023dec51a423a034aa8f307c56776d59c1 20:09:17 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 20:09:17 INFO - mozversion platform_version: 53.0 20:09:17 INFO - using testdate: 1490929757 20:09:17 INFO - actual date: 1490929757 20:09:17 INFO - starting webserver on 'localhost:54376' 20:09:17 INFO - SUITE-START | Running 2 tests 20:09:17 INFO - TEST-START | dromaeo_css 20:09:17 INFO - operating with platform_type : mac_ 20:09:17 INFO - Initialising browser for dromaeo_css test... 20:09:17 INFO - TEST-INFO | started process 1962 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/tmpOP7r1n/profile http://localhost:54376/getInfo.html) 20:09:20 INFO - PROCESS | 1962 | 2017-03-30 20:09:20.716 plugin-container[1965:19222] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f4b, name = 'com.apple.tsm.portname' 20:09:20 INFO - PROCESS | 1962 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:09:20 INFO - PROCESS | 1962 | 2017-03-30 20:09:20.716 plugin-container[1965:19222] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f4f, name = 'com.apple.CFPasteboardClient' 20:09:20 INFO - PROCESS | 1962 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:09:20 INFO - PROCESS | 1962 | 2017-03-30 20:09:20.716 plugin-container[1965:19222] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:09:20 INFO - PROCESS | 1962 | __metrics Screen width/height:1600/1200 20:09:20 INFO - PROCESS | 1962 | colorDepth:24 20:09:20 INFO - PROCESS | 1962 | Browser inner width/height: 1024/689 20:09:20 INFO - PROCESS | 1962 | __metrics 20:09:20 ERROR - PROCESS | 1962 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 20:09:21 INFO - TEST-INFO | 1962: exit 0 20:09:21 INFO - Browser initialized. 20:09:21 INFO - Running cycle 1/1 for dromaeo_css test... 20:09:21 INFO - TEST-INFO | started process 1966 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/tmpOP7r1n/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:09:23 INFO - PROCESS | 1966 | 2017-03-30 20:09:23.962 plugin-container[1967:19344] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4643, name = 'com.apple.tsm.portname' 20:09:23 INFO - PROCESS | 1966 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:09:23 INFO - PROCESS | 1966 | 2017-03-30 20:09:23.963 plugin-container[1967:19344] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x441f, name = 'com.apple.CFPasteboardClient' 20:09:23 INFO - PROCESS | 1966 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:09:23 INFO - PROCESS | 1966 | 2017-03-30 20:09:23.963 plugin-container[1967:19344] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:09:33 INFO - PROCESS | 1966 | RSS: Main: 173223936 20:09:33 INFO - PROCESS | 1966 | 20:12:34 INFO - PROCESS | 1966 | Cycle 1(1): loaded http://localhost:54376/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54376/tests/dromaeo/cssquery-ext.html) 20:12:36 INFO - PROCESS | 1966 | RSS: Main: 190791680 20:12:36 INFO - PROCESS | 1966 | 20:15:39 INFO - PROCESS | 1966 | Cycle 1(1): loaded http://localhost:54376/tests/dromaeo/cssquery-ext.html (next: http://localhost:54376/tests/dromaeo/cssquery-jquery.html) 20:15:39 INFO - PROCESS | 1966 | RSS: Main: 202342400 20:15:39 INFO - PROCESS | 1966 | 20:18:41 INFO - PROCESS | 1966 | Cycle 1(1): loaded http://localhost:54376/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54376/tests/dromaeo/cssquery-mootools.html) 20:18:42 INFO - PROCESS | 1966 | RSS: Main: 205770752 20:18:42 INFO - PROCESS | 1966 | 20:21:43 INFO - PROCESS | 1966 | Cycle 1(1): loaded http://localhost:54376/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54376/tests/dromaeo/cssquery-prototype.html) 20:21:44 INFO - PROCESS | 1966 | RSS: Main: 208801792 20:21:44 INFO - PROCESS | 1966 | 20:24:47 INFO - PROCESS | 1966 | Cycle 1(1): loaded http://localhost:54376/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54376/tests/dromaeo/cssquery-yui.html) 20:24:48 INFO - PROCESS | 1966 | RSS: Main: 210382848 20:24:48 INFO - PROCESS | 1966 | 20:27:50 INFO - PROCESS | 1966 | Cycle 1(1): loaded http://localhost:54376/tests/dromaeo/cssquery-yui.html (next: http://localhost:54376/tests/dromaeo/cssquery-dojo.html) 20:27:50 INFO - PROCESS | 1966 | RSS: Main: 211746816 20:27:51 INFO - PROCESS | 1966 | 20:27:51 INFO - PROCESS | 1966 | __start_tp_report 20:27:51 INFO - PROCESS | 1966 | _x_x_mozilla_page_load 20:27:51 INFO - PROCESS | 1966 | _x_x_mozilla_page_load_details 20:27:51 INFO - PROCESS | 1966 | |i|pagename|runs| 20:27:51 INFO - PROCESS | 1966 | |0;dojo.html;3453.637368076352;3457.7752446091004;3480.7389445791564;3481.990222835378;3511.8595256189756;5799.681017544033;5882.9705851470735;5900.527957763379;5900.557458190631;5918.940810591893;5858.912116318259;5932.228810254672;5941;5950.10748387742;5962.34414214436;5249.790008399659;5272;5275.340582427197;5321.095413779667;5374.408815030363;5248.107821670325;5255.369355677324;5341.439148889369;5358.115910874701;5377.166539186433;5165.560927321182;5208.270842082112;5222.8694282642855;5255.343082114736;5330.92003619946;5232.6860388376435;5244.763985620656;5292.655977361459;5310.495502927216;5310.734463276821;5201.791928322863;5206.947930520684;5215.791368345262;5230.738463076831;5241.820590367137;5685.374608793029;5754.108113242454;5790.623609465413;5795.159701843251;5927.051671732502;6308.905366419507;6309.968450157766;6321.1150438938585;6332.461740752042;6347.143135676671;6288.811335659938;6317.842053948642;6322.146510221107;6323.272823625296;6330.8100756977365;5822.243108395882;6000.609960352563;6016.067509536029;6028.909566356464;6031.728572214217;6223.502119830403;6402.007688808242;6422.03669449577;6445.2265728112925;6536.509761767887;6296.811095667137;6302.558820882494;6316.463100636497;6329.746810127635;6356.110144579762;6301.7164227609865;6368.4586810121655;6368.713407896656;6377.107204991305;6385.936140638534;6162.106494558264;6236.220472440945;6270.184875966186;6287.402696743802;6289.119523266746;6299.338569450233;6302.747890084345;6304.5271604628915;6305.8108256752375;6318.778842740482;5060.013297407044;5079.771410286472;5083.135866902636;5089.440161582223;5127.41034781005;4409.911801763947;4466.0844526871915;4468.754218518013;4473.955260447354;4518.706284091523;5928.288605367384;5938.346781853993;5954.225950626477;5956.9873121569435;6007.519398448114;6210.72051757672;6312.147860038928;6334.651594162273;6337.7148028337915;6338;5743.224664670216;5901.881962360729;5932.7330270137945;5941;5983;5572.721363931787;5595.18869763882;5605.32736071674;5608.887822243532;5625.606207565431;6479.611223326616;6496;6504.121943537752;6516.380943810331;6523.412892839668;5478.178273259043;6073.787417440369;6221.595596286227;6229.906551401823;6292.716827742671;6435.06691529735;6456.322086180883;6457.321981191907;6466.612003279818;6486.740530378732;6319.146915166394;6326.240851097714;6330.3669633036325;6347.841303967438;6365.809025729235;5086.847394578169;6132.722655450899;6254.687265636609;6273.121762953281;6303.873922521432;5053.7473126344275;5607.41122182165;5935.881282374415;6245.906311405423;6301.716422761078;6253.687315634109;6268.529860260407;6296.811095667137;6299.401556852092;6318.968405158129;4912.6561140719805;6000.459958603747;6119.204503414528;6152;6226.59527130735;6202.72087756043;6237.313895471404;6263.123162757309;6267.310595834546;6287.119803227461;6149.508039356931;6159.72281247336;6162.198914141133;6185.876282474235;6221.129041934042 20:27:51 INFO - PROCESS | 1966 | |1;ext.html;494.470916119752;510.5430639577578;520.0924386944781;564.4665790827664;571.2944513525797;40184.19631607366;43250.56749432505;43264;43281.567184328145;43374.78312608437;43958.78020609901;44655.55344446563;44753.77623111888;44808.87977800557;44814.55185448145;8888.111188881125;9094.681686140986;9361.344705870591;9712.854307185398;11706.590269340575;11656.650300490957;11877.406129693523;11906.880931190708;11937.522499100025;11946.283223006603;11975.221610595327;11975.98204152648;11993.40032998351;12013.699657508589;12019.51921923122;10032.899671003343;11829.940850295736;11862.584809531625;11898.345590992492;11913.22564033335;11794.764104717857;11919.463624136935;12018.459169337313;12020.45907934145;12059.93970030153;42247.57752422498;42545.36181957273;42809.57190428087;42895.78552107251;43007.35488967668;4077.2660921034203;4088.716877054181;4152.563980782004;4257.148570285955;4381.255186618282;31384.686153138406;31588.842055789573;31858.522122168186;32220;33485.49771753426;2931.824090554552;3396.1068536010753;3410.897673069812;3458.15275257561;3485.75599708018;486585;487565;491965;492701;494444.5277773624;68543.65728171327;68878;68965.31034689689;69010.3098969014;69062.61874762578;73289.26710732824;73294.63352683203;73478;73501;73592;17679.823201768075;18404.17181226848;18420.631587368178;18438;18466.722999155023;12750.362481876055;13059.869401306056;13719.314034298246;13974.441022359195;14000.159990400609;19246.71129933052;19263.614727705368;19286.4214073578;19289.8071019288;19356.903215484115;6648.601083934979;6663.933360666331;6692.698828552727;6714.764983225564;6754.12196414463;898.3980732908967;900.4327273817453;906.4787747045343;934.9766255843522;955.1117460761419;25411.49177016449;32016.359672806415;32243.51634725434;32415.837920810245;32717.672823271463;894.5214310343935;1104.779044191165;1115.486876037016;1122.8203487442133;1259.6661884600587;13186.670333241746;15926.805489588327;16688.83311166897;16762.413315533897;16778.328866845433;522285;531075;532275;533560;534429.3278533582;64005;64451;64541.67729161324;64629.67685161638;64773;1379.7930310453312;1457.992710036443;1507.4723846653583;1515.8938874278915;1526.175865032892;30171.547426789053;31346.52980205253;31356.52965205478;31809.681903180674;32002.51996220012;54947;55288.17067743907;55330;55354.16968745391;55516.72241638766;539540;541280;541672;541888;542087;37319.81340093282;38147.42778858375;44133.89665258394;44272.77863610661;44585.554144459435;49900.83577705331;50657;50802.49197507978;51904.740476297375;52353;34307.14232144217;34693.95918122461;34761.65238347584;35634.64365356417;35898.28203435864;34094;34394.82802586071;35246.823765881;35429.1142721434;35732.46401304034;39210.803945980086;40074.39888401618;40695;40786.1842763149;40965.59034409618;30054.54918176273;34538.136546585534;35517;35569.1107722309;35648.821755891055;26839.865800670872;26859.865700671373;26923.461530769666;27062.864685676446;27083.1875043749 20:27:51 INFO - PROCESS | 1966 | |2;jquery.html;350.511037103241;394.4851968181522;400.11574420530627;406.2200574896199;431.03663312496565;15341.84658153418;17137;20684.89657551712;22205.888970555145;22358.3292501225;21738.673919891175;22011;22101.557968840614;23498.647520287166;23865.76134238657;5044.495550444954;6120.663363515005;6158.969205153968;6170.7531698732;6199.287081985562;5730.7421166047625;5977.282726072877;6087.726052327655;6147.385261473862;6152.876942461148;6067.7572897084065;6131.785387511438;6137.969310153443;6146.969265153691;6152.3847615238565;5871.559633027518;5978.8505287367725;5998.9700051499685;6041.24484439445;6126.326104128542;5321.308229930123;5325.281087053237;5339.919901201485;5342.973285133588;5355.946440535584;19954.401367958984;24005.759942400702;26715.332116697045;27284.31789205266;27544.586831197546;2405.736988081254;2735.8768855401554;2832.2211391867195;2866.784991125653;2891.089306868351;12927.353632318347;15607.687846243012;15988.960717553438;16120.193990300439;16562.83437165625;1852.3331600623765;1883.9802062838999;1892.1863598652574;1898.6012937283188;1898.6107847891149;128327.71672283251;129014;129385.35307323403;130573;134302.32848835888;9606.711798646053;11841.467133978991;11851.111166662535;12007.399630018639;12012.279263244058;15034.398624054915;15106.244687765788;15414.152221627917;15540.689186216212;15549.84450155484;7832.451728379073;9229;9277.814443711088;9343.34596578233;9352.438853668802;9366;9380;9403.764905877271;9547.045295470536;9565.139137477661;7614.487219217644;7743.109542402696;7761;7772.261635144652;7788.688452461838;1765.620391615783;2012.5471768851892;2033.8983050847398;2035.816776490123;2043.5402034542406;805.4281460163232;965.3869792681558;994.751312171957;1027.6095083868083;1132;21910.23314183996;22353.776462235495;23679.64480532759;23729.05083796629;23792.643110353358;1136.6590022993234;1141.560499207811;1146.2087133732766;1150.4362862197631;1175.0893057880212;12257.203281786655;12359.69100772488;12488.75022499563;12501.062420318513;12591.118621696392;786318;788395;805122;813813;821194;89420;136204;156860;165774;174626;1051.5846240734868;1080.4597701149426;1095.906847917936;1098.3464838421128;1102.1072930925975;19601.70597441011;20345.389638310873;21911.890440547697;21939.451513712283;21943.890280548494;59927;61209.693951531735;61356;62241.68879155575;62857;93081;97152;97414;98114;98507.5074624651;8690.565471726515;8780.78048048804;8784.868226976729;8912.509811960404;8989.910100898907;9425.57584908667;13645.522406715718;14222.359993800097;14281.500147494788;14301.785473218119;11788.882111178778;11874.228175168586;11959.342236177094;12139.453724582592;12145.635630931087;9144.817103658022;9757.902420975699;10546.103581195377;11140.944295278472;11398.94300528492;11758.470868810753;11916.761664766484;11985.101117416054;12236.816447753114;12303.138780285652;8591;8758.868616970623;8870.73387798367;8917.687880924137;9144.497052662167;8668.30653547702;11561.421928903354;11589.40890736511;11750.471228794553;12043.458044387848 20:27:51 INFO - PROCESS | 1966 | |3;mootools.html;1476.6013176442355;1507.8841657173696;1511.9848801511976;1518.8025556677633;1525.3059857764717;7802.492837965542;8026.759197224078;8307.501549907001;8718.825623487526;8744.650213991441;8247.010358756943;8534.530600816952;8541.701040463586;8637.611307491163;8685.131486851311;2376.1683410806254;2421.0436877433403;2424.35754525051;2424.5635785558593;2436.232586735173;2411.0958390603523;2425.9393515162087;2431.6474111253856;2454.7422520635346;2456.459578892641;2397.3886658902043;2402.8438151520095;2420.225527831095;2426.648136020276;2434.1602147259255;2370.561446132471;2374.5725769361507;2394.1261439574428;2395.018042602533;2407.494426170515;2401.375642333006;2405.951880962371;2417.903283868643;2433.6714543536573;2442.4504486490573;8483.872741908875;8549.572521373906;8604.5697715114;8640.827183456358;8667.4366166199;5244.344456942882;5394.865128371783;5409.486098820606;5411.323584551931;5418.133098704227;3042.6957304269617;3628.5827129880136;3647.288778688157;3665;3682.0610744260043;1241.7392347607013;1248.5130798988312;1299.5516546791437;1307.7711400504968;1327.502186679995;10858.23992320538;11164.385958772264;11186.608468703556;11190.608328708537;11200.383978881158;4000.579939106352;4697.436307643036;4823.348847905558;4875.756212189376;4880.243562247857;8812.77968050791;8825.249853762374;8926.464412135292;8946.776330591658;8978.326625503114;4195.622393984556;4922;5287.365516138088;5335.62650614461;5336.039512887717;4447.421835161408;4496.3705081288645;4936.234883593049;4987.326710893983;5014.122528557488;3794.2601192767697;3857.324968130622;3875.6318149775725;3891.1439483313625;3921.5294164700417;1183.083110589283;1196.2762528670203;1196.347990345264;1198.4127777389017;1200.5377929497206;732.1507051819864;744.307793751816;771.4676872957641;773.2074623510833;796.4464697035505;4874.2444921037295;5457;5507.5593952483705;5665.858353541194;5673.262475878109;573.7217449536971;579.9217105690763;586.5161142309962;595.615827791081;607.0590584593862;2897.246715853893;3045.969540304613;3150.070729134911;3224.806821476022;3787.693245830184;9863.950680246553;10344.120749736358;10358.274920755626;10373.533191006423;10375.221858360654;8605.35459840502;10296.22778291631;10311.84532232017;10333.431661258552;10350.585976561004;6579.671016449254;7362.932374805623;7366.631668416558;8041.758747237593;8093.1502192270145;5519.199716041232;5535.501804837584;5560.582956278215;5677.261955945701;5727.971360143172;9306.581203845708;9326.720198393788;9452.95273523628;9460.668876589287;9486.762830929281;9483.952580237055;9507.66723164686;9639.18066964316;9694.321397502106;9787.119159275699;6365.204349456318;6398.464368551608;6518.804435866931;6686.3313668632745;6801.625910574768;8087.9595602023965;8088.191180881865;8093.19068093186;8251.628676709443;8267.710630127916;7004.15950085993;7142.785716428515;7144.571325720265;7205.099362579677;7243.601601911945;6829.453643708392;6971.721131154697;6980.508127441325;6999.30006999296;7091.60996145217;6381.266154392119;6396.424321810873;6465.515086368447;6467.482601391972;6468.902966455413;5052.46949070357;5057.848264552069;5062.569681577107;5123.820666276662;5152.458991805956;5083.796648134181;5189;5203.739813009259;5204.739763011758;5216.843494695166 20:27:51 INFO - PROCESS | 1966 | |4;prototype.html;360.79254428703484;366.325960233171;373.68236998551225;378.59679441394894;381.7156218617131;5400.702961337125;5530.640508366953;5596.552275817935;5597.684544132126;5652.3499797523245;4991.500849915006;5182.507661772135;5501.587380946435;5582.441755824416;5623.156526521024;3962.48487696599;4279.764612946286;4376;4391.429114215148;4422.7788610569505;3096.876124954999;3228.902173261091;4003.559608443069;4369.344598310247;4447;4109;4162.4796900387455;4175.331946888498;4194.391813187086;4215.367694845767;3313.519539666747;3697.963020369789;3973.443717879499;4214.578542145761;4235;3629.94555081674;3667.3765459871884;3669.5779985301497;3673.8530458781615;3705.2404257127223;5174.2238664200295;5442.619016668835;5457.672539647594;5510.448955104497;5543.695096769676;4519.887002824956;4826.565609095198;4908.43552991407;4918.606511479073;4923.236898280772;4671.976640116812;5018.222175562793;5905.29136503618;5909.261342332208;6000.579959402844;3272.918177045569;3492.772969756983;3785.621437856192;4033.9193216135513;4043.6158564936286;5296.77535435122;6242.250929888442;6303.621782693007;6316.873662526724;6352.9188343653295;3439.6560343965402;4092.0588264699286;4101.733387329814;4107.158032603309;4112.547619761824;3498.3877821381157;3511.666391692785;4021.4973128358956;4089.1003979124544;4137.338025915839;4107.8973025674;4238.449001629769;4469.776511174493;4825.638077144158;4890.266460030953;6003.189569408074;6008;6021.397860214031;6080.179175811298;6086.382855372144;1153.8326942593444;1191.9940400298117;1240.5409998300686;1241.9796999204161;1280.5582074184579;883.439016224702;897.533282692996;906.6962567539816;914.8261830252363;916.9633214671338;5277.788888444496;5303.549198318186;5328.333958255218;5337.5463085637375;5364.436734142937;5434.994526012701;5473.890522189534;5542.201188399832;5866.943950088939;5876.7658791653375;4451.354553589711;4696.862927368039;4760.880977975509;4854.616434316274;5182.993863559127;2990.8803647853842;3063.8161710297454;3079.3687294104657;3123.6876312369036;3130.514770210621;5341.118715411992;5342.767625586542;5740.732410697835;5767.568296863359;6212.074256980351;5094;5257.790708137073;5576.888462230732;6051.8198951204095;6289.213848268966;5530.2810634618045;6268.780592679234;6360.678352401433;6363.7908797328355;6404.807855764335;4526.284229462296;5666.951613951433;6141.1402403664415;6187.443130118311;6209.96895015522;5755.510781583613;6003.129546215861;6049.78825741097;6087.62582592798;6133.172021777182;5524.674078221117;5863.794767183128;6337.7148028337915;6373.458256048102;6378.628594852129;3200.7279381251924;3271.574695289597;3597.9460308094876;4132.504099508078;4172.4993000840095;5930.347661757117;6161.72272247741;6197.349278325711;6203.658798766111;6233.532485063548;4858.975705121452;4949.97525012387;5599.888002239851;5928;5968.940310597012;5875.649740103992;5921.299188007495;5927.8221653350465;5939.673317967553;5968.373320801254;4766.189747742961;5342.171963345688;5563.942850858324;5711.942880571141;5734.301375612623;5499.552699049045;5923.08192230205;5952.916479232234;5973.253343332083;5981.962175531586;4873.659743570546;4894.33926419943;5667.574931880208;5851.502622277154;5869.09479807481 20:27:51 INFO - PROCESS | 1966 | |5;yui.html;730.9853802923946;739.8446326271483;784.2432053068787;871.6208449324545;877.4559772940776;15246.92376538117;16342.10118443485;16387.180640967934;16400.26198821053;16438;13781.310934453288;13959.232242226673;14274.286285685726;14985.850141498582;15192.848071519282;885.3050355470973;910.6539514984297;918.1415376622846;919.5999740113039;920.5535315372039;736.7679181057977;754.8075240813612;909.6770646420524;910.1490106750209;911.8313112074287;934.728928610702;956.0009789769707;962.8170647576973;966.415318732164;968.6609686609665;949.0651708067548;951.1202138022371;953.1993125774349;955.2262667239523;961.20700422152;700.6951975890504;705.4814711187273;712.3588770106893;715.7995761186876;717.0069453806516;13918.652033699138;14451.48259432755;15645.374185032584;16021.198940052951;16068.919655401649;14645.560633181021;14827.555173344817;14906.329215185342;14932.402703891832;14966.401343946229;11908.16642835002;12064.215825971283;12124.39378031095;12127.272363658209;12136.696582585506;3713;3833.5208098987628;3834.616538346171;3861.1119442528375;3866.5940076291868;113719.99999999917;115171.42414287875;117739;117796;119654.4017279908;11185.272957257752;11308;11366.033887119522;11583.536658533565;11636.650900473;23307.650385244237;24029.27912162638;24048;24214.515709685707;24223.878880605484;7836.843263134705;8032.437729359006;8068.354531637572;8102.39232057598;8294.709685160991;4105.363668631367;4147.108371700099;4216.409702641633;4283.892902677458;4293.763842988603;3750.099976005739;3769.359208934433;3835.079580900564;3845.423186522044;3845.442410850411;693.6011793218919;696.216756149332;704.4294121761287;710.6944014073895;713.4862898712919;656.7110471392667;675.9256481786999;685;691.9169699636076;699.328525242477;10585.364878107339;10688;10821.296615719953;10842.674719758419;11096.389698566656;611.7919907231562;688.6556721639181;717.6734585763465;734.7281505842873;743.3792783026125;4492.573205545468;4527.637788976874;5267.446918073624;5274.947250527523;5275;109025.45487272512;109445.45277273563;110096;110489.44755276172;113153;74885.62557187179;88023.35929922112;88089.11910880938;88434;88597.11402886017;76122.6193869027;76637.6168119167;77364;78266.6086669563;80537;8892.822143556963;10659.680209593414;11092.833607496055;11359.602413915472;11406.771864562828;35867;46137.76931115323;47773.76113119412;48146.518534814204;49033;61587.692061539405;66116.66941665261;66176.66911665411;67694;68431;13145.73708525805;13400.128991615515;13591;13608.795868061788;14069.648258793612;11970.640880773592;14962.551123466314;15048.021878577858;15124;15506.84493155054;13932.93033534826;14159.36282867294;14324.856751432353;14353.64115897111;14453.132812031312;13616.65958351049;13781.173129612256;14264.358103885557;14289.35697893618;14307.856921431068;13300.334983250605;13610.115342502706;14071.507497237548;14083.929580352033;14383.42466301378;10063.949680251844;10303.124234440156;10359;10447.94776026115;10568.947155264483;9329;10219.948900255451;10853.945730271298;11377.772444551229;11564.132690048113 20:27:51 INFO - PROCESS | 1966 | __end_tp_report 20:27:51 INFO - PROCESS | 1966 | __start_cc_report 20:27:51 INFO - PROCESS | 1966 | _x_x_mozilla_cycle_collect,379 20:27:51 INFO - PROCESS | 1966 | __end_cc_report 20:27:51 INFO - PROCESS | 1966 | __startTimestamp1490930870997__endTimestamp 20:27:51 INFO - PROCESS | 1966 | 20:27:51 INFO - PROCESS | 1966 | ------- Summary: start ------- 20:27:51 INFO - PROCESS | 1966 | Number of tests: 6 20:27:51 INFO - PROCESS | 1966 | 20:27:51 INFO - PROCESS | 1966 | [#0] dojo.html Cycles:165 Average:5842.63 Median:6073.79 stddev:655.73 (10.8%) stddev-sans-first:630.39 20:27:51 INFO - PROCESS | 1966 | Values: 3453.6 3457.8 3480.7 3482.0 3511.9 5799.7 5883.0 5900.5 5900.6 5918.9 5858.9 5932.2 5941.0 5950.1 5962.3 5249.8 5272.0 5275.3 5321.1 5374.4 5248.1 5255.4 5341.4 5358.1 5377.2 5165.6 5208.3 5222.9 5255.3 5330.9 5232.7 5244.8 5292.7 5310.5 5310.7 5201.8 5206.9 5215.8 5230.7 5241.8 5685.4 5754.1 5790.6 5795.2 5927.1 6308.9 6310.0 6321.1 6332.5 6347.1 6288.8 6317.8 6322.1 6323.3 6330.8 5822.2 6000.6 6016.1 6028.9 6031.7 6223.5 6402.0 6422.0 6445.2 6536.5 6296.8 6302.6 6316.5 6329.7 6356.1 6301.7 6368.5 6368.7 6377.1 6385.9 6162.1 6236.2 6270.2 6287.4 6289.1 6299.3 6302.7 6304.5 6305.8 6318.8 5060.0 5079.8 5083.1 5089.4 5127.4 4409.9 4466.1 4468.8 4474.0 4518.7 5928.3 5938.3 5954.2 5957.0 6007.5 6210.7 6312.1 6334.7 6337.7 6338.0 5743.2 5901.9 5932.7 5941.0 5983.0 5572.7 5595.2 5605.3 5608.9 5625.6 6479.6 6496.0 6504.1 6516.4 6523.4 5478.2 6073.8 6221.6 6229.9 6292.7 6435.1 6456.3 6457.3 6466.6 6486.7 6319.1 6326.2 6330.4 6347.8 6365.8 5086.8 6132.7 6254.7 6273.1 6303.9 5053.7 5607.4 5935.9 6245.9 6301.7 6253.7 6268.5 6296.8 6299.4 6319.0 4912.7 6000.5 6119.2 6152.0 6226.6 6202.7 6237.3 6263.1 6267.3 6287.1 6149.5 6159.7 6162.2 6185.9 6221.1 20:27:51 INFO - PROCESS | 1966 | 20:27:51 INFO - PROCESS | 1966 | [#1] ext.html Cycles:180 Average:68217.53 Median:37733.62 stddev:138437.11 (366.9%) stddev-sans-first:138732.08 20:27:51 INFO - PROCESS | 1966 | Values: 494.5 510.5 520.1 564.5 571.3 40184.2 43250.6 43264.0 43281.6 43374.8 43958.8 44655.6 44753.8 44808.9 44814.6 8888.1 9094.7 9361.3 9712.9 11706.6 11656.7 11877.4 11906.9 11937.5 11946.3 11975.2 11976.0 11993.4 12013.7 12019.5 10032.9 11829.9 11862.6 11898.3 11913.2 11794.8 11919.5 12018.5 12020.5 12059.9 42247.6 42545.4 42809.6 42895.8 43007.4 4077.3 4088.7 4152.6 4257.1 4381.3 31384.7 31588.8 31858.5 32220.0 33485.5 2931.8 3396.1 3410.9 3458.2 3485.8 486585.0 487565.0 491965.0 492701.0 494444.5 68543.7 68878.0 68965.3 69010.3 69062.6 73289.3 73294.6 73478.0 73501.0 73592.0 17679.8 18404.2 18420.6 18438.0 18466.7 12750.4 13059.9 13719.3 13974.4 14000.2 19246.7 19263.6 19286.4 19289.8 19356.9 6648.6 6663.9 6692.7 6714.8 6754.1 898.4 900.4 906.5 935.0 955.1 25411.5 32016.4 32243.5 32415.8 32717.7 894.5 1104.8 1115.5 1122.8 1259.7 13186.7 15926.8 16688.8 16762.4 16778.3 522285.0 531075.0 532275.0 533560.0 534429.3 64005.0 64451.0 64541.7 64629.7 64773.0 1379.8 1458.0 1507.5 1515.9 1526.2 30171.5 31346.5 31356.5 31809.7 32002.5 54947.0 55288.2 55330.0 55354.2 55516.7 539540.0 541280.0 541672.0 541888.0 542087.0 37319.8 38147.4 44133.9 44272.8 44585.6 49900.8 50657.0 50802.5 51904.7 52353.0 34307.1 34694.0 34761.7 35634.6 35898.3 34094.0 34394.8 35246.8 35429.1 35732.5 39210.8 40074.4 40695.0 40786.2 40965.6 30054.5 34538.1 35517.0 35569.1 35648.8 26839.9 26859.9 26923.5 27062.9 27083.2 20:27:51 INFO - PROCESS | 1966 | 20:27:51 INFO - PROCESS | 1966 | [#2] jquery.html Cycles:180 Average:42908.23 Median:23829.20 stddev:133204.57 (559.0%) stddev-sans-first:133539.91 20:27:51 INFO - PROCESS | 1966 | Values: 350.5 394.5 400.1 406.2 431.0 15341.8 17137.0 20684.9 22205.9 22358.3 21738.7 22011.0 22101.6 23498.6 23865.8 5044.5 6120.7 6159.0 6170.8 6199.3 5730.7 5977.3 6087.7 6147.4 6152.9 6067.8 6131.8 6138.0 6147.0 6152.4 5871.6 5978.9 5999.0 6041.2 6126.3 5321.3 5325.3 5339.9 5343.0 5355.9 19954.4 24005.8 26715.3 27284.3 27544.6 2405.7 2735.9 2832.2 2866.8 2891.1 12927.4 15607.7 15989.0 16120.2 16562.8 1852.3 1884.0 1892.2 1898.6 1898.6 128327.7 129014.0 129385.4 130573.0 134302.3 9606.7 11841.5 11851.1 12007.4 12012.3 15034.4 15106.2 15414.2 15540.7 15549.8 7832.5 9229.0 9277.8 9343.3 9352.4 9366.0 9380.0 9403.8 9547.0 9565.1 7614.5 7743.1 7761.0 7772.3 7788.7 1765.6 2012.5 2033.9 2035.8 2043.5 805.4 965.4 994.8 1027.6 1132.0 21910.2 22353.8 23679.6 23729.1 23792.6 1136.7 1141.6 1146.2 1150.4 1175.1 12257.2 12359.7 12488.8 12501.1 12591.1 786318.0 788395.0 805122.0 813813.0 821194.0 89420.0 136204.0 156860.0 165774.0 174626.0 1051.6 1080.5 1095.9 1098.3 1102.1 19601.7 20345.4 21911.9 21939.5 21943.9 59927.0 61209.7 61356.0 62241.7 62857.0 93081.0 97152.0 97414.0 98114.0 98507.5 8690.6 8780.8 8784.9 8912.5 8989.9 9425.6 13645.5 14222.4 14281.5 14301.8 11788.9 11874.2 11959.3 12139.5 12145.6 9144.8 9757.9 10546.1 11140.9 11398.9 11758.5 11916.8 11985.1 12236.8 12303.1 8591.0 8758.9 8870.7 8917.7 9144.5 8668.3 11561.4 11589.4 11750.5 12043.5 20:27:51 INFO - PROCESS | 1966 | 20:27:51 INFO - PROCESS | 1966 | [#3] mootools.html Cycles:180 Average:5462.75 Median:5230.59 stddev:3009.40 (57.5%) stddev-sans-first:3002.93 20:27:51 INFO - PROCESS | 1966 | Values: 1476.6 1507.9 1512.0 1518.8 1525.3 7802.5 8026.8 8307.5 8718.8 8744.7 8247.0 8534.5 8541.7 8637.6 8685.1 2376.2 2421.0 2424.4 2424.6 2436.2 2411.1 2425.9 2431.6 2454.7 2456.5 2397.4 2402.8 2420.2 2426.6 2434.2 2370.6 2374.6 2394.1 2395.0 2407.5 2401.4 2406.0 2417.9 2433.7 2442.5 8483.9 8549.6 8604.6 8640.8 8667.4 5244.3 5394.9 5409.5 5411.3 5418.1 3042.7 3628.6 3647.3 3665.0 3682.1 1241.7 1248.5 1299.6 1307.8 1327.5 10858.2 11164.4 11186.6 11190.6 11200.4 4000.6 4697.4 4823.3 4875.8 4880.2 8812.8 8825.2 8926.5 8946.8 8978.3 4195.6 4922.0 5287.4 5335.6 5336.0 4447.4 4496.4 4936.2 4987.3 5014.1 3794.3 3857.3 3875.6 3891.1 3921.5 1183.1 1196.3 1196.3 1198.4 1200.5 732.2 744.3 771.5 773.2 796.4 4874.2 5457.0 5507.6 5665.9 5673.3 573.7 579.9 586.5 595.6 607.1 2897.2 3046.0 3150.1 3224.8 3787.7 9864.0 10344.1 10358.3 10373.5 10375.2 8605.4 10296.2 10311.8 10333.4 10350.6 6579.7 7362.9 7366.6 8041.8 8093.2 5519.2 5535.5 5560.6 5677.3 5728.0 9306.6 9326.7 9453.0 9460.7 9486.8 9484.0 9507.7 9639.2 9694.3 9787.1 6365.2 6398.5 6518.8 6686.3 6801.6 8088.0 8088.2 8093.2 8251.6 8267.7 7004.2 7142.8 7144.6 7205.1 7243.6 6829.5 6971.7 6980.5 6999.3 7091.6 6381.3 6396.4 6465.5 6467.5 6468.9 5052.5 5057.8 5062.6 5123.8 5152.5 5083.8 5189.0 5203.7 5204.7 5216.8 20:27:51 INFO - PROCESS | 1966 | 20:27:51 INFO - PROCESS | 1966 | [#4] prototype.html Cycles:180 Average:4703.93 Median:5315.94 stddev:1496.25 (28.1%) stddev-sans-first:1464.51 20:27:51 INFO - PROCESS | 1966 | Values: 360.8 366.3 373.7 378.6 381.7 5400.7 5530.6 5596.6 5597.7 5652.3 4991.5 5182.5 5501.6 5582.4 5623.2 3962.5 4279.8 4376.0 4391.4 4422.8 3096.9 3228.9 4003.6 4369.3 4447.0 4109.0 4162.5 4175.3 4194.4 4215.4 3313.5 3698.0 3973.4 4214.6 4235.0 3629.9 3667.4 3669.6 3673.9 3705.2 5174.2 5442.6 5457.7 5510.4 5543.7 4519.9 4826.6 4908.4 4918.6 4923.2 4672.0 5018.2 5905.3 5909.3 6000.6 3272.9 3492.8 3785.6 4033.9 4043.6 5296.8 6242.3 6303.6 6316.9 6352.9 3439.7 4092.1 4101.7 4107.2 4112.5 3498.4 3511.7 4021.5 4089.1 4137.3 4107.9 4238.4 4469.8 4825.6 4890.3 6003.2 6008.0 6021.4 6080.2 6086.4 1153.8 1192.0 1240.5 1242.0 1280.6 883.4 897.5 906.7 914.8 917.0 5277.8 5303.5 5328.3 5337.5 5364.4 5435.0 5473.9 5542.2 5866.9 5876.8 4451.4 4696.9 4760.9 4854.6 5183.0 2990.9 3063.8 3079.4 3123.7 3130.5 5341.1 5342.8 5740.7 5767.6 6212.1 5094.0 5257.8 5576.9 6051.8 6289.2 5530.3 6268.8 6360.7 6363.8 6404.8 4526.3 5667.0 6141.1 6187.4 6210.0 5755.5 6003.1 6049.8 6087.6 6133.2 5524.7 5863.8 6337.7 6373.5 6378.6 3200.7 3271.6 3597.9 4132.5 4172.5 5930.3 6161.7 6197.3 6203.7 6233.5 4859.0 4950.0 5599.9 5928.0 5968.9 5875.6 5921.3 5927.8 5939.7 5968.4 4766.2 5342.2 5563.9 5711.9 5734.3 5499.6 5923.1 5952.9 5973.3 5982.0 4873.7 4894.3 5667.6 5851.5 5869.1 20:27:51 INFO - PROCESS | 1966 | 20:27:51 INFO - PROCESS | 1966 | [#5] yui.html Cycles:180 Average:20974.71 Median:3759.73 stddev:30465.80 (810.3%) stddev-sans-first:30513.35 20:27:51 INFO - PROCESS | 1966 | Values: 731.0 739.8 784.2 871.6 877.5 15246.9 16342.1 16387.2 16400.3 16438.0 13781.3 13959.2 14274.3 14985.9 15192.8 885.3 910.7 918.1 919.6 920.6 736.8 754.8 909.7 910.1 911.8 934.7 956.0 962.8 966.4 968.7 949.1 951.1 953.2 955.2 961.2 700.7 705.5 712.4 715.8 717.0 13918.7 14451.5 15645.4 16021.2 16068.9 14645.6 14827.6 14906.3 14932.4 14966.4 11908.2 12064.2 12124.4 12127.3 12136.7 3713.0 3833.5 3834.6 3861.1 3866.6 113720.0 115171.4 117739.0 117796.0 119654.4 11185.3 11308.0 11366.0 11583.5 11636.7 23307.7 24029.3 24048.0 24214.5 24223.9 7836.8 8032.4 8068.4 8102.4 8294.7 4105.4 4147.1 4216.4 4283.9 4293.8 3750.1 3769.4 3835.1 3845.4 3845.4 693.6 696.2 704.4 710.7 713.5 656.7 675.9 685.0 691.9 699.3 10585.4 10688.0 10821.3 10842.7 11096.4 611.8 688.7 717.7 734.7 743.4 4492.6 4527.6 5267.4 5274.9 5275.0 109025.5 109445.5 110096.0 110489.4 113153.0 74885.6 88023.4 88089.1 88434.0 88597.1 76122.6 76637.6 77364.0 78266.6 80537.0 8892.8 10659.7 11092.8 11359.6 11406.8 35867.0 46137.8 47773.8 48146.5 49033.0 61587.7 66116.7 66176.7 67694.0 68431.0 13145.7 13400.1 13591.0 13608.8 14069.6 11970.6 14962.6 15048.0 15124.0 15506.8 13932.9 14159.4 14324.9 14353.6 14453.1 13616.7 13781.2 14264.4 14289.4 14307.9 13300.3 13610.1 14071.5 14083.9 14383.4 10063.9 10303.1 10359.0 10447.9 10568.9 9329.0 10219.9 10853.9 11377.8 11564.1 20:27:51 INFO - PROCESS | 1966 | -------- Summary: end -------- 20:27:51 INFO - PROCESS | 1966 | 20:27:51 INFO - TEST-INFO | 1966: exit 0 20:27:51 INFO - TEST-OK | dromaeo_css | took 1113649ms 20:27:51 INFO - TEST-START | kraken 20:27:51 INFO - operating with platform_type : mac_ 20:27:51 INFO - Initialising browser for kraken test... 20:27:51 INFO - TEST-INFO | started process 1971 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/tmp0vDMNH/profile http://localhost:54376/getInfo.html) 20:27:54 INFO - PROCESS | 1971 | 2017-03-30 20:27:54.051 plugin-container[1973:21196] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 20:27:54 INFO - PROCESS | 1971 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:27:54 INFO - PROCESS | 1971 | 2017-03-30 20:27:54.052 plugin-container[1973:21196] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8743, name = 'com.apple.CFPasteboardClient' 20:27:54 INFO - PROCESS | 1971 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:27:54 INFO - PROCESS | 1971 | 2017-03-30 20:27:54.052 plugin-container[1973:21196] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:27:54 INFO - PROCESS | 1971 | __metrics Screen width/height:1600/1200 20:27:54 INFO - PROCESS | 1971 | colorDepth:24 20:27:54 INFO - PROCESS | 1971 | Browser inner width/height: 1024/689 20:27:54 INFO - PROCESS | 1971 | __metrics 20:27:54 ERROR - PROCESS | 1971 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 20:27:54 INFO - TEST-INFO | 1971: exit 0 20:27:54 INFO - Browser initialized. 20:27:54 INFO - Running cycle 1/1 for kraken test... 20:27:54 INFO - TEST-INFO | started process 1974 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/T/tmp0vDMNH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:27:57 INFO - PROCESS | 1974 | 2017-03-30 20:27:57.173 plugin-container[1975:21318] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 20:27:57 INFO - PROCESS | 1974 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:27:57 INFO - PROCESS | 1974 | 2017-03-30 20:27:57.173 plugin-container[1975:21318] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c03, name = 'com.apple.CFPasteboardClient' 20:27:57 INFO - PROCESS | 1974 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:27:57 INFO - PROCESS | 1974 | 2017-03-30 20:27:57.173 plugin-container[1975:21318] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:28:06 INFO - PROCESS | 1974 | RSS: Main: 173494272 20:28:06 INFO - PROCESS | 1974 | 20:28:09 INFO - PROCESS | 1974 | Cycle 1(1): loaded http://localhost:54376/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54376/tests/kraken/driver.html?testName=audio-beat-detection) 20:28:10 INFO - PROCESS | 1974 | RSS: Main: 186331136 20:28:10 INFO - PROCESS | 1974 | 20:28:13 INFO - PROCESS | 1974 | Cycle 1(1): loaded http://localhost:54376/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54376/tests/kraken/driver.html?testName=audio-dft) 20:28:13 INFO - PROCESS | 1974 | RSS: Main: 194080768 20:28:13 INFO - PROCESS | 1974 | 20:28:17 INFO - PROCESS | 1974 | Cycle 1(1): loaded http://localhost:54376/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54376/tests/kraken/driver.html?testName=audio-fft) 20:28:17 INFO - PROCESS | 1974 | RSS: Main: 197722112 20:28:17 INFO - PROCESS | 1974 | 20:28:19 INFO - PROCESS | 1974 | Cycle 1(1): loaded http://localhost:54376/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54376/tests/kraken/driver.html?testName=audio-oscillator) 20:28:20 INFO - PROCESS | 1974 | RSS: Main: 199409664 20:28:20 INFO - PROCESS | 1974 | 20:28:22 INFO - PROCESS | 1974 | Cycle 1(1): loaded http://localhost:54376/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54376/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:28:22 INFO - PROCESS | 1974 | RSS: Main: 199327744 20:28:22 INFO - PROCESS | 1974 | 20:28:25 INFO - PROCESS | 1974 | Cycle 1(1): loaded http://localhost:54376/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54376/tests/kraken/driver.html?testName=imaging-darkroom) 20:28:26 INFO - PROCESS | 1974 | RSS: Main: 203886592 20:28:26 INFO - PROCESS | 1974 | 20:28:29 INFO - PROCESS | 1974 | Cycle 1(1): loaded http://localhost:54376/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54376/tests/kraken/driver.html?testName=imaging-desaturate) 20:28:30 INFO - PROCESS | 1974 | RSS: Main: 203853824 20:28:30 INFO - PROCESS | 1974 | 20:28:33 INFO - PROCESS | 1974 | Cycle 1(1): loaded http://localhost:54376/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54376/tests/kraken/driver.html?testName=json-parse-financial) 20:28:34 INFO - PROCESS | 1974 | RSS: Main: 203153408 20:28:34 INFO - PROCESS | 1974 | 20:28:35 INFO - PROCESS | 1974 | Cycle 1(1): loaded http://localhost:54376/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54376/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:28:36 INFO - PROCESS | 1974 | RSS: Main: 202215424 20:28:36 INFO - PROCESS | 1974 | 20:28:38 INFO - PROCESS | 1974 | Cycle 1(1): loaded http://localhost:54376/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54376/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:28:38 INFO - PROCESS | 1974 | RSS: Main: 202248192 20:28:38 INFO - PROCESS | 1974 | 20:28:41 INFO - PROCESS | 1974 | Cycle 1(1): loaded http://localhost:54376/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54376/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:28:41 INFO - PROCESS | 1974 | RSS: Main: 202104832 20:28:41 INFO - PROCESS | 1974 | 20:28:44 INFO - PROCESS | 1974 | Cycle 1(1): loaded http://localhost:54376/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54376/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:28:45 INFO - PROCESS | 1974 | RSS: Main: 202674176 20:28:45 INFO - PROCESS | 1974 | 20:28:48 INFO - PROCESS | 1974 | Cycle 1(1): loaded http://localhost:54376/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54376/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:28:49 INFO - PROCESS | 1974 | RSS: Main: 202747904 20:28:49 INFO - PROCESS | 1974 | 20:28:50 INFO - PROCESS | 1974 | Cycle 1(1): loaded http://localhost:54376/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54376/tests/kraken/driver.html?testName=ai-astar) 20:28:51 INFO - PROCESS | 1974 | RSS: Main: 204439552 20:28:51 INFO - PROCESS | 1974 | 20:28:51 INFO - PROCESS | 1974 | __start_tp_report 20:28:51 INFO - PROCESS | 1974 | _x_x_mozilla_page_load 20:28:51 INFO - PROCESS | 1974 | _x_x_mozilla_page_load_details 20:28:51 INFO - PROCESS | 1974 | |i|pagename|runs| 20:28:51 INFO - PROCESS | 1974 | |0;ai-astar;105;118;137;178;157;120;119;118;120;123 20:28:51 INFO - PROCESS | 1974 | |1;audio-beat-detection;144;111;109;145;110;114;111;112;129;111 20:28:51 INFO - PROCESS | 1974 | |2;audio-dft;199;200;195;197;198;198;197;193;200;197 20:28:51 INFO - PROCESS | 1974 | |3;audio-fft;82;58;60;86;58;67;69;84;72;59 20:28:51 INFO - PROCESS | 1974 | |4;audio-oscillator;86;69;84;71;71;71;84;55;73;68 20:28:51 INFO - PROCESS | 1974 | |5;imaging-gaussian-blur;137;132;91;89;96;126;95;81;136;119 20:28:51 INFO - PROCESS | 1974 | |6;imaging-darkroom;95;96;97;96;109;152;153;97;96;96 20:28:51 INFO - PROCESS | 1974 | |7;imaging-desaturate;93;98;92;116;108;76;141;144;130;98 20:28:51 INFO - PROCESS | 1974 | |8;json-parse-financial;58;56;59;60;60;60;62;74;56;61 20:28:51 INFO - PROCESS | 1974 | |9;json-stringify-tinderbox;68;73;86;81;78;78;109;107;107;72 20:28:51 INFO - PROCESS | 1974 | |10;stanford-crypto-aes;85;106;85;85;86;81;84;83;85;88 20:28:51 INFO - PROCESS | 1974 | |11;stanford-crypto-ccm;138;132;135;133;154;136;141;145;175;168 20:28:51 INFO - PROCESS | 1974 | |12;stanford-crypto-pbkdf2;172;161;210;213;164;165;172;229;233;182 20:28:51 INFO - PROCESS | 1974 | |13;stanford-crypto-sha256-iterative;69;59;55;69;90;88;89;65;59;87 20:28:51 INFO - PROCESS | 1974 | __end_tp_report 20:28:51 INFO - PROCESS | 1974 | __start_cc_report 20:28:51 INFO - PROCESS | 1974 | _x_x_mozilla_cycle_collect,931 20:28:51 INFO - PROCESS | 1974 | __end_cc_report 20:28:51 INFO - PROCESS | 1974 | __startTimestamp1490930931092__endTimestamp 20:28:51 INFO - PROCESS | 1974 | 20:28:51 INFO - PROCESS | 1974 | ------- Summary: start ------- 20:28:51 INFO - PROCESS | 1974 | Number of tests: 14 20:28:51 INFO - PROCESS | 1974 | 20:28:51 INFO - PROCESS | 1974 | [#0] ai-astar Cycles:10 Average:129.50 Median:121.50 stddev:22.02 (18.1%) stddev-sans-first:21.49 20:28:51 INFO - PROCESS | 1974 | Values: 105.0 118.0 137.0 178.0 157.0 120.0 119.0 118.0 120.0 123.0 20:28:51 INFO - PROCESS | 1974 | 20:28:51 INFO - PROCESS | 1974 | [#1] audio-beat-detection Cycles:10 Average:119.60 Median:113.00 stddev:14.32 (12.7%) stddev-sans-first:12.16 20:28:51 INFO - PROCESS | 1974 | Values: 144.0 111.0 109.0 145.0 110.0 114.0 111.0 112.0 129.0 111.0 20:28:51 INFO - PROCESS | 1974 | 20:28:51 INFO - PROCESS | 1974 | [#2] audio-dft Cycles:10 Average:197.40 Median:198.00 stddev:2.17 (1.1%) stddev-sans-first:2.22 20:28:51 INFO - PROCESS | 1974 | Values: 199.0 200.0 195.0 197.0 198.0 198.0 197.0 193.0 200.0 197.0 20:28:51 INFO - PROCESS | 1974 | 20:28:51 INFO - PROCESS | 1974 | [#3] audio-fft Cycles:10 Average:69.50 Median:70.50 stddev:11.14 (15.8%) stddev-sans-first:10.86 20:28:51 INFO - PROCESS | 1974 | Values: 82.0 58.0 60.0 86.0 58.0 67.0 69.0 84.0 72.0 59.0 20:28:51 INFO - PROCESS | 1974 | 20:28:51 INFO - PROCESS | 1974 | [#4] audio-oscillator Cycles:10 Average:73.20 Median:72.00 stddev:9.35 (13.0%) stddev-sans-first:8.70 20:28:51 INFO - PROCESS | 1974 | Values: 86.0 69.0 84.0 71.0 71.0 71.0 84.0 55.0 73.0 68.0 20:28:51 INFO - PROCESS | 1974 | 20:28:51 INFO - PROCESS | 1974 | [#5] imaging-gaussian-blur Cycles:10 Average:110.20 Median:85.00 stddev:21.83 (25.7%) stddev-sans-first:20.89 20:28:51 INFO - PROCESS | 1974 | Values: 137.0 132.0 91.0 89.0 96.0 126.0 95.0 81.0 136.0 119.0 20:28:51 INFO - PROCESS | 1974 | 20:28:51 INFO - PROCESS | 1974 | [#6] imaging-darkroom Cycles:10 Average:108.70 Median:96.00 stddev:23.44 (24.4%) stddev-sans-first:24.33 20:28:51 INFO - PROCESS | 1974 | Values: 95.0 96.0 97.0 96.0 109.0 152.0 153.0 97.0 96.0 96.0 20:28:51 INFO - PROCESS | 1974 | 20:28:51 INFO - PROCESS | 1974 | [#7] imaging-desaturate Cycles:10 Average:109.60 Median:84.00 stddev:22.64 (27.0%) stddev-sans-first:23.20 20:28:51 INFO - PROCESS | 1974 | Values: 93.0 98.0 92.0 116.0 108.0 76.0 141.0 144.0 130.0 98.0 20:28:51 INFO - PROCESS | 1974 | 20:28:51 INFO - PROCESS | 1974 | [#8] json-parse-financial Cycles:10 Average:60.60 Median:60.00 stddev:5.10 (8.5%) stddev-sans-first:5.33 20:28:51 INFO - PROCESS | 1974 | Values: 58.0 56.0 59.0 60.0 60.0 60.0 62.0 74.0 56.0 61.0 20:28:51 INFO - PROCESS | 1974 | 20:28:51 INFO - PROCESS | 1974 | [#9] json-stringify-tinderbox Cycles:10 Average:85.90 Median:75.50 stddev:15.82 (21.0%) stddev-sans-first:15.40 20:28:51 INFO - PROCESS | 1974 | Values: 68.0 73.0 86.0 81.0 78.0 78.0 109.0 107.0 107.0 72.0 20:28:51 INFO - PROCESS | 1974 | 20:28:51 INFO - PROCESS | 1974 | [#10] stanford-crypto-aes Cycles:10 Average:86.80 Median:85.00 stddev:6.99 (8.2%) stddev-sans-first:7.38 20:28:51 INFO - PROCESS | 1974 | Values: 85.0 106.0 85.0 85.0 86.0 81.0 84.0 83.0 85.0 88.0 20:28:51 INFO - PROCESS | 1974 | 20:28:51 INFO - PROCESS | 1974 | [#11] stanford-crypto-ccm Cycles:10 Average:145.70 Median:143.00 stddev:15.14 (10.6%) stddev-sans-first:15.80 20:28:51 INFO - PROCESS | 1974 | Values: 138.0 132.0 135.0 133.0 154.0 136.0 141.0 145.0 175.0 168.0 20:28:51 INFO - PROCESS | 1974 | 20:28:51 INFO - PROCESS | 1974 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:190.10 Median:196.00 stddev:28.19 (14.4%) stddev-sans-first:29.13 20:28:51 INFO - PROCESS | 1974 | Values: 172.0 161.0 210.0 213.0 164.0 165.0 172.0 229.0 233.0 182.0 20:28:51 INFO - PROCESS | 1974 | 20:28:51 INFO - PROCESS | 1974 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.00 Median:78.00 stddev:14.06 (18.0%) stddev-sans-first:14.83 20:28:51 INFO - PROCESS | 1974 | Values: 69.0 59.0 55.0 69.0 90.0 88.0 89.0 65.0 59.0 87.0 20:28:51 INFO - PROCESS | 1974 | -------- Summary: end -------- 20:28:51 INFO - PROCESS | 1974 | 20:28:51 INFO - TEST-INFO | 1974: exit 0 20:28:51 INFO - TEST-OK | kraken | took 60334ms 20:28:51 INFO - SUITE-END | took 1173s 20:28:52 INFO - Completed test suite (00:19:34) 20:28:52 INFO - javascript benchmark 20:28:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7567.573102236419, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3453.637368076352, 3457.7752446091004, 3480.7389445791564, 3481.990222835378, 3511.8595256189756, 5799.681017544033, 5882.9705851470735, 5900.527957763379, 5900.557458190631, 5918.940810591893, 5858.912116318259, 5932.228810254672, 5941.0, 5950.10748387742, 5962.34414214436, 5249.790008399659, 5272.0, 5275.340582427197, 5321.095413779667, 5374.408815030363, 5248.107821670325, 5255.369355677324, 5341.439148889369, 5358.115910874701, 5377.166539186433, 5165.560927321182, 5208.270842082112, 5222.8694282642855, 5255.343082114736, 5330.92003619946, 5232.6860388376435, 5244.763985620656, 5292.655977361459, 5310.495502927216, 5310.734463276821, 5201.791928322863, 5206.947930520684, 5215.791368345262, 5230.738463076831, 5241.820590367137, 5685.374608793029, 5754.108113242454, 5790.623609465413, 5795.159701843251, 5927.051671732502, 6308.905366419507, 6309.968450157766, 6321.1150438938585, 6332.461740752042, 6347.143135676671, 6288.811335659938, 6317.842053948642, 6322.146510221107, 6323.272823625296, 6330.8100756977365, 5822.243108395882, 6000.609960352563, 6016.067509536029, 6028.909566356464, 6031.728572214217, 6223.502119830403, 6402.007688808242, 6422.03669449577, 6445.2265728112925, 6536.509761767887, 6296.811095667137, 6302.558820882494, 6316.463100636497, 6329.746810127635, 6356.110144579762, 6301.7164227609865, 6368.4586810121655, 6368.713407896656, 6377.107204991305, 6385.936140638534, 6162.106494558264, 6236.220472440945, 6270.184875966186, 6287.402696743802, 6289.119523266746, 6299.338569450233, 6302.747890084345, 6304.5271604628915, 6305.8108256752375, 6318.778842740482, 5060.013297407044, 5079.771410286472, 5083.135866902636, 5089.440161582223, 5127.41034781005, 4409.911801763947, 4466.0844526871915, 4468.754218518013, 4473.955260447354, 4518.706284091523, 5928.288605367384, 5938.346781853993, 5954.225950626477, 5956.9873121569435, 6007.519398448114, 6210.72051757672, 6312.147860038928, 6334.651594162273, 6337.7148028337915, 6338.0, 5743.224664670216, 5901.881962360729, 5932.7330270137945, 5941.0, 5983.0, 5572.721363931787, 5595.18869763882, 5605.32736071674, 5608.887822243532, 5625.606207565431, 6479.611223326616, 6496.0, 6504.121943537752, 6516.380943810331, 6523.412892839668, 5478.178273259043, 6073.787417440369, 6221.595596286227, 6229.906551401823, 6292.716827742671, 6435.06691529735, 6456.322086180883, 6457.321981191907, 6466.612003279818, 6486.740530378732, 6319.146915166394, 6326.240851097714, 6330.3669633036325, 6347.841303967438, 6365.809025729235, 5086.847394578169, 6132.722655450899, 6254.687265636609, 6273.121762953281, 6303.873922521432, 5053.7473126344275, 5607.41122182165, 5935.881282374415, 6245.906311405423, 6301.716422761078, 6253.687315634109, 6268.529860260407, 6296.811095667137, 6299.401556852092, 6318.968405158129, 4912.6561140719805, 6000.459958603747, 6119.204503414528, 6152.0, 6226.59527130735, 6202.72087756043, 6237.313895471404, 6263.123162757309, 6267.310595834546, 6287.119803227461, 6149.508039356931, 6159.72281247336, 6162.198914141133, 6185.876282474235, 6221.129041934042], "value": 5801.729251995112, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [494.470916119752, 510.5430639577578, 520.0924386944781, 564.4665790827664, 571.2944513525797, 40184.19631607366, 43250.56749432505, 43264.0, 43281.567184328145, 43374.78312608437, 43958.78020609901, 44655.55344446563, 44753.77623111888, 44808.87977800557, 44814.55185448145, 8888.111188881125, 9094.681686140986, 9361.344705870591, 9712.854307185398, 11706.590269340575, 11656.650300490957, 11877.406129693523, 11906.880931190708, 11937.522499100025, 11946.283223006603, 11975.221610595327, 11975.98204152648, 11993.40032998351, 12013.699657508589, 12019.51921923122, 10032.899671003343, 11829.940850295736, 11862.584809531625, 11898.345590992492, 11913.22564033335, 11794.764104717857, 11919.463624136935, 12018.459169337313, 12020.45907934145, 12059.93970030153, 42247.57752422498, 42545.36181957273, 42809.57190428087, 42895.78552107251, 43007.35488967668, 4077.2660921034203, 4088.716877054181, 4152.563980782004, 4257.148570285955, 4381.255186618282, 31384.686153138406, 31588.842055789573, 31858.522122168186, 32220.0, 33485.49771753426, 2931.824090554552, 3396.1068536010753, 3410.897673069812, 3458.15275257561, 3485.75599708018, 486585.0, 487565.0, 491965.0, 492701.0, 494444.5277773624, 68543.65728171327, 68878.0, 68965.31034689689, 69010.3098969014, 69062.61874762578, 73289.26710732824, 73294.63352683203, 73478.0, 73501.0, 73592.0, 17679.823201768075, 18404.17181226848, 18420.631587368178, 18438.0, 18466.722999155023, 12750.362481876055, 13059.869401306056, 13719.314034298246, 13974.441022359195, 14000.159990400609, 19246.71129933052, 19263.614727705368, 19286.4214073578, 19289.8071019288, 19356.903215484115, 6648.601083934979, 6663.933360666331, 6692.698828552727, 6714.764983225564, 6754.12196414463, 898.3980732908967, 900.4327273817453, 906.4787747045343, 934.9766255843522, 955.1117460761419, 25411.49177016449, 32016.359672806415, 32243.51634725434, 32415.837920810245, 32717.672823271463, 894.5214310343935, 1104.779044191165, 1115.486876037016, 1122.8203487442133, 1259.6661884600587, 13186.670333241746, 15926.805489588327, 16688.83311166897, 16762.413315533897, 16778.328866845433, 522285.0, 531075.0, 532275.0, 533560.0, 534429.3278533582, 64005.0, 64451.0, 64541.67729161324, 64629.67685161638, 64773.0, 1379.7930310453312, 1457.992710036443, 1507.4723846653583, 1515.8938874278915, 1526.175865032892, 30171.547426789053, 31346.52980205253, 31356.52965205478, 31809.681903180674, 32002.51996220012, 54947.0, 55288.17067743907, 55330.0, 55354.16968745391, 55516.72241638766, 539540.0, 541280.0, 541672.0, 541888.0, 542087.0, 37319.81340093282, 38147.42778858375, 44133.89665258394, 44272.77863610661, 44585.554144459435, 49900.83577705331, 50657.0, 50802.49197507978, 51904.740476297375, 52353.0, 34307.14232144217, 34693.95918122461, 34761.65238347584, 35634.64365356417, 35898.28203435864, 34094.0, 34394.82802586071, 35246.823765881, 35429.1142721434, 35732.46401304034, 39210.803945980086, 40074.39888401618, 40695.0, 40786.1842763149, 40965.59034409618, 30054.54918176273, 34538.136546585534, 35517.0, 35569.1107722309, 35648.821755891055, 26839.865800670872, 26859.865700671373, 26923.461530769666, 27062.864685676446, 27083.1875043749], "value": 21311.459815120736, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [350.511037103241, 394.4851968181522, 400.11574420530627, 406.2200574896199, 431.03663312496565, 15341.84658153418, 17137.0, 20684.89657551712, 22205.888970555145, 22358.3292501225, 21738.673919891175, 22011.0, 22101.557968840614, 23498.647520287166, 23865.76134238657, 5044.495550444954, 6120.663363515005, 6158.969205153968, 6170.7531698732, 6199.287081985562, 5730.7421166047625, 5977.282726072877, 6087.726052327655, 6147.385261473862, 6152.876942461148, 6067.7572897084065, 6131.785387511438, 6137.969310153443, 6146.969265153691, 6152.3847615238565, 5871.559633027518, 5978.8505287367725, 5998.9700051499685, 6041.24484439445, 6126.326104128542, 5321.308229930123, 5325.281087053237, 5339.919901201485, 5342.973285133588, 5355.946440535584, 19954.401367958984, 24005.759942400702, 26715.332116697045, 27284.31789205266, 27544.586831197546, 2405.736988081254, 2735.8768855401554, 2832.2211391867195, 2866.784991125653, 2891.089306868351, 12927.353632318347, 15607.687846243012, 15988.960717553438, 16120.193990300439, 16562.83437165625, 1852.3331600623765, 1883.9802062838999, 1892.1863598652574, 1898.6012937283188, 1898.6107847891149, 128327.71672283251, 129014.0, 129385.35307323403, 130573.0, 134302.32848835888, 9606.711798646053, 11841.467133978991, 11851.111166662535, 12007.399630018639, 12012.279263244058, 15034.398624054915, 15106.244687765788, 15414.152221627917, 15540.689186216212, 15549.84450155484, 7832.451728379073, 9229.0, 9277.814443711088, 9343.34596578233, 9352.438853668802, 9366.0, 9380.0, 9403.764905877271, 9547.045295470536, 9565.139137477661, 7614.487219217644, 7743.109542402696, 7761.0, 7772.261635144652, 7788.688452461838, 1765.620391615783, 2012.5471768851892, 2033.8983050847398, 2035.816776490123, 2043.5402034542406, 805.4281460163232, 965.3869792681558, 994.751312171957, 1027.6095083868083, 1132.0, 21910.23314183996, 22353.776462235495, 23679.64480532759, 23729.05083796629, 23792.643110353358, 1136.6590022993234, 1141.560499207811, 1146.2087133732766, 1150.4362862197631, 1175.0893057880212, 12257.203281786655, 12359.69100772488, 12488.75022499563, 12501.062420318513, 12591.118621696392, 786318.0, 788395.0, 805122.0, 813813.0, 821194.0, 89420.0, 136204.0, 156860.0, 165774.0, 174626.0, 1051.5846240734868, 1080.4597701149426, 1095.906847917936, 1098.3464838421128, 1102.1072930925975, 19601.70597441011, 20345.389638310873, 21911.890440547697, 21939.451513712283, 21943.890280548494, 59927.0, 61209.693951531735, 61356.0, 62241.68879155575, 62857.0, 93081.0, 97152.0, 97414.0, 98114.0, 98507.5074624651, 8690.565471726515, 8780.78048048804, 8784.868226976729, 8912.509811960404, 8989.910100898907, 9425.57584908667, 13645.522406715718, 14222.359993800097, 14281.500147494788, 14301.785473218119, 11788.882111178778, 11874.228175168586, 11959.342236177094, 12139.453724582592, 12145.635630931087, 9144.817103658022, 9757.902420975699, 10546.103581195377, 11140.944295278472, 11398.94300528492, 11758.470868810753, 11916.761664766484, 11985.101117416054, 12236.816447753114, 12303.138780285652, 8591.0, 8758.868616970623, 8870.73387798367, 8917.687880924137, 9144.497052662167, 8668.30653547702, 11561.421928903354, 11589.40890736511, 11750.471228794553, 12043.458044387848], "value": 10537.696801692055, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1476.6013176442355, 1507.8841657173696, 1511.9848801511976, 1518.8025556677633, 1525.3059857764717, 7802.492837965542, 8026.759197224078, 8307.501549907001, 8718.825623487526, 8744.650213991441, 8247.010358756943, 8534.530600816952, 8541.701040463586, 8637.611307491163, 8685.131486851311, 2376.1683410806254, 2421.0436877433403, 2424.35754525051, 2424.5635785558593, 2436.232586735173, 2411.0958390603523, 2425.9393515162087, 2431.6474111253856, 2454.7422520635346, 2456.459578892641, 2397.3886658902043, 2402.8438151520095, 2420.225527831095, 2426.648136020276, 2434.1602147259255, 2370.561446132471, 2374.5725769361507, 2394.1261439574428, 2395.018042602533, 2407.494426170515, 2401.375642333006, 2405.951880962371, 2417.903283868643, 2433.6714543536573, 2442.4504486490573, 8483.872741908875, 8549.572521373906, 8604.5697715114, 8640.827183456358, 8667.4366166199, 5244.344456942882, 5394.865128371783, 5409.486098820606, 5411.323584551931, 5418.133098704227, 3042.6957304269617, 3628.5827129880136, 3647.288778688157, 3665.0, 3682.0610744260043, 1241.7392347607013, 1248.5130798988312, 1299.5516546791437, 1307.7711400504968, 1327.502186679995, 10858.23992320538, 11164.385958772264, 11186.608468703556, 11190.608328708537, 11200.383978881158, 4000.579939106352, 4697.436307643036, 4823.348847905558, 4875.756212189376, 4880.243562247857, 8812.77968050791, 8825.249853762374, 8926.464412135292, 8946.776330591658, 8978.326625503114, 4195.622393984556, 4922.0, 5287.365516138088, 5335.62650614461, 5336.039512887717, 4447.421835161408, 4496.3705081288645, 4936.234883593049, 4987.326710893983, 5014.122528557488, 3794.2601192767697, 3857.324968130622, 3875.6318149775725, 3891.1439483313625, 3921.5294164700417, 1183.083110589283, 1196.2762528670203, 1196.347990345264, 1198.4127777389017, 1200.5377929497206, 732.1507051819864, 744.307793751816, 771.4676872957641, 773.2074623510833, 796.4464697035505, 4874.2444921037295, 5457.0, 5507.5593952483705, 5665.858353541194, 5673.262475878109, 573.7217449536971, 579.9217105690763, 586.5161142309962, 595.615827791081, 607.0590584593862, 2897.246715853893, 3045.969540304613, 3150.070729134911, 3224.806821476022, 3787.693245830184, 9863.950680246553, 10344.120749736358, 10358.274920755626, 10373.533191006423, 10375.221858360654, 8605.35459840502, 10296.22778291631, 10311.84532232017, 10333.431661258552, 10350.585976561004, 6579.671016449254, 7362.932374805623, 7366.631668416558, 8041.758747237593, 8093.1502192270145, 5519.199716041232, 5535.501804837584, 5560.582956278215, 5677.261955945701, 5727.971360143172, 9306.581203845708, 9326.720198393788, 9452.95273523628, 9460.668876589287, 9486.762830929281, 9483.952580237055, 9507.66723164686, 9639.18066964316, 9694.321397502106, 9787.119159275699, 6365.204349456318, 6398.464368551608, 6518.804435866931, 6686.3313668632745, 6801.625910574768, 8087.9595602023965, 8088.191180881865, 8093.19068093186, 8251.628676709443, 8267.710630127916, 7004.15950085993, 7142.785716428515, 7144.571325720265, 7205.099362579677, 7243.601601911945, 6829.453643708392, 6971.721131154697, 6980.508127441325, 6999.30006999296, 7091.60996145217, 6381.266154392119, 6396.424321810873, 6465.515086368447, 6467.482601391972, 6468.902966455413, 5052.46949070357, 5057.848264552069, 5062.569681577107, 5123.820666276662, 5152.458991805956, 5083.796648134181, 5189.0, 5203.739813009259, 5204.739763011758, 5216.843494695166], "value": 4386.490270612534, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [360.79254428703484, 366.325960233171, 373.68236998551225, 378.59679441394894, 381.7156218617131, 5400.702961337125, 5530.640508366953, 5596.552275817935, 5597.684544132126, 5652.3499797523245, 4991.500849915006, 5182.507661772135, 5501.587380946435, 5582.441755824416, 5623.156526521024, 3962.48487696599, 4279.764612946286, 4376.0, 4391.429114215148, 4422.7788610569505, 3096.876124954999, 3228.902173261091, 4003.559608443069, 4369.344598310247, 4447.0, 4109.0, 4162.4796900387455, 4175.331946888498, 4194.391813187086, 4215.367694845767, 3313.519539666747, 3697.963020369789, 3973.443717879499, 4214.578542145761, 4235.0, 3629.94555081674, 3667.3765459871884, 3669.5779985301497, 3673.8530458781615, 3705.2404257127223, 5174.2238664200295, 5442.619016668835, 5457.672539647594, 5510.448955104497, 5543.695096769676, 4519.887002824956, 4826.565609095198, 4908.43552991407, 4918.606511479073, 4923.236898280772, 4671.976640116812, 5018.222175562793, 5905.29136503618, 5909.261342332208, 6000.579959402844, 3272.918177045569, 3492.772969756983, 3785.621437856192, 4033.9193216135513, 4043.6158564936286, 5296.77535435122, 6242.250929888442, 6303.621782693007, 6316.873662526724, 6352.9188343653295, 3439.6560343965402, 4092.0588264699286, 4101.733387329814, 4107.158032603309, 4112.547619761824, 3498.3877821381157, 3511.666391692785, 4021.4973128358956, 4089.1003979124544, 4137.338025915839, 4107.8973025674, 4238.449001629769, 4469.776511174493, 4825.638077144158, 4890.266460030953, 6003.189569408074, 6008.0, 6021.397860214031, 6080.179175811298, 6086.382855372144, 1153.8326942593444, 1191.9940400298117, 1240.5409998300686, 1241.9796999204161, 1280.5582074184579, 883.439016224702, 897.533282692996, 906.6962567539816, 914.8261830252363, 916.9633214671338, 5277.788888444496, 5303.549198318186, 5328.333958255218, 5337.5463085637375, 5364.436734142937, 5434.994526012701, 5473.890522189534, 5542.201188399832, 5866.943950088939, 5876.7658791653375, 4451.354553589711, 4696.862927368039, 4760.880977975509, 4854.616434316274, 5182.993863559127, 2990.8803647853842, 3063.8161710297454, 3079.3687294104657, 3123.6876312369036, 3130.514770210621, 5341.118715411992, 5342.767625586542, 5740.732410697835, 5767.568296863359, 6212.074256980351, 5094.0, 5257.790708137073, 5576.888462230732, 6051.8198951204095, 6289.213848268966, 5530.2810634618045, 6268.780592679234, 6360.678352401433, 6363.7908797328355, 6404.807855764335, 4526.284229462296, 5666.951613951433, 6141.1402403664415, 6187.443130118311, 6209.96895015522, 5755.510781583613, 6003.129546215861, 6049.78825741097, 6087.62582592798, 6133.172021777182, 5524.674078221117, 5863.794767183128, 6337.7148028337915, 6373.458256048102, 6378.628594852129, 3200.7279381251924, 3271.574695289597, 3597.9460308094876, 4132.504099508078, 4172.4993000840095, 5930.347661757117, 6161.72272247741, 6197.349278325711, 6203.658798766111, 6233.532485063548, 4858.975705121452, 4949.97525012387, 5599.888002239851, 5928.0, 5968.940310597012, 5875.649740103992, 5921.299188007495, 5927.8221653350465, 5939.673317967553, 5968.373320801254, 4766.189747742961, 5342.171963345688, 5563.942850858324, 5711.942880571141, 5734.301375612623, 5499.552699049045, 5923.08192230205, 5952.916479232234, 5973.253343332083, 5981.962175531586, 4873.659743570546, 4894.33926419943, 5667.574931880208, 5851.502622277154, 5869.09479807481], "value": 4239.242838717525, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [730.9853802923946, 739.8446326271483, 784.2432053068787, 871.6208449324545, 877.4559772940776, 15246.92376538117, 16342.10118443485, 16387.180640967934, 16400.26198821053, 16438.0, 13781.310934453288, 13959.232242226673, 14274.286285685726, 14985.850141498582, 15192.848071519282, 885.3050355470973, 910.6539514984297, 918.1415376622846, 919.5999740113039, 920.5535315372039, 736.7679181057977, 754.8075240813612, 909.6770646420524, 910.1490106750209, 911.8313112074287, 934.728928610702, 956.0009789769707, 962.8170647576973, 966.415318732164, 968.6609686609665, 949.0651708067548, 951.1202138022371, 953.1993125774349, 955.2262667239523, 961.20700422152, 700.6951975890504, 705.4814711187273, 712.3588770106893, 715.7995761186876, 717.0069453806516, 13918.652033699138, 14451.48259432755, 15645.374185032584, 16021.198940052951, 16068.919655401649, 14645.560633181021, 14827.555173344817, 14906.329215185342, 14932.402703891832, 14966.401343946229, 11908.16642835002, 12064.215825971283, 12124.39378031095, 12127.272363658209, 12136.696582585506, 3713.0, 3833.5208098987628, 3834.616538346171, 3861.1119442528375, 3866.5940076291868, 113719.99999999917, 115171.42414287875, 117739.0, 117796.0, 119654.4017279908, 11185.272957257752, 11308.0, 11366.033887119522, 11583.536658533565, 11636.650900473, 23307.650385244237, 24029.27912162638, 24048.0, 24214.515709685707, 24223.878880605484, 7836.843263134705, 8032.437729359006, 8068.354531637572, 8102.39232057598, 8294.709685160991, 4105.363668631367, 4147.108371700099, 4216.409702641633, 4283.892902677458, 4293.763842988603, 3750.099976005739, 3769.359208934433, 3835.079580900564, 3845.423186522044, 3845.442410850411, 693.6011793218919, 696.216756149332, 704.4294121761287, 710.6944014073895, 713.4862898712919, 656.7110471392667, 675.9256481786999, 685.0, 691.9169699636076, 699.328525242477, 10585.364878107339, 10688.0, 10821.296615719953, 10842.674719758419, 11096.389698566656, 611.7919907231562, 688.6556721639181, 717.6734585763465, 734.7281505842873, 743.3792783026125, 4492.573205545468, 4527.637788976874, 5267.446918073624, 5274.947250527523, 5275.0, 109025.45487272512, 109445.45277273563, 110096.0, 110489.44755276172, 113153.0, 74885.62557187179, 88023.35929922112, 88089.11910880938, 88434.0, 88597.11402886017, 76122.6193869027, 76637.6168119167, 77364.0, 78266.6086669563, 80537.0, 8892.822143556963, 10659.680209593414, 11092.833607496055, 11359.602413915472, 11406.771864562828, 35867.0, 46137.76931115323, 47773.76113119412, 48146.518534814204, 49033.0, 61587.692061539405, 66116.66941665261, 66176.66911665411, 67694.0, 68431.0, 13145.73708525805, 13400.128991615515, 13591.0, 13608.795868061788, 14069.648258793612, 11970.640880773592, 14962.551123466314, 15048.021878577858, 15124.0, 15506.84493155054, 13932.93033534826, 14159.36282867294, 14324.856751432353, 14353.64115897111, 14453.132812031312, 13616.65958351049, 13781.173129612256, 14264.358103885557, 14289.35697893618, 14307.856921431068, 13300.334983250605, 13610.115342502706, 14071.507497237548, 14083.929580352033, 14383.42466301378, 10063.949680251844, 10303.124234440156, 10359.0, 10447.94776026115, 10568.947155264483, 9329.0, 10219.948900255451, 10853.945730271298, 11377.772444551229, 11564.132690048113], "value": 7751.1893009614205, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1559.8000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 118.0, 137.0, 178.0, 157.0, 120.0, 119.0, 118.0, 120.0, 123.0], "value": 129.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 111.0, 109.0, 145.0, 110.0, 114.0, 111.0, 112.0, 129.0, 111.0], "value": 119.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 200.0, 195.0, 197.0, 198.0, 198.0, 197.0, 193.0, 200.0, 197.0], "value": 197.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 58.0, 60.0, 86.0, 58.0, 67.0, 69.0, 84.0, 72.0, 59.0], "value": 69.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 69.0, 84.0, 71.0, 71.0, 71.0, 84.0, 55.0, 73.0, 68.0], "value": 73.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 132.0, 91.0, 89.0, 96.0, 126.0, 95.0, 81.0, 136.0, 119.0], "value": 110.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 97.0, 96.0, 109.0, 152.0, 153.0, 97.0, 96.0, 96.0], "value": 108.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 98.0, 92.0, 116.0, 108.0, 76.0, 141.0, 144.0, 130.0, 98.0], "value": 109.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 56.0, 59.0, 60.0, 60.0, 60.0, 62.0, 74.0, 56.0, 61.0], "value": 60.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 73.0, 86.0, 81.0, 78.0, 78.0, 109.0, 107.0, 107.0, 72.0], "value": 85.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 106.0, 85.0, 85.0, 86.0, 81.0, 84.0, 83.0, 85.0, 88.0], "value": 86.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 132.0, 135.0, 133.0, 154.0, 136.0, 141.0, 145.0, 175.0, 168.0], "value": 145.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 161.0, 210.0, 213.0, 164.0, 165.0, 172.0, 229.0, 233.0, 182.0], "value": 190.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 59.0, 55.0, 69.0, 90.0, 88.0, 89.0, 65.0, 59.0, 87.0], "value": 73.0, "unit": "score"}]}]} 20:28:52 INFO - Return code: 0 20:28:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:28:52 ERROR - # TBPL SUCCESS # 20:28:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:28:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:28:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:28:52 INFO - 2017-03-30 20:28:52.378 screenresolution[1979:21707] starting screenresolution argv=screenresolution get 20:28:52 INFO - 2017-03-30 20:28:52.381 screenresolution[1979:21707] Display 0: 1600x1200x32@60 20:28:52 INFO - 2017-03-30 20:28:52.387 screenresolution[1980:21710] starting screenresolution argv=screenresolution list 20:28:52 INFO - Available Modes on Display 0 20:28:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:28:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:28:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:28:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:28:52 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:28:52 INFO - Intel Iris: 20:28:52 INFO - Chipset Model: Intel Iris 20:28:52 INFO - Type: GPU 20:28:52 INFO - Bus: Built-In 20:28:52 INFO - VRAM (Dynamic, Max): 1536 MB 20:28:52 INFO - Vendor: Intel (0x8086) 20:28:52 INFO - Device ID: 0x0a2e 20:28:52 INFO - Revision ID: 0x0009 20:28:52 INFO - Displays: 20:28:52 INFO - Display: 20:28:52 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:28:52 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:28:52 INFO - Display Serial Number: 1600x1200 60 20:28:52 INFO - Main Display: Yes 20:28:52 INFO - Mirror: Off 20:28:52 INFO - Online: Yes 20:28:52 INFO - Rotation: Supported 20:28:52 INFO - Return code: 0 20:28:52 INFO - Running post-action listener: _resource_record_post_action 20:28:52 INFO - [mozharness: 2017-03-31 03:28:52.626165Z] Finished run-tests step (success) 20:28:52 INFO - Running post-run listener: _resource_record_post_run 20:28:52 INFO - Total resource usage - Wall time: 1190s; CPU: 54.0%; Read bytes: 1069056; Write bytes: 423671296; Read time: 8; Write time: 1425 20:28:52 INFO - TinderboxPrint: CPU usage
54.2% 20:28:52 INFO - TinderboxPrint: I/O read bytes / time
1,069,056 / 8 20:28:52 INFO - TinderboxPrint: I/O write bytes / time
423,671,296 / 1,425 20:28:52 INFO - TinderboxPrint: CPU idle
2,180.4 (45.8%) 20:28:52 INFO - TinderboxPrint: CPU system
961.2 (20.2%) 20:28:52 INFO - TinderboxPrint: CPU user
1,620.0 (34.0%) 20:28:52 INFO - TinderboxPrint: Swap in / out
122,146,816 / 0 20:28:52 INFO - install - Wall time: 16s; CPU: 55.0%; Read bytes: 113885696; Write bytes: 114456576; Read time: 10860; Write time: 257 20:28:52 INFO - run-tests - Wall time: 1175s; CPU: 54.0%; Read bytes: 1069056; Write bytes: 299372032; Read time: 8; Write time: 1133 20:28:53 INFO - Running post-run listener: _upload_blobber_files 20:28:53 INFO - Blob upload gear active. 20:28:53 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:28:53 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 20:28:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 20:28:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 20:28:53 INFO - (blobuploader) - INFO - Open directory for files ... 20:28:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 20:28:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:28:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:28:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 20:28:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:28:54 INFO - (blobuploader) - INFO - Done attempting. 20:28:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 20:28:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:28:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:28:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 20:28:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:28:55 INFO - (blobuploader) - INFO - Done attempting. 20:28:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 20:28:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:28:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:28:56 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 20:28:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:28:56 INFO - (blobuploader) - INFO - Done attempting. 20:28:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:28:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:28:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:28:58 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:28:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:28:58 INFO - (blobuploader) - INFO - Done attempting. 20:28:58 INFO - (blobuploader) - INFO - Iteration through files over. 20:28:58 INFO - Return code: 0 20:28:58 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:28:58 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:28:58 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e52131ec65d86ace9462782db54402e1c589b96e3f721f3ca965cfb9fac3b09860ecf01fa2dd44bc8f4dcdab4138f036238b1c9e72d440ac3c88356b06cceba4", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d87d3754492f74bc22ed31aa38bbe79ced7214539d8d86ed52a14eb6ab3a5f492503fca1ab8bb4c27f5b515a8fc176ce2b55548e3627bb74b2fbf74a76a1d2d2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/aaf6907bbca5c1475be5ef1cfe1514ed163be80c0b635c54553ab1b64f38b0c6bfd10a9e06bd0aaadc95ebb8777fbfc672ac18a78d3f3753af37a4bf517a52f8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c73aa660a9ee965e3d8560227d71849ecae3c403c01358245bc4964fdbd3c0064d56ff4a6ecc39d39acfe6c24f4ce6439524cedfa35ef53a6fe41ab5c7129092"} 20:28:58 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:28:58 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:28:58 INFO - Contents: 20:28:58 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e52131ec65d86ace9462782db54402e1c589b96e3f721f3ca965cfb9fac3b09860ecf01fa2dd44bc8f4dcdab4138f036238b1c9e72d440ac3c88356b06cceba4", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d87d3754492f74bc22ed31aa38bbe79ced7214539d8d86ed52a14eb6ab3a5f492503fca1ab8bb4c27f5b515a8fc176ce2b55548e3627bb74b2fbf74a76a1d2d2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/aaf6907bbca5c1475be5ef1cfe1514ed163be80c0b635c54553ab1b64f38b0c6bfd10a9e06bd0aaadc95ebb8777fbfc672ac18a78d3f3753af37a4bf517a52f8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c73aa660a9ee965e3d8560227d71849ecae3c403c01358245bc4964fdbd3c0064d56ff4a6ecc39d39acfe6c24f4ce6439524cedfa35ef53a6fe41ab5c7129092"} 20:28:58 INFO - Running post-run listener: copy_logs_to_upload_dir 20:28:58 INFO - Copying logs to upload dir... 20:28:58 INFO - mkdir: /builds/slave/test/build/upload/logs 20:28:58 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1252.903301 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2017-03-30 20:28:58.075081) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-30 20:28:58.078818) ========= 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.PMupApxgVF/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.DwotjDSIbz/Listeners TMPDIR=/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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/Mozilla-Beta/sha512/e52131ec65d86ace9462782db54402e1c589b96e3f721f3ca965cfb9fac3b09860ecf01fa2dd44bc8f4dcdab4138f036238b1c9e72d440ac3c88356b06cceba4", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d87d3754492f74bc22ed31aa38bbe79ced7214539d8d86ed52a14eb6ab3a5f492503fca1ab8bb4c27f5b515a8fc176ce2b55548e3627bb74b2fbf74a76a1d2d2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/aaf6907bbca5c1475be5ef1cfe1514ed163be80c0b635c54553ab1b64f38b0c6bfd10a9e06bd0aaadc95ebb8777fbfc672ac18a78d3f3753af37a4bf517a52f8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c73aa660a9ee965e3d8560227d71849ecae3c403c01358245bc4964fdbd3c0064d56ff4a6ecc39d39acfe6c24f4ce6439524cedfa35ef53a6fe41ab5c7129092"} build_url:https://queue.taskcluster.net/v1/task/GiSEB9oaTUiJNkBULDebLQ/artifacts/public/build/firefox-53.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010320 build_url: 'https://queue.taskcluster.net/v1/task/GiSEB9oaTUiJNkBULDebLQ/artifacts/public/build/firefox-53.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e52131ec65d86ace9462782db54402e1c589b96e3f721f3ca965cfb9fac3b09860ecf01fa2dd44bc8f4dcdab4138f036238b1c9e72d440ac3c88356b06cceba4", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d87d3754492f74bc22ed31aa38bbe79ced7214539d8d86ed52a14eb6ab3a5f492503fca1ab8bb4c27f5b515a8fc176ce2b55548e3627bb74b2fbf74a76a1d2d2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/aaf6907bbca5c1475be5ef1cfe1514ed163be80c0b635c54553ab1b64f38b0c6bfd10a9e06bd0aaadc95ebb8777fbfc672ac18a78d3f3753af37a4bf517a52f8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c73aa660a9ee965e3d8560227d71849ecae3c403c01358245bc4964fdbd3c0064d56ff4a6ecc39d39acfe6c24f4ce6439524cedfa35ef53a6fe41ab5c7129092"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-30 20:28:58.113175) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-30 20:28:58.113608) ========= 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.PMupApxgVF/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.DwotjDSIbz/Listeners TMPDIR=/var/folders/dv/frhyy9rn3p3fbk6y0hr9dblm00000w/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.005157 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-30 20:28:58.163996) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-30 20:28:58.164369) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-30 20:28:58.164762) ========= ========= Total master_lag: 0.20 =========