builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0368 starttime: 1485515729.41 results: success (0) buildid: 20170127014221 builduid: cf1d1cafc1ac4275a35ab3e657034fec revision: dad46f4125887d88734759a91595738105f85b37 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-27 03:15:29.414712) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-27 03:15:29.415269) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-27 03:15:29.415596) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-27 03:15:29.447477) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-27 03:15:29.447866) ========= 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.84tvuSAr4J/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.RGMwrtrArF/Listeners TMPDIR=/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/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-01-27 03:15:29-- 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% 176M=0s 2017-01-27 03:15:30 (176 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.649653 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-27 03:15:30.121707) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-27 03:15:30.125080) ========= 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.84tvuSAr4J/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.RGMwrtrArF/Listeners TMPDIR=/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/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.025385 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-27 03:15:30.193929) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-27 03:15:30.194316) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev dad46f4125887d88734759a91595738105f85b37 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev dad46f4125887d88734759a91595738105f85b37 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.84tvuSAr4J/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.RGMwrtrArF/Listeners TMPDIR=/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/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-01-27 03:15:30,301 truncating revision to first 12 chars 2017-01-27 03:15:30,301 Setting DEBUG logging. 2017-01-27 03:15:30,301 attempt 1/10 2017-01-27 03:15:30,301 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/dad46f412588?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-27 03:15:30,625 unpacking tar archive at: mozilla-central-dad46f412588/testing/mozharness/ program finished with exit code 0 elapsedTime=0.619785 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-27 03:15:30.840519) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-27 03:15:30.840832) ========= script_repo_revision: dad46f4125887d88734759a91595738105f85b37 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-27 03:15:30.844253) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-27 03:15:30.844537) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-27 03:15:30.872226) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2017-01-27 03:15:30.872564) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.84tvuSAr4J/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.RGMwrtrArF/Listeners TMPDIR=/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/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 03:15:31 INFO - MultiFileLogger online at 20170127 03:15:31 in /builds/slave/test 03:15:31 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 03:15:31 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:15:31 INFO - {'append_to_log': False, 03:15:31 INFO - 'base_work_dir': '/builds/slave/test', 03:15:31 INFO - 'blob_upload_branch': 'Firefox', 03:15:31 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:15:31 INFO - 'branch': 'Firefox', 03:15:31 INFO - 'buildbot_json_path': 'buildprops.json', 03:15:31 INFO - 'config_files': ('talos/mac_config.py',), 03:15:31 INFO - 'default_actions': ('clobber', 03:15:31 INFO - 'read-buildbot-config', 03:15:31 INFO - 'download-and-extract', 03:15:31 INFO - 'populate-webroot', 03:15:31 INFO - 'create-virtualenv', 03:15:31 INFO - 'install', 03:15:31 INFO - 'run-tests'), 03:15:31 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:15:31 INFO - 'download_minidump_stackwalk': True, 03:15:31 INFO - 'download_symbols': 'ondemand', 03:15:31 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:15:31 INFO - 'tooltool.py': '/tools/tooltool.py', 03:15:31 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:15:31 INFO - '/tools/misc-python/virtualenv.py')}, 03:15:31 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:15:31 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:15:31 INFO - 'installer_path': 'installer.exe', 03:15:31 INFO - 'log_level': 'info', 03:15:31 INFO - 'log_name': 'talos', 03:15:31 INFO - 'log_to_console': True, 03:15:31 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:15:31 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:15:31 INFO - 'opt_config_files': (), 03:15:31 INFO - 'pip_index': False, 03:15:31 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:15:31 INFO - 'cmd': ('bash', 03:15:31 INFO - '-c', 03:15:31 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:15:31 INFO - 'enabled': True, 03:15:31 INFO - 'halt_on_failure': False, 03:15:31 INFO - 'name': 'check_screen_resolution'},), 03:15:31 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:15:31 INFO - 'cmd': ('bash', 03:15:31 INFO - '-c', 03:15:31 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:15:31 INFO - 'enabled': True, 03:15:31 INFO - 'halt_on_failure': False, 03:15:31 INFO - 'name': 'check_screen_resolution'},), 03:15:31 INFO - 'run_cmd_checks_enabled': True, 03:15:31 INFO - 'sps_profile': False, 03:15:31 INFO - 'sps_profile_interval': 0, 03:15:31 INFO - 'suite': 'dromaeojs-e10s', 03:15:31 INFO - 'system_bits': '32', 03:15:31 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:15:31 INFO - 'title': 't-yosemite-r7-0368', 03:15:31 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:15:31 INFO - 'use_talos_json': True, 03:15:31 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:15:31 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:15:31 INFO - 'work_dir': 'build'} 03:15:31 INFO - [mozharness: 2017-01-27 11:15:31.082743Z] Running clobber step. 03:15:31 INFO - Running pre-action listener: _resource_record_pre_action 03:15:31 INFO - Running main action method: clobber 03:15:31 INFO - rmtree: /builds/slave/test/build 03:15:31 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:15:33 INFO - Running post-action listener: _resource_record_post_action 03:15:33 INFO - [mozharness: 2017-01-27 11:15:33.687542Z] Finished clobber step (success) 03:15:33 INFO - [mozharness: 2017-01-27 11:15:33.687826Z] Running read-buildbot-config step. 03:15:33 INFO - Running pre-action listener: _resource_record_pre_action 03:15:33 INFO - Running main action method: read_buildbot_config 03:15:33 INFO - Using buildbot properties: 03:15:33 INFO - { 03:15:33 INFO - "project": "", 03:15:33 INFO - "product": "firefox", 03:15:33 INFO - "script_repo_revision": "production", 03:15:33 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 03:15:33 INFO - "repository": "", 03:15:33 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 03:15:33 INFO - "buildid": "20170127014221", 03:15:33 INFO - "pgo_build": "False", 03:15:33 INFO - "basedir": "/builds/slave/test", 03:15:33 INFO - "buildnumber": 26, 03:15:33 INFO - "slavename": "t-yosemite-r7-0368", 03:15:33 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 03:15:33 INFO - "platform": "yosemite_r7", 03:15:33 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 03:15:33 INFO - "branch": "mozilla-central", 03:15:33 INFO - "repo_path": "mozilla-central", 03:15:33 INFO - "revision": "dad46f4125887d88734759a91595738105f85b37", 03:15:33 INFO - "stage_platform": "macosx64", 03:15:33 INFO - "builduid": "cf1d1cafc1ac4275a35ab3e657034fec", 03:15:33 INFO - "slavebuilddir": "test" 03:15:33 INFO - } 03:15:33 INFO - Found installer url https://queue.taskcluster.net/v1/task/ZIw_youVQ5m5pP6RAkUf1A/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg. 03:15:33 INFO - Running post-action listener: _resource_record_post_action 03:15:33 INFO - [mozharness: 2017-01-27 11:15:33.693542Z] Finished read-buildbot-config step (success) 03:15:33 INFO - [mozharness: 2017-01-27 11:15:33.693771Z] Running download-and-extract step. 03:15:33 INFO - Running pre-action listener: _resource_record_pre_action 03:15:33 INFO - Running main action method: download_and_extract 03:15:33 INFO - mkdir: /builds/slave/test/build/tests 03:15:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:15:33 INFO - trying https://queue.taskcluster.net/v1/task/ZIw_youVQ5m5pP6RAkUf1A/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json 03:15:33 INFO - Downloading https://queue.taskcluster.net/v1/task/ZIw_youVQ5m5pP6RAkUf1A/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 03:15:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZIw_youVQ5m5pP6RAkUf1A/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json'}, attempt #1 03:15:35 INFO - Downloaded 1199 bytes. 03:15:35 INFO - Reading from file /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 03:15:35 INFO - Using the following test package requirements: 03:15:35 INFO - {u'common': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 03:15:35 INFO - u'cppunittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 03:15:35 INFO - u'firefox-54.0a1.en-US.mac.cppunittest.tests.zip'], 03:15:35 INFO - u'gtest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 03:15:35 INFO - u'firefox-54.0a1.en-US.mac.gtest.tests.zip'], 03:15:35 INFO - u'jittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 03:15:35 INFO - u'jsshell-mac.zip'], 03:15:35 INFO - u'mochitest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 03:15:35 INFO - u'firefox-54.0a1.en-US.mac.mochitest.tests.zip'], 03:15:35 INFO - u'mozbase': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 03:15:35 INFO - u'reftest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 03:15:35 INFO - u'firefox-54.0a1.en-US.mac.reftest.tests.zip'], 03:15:35 INFO - u'talos': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 03:15:35 INFO - u'firefox-54.0a1.en-US.mac.talos.tests.zip'], 03:15:35 INFO - u'web-platform': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 03:15:35 INFO - u'firefox-54.0a1.en-US.mac.web-platform.tests.zip'], 03:15:35 INFO - u'xpcshell': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 03:15:35 INFO - u'firefox-54.0a1.en-US.mac.xpcshell.tests.zip']} 03:15:35 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip'] for test suite category: common 03:15:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZIw_youVQ5m5pP6RAkUf1A/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 03:15:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZIw_youVQ5m5pP6RAkUf1A/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 03:15:35 INFO - Fetch https://queue.taskcluster.net/v1/task/ZIw_youVQ5m5pP6RAkUf1A/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 03:15:36 INFO - Expected file size: 16363230 03:15:36 INFO - Obtained file size: 16363230 03:15:42 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip', u'firefox-54.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 03:15:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZIw_youVQ5m5pP6RAkUf1A/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 03:15:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZIw_youVQ5m5pP6RAkUf1A/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 03:15:42 INFO - Fetch https://queue.taskcluster.net/v1/task/ZIw_youVQ5m5pP6RAkUf1A/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 03:15:43 INFO - Expected file size: 16363230 03:15:43 INFO - Obtained file size: 16363230 03:15:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZIw_youVQ5m5pP6RAkUf1A/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip 03:15:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZIw_youVQ5m5pP6RAkUf1A/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip'}, attempt #1 03:15:50 INFO - Fetch https://queue.taskcluster.net/v1/task/ZIw_youVQ5m5pP6RAkUf1A/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip into memory 03:15:51 INFO - Expected file size: 14403438 03:15:51 INFO - Obtained file size: 14403438 03:15:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:15:51 INFO - trying https://queue.taskcluster.net/v1/task/ZIw_youVQ5m5pP6RAkUf1A/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 03:15:51 INFO - Downloading https://queue.taskcluster.net/v1/task/ZIw_youVQ5m5pP6RAkUf1A/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg 03:15:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZIw_youVQ5m5pP6RAkUf1A/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg'}, attempt #1 03:15:53 INFO - Downloaded 70123136 bytes. 03:15:53 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ZIw_youVQ5m5pP6RAkUf1A/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 03:15:53 INFO - mkdir: /builds/slave/test/properties 03:15:53 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:15:53 INFO - Writing to file /builds/slave/test/properties/build_url 03:15:53 INFO - Contents: 03:15:53 INFO - build_url:https://queue.taskcluster.net/v1/task/ZIw_youVQ5m5pP6RAkUf1A/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 03:15:54 INFO - Running post-action listener: _resource_record_post_action 03:15:54 INFO - Running post-action listener: set_extra_try_arguments 03:15:54 INFO - [mozharness: 2017-01-27 11:15:54.448454Z] Finished download-and-extract step (success) 03:15:54 INFO - [mozharness: 2017-01-27 11:15:54.448612Z] Running populate-webroot step. 03:15:54 INFO - Running pre-action listener: _resource_record_pre_action 03:15:54 INFO - Running main action method: populate_webroot 03:15:54 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:15:54 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:15:54 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:15:54 INFO - '--tppagecycles', 03:15:54 INFO - '7'], 03:15:54 INFO - 'tests': ['tsvgm']}}, 03:15:54 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:15:54 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:15:54 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:15:54 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:15:54 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:15:54 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:15:54 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 03:15:54 INFO - 'talos_options': ['--disable-e10s'], 03:15:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:15:54 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 03:15:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:15:54 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 03:15:54 INFO - 'talos_options': ['--disable-e10s'], 03:15:54 INFO - 'tests': ['damp', 'tps']}, 03:15:54 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 03:15:54 INFO - 'tests': ['damp', 'tps']}, 03:15:54 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:15:54 INFO - 'tests': ['dromaeo_dom']}, 03:15:54 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:15:54 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:15:54 INFO - 'tests': ['basic_compositor_video']}, 03:15:54 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:15:54 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:15:54 INFO - 'tests': ['a11yr', 03:15:54 INFO - 'ts_paint', 03:15:54 INFO - 'tpaint', 03:15:54 INFO - 'sessionrestore', 03:15:54 INFO - 'sessionrestore_no_auto_restore', 03:15:54 INFO - 'tabpaint']}, 03:15:54 INFO - 'other-e10s': {'tests': ['a11yr', 03:15:54 INFO - 'ts_paint', 03:15:54 INFO - 'tpaint', 03:15:54 INFO - 'sessionrestore', 03:15:54 INFO - 'sessionrestore_no_auto_restore', 03:15:54 INFO - 'tabpaint']}, 03:15:54 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:15:54 INFO - 'ts_paint', 03:15:54 INFO - 'tpaint', 03:15:54 INFO - 'sessionrestore', 03:15:54 INFO - 'sessionrestore_no_auto_restore', 03:15:54 INFO - 'tabpaint']}, 03:15:54 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:15:54 INFO - 'ts_paint', 03:15:54 INFO - 'tpaint', 03:15:54 INFO - 'sessionrestore', 03:15:54 INFO - 'sessionrestore_no_auto_restore', 03:15:54 INFO - 'tabpaint']}, 03:15:54 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:15:54 INFO - 'tests': ['a11yr', 03:15:54 INFO - 'ts_paint', 03:15:54 INFO - 'tpaint', 03:15:54 INFO - 'sessionrestore', 03:15:54 INFO - 'sessionrestore_no_auto_restore', 03:15:54 INFO - 'tabpaint']}, 03:15:54 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:15:54 INFO - 'tests': ['a11yr', 03:15:54 INFO - 'ts_paint', 03:15:54 INFO - 'tpaint', 03:15:54 INFO - 'sessionrestore', 03:15:54 INFO - 'sessionrestore_no_auto_restore', 03:15:54 INFO - 'tabpaint']}, 03:15:54 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:15:54 INFO - 'tests': ['tsvgx', 03:15:54 INFO - 'tsvgr_opacity', 03:15:54 INFO - 'tart', 03:15:54 INFO - 'tscrollx', 03:15:54 INFO - 'cart', 03:15:54 INFO - 'tsvg_static']}, 03:15:54 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:15:54 INFO - 'tsvgr_opacity', 03:15:54 INFO - 'tart', 03:15:54 INFO - 'tscrollx', 03:15:54 INFO - 'cart', 03:15:54 INFO - 'tsvg_static']}, 03:15:54 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 03:15:54 INFO - 'talos_options': ['--disable-e10s'], 03:15:54 INFO - 'tests': ['tp5o']}, 03:15:54 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 03:15:54 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 03:15:54 INFO - 'talos_options': ['--disable-e10s', 03:15:54 INFO - '--xperf_path', 03:15:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:15:54 INFO - 'tests': ['tp5n']}, 03:15:54 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 03:15:54 INFO - 'talos_options': ['--xperf_path', 03:15:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:15:54 INFO - 'tests': ['tp5n']}}, 03:15:54 INFO - 'talos.zip': {'path': '', 03:15:54 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:15:54 INFO - Running post-action listener: _resource_record_post_action 03:15:54 INFO - [mozharness: 2017-01-27 11:15:54.466809Z] Finished populate-webroot step (success) 03:15:54 INFO - [mozharness: 2017-01-27 11:15:54.466901Z] Running create-virtualenv step. 03:15:54 INFO - Running pre-action listener: _resource_record_pre_action 03:15:54 INFO - Running main action method: create_virtualenv 03:15:54 INFO - Creating virtualenv /builds/slave/test/build/venv 03:15:54 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 03:15:54 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:15:54 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:15:54 INFO - Using real prefix '/tools/python27' 03:15:54 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:15:55 INFO - Installing distribute.............................................................................................................................................................................................done. 03:15:58 INFO - Installing pip.................done. 03:15:58 INFO - Return code: 0 03:15:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:15:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:15:58 INFO - Reading from file tmpfile_stdout 03:15:58 INFO - Output received: 03:15:58 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) 03:15:58 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:15:58 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')]} 03:15:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:15:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:15:58 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')]} 03:15:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:15:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:15:58 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 0x1092fdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109300768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd750d71f10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090dbd30>, '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 0x109002db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1090d0be8>, '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.84tvuSAr4J/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.RGMwrtrArF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/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 03:15:58 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 03:15:58 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 03:15:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.84tvuSAr4J/Render', 03:15:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:15:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:15:58 INFO - 'HOME': '/Users/cltbld', 03:15:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:15:58 INFO - 'LOGNAME': 'cltbld', 03:15:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:15:58 INFO - 'MOZ_NO_REMOTE': '1', 03:15:58 INFO - 'NO_EM_RESTART': '1', 03:15:58 INFO - 'PAGER': '/bin/cat', 03:15:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:15:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:15:58 INFO - 'PWD': '/builds/slave/test', 03:15:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:15:58 INFO - 'SHELL': '/bin/bash', 03:15:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RGMwrtrArF/Listeners', 03:15:58 INFO - 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/', 03:15:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:15:58 INFO - 'USER': 'cltbld', 03:15:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:15:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:15:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:15:58 INFO - 'XPC_FLAGS': '0x0', 03:15:58 INFO - 'XPC_SERVICE_NAME': '0', 03:15:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:15:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:15:58 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 03:15:58 INFO - Cleaning up... 03:15:58 INFO - Return code: 0 03:15:58 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:15:58 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')]} 03:15:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:15:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:15:58 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')]} 03:15:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:15:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:15:58 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 0x1092fdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109300768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd750d71f10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090dbd30>, '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 0x109002db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1090d0be8>, '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.84tvuSAr4J/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.RGMwrtrArF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/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 03:15:58 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 03:15:58 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 03:15:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.84tvuSAr4J/Render', 03:15:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:15:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:15:58 INFO - 'HOME': '/Users/cltbld', 03:15:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:15:58 INFO - 'LOGNAME': 'cltbld', 03:15:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:15:58 INFO - 'MOZ_NO_REMOTE': '1', 03:15:58 INFO - 'NO_EM_RESTART': '1', 03:15:58 INFO - 'PAGER': '/bin/cat', 03:15:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:15:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:15:58 INFO - 'PWD': '/builds/slave/test', 03:15:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:15:58 INFO - 'SHELL': '/bin/bash', 03:15:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RGMwrtrArF/Listeners', 03:15:58 INFO - 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/', 03:15:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:15:58 INFO - 'USER': 'cltbld', 03:15:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:15:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:15:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:15:58 INFO - 'XPC_FLAGS': '0x0', 03:15:58 INFO - 'XPC_SERVICE_NAME': '0', 03:15:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:15:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:15:58 INFO - Downloading/unpacking psutil>=3.1.1 03:15:58 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 03:15:58 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 03:15:58 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 03:15:58 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 03:15:58 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 03:15:58 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 03:16:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:16:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:16:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:16:03 INFO - Installing collected packages: psutil 03:16:03 INFO - Running setup.py install for psutil 03:16:03 INFO - building 'psutil._psutil_osx' extension 03:16:03 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 03:16:03 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 03:16:03 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 03:16:03 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 03:16:03 INFO - building 'psutil._psutil_posix' extension 03:16:03 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 03:16:03 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:16:03 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:16:03 INFO - ^ 03:16:03 INFO - 1 warning generated. 03:16:03 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 03:16:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:16:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:16:04 INFO - Successfully installed psutil 03:16:04 INFO - Cleaning up... 03:16:04 INFO - Return code: 0 03:16:04 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:16:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:16:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:16:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:16:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:16:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:16:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:16:04 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 0x1092fdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109300768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd750d71f10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090dbd30>, '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 0x109002db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1090d0be8>, '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.84tvuSAr4J/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.RGMwrtrArF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/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 03:16:04 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 03:16:04 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 03:16:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.84tvuSAr4J/Render', 03:16:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:16:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:16:04 INFO - 'HOME': '/Users/cltbld', 03:16:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:16:04 INFO - 'LOGNAME': 'cltbld', 03:16:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:16:04 INFO - 'MOZ_NO_REMOTE': '1', 03:16:04 INFO - 'NO_EM_RESTART': '1', 03:16:04 INFO - 'PAGER': '/bin/cat', 03:16:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:16:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:16:04 INFO - 'PWD': '/builds/slave/test', 03:16:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:16:04 INFO - 'SHELL': '/bin/bash', 03:16:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RGMwrtrArF/Listeners', 03:16:04 INFO - 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/', 03:16:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:16:04 INFO - 'USER': 'cltbld', 03:16:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:16:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:16:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:16:04 INFO - 'XPC_FLAGS': '0x0', 03:16:04 INFO - 'XPC_SERVICE_NAME': '0', 03:16:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:16:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:16:04 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:16:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:16:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:16:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:16:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:16:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:16:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:16:07 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:16:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:16:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:16:07 INFO - Installing collected packages: mozsystemmonitor 03:16:07 INFO - Running setup.py install for mozsystemmonitor 03:16:07 INFO - Successfully installed mozsystemmonitor 03:16:07 INFO - Cleaning up... 03:16:07 INFO - Return code: 0 03:16:07 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:16: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')]} 03:16:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:16:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:16: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')]} 03:16:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:16:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:16:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1092fdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109300768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd750d71f10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090dbd30>, '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 0x109002db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1090d0be8>, '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.84tvuSAr4J/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.RGMwrtrArF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/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 03:16:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 03:16:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 03:16:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.84tvuSAr4J/Render', 03:16:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:16:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:16:07 INFO - 'HOME': '/Users/cltbld', 03:16:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:16:07 INFO - 'LOGNAME': 'cltbld', 03:16:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:16:07 INFO - 'MOZ_NO_REMOTE': '1', 03:16:07 INFO - 'NO_EM_RESTART': '1', 03:16:07 INFO - 'PAGER': '/bin/cat', 03:16:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:16:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:16:07 INFO - 'PWD': '/builds/slave/test', 03:16:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:16:07 INFO - 'SHELL': '/bin/bash', 03:16:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RGMwrtrArF/Listeners', 03:16:07 INFO - 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/', 03:16:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:16:07 INFO - 'USER': 'cltbld', 03:16:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:16:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:16:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:16:07 INFO - 'XPC_FLAGS': '0x0', 03:16:07 INFO - 'XPC_SERVICE_NAME': '0', 03:16:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:16:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:16:07 INFO - Downloading/unpacking jsonschema==2.5.1 03:16:07 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 03:16:07 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 03:16:07 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 03:16:07 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 03:16:07 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 03:16:07 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 03:16:10 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:16:10 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:16:10 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 03:16:10 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 03:16:10 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 03:16:10 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 03:16:10 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 03:16:10 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 03:16:10 INFO - Downloading functools32-3.2.3-2.tar.gz 03:16:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:16:10 INFO - warning: no files found matching '*.txt' 03:16:10 INFO - no previously-included directories found matching 'build' 03:16:10 INFO - no previously-included directories found matching 'dist' 03:16:10 INFO - no previously-included directories found matching '.git*' 03:16:10 INFO - Installing collected packages: jsonschema, functools32 03:16:10 INFO - Running setup.py install for functools32 03:16:10 INFO - warning: no files found matching '*.txt' 03:16:10 INFO - no previously-included directories found matching 'build' 03:16:10 INFO - no previously-included directories found matching 'dist' 03:16:10 INFO - no previously-included directories found matching '.git*' 03:16:10 INFO - Successfully installed jsonschema functools32 03:16:10 INFO - Cleaning up... 03:16:11 INFO - Return code: 0 03:16:11 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:16:11 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')]} 03:16:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:16:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:16:11 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')]} 03:16:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:16:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:16:11 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 0x1092fdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109300768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd750d71f10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090dbd30>, '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 0x109002db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1090d0be8>, '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.84tvuSAr4J/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.RGMwrtrArF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/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 03:16:11 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 03:16:11 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 03:16:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.84tvuSAr4J/Render', 03:16:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:16:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:16:11 INFO - 'HOME': '/Users/cltbld', 03:16:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:16:11 INFO - 'LOGNAME': 'cltbld', 03:16:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:16:11 INFO - 'MOZ_NO_REMOTE': '1', 03:16:11 INFO - 'NO_EM_RESTART': '1', 03:16:11 INFO - 'PAGER': '/bin/cat', 03:16:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:16:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:16:11 INFO - 'PWD': '/builds/slave/test', 03:16:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:16:11 INFO - 'SHELL': '/bin/bash', 03:16:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RGMwrtrArF/Listeners', 03:16:11 INFO - 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/', 03:16:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:16:11 INFO - 'USER': 'cltbld', 03:16:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:16:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:16:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:16:11 INFO - 'XPC_FLAGS': '0x0', 03:16:11 INFO - 'XPC_SERVICE_NAME': '0', 03:16:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:16:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:16:11 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:16:11 INFO - Cleaning up... 03:16:11 INFO - Return code: 0 03:16:11 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:16:11 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')]} 03:16:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:16:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:16:11 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')]} 03:16:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:16:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:16:11 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 0x1092fdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109300768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd750d71f10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090dbd30>, '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 0x109002db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1090d0be8>, '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.84tvuSAr4J/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.RGMwrtrArF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/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 03:16:11 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 03:16:11 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 03:16:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.84tvuSAr4J/Render', 03:16:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:16:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:16:11 INFO - 'HOME': '/Users/cltbld', 03:16:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:16:11 INFO - 'LOGNAME': 'cltbld', 03:16:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:16:11 INFO - 'MOZ_NO_REMOTE': '1', 03:16:11 INFO - 'NO_EM_RESTART': '1', 03:16:11 INFO - 'PAGER': '/bin/cat', 03:16:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:16:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:16:11 INFO - 'PWD': '/builds/slave/test', 03:16:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:16:11 INFO - 'SHELL': '/bin/bash', 03:16:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RGMwrtrArF/Listeners', 03:16:11 INFO - 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/', 03:16:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:16:11 INFO - 'USER': 'cltbld', 03:16:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:16:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:16:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:16:11 INFO - 'XPC_FLAGS': '0x0', 03:16:11 INFO - 'XPC_SERVICE_NAME': '0', 03:16:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:16:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:16:11 INFO - Downloading/unpacking blobuploader==1.2.4 03:16:11 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 03:16:11 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 03:16:11 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 03:16:11 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 03:16:11 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 03:16:11 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 03:16:14 INFO - Downloading blobuploader-1.2.4.tar.gz 03:16:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:16:14 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:16:14 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 03:16:14 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 03:16:14 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 03:16:14 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 03:16:14 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 03:16:14 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 03:16:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:16:14 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:16:14 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 03:16:14 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 03:16:14 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 03:16:14 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 03:16:14 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 03:16:14 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 03:16:15 INFO - Downloading docopt-0.6.1.tar.gz 03:16:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:16:15 INFO - Installing collected packages: blobuploader, requests, docopt 03:16:15 INFO - Running setup.py install for blobuploader 03:16:15 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:16:15 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:16:15 INFO - Running setup.py install for requests 03:16:16 INFO - Running setup.py install for docopt 03:16:16 INFO - Successfully installed blobuploader requests docopt 03:16:16 INFO - Cleaning up... 03:16:16 INFO - Return code: 0 03:16:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:16:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:16:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:16:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:16:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:16:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:16:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:16:16 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 0x1092fdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109300768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd750d71f10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090dbd30>, '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 0x109002db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1090d0be8>, '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.84tvuSAr4J/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.RGMwrtrArF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/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 03:16:16 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 03:16:16 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 03:16:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.84tvuSAr4J/Render', 03:16:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:16:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:16:16 INFO - 'HOME': '/Users/cltbld', 03:16:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:16:16 INFO - 'LOGNAME': 'cltbld', 03:16:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:16:16 INFO - 'MOZ_NO_REMOTE': '1', 03:16:16 INFO - 'NO_EM_RESTART': '1', 03:16:16 INFO - 'PAGER': '/bin/cat', 03:16:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:16:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:16:16 INFO - 'PWD': '/builds/slave/test', 03:16:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:16:16 INFO - 'SHELL': '/bin/bash', 03:16:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RGMwrtrArF/Listeners', 03:16:16 INFO - 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/', 03:16:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:16:16 INFO - 'USER': 'cltbld', 03:16:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:16:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:16:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:16:16 INFO - 'XPC_FLAGS': '0x0', 03:16:16 INFO - 'XPC_SERVICE_NAME': '0', 03:16:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:16:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:16:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:16:16 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-wm_GZm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:16:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:16:16 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-XRUHjf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:16:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:16:16 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-Syozb3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:16:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:16:17 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-Ywuj6I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:16:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:16:17 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-NyVT6Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:16:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:16:17 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-axJkGm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:16:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:16:17 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-LDfxo0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:16:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:16:17 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-wUfiPg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:16:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:16:17 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-uyrhbS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:16:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:16:17 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-2mN4XY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:16:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:16:17 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-ex7AmF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:16:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:16:17 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-mrb6c5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:16:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:16:17 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-iZX0Qg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:16:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:16:18 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-LfiqXe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:16:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:16:18 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-T9ylYR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:16:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:16:18 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-K8i30J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:16:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:16:18 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-dxkivJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:16:18 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:16:18 INFO - Running setup.py install for manifestparser 03:16:18 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:16:18 INFO - Running setup.py install for mozcrash 03:16:18 INFO - Running setup.py install for mozdebug 03:16:18 INFO - Running setup.py install for mozdevice 03:16:19 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:16:19 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:16:19 INFO - Running setup.py install for mozfile 03:16:19 INFO - Running setup.py install for mozhttpd 03:16:19 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:16:19 INFO - Running setup.py install for mozinfo 03:16:19 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:16:19 INFO - Running setup.py install for mozInstall 03:16:19 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:16:19 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:16:19 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:16:19 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:16:19 INFO - Running setup.py install for mozleak 03:16:19 INFO - Running setup.py install for mozlog 03:16:19 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:16:19 INFO - Running setup.py install for moznetwork 03:16:20 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:16:20 INFO - Running setup.py install for mozprocess 03:16:20 INFO - Running setup.py install for mozprofile 03:16:20 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:16:20 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:16:20 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:16:20 INFO - Running setup.py install for mozrunner 03:16:20 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:16:20 INFO - Running setup.py install for mozscreenshot 03:16:20 INFO - Running setup.py install for moztest 03:16:20 INFO - Running setup.py install for mozversion 03:16:21 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:16:21 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:16:21 INFO - Cleaning up... 03:16:21 INFO - Return code: 0 03:16:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:16:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:16:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:16:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:16:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:16:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:16:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:16:21 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 0x1092fdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109300768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd750d71f10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090dbd30>, '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 0x109002db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1090d0be8>, '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.84tvuSAr4J/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.RGMwrtrArF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/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 03:16:21 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 03:16:21 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 03:16:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.84tvuSAr4J/Render', 03:16:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:16:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:16:21 INFO - 'HOME': '/Users/cltbld', 03:16:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:16:21 INFO - 'LOGNAME': 'cltbld', 03:16:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:16:21 INFO - 'MOZ_NO_REMOTE': '1', 03:16:21 INFO - 'NO_EM_RESTART': '1', 03:16:21 INFO - 'PAGER': '/bin/cat', 03:16:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:16:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:16:21 INFO - 'PWD': '/builds/slave/test', 03:16:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:16:21 INFO - 'SHELL': '/bin/bash', 03:16:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RGMwrtrArF/Listeners', 03:16:21 INFO - 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/', 03:16:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:16:21 INFO - 'USER': 'cltbld', 03:16:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:16:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:16:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:16:21 INFO - 'XPC_FLAGS': '0x0', 03:16:21 INFO - 'XPC_SERVICE_NAME': '0', 03:16:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:16:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:16:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:16:21 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-Bt8Ars-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:16:21 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)) 03:16:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:16:21 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-TUttOW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:16:21 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)) 03:16:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:16:21 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-o6ecsj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:16:21 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)) 03:16:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:16:21 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-_H8JtP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:16:21 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)) 03:16:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:16:21 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-VLNvMN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:16:21 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)) 03:16:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:16:21 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-o_PXEt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:16:21 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)) 03:16:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:16:21 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-yvfm_m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:16:22 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)) 03:16:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:16:22 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-K1w5Ar-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:16:22 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)) 03:16:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:16:22 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-kTaL86-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:16:22 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)) 03:16:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:16:22 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-zZHQWX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:16:22 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)) 03:16:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:16:22 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-CvfXRd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:16:22 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)) 03:16:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:16:22 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-7t8Wx3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:16:22 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)) 03:16:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:16:22 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-sgwqj8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:16:22 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)) 03:16:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:16:22 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-M6BFz1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:16:22 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)) 03:16:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:16:22 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-B2l9fK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:16:22 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)) 03:16:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:16:22 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-_ZUCNi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:16:22 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)) 03:16:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:16:23 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-S0g4YS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:16:23 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)) 03:16:23 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)) 03:16:23 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)) 03:16:23 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)) 03:16:23 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)) 03:16:23 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)) 03:16:23 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:16:23 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 03:16:23 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 03:16:23 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 03:16:23 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 03:16:23 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 03:16:23 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 03:16:25 INFO - Downloading blessings-1.6.tar.gz 03:16:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:16:25 INFO - Installing collected packages: blessings 03:16:25 INFO - Running setup.py install for blessings 03:16:25 INFO - Successfully installed blessings 03:16:25 INFO - Cleaning up... 03:16:26 INFO - Return code: 0 03:16:26 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:16:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:16:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:16:26 INFO - Reading from file tmpfile_stdout 03:16:26 INFO - Current package versions: 03:16:26 INFO - blessings == 1.6 03:16:26 INFO - blobuploader == 1.2.4 03:16:26 INFO - docopt == 0.6.1 03:16:26 INFO - functools32 == 3.2.3-2 03:16:26 INFO - jsonschema == 2.5.1 03:16:26 INFO - manifestparser == 1.1 03:16:26 INFO - mozInstall == 1.12 03:16:26 INFO - mozcrash == 1.0 03:16:26 INFO - mozdebug == 0.1 03:16:26 INFO - mozdevice == 0.49 03:16:26 INFO - mozfile == 1.2 03:16:26 INFO - mozhttpd == 0.7 03:16:26 INFO - mozinfo == 0.9 03:16:26 INFO - mozleak == 0.1 03:16:26 INFO - mozlog == 3.4 03:16:26 INFO - moznetwork == 0.27 03:16:26 INFO - mozprocess == 0.24 03:16:26 INFO - mozprofile == 0.28 03:16:26 INFO - mozrunner == 6.13 03:16:26 INFO - mozscreenshot == 0.1 03:16:26 INFO - mozsystemmonitor == 0.3 03:16:26 INFO - moztest == 0.8 03:16:26 INFO - mozversion == 1.4 03:16:26 INFO - psutil == 3.1.1 03:16:26 INFO - requests == 1.2.3 03:16:26 INFO - wsgiref == 0.1.2 03:16:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:16:26 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')]} 03:16:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:16:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:16:26 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')]} 03:16:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:16:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:16:26 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 0x1092fdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109300768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd750d71f10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090dbd30>, '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 0x109002db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1090d0be8>, '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.84tvuSAr4J/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.RGMwrtrArF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/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 03:16:26 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 03:16:26 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 03:16:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.84tvuSAr4J/Render', 03:16:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:16:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:16:26 INFO - 'HOME': '/Users/cltbld', 03:16:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:16:26 INFO - 'LOGNAME': 'cltbld', 03:16:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:16:26 INFO - 'MOZ_NO_REMOTE': '1', 03:16:26 INFO - 'NO_EM_RESTART': '1', 03:16:26 INFO - 'PAGER': '/bin/cat', 03:16:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:16:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:16:26 INFO - 'PWD': '/builds/slave/test', 03:16:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:16:26 INFO - 'SHELL': '/bin/bash', 03:16:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RGMwrtrArF/Listeners', 03:16:26 INFO - 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/', 03:16:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:16:26 INFO - 'USER': 'cltbld', 03:16:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:16:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:16:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:16:26 INFO - 'XPC_FLAGS': '0x0', 03:16:26 INFO - 'XPC_SERVICE_NAME': '0', 03:16:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:16:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:16:26 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)) 03:16:26 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)) 03:16:26 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)) 03:16:26 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)) 03:16:26 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)) 03:16:26 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)) 03:16:26 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)) 03:16:26 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)) 03:16:26 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)) 03:16:26 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)) 03:16:26 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)) 03:16:26 INFO - Cleaning up... 03:16:26 INFO - Return code: 0 03:16:26 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:16:26 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')]} 03:16:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:16:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:16:26 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')]} 03:16:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:16:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:16:26 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 0x1092fdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109300768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd750d71f10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090dbd30>, '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 0x109002db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1090d0be8>, '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.84tvuSAr4J/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.RGMwrtrArF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/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 03:16:26 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 03:16:26 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 03:16:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.84tvuSAr4J/Render', 03:16:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:16:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:16:26 INFO - 'HOME': '/Users/cltbld', 03:16:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:16:26 INFO - 'LOGNAME': 'cltbld', 03:16:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:16:26 INFO - 'MOZ_NO_REMOTE': '1', 03:16:26 INFO - 'NO_EM_RESTART': '1', 03:16:26 INFO - 'PAGER': '/bin/cat', 03:16:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:16:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:16:26 INFO - 'PWD': '/builds/slave/test', 03:16:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:16:26 INFO - 'SHELL': '/bin/bash', 03:16:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RGMwrtrArF/Listeners', 03:16:26 INFO - 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/', 03:16:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:16:26 INFO - 'USER': 'cltbld', 03:16:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:16:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:16:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:16:26 INFO - 'XPC_FLAGS': '0x0', 03:16:26 INFO - 'XPC_SERVICE_NAME': '0', 03:16:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:16:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:16:26 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:16:26 INFO - Cleaning up... 03:16:26 INFO - Return code: 0 03:16:26 INFO - Running post-action listener: _resource_record_post_action 03:16:26 INFO - Running post-action listener: _start_resource_monitoring 03:16:26 INFO - Starting resource monitoring. 03:16:26 INFO - [mozharness: 2017-01-27 11:16:26.949369Z] Finished create-virtualenv step (success) 03:16:26 INFO - [mozharness: 2017-01-27 11:16:26.950366Z] Running install step. 03:16:26 INFO - Running pre-action listener: _resource_record_pre_action 03:16:26 INFO - Running main action method: install 03:16:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:16:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:16:27 INFO - Reading from file tmpfile_stdout 03:16:27 INFO - Detecting whether we're running mozinstall >=1.0... 03:16:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:16:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:16:27 INFO - Reading from file tmpfile_stdout 03:16:27 INFO - Output received: 03:16:27 INFO - Usage: mozinstall [options] installer 03:16:27 INFO - Options: 03:16:27 INFO - -h, --help show this help message and exit 03:16:27 INFO - -d DEST, --destination=DEST 03:16:27 INFO - Directory to install application into. [default: 03:16:27 INFO - "/builds/slave/test"] 03:16:27 INFO - --app=APP Application being installed. [default: firefox] 03:16:27 INFO - mkdir: /builds/slave/test/build/application 03:16:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 03:16:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 03:16:49 INFO - Reading from file tmpfile_stdout 03:16:49 INFO - Output received: 03:16:49 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 03:16:49 INFO - Running post-action listener: _resource_record_post_action 03:16:49 INFO - [mozharness: 2017-01-27 11:16:49.191193Z] Finished install step (success) 03:16:49 INFO - [mozharness: 2017-01-27 11:16:49.191331Z] Running run-tests step. 03:16:49 INFO - Running pre-action listener: _resource_record_pre_action 03:16:49 INFO - Running main action method: run_tests 03:16:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:16:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:16:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:16:49 INFO - 2017-01-27 03:16:49.205 screenresolution[1632:8673] starting screenresolution argv=screenresolution get 03:16:49 INFO - 2017-01-27 03:16:49.208 screenresolution[1632:8673] Display 0: 1600x1200x32@60 03:16:49 INFO - 2017-01-27 03:16:49.214 screenresolution[1633:8675] starting screenresolution argv=screenresolution list 03:16:49 INFO - Available Modes on Display 0 03:16:49 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:16:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:16:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:16:49 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:16:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:16:49 INFO - Intel Iris: 03:16:49 INFO - Chipset Model: Intel Iris 03:16:49 INFO - Type: GPU 03:16:49 INFO - Bus: Built-In 03:16:49 INFO - VRAM (Dynamic, Max): 1536 MB 03:16:49 INFO - Vendor: Intel (0x8086) 03:16:49 INFO - Device ID: 0x0a2e 03:16:49 INFO - Revision ID: 0x0009 03:16:49 INFO - Displays: 03:16:49 INFO - Display: 03:16:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:16:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:16:49 INFO - Display Serial Number: 1600x1200 60 03:16:49 INFO - Main Display: Yes 03:16:49 INFO - Mirror: Off 03:16:49 INFO - Online: Yes 03:16:49 INFO - Rotation: Supported 03:16:49 INFO - Return code: 0 03:16:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:16:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:16:49 INFO - Python 2.7.3 03:16:49 INFO - Return code: 0 03:16:49 INFO - grabbing minidump binary from tooltool 03:16: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')]} 03:16:49 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 0x1090dbd30>, '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 0x109002db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1090d0be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:16:49 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 03:16:49 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 03:16:49 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 03:16:49 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:16:49 INFO - Return code: 0 03:16:49 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:16:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:16:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:16:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:16:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:16:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZIw_youVQ5m5pP6RAkUf1A/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0368', '--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 03:16:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ZIw_youVQ5m5pP6RAkUf1A/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0368 --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 03:16:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.84tvuSAr4J/Render', 03:16:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:16:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:16:49 INFO - 'HOME': '/Users/cltbld', 03:16:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:16:49 INFO - 'LOGNAME': 'cltbld', 03:16:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:16:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:16:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:16:49 INFO - 'MOZ_NO_REMOTE': '1', 03:16:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:16:49 INFO - 'NO_EM_RESTART': '1', 03:16:49 INFO - 'PAGER': '/bin/cat', 03:16:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:16:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:16:49 INFO - 'PWD': '/builds/slave/test', 03:16:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:16:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:16:49 INFO - 'SHELL': '/bin/bash', 03:16:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RGMwrtrArF/Listeners', 03:16:49 INFO - 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/', 03:16:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:16:49 INFO - 'USER': 'cltbld', 03:16:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:16:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:16:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:16:49 INFO - 'XPC_FLAGS': '0x0', 03:16:49 INFO - 'XPC_SERVICE_NAME': '0', 03:16:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:16:49 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZIw_youVQ5m5pP6RAkUf1A/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0368', '--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 03:16:49 INFO - mozversion application_buildid: 20170127014221 03:16:49 INFO - mozversion application_changeset: dad46f4125887d88734759a91595738105f85b37 03:16:49 INFO - mozversion application_display_name: Nightly 03:16:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:16:49 INFO - mozversion application_name: Firefox 03:16:49 INFO - mozversion application_remotingname: firefox 03:16:49 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 03:16:49 INFO - mozversion application_vendor: Mozilla 03:16:49 INFO - mozversion application_version: 54.0a1 03:16:49 INFO - mozversion platform_buildid: 20170127014221 03:16:49 INFO - mozversion platform_changeset: dad46f4125887d88734759a91595738105f85b37 03:16:49 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 03:16:49 INFO - mozversion platform_version: 54.0a1 03:16:49 INFO - using testdate: 1485515809 03:16:49 INFO - actual date: 1485515809 03:16:49 INFO - starting webserver on 'localhost:49249' 03:16:49 INFO - SUITE-START | Running 2 tests 03:16:49 INFO - TEST-START | dromaeo_css 03:16:49 INFO - operating with platform_type : mac_ 03:16:49 INFO - Initialising browser for dromaeo_css test... 03:16:49 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/tmp4EfXJV/profile http://localhost:49249/getInfo.html) 03:16:52 INFO - PROCESS | 1643 | 2017-01-27 03:16:52.822 plugin-container[1645:8829] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x904b, name = 'com.apple.tsm.portname' 03:16:52 INFO - PROCESS | 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:16:52 INFO - PROCESS | 1643 | 2017-01-27 03:16:52.825 plugin-container[1645:8829] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x950f, name = 'com.apple.CFPasteboardClient' 03:16:52 INFO - PROCESS | 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:16:52 INFO - PROCESS | 1643 | 2017-01-27 03:16:52.825 plugin-container[1645:8829] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:16:52 INFO - PROCESS | 1643 | __metrics Screen width/height:1600/1200 03:16:52 INFO - PROCESS | 1643 | colorDepth:24 03:16:52 INFO - PROCESS | 1643 | Browser inner width/height: 1024/689 03:16:52 INFO - PROCESS | 1643 | __metrics 03:16:52 ERROR - PROCESS | 1643 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3279: TypeError: this.tabs is undefined 03:16:53 INFO - TEST-INFO | 1643: exit 0 03:16:53 INFO - Browser initialized. 03:16:53 INFO - Running cycle 1/1 for dromaeo_css test... 03:16:53 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/tmp4EfXJV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:16:56 INFO - PROCESS | 1647 | 2017-01-27 03:16:56.117 plugin-container[1648:8953] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x863f, name = 'com.apple.tsm.portname' 03:16:56 INFO - PROCESS | 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:16:56 INFO - PROCESS | 1647 | 2017-01-27 03:16:56.118 plugin-container[1648:8953] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 03:16:56 INFO - PROCESS | 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:16:56 INFO - PROCESS | 1647 | 2017-01-27 03:16:56.118 plugin-container[1648:8953] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:17:05 INFO - PROCESS | 1647 | RSS: Main: 233435136 03:17:05 INFO - PROCESS | 1647 | 03:20:08 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49249/tests/dromaeo/cssquery-ext.html) 03:20:10 INFO - PROCESS | 1647 | RSS: Main: 258822144 03:20:10 INFO - PROCESS | 1647 | 03:21:53 INFO - PROCESS | 1647 | ************************* 03:21:53 INFO - PROCESS | 1647 | A coding exception was thrown and uncaught in a Task. 03:21:53 INFO - PROCESS | 1647 | 03:21:53 ERROR - PROCESS | 1647 | Full message: TypeError: NetworkError when attempting to fetch resource. 03:21:53 INFO - PROCESS | 1647 | Full stack: 03:21:53 INFO - PROCESS | 1647 | ************************* 03:23:12 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-ext.html (next: http://localhost:49249/tests/dromaeo/cssquery-jquery.html) 03:23:13 INFO - PROCESS | 1647 | RSS: Main: 265637888 03:23:13 INFO - PROCESS | 1647 | 03:26:15 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49249/tests/dromaeo/cssquery-mootools.html) 03:26:15 INFO - PROCESS | 1647 | RSS: Main: 276578304 03:26:15 INFO - PROCESS | 1647 | 03:29:17 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49249/tests/dromaeo/cssquery-prototype.html) 03:29:18 INFO - PROCESS | 1647 | RSS: Main: 269488128 03:29:18 INFO - PROCESS | 1647 | 03:32:21 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49249/tests/dromaeo/cssquery-yui.html) 03:32:21 INFO - PROCESS | 1647 | RSS: Main: 277909504 03:32:21 INFO - PROCESS | 1647 | 03:35:24 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-yui.html (next: http://localhost:49249/tests/dromaeo/cssquery-dojo.html) 03:35:25 INFO - PROCESS | 1647 | RSS: Main: 279703552 03:35:25 INFO - PROCESS | 1647 | 03:35:25 INFO - PROCESS | 1647 | __start_tp_report 03:35:25 INFO - PROCESS | 1647 | _x_x_mozilla_page_load 03:35:25 INFO - PROCESS | 1647 | _x_x_mozilla_page_load_details 03:35:25 INFO - PROCESS | 1647 | |i|pagename|runs| 03:35:25 INFO - PROCESS | 1647 | |0;dojo.html;3322.6012878454576;3333.8999830005096;3367.797932124074;3378.949315760264;3384.8646054157834;5325.961437519685;5455.290812194409;5519.889602207953;5528.419515950823;5595.916061259084;5463.098588732864;5477.3427188737305;5496.725163741807;5505.587080968933;5549.445055494449;4953.257011448276;5031.396232452111;5074;5123.2827404163445;5134.281200631895;5008.624353173508;5176.120059589879;5211.973940130294;5223.6343455958095;5230.8692282692855;5011.548960593527;5027.924581131286;5148.304978827885;5163.535281824635;5174.97412512935;5049.065922804256;5071.771770270347;5118.513741194581;5149.974250128763;5171.870703232412;5020.523050310215;5032.874178145539;5063.240513922904;5072.163093089635;5117.641765076446;5378.435264297271;5414.052540805344;5513.807016754395;5587.1339942309005;5601.215829783833;5950.315713692894;5988.520918326567;6046.09308603713;6076.544259180535;6121.300552271608;6154.138420621117;6170.043643235305;6181.536384771161;6196.628202307831;6200.751969921198;4622.445306563201;5616.1014237721765;5656.321241451011;5657.745401456944;5664.348599911022;5004.9499505005215;6180.47465965398;6200.3799620038535;6220.25356957159;6255.499560035278;4956.603471722254;5288.762005709752;5857.502112320501;6032.245969253843;6049.213602231682;6106.236720409949;6135.478484328793;6153.907691384633;6174.413430724074;6314.936850631527;5828.300603927556;5864.912026319608;6013.218281623361;6093.512518998469;6108.633481991094;5053.974730126326;6120.877582448326;6148.047052706837;6150.323464418911;6156.168917196211;4162.62536371728;4731.787069581877;4811.759412029384;4840.370751802314;4882.731449770296;3560.839762210707;4286.849960251377;4303.117860838521;4329.09089091288;4360.4767427908855;4696.647751418657;5629.7185140742795;5670.064439367458;5709.200711900337;5744.339400968942;5069.366329208849;6111.480524155497;6188.2883468400805;6191.5665903387235;6214.533909956681;5485;5543.556515478752;5616.775328986795;5665.3768085510565;5705.800296989669;5245.26566280721;5249.632525723239;5290.470952904679;5305.867353316198;5342.786288548492;6257.7184026718905;6271.843203919939;6274.278457977391;6278.340774218641;6296.150019747459;6023.6385816851125;6192.659403732838;6198.969005154945;6211.596246243979;6222.968885155546;6199.504039676905;6203.16257305258;6228.4394404502;6266.655333956676;6278.340774218641;6017.38800417481;6075.997460418981;6099.786507472217;6099.84750381244;6141.508679305734;6133.172021777003;6145.476426095726;6184.886720390373;6213.751449941951;6221.06683997404;5670.177824215548;5766.163906233656;5904.114382842608;5904.114382842608;5935.792247271324;5975.312839023568;5981.0729336952845;5983.970080149571;5990.071538911476;6021.789237376671;4855.174620314626;5662.716864156858;5693.971530142323;5751.4536119068625;5798.478136967693;5891.558133139946;5893;5941.851453713692;5972.731227094878;6015.578909476295;5756.395578464369;5782.1615865698395;5794.5364370851075;5809.128630705428;5821.417858214314 03:35:25 INFO - PROCESS | 1647 | |1;ext.html;478.3303375274615;488.5969075512703;499.97000179989203;512.004151924507;534.7219445888136;31639.367212655732;35639.46540801887;36738.89783306502;39240.822775316716;39303.82088537348;38416.03959901004;38836.41745373815;38895.805520972426;38899;39008.80495597518;7913.327367173797;8992.460452372863;9231.215346695539;10613.628523001697;11051.557937682483;9105.499197544132;9476.43141411514;9791.755206119833;10826.945865270702;10841.403722795243;10725.249232553724;10772.73068173294;10774.191935604822;10777.137828973664;10812.189085818556;10764.515596798163;10806.135509159249;10814.513346899408;10835.133189344833;10846.783064338748;10635.46822658864;10706.946465267623;10727.409992450412;10737.087347575465;10756.784864302748;35161.64838351635;35345.11637209065;35755.463668045;35769.28461430783;35794.82102589496;3870.593587673282;3905.297046531623;3921.901952451183;3924.7841368724708;3947.131631041166;27117.915283388837;27456.588151177748;27472.313192170157;27605.585916211272;27830.443391132063;3103.674114217997;3158.936821263585;3179.459491886385;3197.328561002169;3238.5142228665654;367509.9999999973;370356;370814;372069;372550;52167.73916130395;52448.73775631098;52485.475145248056;52554.73722631439;52611.473885261425;54703;54887;55084.44915550874;55180.724096379265;55386.16920746192;14995.550133496012;15030.473933412279;15083.396664133532;15201.543953681406;15240.923795380952;10702.73243168927;10974.012338889539;11630.244034137753;11861.46623401949;11875.821862672066;16938;16966.491005269614;16996;17043.40348087811;17109.28907109304;7046.401055910305;7247.528910620793;7703.189750408649;7823.843523129505;7894.802629934297;855.0936007831718;863.8536810592589;868.7480630617176;871.263782104121;888.320434867327;29097.418051638848;29143.708562914522;29247.85376073106;29399.853000735286;29552.556711649344;1028.6862506935306;1041.2242879055152;1053.089077947585;1054.1092776603757;1117.1621284036971;14676.853231467761;15140.545783626509;15251.694966100617;15529.301181446863;15573.065616062846;491349;502712;503229;504720;507194;55881.720591396785;56432.87134257292;56455;56477.43522564722;56617.716911416006;1348.480834878579;1409.985900141006;1414.2221778022047;1421.8151640286696;1422.3386125451714;27796.58305125384;28227.858860706387;29169.53970246104;29322.413551729274;29326.56010159892;47314.053718926116;47480;47825;47891;47980;420127;420831;423217.2587056045;426618;427546;22373.44066398288;22929.082836687012;25341.493170136862;25676;25722.61416078723;42730.78446909643;47219.29171062505;47415;47511.76244118757;48067;31579.21051973719;32140.517892231168;33954.49068263928;37058;37688;29794.851025744734;33624.831875841446;35646.82176589101;36396;37776;33022.17444563905;33182.17044573905;34165;35844.462333065545;36675.26649467049;20924.267650632155;26051.348716282246;27107.86446067757;29490.852545737132;31624.051278461684;19835.900820496383;23108.96009679602;23427.531449370577;23699.2890213287;24079.759202407753 03:35:25 INFO - PROCESS | 1647 | |2;jquery.html;338.92713066690663;383.7122158381214;395.5522786601041;403.66899142702994;420.13033021645185;18611.627767444643;19164.61670766584;20535.178592856286;20762.896185519072;20773.480662983435;10807.83788243177;12363.93818030911;12386.318752468611;12439.253644781296;19566.70649940252;5109.463506331837;5295.073362161606;5409.188621706756;5420.186971954199;5422.566194704434;3889.630485103911;5300.840974770744;5426.701531415771;6004.939950600504;6052.546059045567;6116.113163591277;6123.326434092246;6123.357047510014;6140.07898815179;6145.5390845686525;6213.533984951124;6582.440492558138;6630.502712296597;6646.900296495556;6648.102506161679;5751.396103409165;5854.326752423483;5865.501432378253;5866.677332746697;5934.465898069151;13269;13337.86662133376;13353.332333383292;13368.064235503518;21839.126434942584;2560.9487810243877;2605.3747100695887;2606;2613.9215823525324;2641.828281161738;9623.518824058769;10107.64623238183;10321.380717156995;10524.421156836448;10530.42082685452;1840.6870831958465;1842.5577861313188;1842.9539261518435;1858.7026075827803;1861.5718384771444;103332.96667033309;125585;128433;130521;132685.33657331747;10928.6667566393;11355.716107097389;11785.764284714429;11848.644540663794;11879.940600296943;13926.860731392557;15446.382144714085;15467.845321546865;15493;15508.06951582886;7320.975063491115;8311.916880831235;8585.785355366166;8610.698625548075;8709.520976346235;8077.7576672699915;8255.463394879314;8266.586670666442;8389.916100839035;8410;3825.6556909878245;4696.436427628638;4785.114753770564;4864.221724524059;4875.391665458962;1428.3929330034694;1675.1373042882924;1769.7787776527935;1989.4926793667812;1992.8043174095717;986.9851952220722;994.6319861651235;996.2428554298785;1003.7039073473343;1049.7585555322319;19978.70031949522;20462.488437789176;21043.68434473484;21076.894615526824;21233;824.8515267251832;1022.882368527614;1044.8693913260843;1052.5211028981794;1058.6612284069179;10474.528646210938;10851.294665846852;10991.175661825395;11027.944860275808;11036.392998385005;487787;560707;603552;610559;611367;135867.32066339802;140704.29647851695;142745;148781;148971.25514372575;955.8816185063631;1032.5766435761302;1036.9896301037045;1050.8318669012922;1057.8201705709894;14721.631959200677;15145.924270378577;16698.499045028668;18462.26150953947;18482;57669.233076692;57812.421875782384;58167.70916145561;58817;59010;79240.415191697;82583;83603;83760.58119709362;83898.58050709707;5129.87175320605;5285.550728188147;6663.600183988976;6989.685464154227;7331;9248.583813728264;12446.626601201617;12609.30648814324;12825.935870320589;12847;10347.91346908594;10435.478226088817;10751.892481075089;10836.891631083588;10976.451177441255;5187;7604.961975190088;9398.859017114612;9783.581380699901;10442.216833737348;10682;10694.572217111228;10734.087602553871;10914.945425272823;10974.286671366335;7151.8569628608175;7154.570725756471;7307.7807665770115;7456.7762967111075;7476.813079673052;9088.500132492776;10635.521401536795;10682.412467314372;10728.678139655824;10767.192460565333 03:35:25 INFO - PROCESS | 1647 | |3;mootools.html;1403.8525954774748;1420.0840457904649;1423.5800438870533;1427.7287315410072;1445.190693211801;7436.628168591569;7538.585377804218;7807.882881756778;8491.23578877901;8518.701845435404;8020.67917283308;8293.792655183623;8324.83350332993;8340.165983401657;8398.538080405591;2238.462768935456;2286.1541229745017;2287.8627282363027;2289.782470665284;2289.9084036638515;2016.6168427998707;2065.8863762493056;2258.1757658454626;2295.2425699519204;2300.321405185466;1799.2982736732686;2264.6829443877787;2271.1029143488313;2272.5000499889998;2284.7144106986625;2258.06290389489;2263.5586060718165;2288.9084436622516;2293.862368257893;2294.265834932822;2251.0545570860277;2257.164849005862;2275.3515248154363;2299.2297580310615;2326.953460930772;8441.915580844174;8469.74590762278;8491.532965686885;8491.702790402307;8498.362622803254;5081.517255860687;5199.558037566811;5229.5293423591675;5248.8687782805355;5254.369475662907;3496.2657841853243;3504.842282097312;3522.3659741246565;3545.5745310562897;3584.4623306503972;1206.523423247821;1217.6286232699024;1244.6203907808115;1257.9119461637688;1263.444084602772;10725.034746872741;10779.892201077968;10786.029257366874;10815.621453249178;10850.620228291971;3872.593377695358;4323.956760432356;4696.825793551612;4718.669693121448;4729.881752956135;9467.66863159786;9562.713118606453;9572.329936904489;9582.041795820362;9588.993155718688;5242.97378513105;5243;5243.947560524346;5252.894942101137;5289.603279754034;4655.74393408359;4718.7640617969655;4764.809407623726;4826.0830442215865;4826.348442960156;3770.5852356240794;3807.333716599584;3854.8650797221962;3904.668103211202;3939.0546268895832;1001.5142655811925;1027.8406846938738;1202.699325168708;1252.9976019184617;1254.8933340666147;611.9571629985858;719.8632259870608;786.697121608185;801.194799226785;840.9369297302604;5086.491350864884;5648.729035966875;5733.283339582552;5776.133579963039;6012.428819262163;776.0570906348701;872.5549969515592;927.2952556057445;971.7376308396694;996.7408473796866;3666.2300916807235;3672.467492213614;3675.4119340905327;3780.7542509736777;3813.237352529505;9757.463339516395;10069.446180459998;10088.293819432569;10103.747406314755;10120;9863.75340616476;10031.69904902854;10036.498175091216;10042.14641755459;10080.3951762893;7411.925880741232;7733.806654833674;7742.845143097219;7753.961230193813;7764.378849692124;5310.8141215057285;5435;5548.833534993957;5551.805686800943;5574.554035677217;8878;8912.86630700527;8993.55032248372;9158.95420522893;9222.907770922206;9117.544122793966;9224.40041397307;9252;9396.67111651089;9416.576254068446;7649.617519124133;7701.345385642283;7767.262110099531;7786.844263114593;7814.921850781419;7630.618469076635;7776.766696999099;7853.09689385728;7859.253370929752;7873.448858580074;7071.718605983731;7081.327273908971;7102.3962963148715;7194.280571942764;7209.459290553124;6758.290379510081;6775;6825.965870170816;6863.6568171590225;6873.312668733287;6201.286852012076;6321.810345689637;6397.96801015992;6406.519511036784;6412.9679351602945;4853.587445067209;4960.627952903475;4964.10646083694;5022.623303252344;5132.666376685504;4759.476457589593;4973.825916093064;4998.875028124326;5020.472850350807;5168.3797944246935 03:35:25 INFO - PROCESS | 1647 | |4;prototype.html;356.31765169700026;365.3697372033243;367.4011361480786;368.9317476266889;373.22183247928086;4640.048789998051;4936.950630493694;5222.242774797652;5270.446603106671;5364.839054828357;4949.455559888418;5313.123334649778;5323.627346085776;5323.840284791453;5337.386200586933;3689.1330537323774;3765.2469506098755;4074.3684728866924;4084.4281800547797;4139.317012692917;4132.628063474287;4134.937975930364;4134.9531981669115;4161.230172418097;4177.95551112222;3632.6367363263726;4145.626893579577;4157.2955088413755;4171.749695018309;4192.909843440697;3058.143719758471;3326.7005969462866;3555.453377780661;3843.5195600549932;4131.6281534661875;2957.7190166934106;3437.6218615952225;3437.759356845022;3445.6554344565343;3452.602950660681;3691.907702307437;4025.9798701006603;4409.669274804402;4785.66500344976;5164.45773193813;3795.981020094882;3963.1677347757004;4535.614472769818;4571.6114130298965;4611.262198048296;5398.082326004549;5538.695371754552;5542.251796007568;5599.804006859781;5653.406392328829;3137.890173843927;3194.34515924235;3362.7309815214726;3816.1604447021887;3843.5772065073097;4911.828086017008;5413.648112872611;5462.399136095027;5880.7353669085;5940.37626049263;3594.7483676142942;3775.528058992626;3848.634379733921;3853.1523064925673;3861.1119442527815;3294.275259442919;3612.7832330060282;3901.2496190185852;3922;3947.8618248361167;4036.9394459083137;4048.736832105904;4578.748168850745;4589.013362127092;4619.5611416915335;5230.686158830483;5444.210589464506;5638.85902852432;5658.85852853682;5695.772169113189;1144.3191301175787;1200.5797970710182;1271.5486002469174;1283.4288741510125;1291.199456337077;818.8239528501401;818.9517417705347;864.8367945113938;870.9956450217709;871.507598207011;4751.40607424072;4903.190973489405;4962.419541838224;4966.528179822911;4973.776180071905;4350.434443522385;4556.908861822745;5019.247112933016;5233.4243233244315;5514.92458970505;4011.152082021739;4367.847125350598;4577.992841574848;4990.5508504234795;5035.446100928895;2479.727230004698;2532.7087384950964;2877.49643812332;2907.095852216801;2919.5913371468623;5330.013947419739;5700.4299570043495;5797.652140871562;5854.414558544111;5872.677002764889;5324.307839980777;5456.645318054314;5660.047458473836;5679.290088738908;5729.627574207663;4810.927836082462;5200.63595548308;5315.810513691754;5802.272931722084;5859.677717725481;4638.608417474677;4986.276989836381;5477.534409575231;5598.744953835033;5666.631668941505;4713.929291060568;4977.8257760978195;5071.264666623393;5167.302414174189;5363.329583802025;5532.3637781655625;5667.234923285304;5696.686682232517;5751.913721294268;5816.941830581809;3765.6610905017583;3800.1639639279315;3882.1071153634257;3887.3197190490987;3892.8637497687446;4871.710296160941;5204.8178313758835;5539.723013849372;5664.745086471036;5794.18881356602;4278.6790990676445;4834.468208496992;4887.437944636412;5487.259220005408;5604.590026288735;5044.369453818273;5501.972490137684;5531.944680553143;5576.553875689854;5589.832305030855;4381.130359614458;5036.320096787033;5218.956208758339;5445.918311225256;5465.699386533779;4239.152169566038;4672.995306009224;4957.694671977548;5291.708956007456;5303.298953624946;4294.9355759663;4413.646908247269;4705.976470117765;5084.745762711924;5375.704336261543 03:35:25 INFO - PROCESS | 1647 | |5;yui.html;625.7797295274214;760.893474913512;776.8523980443715;799.6561478564215;823.2590668398443;13202.801957970614;15352.385904563818;15991.280392382341;15991.680166396665;16000.599985000381;14702.191379474125;14875.776863347031;14888.553343399715;14892.329845156966;14908.18005009724;633.3349982518338;673.1955313400489;696.2897844198914;850.8681154421074;860.789106668867;716.247939663354;863.5984267315697;876.176394189463;876.8509353409936;879.5778026547262;875.2385424680505;901.8151278987824;903.6475774447971;907.3240435875249;912.1061359867334;894.9642014319419;898.9931276969836;899.0065977095281;902.5306840442998;904.3262769236895;663.1942190238883;669.0232260899094;671.1509939925998;672.0893189727897;672.2739441403272;12666.683332916658;15042.774358384633;15287.388504459808;15294.617634559114;15808.762868556982;13798.793018104736;14327.426902923871;14544.563663090124;14622.268886555736;14693.26533673312;11799.882001179963;11846.111541634413;11854.466549005316;11941;11956.58151964677;3350.329934013207;3683.686886614641;3778.981105094457;3931.056546428865;3947.3486874665646;89643;91976.54011729898;92332.53833730855;92711.53644231803;92962.53518732364;11228.719282018015;11279.830802537968;11383.54465821358;11436.097783191432;11440.771184576428;18280.81719182791;23319.88340058323;23451.41371465693;23452.179173728837;23546.411339716302;7835.511252861938;7875.330596899214;7916.802079947932;7997.400194985399;8093.109757926623;4174.686898482626;4262.786860656955;4277.294246449301;4300.182965236595;4406.537313582027;3564.7148228141687;3625.5286812714708;3633.563972323338;3653.3789255826573;3669.3762060449253;630.4578062865932;641.9518536109811;647.9254885688206;657.917760279965;661.0414898397247;600.6486205569703;615.4552074293556;654.3587284461255;676.7766637009775;694.6526736631685;7818.530888146729;8402.747917562483;8571.52856592881;9206.355555111211;10298;668.2582333609689;703.8451540661047;704.68993642797;706.5619316024098;734.3317581001263;4636.582707556337;4942.40691117061;4950.158473059589;4951.554360107608;4992.550670439678;66989.32526686872;84611.5769421149;94209.5289523548;95335;95440.56839147418;69772.3022769776;69828.06461450733;74748.62625686945;82663;82730;76025;76659;76723.61638191885;76941.84587231308;77640.22359776443;10889.836652450378;10965.61620343284;11043;11111.277766945122;11158.665240042812;37899.43150852684;47597.52402475931;51762.44712658626;53630.73184634052;54221;66164.01312742436;66504.66747666424;67976;68825.3117468819;69355.65322173356;11044.779104418027;11270.549178032787;11503.482343294405;11637.76724465523;13281.66795830112;12363.567275145686;12392.380380981096;12436.31600261958;12472.376381181086;14366.425342986582;13544.661383465493;13959.930200348934;13964.930175349058;13997;14203.786943195653;12503.87496125027;13389.799153012518;13499.595012149259;13610;13950.511732089328;13092.476300948236;13344.132631378929;13471.595852124452;13640.65898352549;13978.370973306022;9244.852626231403;10924.453777311262;10931.945340273247;11247.718807029889;11782.234154779911;9296.674616388664;10140.391576505433;10976.286541374786;10989.890101098887;11043.116550676088 03:35:25 INFO - PROCESS | 1647 | __end_tp_report 03:35:25 INFO - PROCESS | 1647 | __start_cc_report 03:35:25 INFO - PROCESS | 1647 | _x_x_mozilla_cycle_collect,382 03:35:25 INFO - PROCESS | 1647 | __end_cc_report 03:35:25 INFO - PROCESS | 1647 | __startTimestamp1485516925002__endTimestamp 03:35:25 INFO - PROCESS | 1647 | 03:35:25 INFO - PROCESS | 1647 | ------- Summary: start ------- 03:35:25 INFO - PROCESS | 1647 | Number of tests: 6 03:35:25 INFO - PROCESS | 1647 | 03:35:25 INFO - PROCESS | 1647 | [#0] dojo.html Cycles:165 Average:5574.48 Median:5709.20 stddev:660.94 (11.6%) stddev-sans-first:638.92 03:35:25 INFO - PROCESS | 1647 | Values: 3322.6 3333.9 3367.8 3378.9 3384.9 5326.0 5455.3 5519.9 5528.4 5595.9 5463.1 5477.3 5496.7 5505.6 5549.4 4953.3 5031.4 5074.0 5123.3 5134.3 5008.6 5176.1 5212.0 5223.6 5230.9 5011.5 5027.9 5148.3 5163.5 5175.0 5049.1 5071.8 5118.5 5150.0 5171.9 5020.5 5032.9 5063.2 5072.2 5117.6 5378.4 5414.1 5513.8 5587.1 5601.2 5950.3 5988.5 6046.1 6076.5 6121.3 6154.1 6170.0 6181.5 6196.6 6200.8 4622.4 5616.1 5656.3 5657.7 5664.3 5004.9 6180.5 6200.4 6220.3 6255.5 4956.6 5288.8 5857.5 6032.2 6049.2 6106.2 6135.5 6153.9 6174.4 6314.9 5828.3 5864.9 6013.2 6093.5 6108.6 5054.0 6120.9 6148.0 6150.3 6156.2 4162.6 4731.8 4811.8 4840.4 4882.7 3560.8 4286.8 4303.1 4329.1 4360.5 4696.6 5629.7 5670.1 5709.2 5744.3 5069.4 6111.5 6188.3 6191.6 6214.5 5485.0 5543.6 5616.8 5665.4 5705.8 5245.3 5249.6 5290.5 5305.9 5342.8 6257.7 6271.8 6274.3 6278.3 6296.2 6023.6 6192.7 6199.0 6211.6 6223.0 6199.5 6203.2 6228.4 6266.7 6278.3 6017.4 6076.0 6099.8 6099.8 6141.5 6133.2 6145.5 6184.9 6213.8 6221.1 5670.2 5766.2 5904.1 5904.1 5935.8 5975.3 5981.1 5984.0 5990.1 6021.8 4855.2 5662.7 5694.0 5751.5 5798.5 5891.6 5893.0 5941.9 5972.7 6015.6 5756.4 5782.2 5794.5 5809.1 5821.4 03:35:25 INFO - PROCESS | 1647 | 03:35:25 INFO - PROCESS | 1647 | [#1] ext.html Cycles:180 Average:57162.75 Median:33789.66 stddev:115559.71 (342.0%) stddev-sans-first:115805.52 03:35:25 INFO - PROCESS | 1647 | Values: 478.3 488.6 500.0 512.0 534.7 31639.4 35639.5 36738.9 39240.8 39303.8 38416.0 38836.4 38895.8 38899.0 39008.8 7913.3 8992.5 9231.2 10613.6 11051.6 9105.5 9476.4 9791.8 10826.9 10841.4 10725.2 10772.7 10774.2 10777.1 10812.2 10764.5 10806.1 10814.5 10835.1 10846.8 10635.5 10706.9 10727.4 10737.1 10756.8 35161.6 35345.1 35755.5 35769.3 35794.8 3870.6 3905.3 3921.9 3924.8 3947.1 27117.9 27456.6 27472.3 27605.6 27830.4 3103.7 3158.9 3179.5 3197.3 3238.5 367510.0 370356.0 370814.0 372069.0 372550.0 52167.7 52448.7 52485.5 52554.7 52611.5 54703.0 54887.0 55084.4 55180.7 55386.2 14995.6 15030.5 15083.4 15201.5 15240.9 10702.7 10974.0 11630.2 11861.5 11875.8 16938.0 16966.5 16996.0 17043.4 17109.3 7046.4 7247.5 7703.2 7823.8 7894.8 855.1 863.9 868.7 871.3 888.3 29097.4 29143.7 29247.9 29399.9 29552.6 1028.7 1041.2 1053.1 1054.1 1117.2 14676.9 15140.5 15251.7 15529.3 15573.1 491349.0 502712.0 503229.0 504720.0 507194.0 55881.7 56432.9 56455.0 56477.4 56617.7 1348.5 1410.0 1414.2 1421.8 1422.3 27796.6 28227.9 29169.5 29322.4 29326.6 47314.1 47480.0 47825.0 47891.0 47980.0 420127.0 420831.0 423217.3 426618.0 427546.0 22373.4 22929.1 25341.5 25676.0 25722.6 42730.8 47219.3 47415.0 47511.8 48067.0 31579.2 32140.5 33954.5 37058.0 37688.0 29794.9 33624.8 35646.8 36396.0 37776.0 33022.2 33182.2 34165.0 35844.5 36675.3 20924.3 26051.3 27107.9 29490.9 31624.1 19835.9 23109.0 23427.5 23699.3 24079.8 03:35:25 INFO - PROCESS | 1647 | 03:35:25 INFO - PROCESS | 1647 | [#2] jquery.html Cycles:180 Average:34527.59 Median:21154.95 stddev:97336.21 (460.1%) stddev-sans-first:97575.41 03:35:25 INFO - PROCESS | 1647 | Values: 338.9 383.7 395.6 403.7 420.1 18611.6 19164.6 20535.2 20762.9 20773.5 10807.8 12363.9 12386.3 12439.3 19566.7 5109.5 5295.1 5409.2 5420.2 5422.6 3889.6 5300.8 5426.7 6004.9 6052.5 6116.1 6123.3 6123.4 6140.1 6145.5 6213.5 6582.4 6630.5 6646.9 6648.1 5751.4 5854.3 5865.5 5866.7 5934.5 13269.0 13337.9 13353.3 13368.1 21839.1 2560.9 2605.4 2606.0 2613.9 2641.8 9623.5 10107.6 10321.4 10524.4 10530.4 1840.7 1842.6 1843.0 1858.7 1861.6 103333.0 125585.0 128433.0 130521.0 132685.3 10928.7 11355.7 11785.8 11848.6 11879.9 13926.9 15446.4 15467.8 15493.0 15508.1 7321.0 8311.9 8585.8 8610.7 8709.5 8077.8 8255.5 8266.6 8389.9 8410.0 3825.7 4696.4 4785.1 4864.2 4875.4 1428.4 1675.1 1769.8 1989.5 1992.8 987.0 994.6 996.2 1003.7 1049.8 19978.7 20462.5 21043.7 21076.9 21233.0 824.9 1022.9 1044.9 1052.5 1058.7 10474.5 10851.3 10991.2 11027.9 11036.4 487787.0 560707.0 603552.0 610559.0 611367.0 135867.3 140704.3 142745.0 148781.0 148971.3 955.9 1032.6 1037.0 1050.8 1057.8 14721.6 15145.9 16698.5 18462.3 18482.0 57669.2 57812.4 58167.7 58817.0 59010.0 79240.4 82583.0 83603.0 83760.6 83898.6 5129.9 5285.6 6663.6 6989.7 7331.0 9248.6 12446.6 12609.3 12825.9 12847.0 10347.9 10435.5 10751.9 10836.9 10976.5 5187.0 7605.0 9398.9 9783.6 10442.2 10682.0 10694.6 10734.1 10914.9 10974.3 7151.9 7154.6 7307.8 7456.8 7476.8 9088.5 10635.5 10682.4 10728.7 10767.2 03:35:25 INFO - PROCESS | 1647 | 03:35:25 INFO - PROCESS | 1647 | [#3] mootools.html Cycles:180 Average:5430.85 Median:5214.54 stddev:2979.92 (57.1%) stddev-sans-first:2972.91 03:35:25 INFO - PROCESS | 1647 | Values: 1403.9 1420.1 1423.6 1427.7 1445.2 7436.6 7538.6 7807.9 8491.2 8518.7 8020.7 8293.8 8324.8 8340.2 8398.5 2238.5 2286.2 2287.9 2289.8 2289.9 2016.6 2065.9 2258.2 2295.2 2300.3 1799.3 2264.7 2271.1 2272.5 2284.7 2258.1 2263.6 2288.9 2293.9 2294.3 2251.1 2257.2 2275.4 2299.2 2327.0 8441.9 8469.7 8491.5 8491.7 8498.4 5081.5 5199.6 5229.5 5248.9 5254.4 3496.3 3504.8 3522.4 3545.6 3584.5 1206.5 1217.6 1244.6 1257.9 1263.4 10725.0 10779.9 10786.0 10815.6 10850.6 3872.6 4324.0 4696.8 4718.7 4729.9 9467.7 9562.7 9572.3 9582.0 9589.0 5243.0 5243.0 5243.9 5252.9 5289.6 4655.7 4718.8 4764.8 4826.1 4826.3 3770.6 3807.3 3854.9 3904.7 3939.1 1001.5 1027.8 1202.7 1253.0 1254.9 612.0 719.9 786.7 801.2 840.9 5086.5 5648.7 5733.3 5776.1 6012.4 776.1 872.6 927.3 971.7 996.7 3666.2 3672.5 3675.4 3780.8 3813.2 9757.5 10069.4 10088.3 10103.7 10120.0 9863.8 10031.7 10036.5 10042.1 10080.4 7411.9 7733.8 7742.8 7754.0 7764.4 5310.8 5435.0 5548.8 5551.8 5574.6 8878.0 8912.9 8993.6 9159.0 9222.9 9117.5 9224.4 9252.0 9396.7 9416.6 7649.6 7701.3 7767.3 7786.8 7814.9 7630.6 7776.8 7853.1 7859.3 7873.4 7071.7 7081.3 7102.4 7194.3 7209.5 6758.3 6775.0 6826.0 6863.7 6873.3 6201.3 6321.8 6398.0 6406.5 6413.0 4853.6 4960.6 4964.1 5022.6 5132.7 4759.5 4973.8 4998.9 5020.5 5168.4 03:35:25 INFO - PROCESS | 1647 | 03:35:25 INFO - PROCESS | 1647 | [#4] prototype.html Cycles:180 Average:4359.87 Median:4822.70 stddev:1342.87 (27.8%) stddev-sans-first:1312.58 03:35:25 INFO - PROCESS | 1647 | Values: 356.3 365.4 367.4 368.9 373.2 4640.0 4937.0 5222.2 5270.4 5364.8 4949.5 5313.1 5323.6 5323.8 5337.4 3689.1 3765.2 4074.4 4084.4 4139.3 4132.6 4134.9 4135.0 4161.2 4178.0 3632.6 4145.6 4157.3 4171.7 4192.9 3058.1 3326.7 3555.5 3843.5 4131.6 2957.7 3437.6 3437.8 3445.7 3452.6 3691.9 4026.0 4409.7 4785.7 5164.5 3796.0 3963.2 4535.6 4571.6 4611.3 5398.1 5538.7 5542.3 5599.8 5653.4 3137.9 3194.3 3362.7 3816.2 3843.6 4911.8 5413.6 5462.4 5880.7 5940.4 3594.7 3775.5 3848.6 3853.2 3861.1 3294.3 3612.8 3901.2 3922.0 3947.9 4036.9 4048.7 4578.7 4589.0 4619.6 5230.7 5444.2 5638.9 5658.9 5695.8 1144.3 1200.6 1271.5 1283.4 1291.2 818.8 819.0 864.8 871.0 871.5 4751.4 4903.2 4962.4 4966.5 4973.8 4350.4 4556.9 5019.2 5233.4 5514.9 4011.2 4367.8 4578.0 4990.6 5035.4 2479.7 2532.7 2877.5 2907.1 2919.6 5330.0 5700.4 5797.7 5854.4 5872.7 5324.3 5456.6 5660.0 5679.3 5729.6 4810.9 5200.6 5315.8 5802.3 5859.7 4638.6 4986.3 5477.5 5598.7 5666.6 4713.9 4977.8 5071.3 5167.3 5363.3 5532.4 5667.2 5696.7 5751.9 5816.9 3765.7 3800.2 3882.1 3887.3 3892.9 4871.7 5204.8 5539.7 5664.7 5794.2 4278.7 4834.5 4887.4 5487.3 5604.6 5044.4 5502.0 5531.9 5576.6 5589.8 4381.1 5036.3 5219.0 5445.9 5465.7 4239.2 4673.0 4957.7 5291.7 5303.3 4294.9 4413.6 4706.0 5084.7 5375.7 03:35:25 INFO - PROCESS | 1647 | 03:35:25 INFO - PROCESS | 1647 | [#5] yui.html Cycles:180 Average:19213.70 Median:26117.05 stddev:26280.84 (100.6%) stddev-sans-first:26317.50 03:35:25 INFO - PROCESS | 1647 | Values: 625.8 760.9 776.9 799.7 823.3 13202.8 15352.4 15991.3 15991.7 16000.6 14702.2 14875.8 14888.6 14892.3 14908.2 633.3 673.2 696.3 850.9 860.8 716.2 863.6 876.2 876.9 879.6 875.2 901.8 903.6 907.3 912.1 895.0 899.0 899.0 902.5 904.3 663.2 669.0 671.2 672.1 672.3 12666.7 15042.8 15287.4 15294.6 15808.8 13798.8 14327.4 14544.6 14622.3 14693.3 11799.9 11846.1 11854.5 11941.0 11956.6 3350.3 3683.7 3779.0 3931.1 3947.3 89643.0 91976.5 92332.5 92711.5 92962.5 11228.7 11279.8 11383.5 11436.1 11440.8 18280.8 23319.9 23451.4 23452.2 23546.4 7835.5 7875.3 7916.8 7997.4 8093.1 4174.7 4262.8 4277.3 4300.2 4406.5 3564.7 3625.5 3633.6 3653.4 3669.4 630.5 642.0 647.9 657.9 661.0 600.6 615.5 654.4 676.8 694.7 7818.5 8402.7 8571.5 9206.4 10298.0 668.3 703.8 704.7 706.6 734.3 4636.6 4942.4 4950.2 4951.6 4992.6 66989.3 84611.6 94209.5 95335.0 95440.6 69772.3 69828.1 74748.6 82663.0 82730.0 76025.0 76659.0 76723.6 76941.8 77640.2 10889.8 10965.6 11043.0 11111.3 11158.7 37899.4 47597.5 51762.4 53630.7 54221.0 66164.0 66504.7 67976.0 68825.3 69355.7 11044.8 11270.5 11503.5 11637.8 13281.7 12363.6 12392.4 12436.3 12472.4 14366.4 13544.7 13959.9 13964.9 13997.0 14203.8 12503.9 13389.8 13499.6 13610.0 13950.5 13092.5 13344.1 13471.6 13640.7 13978.4 9244.9 10924.5 10931.9 11247.7 11782.2 9296.7 10140.4 10976.3 10989.9 11043.1 03:35:25 INFO - PROCESS | 1647 | -------- Summary: end -------- 03:35:25 INFO - PROCESS | 1647 | 03:35:25 INFO - TEST-INFO | 1647: exit 0 03:35:25 INFO - TEST-OK | dromaeo_css | took 1115891ms 03:35:25 INFO - TEST-START | kraken 03:35:25 INFO - operating with platform_type : mac_ 03:35:25 INFO - Initialising browser for kraken test... 03:35:25 INFO - TEST-INFO | started process 1677 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/tmpu3wW9v/profile http://localhost:49249/getInfo.html) 03:35:28 INFO - PROCESS | 1677 | 2017-01-27 03:35:28.699 plugin-container[1678:11110] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 03:35:28 INFO - PROCESS | 1677 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:35:28 INFO - PROCESS | 1677 | 2017-01-27 03:35:28.702 plugin-container[1678:11110] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0b, name = 'com.apple.CFPasteboardClient' 03:35:28 INFO - PROCESS | 1677 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:35:28 INFO - PROCESS | 1677 | 2017-01-27 03:35:28.702 plugin-container[1678:11110] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:35:28 INFO - PROCESS | 1677 | __metrics Screen width/height:1600/1200 03:35:28 INFO - PROCESS | 1677 | colorDepth:24 03:35:28 INFO - PROCESS | 1677 | Browser inner width/height: 1024/689 03:35:28 INFO - PROCESS | 1677 | __metrics 03:35:28 ERROR - PROCESS | 1677 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3279: TypeError: this.tabs is undefined 03:35:29 INFO - TEST-INFO | 1677: exit 0 03:35:29 INFO - Browser initialized. 03:35:29 INFO - Running cycle 1/1 for kraken test... 03:35:29 INFO - TEST-INFO | started process 1679 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/tmpu3wW9v/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:35:32 INFO - PROCESS | 1679 | 2017-01-27 03:35:32.103 plugin-container[1680:11228] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x863f, name = 'com.apple.tsm.portname' 03:35:32 INFO - PROCESS | 1679 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:35:32 INFO - PROCESS | 1679 | 2017-01-27 03:35:32.104 plugin-container[1680:11228] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 03:35:32 INFO - PROCESS | 1679 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:35:32 INFO - PROCESS | 1679 | 2017-01-27 03:35:32.104 plugin-container[1680:11228] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:35:41 INFO - PROCESS | 1679 | RSS: Main: 233361408 03:35:41 INFO - PROCESS | 1679 | 03:35:45 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-beat-detection) 03:35:45 INFO - PROCESS | 1679 | RSS: Main: 246927360 03:35:45 INFO - PROCESS | 1679 | 03:35:48 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-dft) 03:35:49 INFO - PROCESS | 1679 | RSS: Main: 254898176 03:35:49 INFO - PROCESS | 1679 | 03:35:52 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-fft) 03:35:53 INFO - PROCESS | 1679 | RSS: Main: 257589248 03:35:53 INFO - PROCESS | 1679 | 03:35:55 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-oscillator) 03:35:55 INFO - PROCESS | 1679 | RSS: Main: 258834432 03:35:55 INFO - PROCESS | 1679 | 03:35:57 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:35:58 INFO - PROCESS | 1679 | RSS: Main: 260460544 03:35:58 INFO - PROCESS | 1679 | 03:36:01 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-darkroom) 03:36:02 INFO - PROCESS | 1679 | RSS: Main: 265957376 03:36:02 INFO - PROCESS | 1679 | 03:36:05 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-desaturate) 03:36:06 INFO - PROCESS | 1679 | RSS: Main: 265322496 03:36:06 INFO - PROCESS | 1679 | 03:36:08 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49249/tests/kraken/driver.html?testName=json-parse-financial) 03:36:09 INFO - PROCESS | 1679 | RSS: Main: 264986624 03:36:09 INFO - PROCESS | 1679 | 03:36:11 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49249/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:36:11 INFO - PROCESS | 1679 | RSS: Main: 264634368 03:36:11 INFO - PROCESS | 1679 | 03:36:13 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:36:14 INFO - PROCESS | 1679 | RSS: Main: 264667136 03:36:14 INFO - PROCESS | 1679 | 03:36:16 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:36:17 INFO - PROCESS | 1679 | RSS: Main: 264806400 03:36:17 INFO - PROCESS | 1679 | 03:36:20 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:36:21 INFO - PROCESS | 1679 | RSS: Main: 265326592 03:36:21 INFO - PROCESS | 1679 | 03:36:24 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:36:24 INFO - PROCESS | 1679 | RSS: Main: 264937472 03:36:24 INFO - PROCESS | 1679 | 03:36:26 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49249/tests/kraken/driver.html?testName=ai-astar) 03:36:26 INFO - PROCESS | 1679 | RSS: Main: 266850304 03:36:26 INFO - PROCESS | 1679 | 03:36:26 INFO - PROCESS | 1679 | __start_tp_report 03:36:26 INFO - PROCESS | 1679 | _x_x_mozilla_page_load 03:36:26 INFO - PROCESS | 1679 | _x_x_mozilla_page_load_details 03:36:26 INFO - PROCESS | 1679 | |i|pagename|runs| 03:36:26 INFO - PROCESS | 1679 | |0;ai-astar;122;162;120;118;119;120;130;171;116;118 03:36:26 INFO - PROCESS | 1679 | |1;audio-beat-detection;146;114;114;150;113;114;111;113;130;113 03:36:26 INFO - PROCESS | 1679 | |2;audio-dft;239;275;281;198;198;197;194;200;193;191 03:36:26 INFO - PROCESS | 1679 | |3;audio-fft;81;57;60;85;61;61;59;60;75;89 03:36:26 INFO - PROCESS | 1679 | |4;audio-oscillator;89;71;87;74;70;71;68;87;104;115 03:36:26 INFO - PROCESS | 1679 | |5;imaging-gaussian-blur;92;73;139;136;90;81;82;117;137;118 03:36:26 INFO - PROCESS | 1679 | |6;imaging-darkroom;154;91;106;144;132;110;99;148;131;95 03:36:26 INFO - PROCESS | 1679 | |7;imaging-desaturate;90;96;104;93;94;92;91;91;90;90 03:36:26 INFO - PROCESS | 1679 | |8;json-parse-financial;62;60;60;62;62;63;63;66;98;61 03:36:26 INFO - PROCESS | 1679 | |9;json-stringify-tinderbox;69;69;67;85;79;77;69;70;72;72 03:36:26 INFO - PROCESS | 1679 | |10;stanford-crypto-aes;97;113;84;83;97;75;95;109;112;113 03:36:26 INFO - PROCESS | 1679 | |11;stanford-crypto-ccm;177;162;134;156;157;160;163;163;194;169 03:36:26 INFO - PROCESS | 1679 | |12;stanford-crypto-pbkdf2;172;180;177;174;179;178;178;176;177;177 03:36:26 INFO - PROCESS | 1679 | |13;stanford-crypto-sha256-iterative;71;82;80;91;92;91;65;75;95;92 03:36:26 INFO - PROCESS | 1679 | __end_tp_report 03:36:26 INFO - PROCESS | 1679 | __start_cc_report 03:36:26 INFO - PROCESS | 1679 | _x_x_mozilla_cycle_collect,926 03:36:26 INFO - PROCESS | 1679 | __end_cc_report 03:36:26 INFO - PROCESS | 1679 | __startTimestamp1485516986932__endTimestamp 03:36:26 INFO - PROCESS | 1679 | 03:36:26 INFO - PROCESS | 1679 | ------- Summary: start ------- 03:36:26 INFO - PROCESS | 1679 | Number of tests: 14 03:36:26 INFO - PROCESS | 1679 | 03:36:26 INFO - PROCESS | 1679 | [#0] ai-astar Cycles:10 Average:129.60 Median:121.00 stddev:19.92 (16.5%) stddev-sans-first:20.94 03:36:26 INFO - PROCESS | 1679 | Values: 122.0 162.0 120.0 118.0 119.0 120.0 130.0 171.0 116.0 118.0 03:36:26 INFO - PROCESS | 1679 | 03:36:26 INFO - PROCESS | 1679 | [#1] audio-beat-detection Cycles:10 Average:121.80 Median:114.00 stddev:14.83 (13.0%) stddev-sans-first:12.89 03:36:26 INFO - PROCESS | 1679 | Values: 146.0 114.0 114.0 150.0 113.0 114.0 111.0 113.0 130.0 113.0 03:36:26 INFO - PROCESS | 1679 | 03:36:26 INFO - PROCESS | 1679 | [#2] audio-dft Cycles:10 Average:216.60 Median:199.00 stddev:35.17 (17.7%) stddev-sans-first:36.36 03:36:26 INFO - PROCESS | 1679 | Values: 239.0 275.0 281.0 198.0 198.0 197.0 194.0 200.0 193.0 191.0 03:36:26 INFO - PROCESS | 1679 | 03:36:26 INFO - PROCESS | 1679 | [#3] audio-fft Cycles:10 Average:68.80 Median:68.00 stddev:12.34 (18.1%) stddev-sans-first:12.27 03:36:26 INFO - PROCESS | 1679 | Values: 81.0 57.0 60.0 85.0 61.0 61.0 59.0 60.0 75.0 89.0 03:36:26 INFO - PROCESS | 1679 | 03:36:26 INFO - PROCESS | 1679 | [#4] audio-oscillator Cycles:10 Average:83.60 Median:72.50 stddev:15.96 (22.0%) stddev-sans-first:16.81 03:36:26 INFO - PROCESS | 1679 | Values: 89.0 71.0 87.0 74.0 70.0 71.0 68.0 87.0 104.0 115.0 03:36:26 INFO - PROCESS | 1679 | 03:36:26 INFO - PROCESS | 1679 | [#5] imaging-gaussian-blur Cycles:10 Average:106.50 Median:77.00 stddev:25.72 (33.4%) stddev-sans-first:26.74 03:36:26 INFO - PROCESS | 1679 | Values: 92.0 73.0 139.0 136.0 90.0 81.0 82.0 117.0 137.0 118.0 03:36:26 INFO - PROCESS | 1679 | 03:36:26 INFO - PROCESS | 1679 | [#6] imaging-darkroom Cycles:10 Average:121.00 Median:151.00 stddev:23.51 (15.6%) stddev-sans-first:21.69 03:36:26 INFO - PROCESS | 1679 | Values: 154.0 91.0 106.0 144.0 132.0 110.0 99.0 148.0 131.0 95.0 03:36:26 INFO - PROCESS | 1679 | 03:36:26 INFO - PROCESS | 1679 | [#7] imaging-desaturate Cycles:10 Average:93.10 Median:91.50 stddev:4.31 (4.7%) stddev-sans-first:4.42 03:36:26 INFO - PROCESS | 1679 | Values: 90.0 96.0 104.0 93.0 94.0 92.0 91.0 91.0 90.0 90.0 03:36:26 INFO - PROCESS | 1679 | 03:36:26 INFO - PROCESS | 1679 | [#8] json-parse-financial Cycles:10 Average:65.70 Median:62.50 stddev:11.48 (18.4%) stddev-sans-first:12.10 03:36:26 INFO - PROCESS | 1679 | Values: 62.0 60.0 60.0 62.0 62.0 63.0 63.0 66.0 98.0 61.0 03:36:26 INFO - PROCESS | 1679 | 03:36:26 INFO - PROCESS | 1679 | [#9] json-stringify-tinderbox Cycles:10 Average:72.90 Median:72.00 stddev:5.69 (7.9%) stddev-sans-first:5.85 03:36:26 INFO - PROCESS | 1679 | Values: 69.0 69.0 67.0 85.0 79.0 77.0 69.0 70.0 72.0 72.0 03:36:26 INFO - PROCESS | 1679 | 03:36:26 INFO - PROCESS | 1679 | [#10] stanford-crypto-aes Cycles:10 Average:97.80 Median:83.50 stddev:13.85 (16.6%) stddev-sans-first:14.69 03:36:26 INFO - PROCESS | 1679 | Values: 97.0 113.0 84.0 83.0 97.0 75.0 95.0 109.0 112.0 113.0 03:36:26 INFO - PROCESS | 1679 | 03:36:26 INFO - PROCESS | 1679 | [#11] stanford-crypto-ccm Cycles:10 Average:163.50 Median:163.00 stddev:15.37 (9.4%) stddev-sans-first:15.51 03:36:26 INFO - PROCESS | 1679 | Values: 177.0 162.0 134.0 156.0 157.0 160.0 163.0 163.0 194.0 169.0 03:36:26 INFO - PROCESS | 1679 | 03:36:26 INFO - PROCESS | 1679 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.80 Median:177.50 stddev:2.35 (1.3%) stddev-sans-first:1.73 03:36:26 INFO - PROCESS | 1679 | Values: 172.0 180.0 177.0 174.0 179.0 178.0 178.0 176.0 177.0 177.0 03:36:26 INFO - PROCESS | 1679 | 03:36:26 INFO - PROCESS | 1679 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:83.40 Median:91.00 stddev:10.41 (11.4%) stddev-sans-first:10.02 03:36:26 INFO - PROCESS | 1679 | Values: 71.0 82.0 80.0 91.0 92.0 91.0 65.0 75.0 95.0 92.0 03:36:26 INFO - PROCESS | 1679 | -------- Summary: end -------- 03:36:26 INFO - PROCESS | 1679 | 03:36:27 INFO - TEST-INFO | 1679: exit 0 03:36:27 INFO - TEST-OK | kraken | took 62159ms 03:36:27 INFO - SUITE-END | took 1178s 03:36:28 INFO - Completed test suite (00:19:38) 03:36:28 INFO - javascript benchmark 03:36:28 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7043.514278300195, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3322.6012878454576, 3333.8999830005096, 3367.797932124074, 3378.949315760264, 3384.8646054157834, 5325.961437519685, 5455.290812194409, 5519.889602207953, 5528.419515950823, 5595.916061259084, 5463.098588732864, 5477.3427188737305, 5496.725163741807, 5505.587080968933, 5549.445055494449, 4953.257011448276, 5031.396232452111, 5074.0, 5123.2827404163445, 5134.281200631895, 5008.624353173508, 5176.120059589879, 5211.973940130294, 5223.6343455958095, 5230.8692282692855, 5011.548960593527, 5027.924581131286, 5148.304978827885, 5163.535281824635, 5174.97412512935, 5049.065922804256, 5071.771770270347, 5118.513741194581, 5149.974250128763, 5171.870703232412, 5020.523050310215, 5032.874178145539, 5063.240513922904, 5072.163093089635, 5117.641765076446, 5378.435264297271, 5414.052540805344, 5513.807016754395, 5587.1339942309005, 5601.215829783833, 5950.315713692894, 5988.520918326567, 6046.09308603713, 6076.544259180535, 6121.300552271608, 6154.138420621117, 6170.043643235305, 6181.536384771161, 6196.628202307831, 6200.751969921198, 4622.445306563201, 5616.1014237721765, 5656.321241451011, 5657.745401456944, 5664.348599911022, 5004.9499505005215, 6180.47465965398, 6200.3799620038535, 6220.25356957159, 6255.499560035278, 4956.603471722254, 5288.762005709752, 5857.502112320501, 6032.245969253843, 6049.213602231682, 6106.236720409949, 6135.478484328793, 6153.907691384633, 6174.413430724074, 6314.936850631527, 5828.300603927556, 5864.912026319608, 6013.218281623361, 6093.512518998469, 6108.633481991094, 5053.974730126326, 6120.877582448326, 6148.047052706837, 6150.323464418911, 6156.168917196211, 4162.62536371728, 4731.787069581877, 4811.759412029384, 4840.370751802314, 4882.731449770296, 3560.839762210707, 4286.849960251377, 4303.117860838521, 4329.09089091288, 4360.4767427908855, 4696.647751418657, 5629.7185140742795, 5670.064439367458, 5709.200711900337, 5744.339400968942, 5069.366329208849, 6111.480524155497, 6188.2883468400805, 6191.5665903387235, 6214.533909956681, 5485.0, 5543.556515478752, 5616.775328986795, 5665.3768085510565, 5705.800296989669, 5245.26566280721, 5249.632525723239, 5290.470952904679, 5305.867353316198, 5342.786288548492, 6257.7184026718905, 6271.843203919939, 6274.278457977391, 6278.340774218641, 6296.150019747459, 6023.6385816851125, 6192.659403732838, 6198.969005154945, 6211.596246243979, 6222.968885155546, 6199.504039676905, 6203.16257305258, 6228.4394404502, 6266.655333956676, 6278.340774218641, 6017.38800417481, 6075.997460418981, 6099.786507472217, 6099.84750381244, 6141.508679305734, 6133.172021777003, 6145.476426095726, 6184.886720390373, 6213.751449941951, 6221.06683997404, 5670.177824215548, 5766.163906233656, 5904.114382842608, 5904.114382842608, 5935.792247271324, 5975.312839023568, 5981.0729336952845, 5983.970080149571, 5990.071538911476, 6021.789237376671, 4855.174620314626, 5662.716864156858, 5693.971530142323, 5751.4536119068625, 5798.478136967693, 5891.558133139946, 5893.0, 5941.851453713692, 5972.731227094878, 6015.578909476295, 5756.395578464369, 5782.1615865698395, 5794.5364370851075, 5809.128630705428, 5821.417858214314], "value": 5533.782917681333, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [478.3303375274615, 488.5969075512703, 499.97000179989203, 512.004151924507, 534.7219445888136, 31639.367212655732, 35639.46540801887, 36738.89783306502, 39240.822775316716, 39303.82088537348, 38416.03959901004, 38836.41745373815, 38895.805520972426, 38899.0, 39008.80495597518, 7913.327367173797, 8992.460452372863, 9231.215346695539, 10613.628523001697, 11051.557937682483, 9105.499197544132, 9476.43141411514, 9791.755206119833, 10826.945865270702, 10841.403722795243, 10725.249232553724, 10772.73068173294, 10774.191935604822, 10777.137828973664, 10812.189085818556, 10764.515596798163, 10806.135509159249, 10814.513346899408, 10835.133189344833, 10846.783064338748, 10635.46822658864, 10706.946465267623, 10727.409992450412, 10737.087347575465, 10756.784864302748, 35161.64838351635, 35345.11637209065, 35755.463668045, 35769.28461430783, 35794.82102589496, 3870.593587673282, 3905.297046531623, 3921.901952451183, 3924.7841368724708, 3947.131631041166, 27117.915283388837, 27456.588151177748, 27472.313192170157, 27605.585916211272, 27830.443391132063, 3103.674114217997, 3158.936821263585, 3179.459491886385, 3197.328561002169, 3238.5142228665654, 367509.9999999973, 370356.0, 370814.0, 372069.0, 372550.0, 52167.73916130395, 52448.73775631098, 52485.475145248056, 52554.73722631439, 52611.473885261425, 54703.0, 54887.0, 55084.44915550874, 55180.724096379265, 55386.16920746192, 14995.550133496012, 15030.473933412279, 15083.396664133532, 15201.543953681406, 15240.923795380952, 10702.73243168927, 10974.012338889539, 11630.244034137753, 11861.46623401949, 11875.821862672066, 16938.0, 16966.491005269614, 16996.0, 17043.40348087811, 17109.28907109304, 7046.401055910305, 7247.528910620793, 7703.189750408649, 7823.843523129505, 7894.802629934297, 855.0936007831718, 863.8536810592589, 868.7480630617176, 871.263782104121, 888.320434867327, 29097.418051638848, 29143.708562914522, 29247.85376073106, 29399.853000735286, 29552.556711649344, 1028.6862506935306, 1041.2242879055152, 1053.089077947585, 1054.1092776603757, 1117.1621284036971, 14676.853231467761, 15140.545783626509, 15251.694966100617, 15529.301181446863, 15573.065616062846, 491349.0, 502712.0, 503229.0, 504720.0, 507194.0, 55881.720591396785, 56432.87134257292, 56455.0, 56477.43522564722, 56617.716911416006, 1348.480834878579, 1409.985900141006, 1414.2221778022047, 1421.8151640286696, 1422.3386125451714, 27796.58305125384, 28227.858860706387, 29169.53970246104, 29322.413551729274, 29326.56010159892, 47314.053718926116, 47480.0, 47825.0, 47891.0, 47980.0, 420127.0, 420831.0, 423217.2587056045, 426618.0, 427546.0, 22373.44066398288, 22929.082836687012, 25341.493170136862, 25676.0, 25722.61416078723, 42730.78446909643, 47219.29171062505, 47415.0, 47511.76244118757, 48067.0, 31579.21051973719, 32140.517892231168, 33954.49068263928, 37058.0, 37688.0, 29794.851025744734, 33624.831875841446, 35646.82176589101, 36396.0, 37776.0, 33022.17444563905, 33182.17044573905, 34165.0, 35844.462333065545, 36675.26649467049, 20924.267650632155, 26051.348716282246, 27107.86446067757, 29490.852545737132, 31624.051278461684, 19835.900820496383, 23108.96009679602, 23427.531449370577, 23699.2890213287, 24079.759202407753], "value": 18752.52425507237, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [338.92713066690663, 383.7122158381214, 395.5522786601041, 403.66899142702994, 420.13033021645185, 18611.627767444643, 19164.61670766584, 20535.178592856286, 20762.896185519072, 20773.480662983435, 10807.83788243177, 12363.93818030911, 12386.318752468611, 12439.253644781296, 19566.70649940252, 5109.463506331837, 5295.073362161606, 5409.188621706756, 5420.186971954199, 5422.566194704434, 3889.630485103911, 5300.840974770744, 5426.701531415771, 6004.939950600504, 6052.546059045567, 6116.113163591277, 6123.326434092246, 6123.357047510014, 6140.07898815179, 6145.5390845686525, 6213.533984951124, 6582.440492558138, 6630.502712296597, 6646.900296495556, 6648.102506161679, 5751.396103409165, 5854.326752423483, 5865.501432378253, 5866.677332746697, 5934.465898069151, 13269.0, 13337.86662133376, 13353.332333383292, 13368.064235503518, 21839.126434942584, 2560.9487810243877, 2605.3747100695887, 2606.0, 2613.9215823525324, 2641.828281161738, 9623.518824058769, 10107.64623238183, 10321.380717156995, 10524.421156836448, 10530.42082685452, 1840.6870831958465, 1842.5577861313188, 1842.9539261518435, 1858.7026075827803, 1861.5718384771444, 103332.96667033309, 125585.0, 128433.0, 130521.0, 132685.33657331747, 10928.6667566393, 11355.716107097389, 11785.764284714429, 11848.644540663794, 11879.940600296943, 13926.860731392557, 15446.382144714085, 15467.845321546865, 15493.0, 15508.06951582886, 7320.975063491115, 8311.916880831235, 8585.785355366166, 8610.698625548075, 8709.520976346235, 8077.7576672699915, 8255.463394879314, 8266.586670666442, 8389.916100839035, 8410.0, 3825.6556909878245, 4696.436427628638, 4785.114753770564, 4864.221724524059, 4875.391665458962, 1428.3929330034694, 1675.1373042882924, 1769.7787776527935, 1989.4926793667812, 1992.8043174095717, 986.9851952220722, 994.6319861651235, 996.2428554298785, 1003.7039073473343, 1049.7585555322319, 19978.70031949522, 20462.488437789176, 21043.68434473484, 21076.894615526824, 21233.0, 824.8515267251832, 1022.882368527614, 1044.8693913260843, 1052.5211028981794, 1058.6612284069179, 10474.528646210938, 10851.294665846852, 10991.175661825395, 11027.944860275808, 11036.392998385005, 487787.0, 560707.0, 603552.0, 610559.0, 611367.0, 135867.32066339802, 140704.29647851695, 142745.0, 148781.0, 148971.25514372575, 955.8816185063631, 1032.5766435761302, 1036.9896301037045, 1050.8318669012922, 1057.8201705709894, 14721.631959200677, 15145.924270378577, 16698.499045028668, 18462.26150953947, 18482.0, 57669.233076692, 57812.421875782384, 58167.70916145561, 58817.0, 59010.0, 79240.415191697, 82583.0, 83603.0, 83760.58119709362, 83898.58050709707, 5129.87175320605, 5285.550728188147, 6663.600183988976, 6989.685464154227, 7331.0, 9248.583813728264, 12446.626601201617, 12609.30648814324, 12825.935870320589, 12847.0, 10347.91346908594, 10435.478226088817, 10751.892481075089, 10836.891631083588, 10976.451177441255, 5187.0, 7604.961975190088, 9398.859017114612, 9783.581380699901, 10442.216833737348, 10682.0, 10694.572217111228, 10734.087602553871, 10914.945425272823, 10974.286671366335, 7151.8569628608175, 7154.570725756471, 7307.7807665770115, 7456.7762967111075, 7476.813079673052, 9088.500132492776, 10635.521401536795, 10682.412467314372, 10728.678139655824, 10767.192460565333], "value": 9226.494239927666, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1403.8525954774748, 1420.0840457904649, 1423.5800438870533, 1427.7287315410072, 1445.190693211801, 7436.628168591569, 7538.585377804218, 7807.882881756778, 8491.23578877901, 8518.701845435404, 8020.67917283308, 8293.792655183623, 8324.83350332993, 8340.165983401657, 8398.538080405591, 2238.462768935456, 2286.1541229745017, 2287.8627282363027, 2289.782470665284, 2289.9084036638515, 2016.6168427998707, 2065.8863762493056, 2258.1757658454626, 2295.2425699519204, 2300.321405185466, 1799.2982736732686, 2264.6829443877787, 2271.1029143488313, 2272.5000499889998, 2284.7144106986625, 2258.06290389489, 2263.5586060718165, 2288.9084436622516, 2293.862368257893, 2294.265834932822, 2251.0545570860277, 2257.164849005862, 2275.3515248154363, 2299.2297580310615, 2326.953460930772, 8441.915580844174, 8469.74590762278, 8491.532965686885, 8491.702790402307, 8498.362622803254, 5081.517255860687, 5199.558037566811, 5229.5293423591675, 5248.8687782805355, 5254.369475662907, 3496.2657841853243, 3504.842282097312, 3522.3659741246565, 3545.5745310562897, 3584.4623306503972, 1206.523423247821, 1217.6286232699024, 1244.6203907808115, 1257.9119461637688, 1263.444084602772, 10725.034746872741, 10779.892201077968, 10786.029257366874, 10815.621453249178, 10850.620228291971, 3872.593377695358, 4323.956760432356, 4696.825793551612, 4718.669693121448, 4729.881752956135, 9467.66863159786, 9562.713118606453, 9572.329936904489, 9582.041795820362, 9588.993155718688, 5242.97378513105, 5243.0, 5243.947560524346, 5252.894942101137, 5289.603279754034, 4655.74393408359, 4718.7640617969655, 4764.809407623726, 4826.0830442215865, 4826.348442960156, 3770.5852356240794, 3807.333716599584, 3854.8650797221962, 3904.668103211202, 3939.0546268895832, 1001.5142655811925, 1027.8406846938738, 1202.699325168708, 1252.9976019184617, 1254.8933340666147, 611.9571629985858, 719.8632259870608, 786.697121608185, 801.194799226785, 840.9369297302604, 5086.491350864884, 5648.729035966875, 5733.283339582552, 5776.133579963039, 6012.428819262163, 776.0570906348701, 872.5549969515592, 927.2952556057445, 971.7376308396694, 996.7408473796866, 3666.2300916807235, 3672.467492213614, 3675.4119340905327, 3780.7542509736777, 3813.237352529505, 9757.463339516395, 10069.446180459998, 10088.293819432569, 10103.747406314755, 10120.0, 9863.75340616476, 10031.69904902854, 10036.498175091216, 10042.14641755459, 10080.3951762893, 7411.925880741232, 7733.806654833674, 7742.845143097219, 7753.961230193813, 7764.378849692124, 5310.8141215057285, 5435.0, 5548.833534993957, 5551.805686800943, 5574.554035677217, 8878.0, 8912.86630700527, 8993.55032248372, 9158.95420522893, 9222.907770922206, 9117.544122793966, 9224.40041397307, 9252.0, 9396.67111651089, 9416.576254068446, 7649.617519124133, 7701.345385642283, 7767.262110099531, 7786.844263114593, 7814.921850781419, 7630.618469076635, 7776.766696999099, 7853.09689385728, 7859.253370929752, 7873.448858580074, 7071.718605983731, 7081.327273908971, 7102.3962963148715, 7194.280571942764, 7209.459290553124, 6758.290379510081, 6775.0, 6825.965870170816, 6863.6568171590225, 6873.312668733287, 6201.286852012076, 6321.810345689637, 6397.96801015992, 6406.519511036784, 6412.9679351602945, 4853.587445067209, 4960.627952903475, 4964.10646083694, 5022.623303252344, 5132.666376685504, 4759.476457589593, 4973.825916093064, 4998.875028124326, 5020.472850350807, 5168.3797944246935], "value": 4379.504206317115, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [356.31765169700026, 365.3697372033243, 367.4011361480786, 368.9317476266889, 373.22183247928086, 4640.048789998051, 4936.950630493694, 5222.242774797652, 5270.446603106671, 5364.839054828357, 4949.455559888418, 5313.123334649778, 5323.627346085776, 5323.840284791453, 5337.386200586933, 3689.1330537323774, 3765.2469506098755, 4074.3684728866924, 4084.4281800547797, 4139.317012692917, 4132.628063474287, 4134.937975930364, 4134.9531981669115, 4161.230172418097, 4177.95551112222, 3632.6367363263726, 4145.626893579577, 4157.2955088413755, 4171.749695018309, 4192.909843440697, 3058.143719758471, 3326.7005969462866, 3555.453377780661, 3843.5195600549932, 4131.6281534661875, 2957.7190166934106, 3437.6218615952225, 3437.759356845022, 3445.6554344565343, 3452.602950660681, 3691.907702307437, 4025.9798701006603, 4409.669274804402, 4785.66500344976, 5164.45773193813, 3795.981020094882, 3963.1677347757004, 4535.614472769818, 4571.6114130298965, 4611.262198048296, 5398.082326004549, 5538.695371754552, 5542.251796007568, 5599.804006859781, 5653.406392328829, 3137.890173843927, 3194.34515924235, 3362.7309815214726, 3816.1604447021887, 3843.5772065073097, 4911.828086017008, 5413.648112872611, 5462.399136095027, 5880.7353669085, 5940.37626049263, 3594.7483676142942, 3775.528058992626, 3848.634379733921, 3853.1523064925673, 3861.1119442527815, 3294.275259442919, 3612.7832330060282, 3901.2496190185852, 3922.0, 3947.8618248361167, 4036.9394459083137, 4048.736832105904, 4578.748168850745, 4589.013362127092, 4619.5611416915335, 5230.686158830483, 5444.210589464506, 5638.85902852432, 5658.85852853682, 5695.772169113189, 1144.3191301175787, 1200.5797970710182, 1271.5486002469174, 1283.4288741510125, 1291.199456337077, 818.8239528501401, 818.9517417705347, 864.8367945113938, 870.9956450217709, 871.507598207011, 4751.40607424072, 4903.190973489405, 4962.419541838224, 4966.528179822911, 4973.776180071905, 4350.434443522385, 4556.908861822745, 5019.247112933016, 5233.4243233244315, 5514.92458970505, 4011.152082021739, 4367.847125350598, 4577.992841574848, 4990.5508504234795, 5035.446100928895, 2479.727230004698, 2532.7087384950964, 2877.49643812332, 2907.095852216801, 2919.5913371468623, 5330.013947419739, 5700.4299570043495, 5797.652140871562, 5854.414558544111, 5872.677002764889, 5324.307839980777, 5456.645318054314, 5660.047458473836, 5679.290088738908, 5729.627574207663, 4810.927836082462, 5200.63595548308, 5315.810513691754, 5802.272931722084, 5859.677717725481, 4638.608417474677, 4986.276989836381, 5477.534409575231, 5598.744953835033, 5666.631668941505, 4713.929291060568, 4977.8257760978195, 5071.264666623393, 5167.302414174189, 5363.329583802025, 5532.3637781655625, 5667.234923285304, 5696.686682232517, 5751.913721294268, 5816.941830581809, 3765.6610905017583, 3800.1639639279315, 3882.1071153634257, 3887.3197190490987, 3892.8637497687446, 4871.710296160941, 5204.8178313758835, 5539.723013849372, 5664.745086471036, 5794.18881356602, 4278.6790990676445, 4834.468208496992, 4887.437944636412, 5487.259220005408, 5604.590026288735, 5044.369453818273, 5501.972490137684, 5531.944680553143, 5576.553875689854, 5589.832305030855, 4381.130359614458, 5036.320096787033, 5218.956208758339, 5445.918311225256, 5465.699386533779, 4239.152169566038, 4672.995306009224, 4957.694671977548, 5291.708956007456, 5303.298953624946, 4294.9355759663, 4413.646908247269, 4705.976470117765, 5084.745762711924, 5375.704336261543], "value": 3952.700429320123, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [625.7797295274214, 760.893474913512, 776.8523980443715, 799.6561478564215, 823.2590668398443, 13202.801957970614, 15352.385904563818, 15991.280392382341, 15991.680166396665, 16000.599985000381, 14702.191379474125, 14875.776863347031, 14888.553343399715, 14892.329845156966, 14908.18005009724, 633.3349982518338, 673.1955313400489, 696.2897844198914, 850.8681154421074, 860.789106668867, 716.247939663354, 863.5984267315697, 876.176394189463, 876.8509353409936, 879.5778026547262, 875.2385424680505, 901.8151278987824, 903.6475774447971, 907.3240435875249, 912.1061359867334, 894.9642014319419, 898.9931276969836, 899.0065977095281, 902.5306840442998, 904.3262769236895, 663.1942190238883, 669.0232260899094, 671.1509939925998, 672.0893189727897, 672.2739441403272, 12666.683332916658, 15042.774358384633, 15287.388504459808, 15294.617634559114, 15808.762868556982, 13798.793018104736, 14327.426902923871, 14544.563663090124, 14622.268886555736, 14693.26533673312, 11799.882001179963, 11846.111541634413, 11854.466549005316, 11941.0, 11956.58151964677, 3350.329934013207, 3683.686886614641, 3778.981105094457, 3931.056546428865, 3947.3486874665646, 89643.0, 91976.54011729898, 92332.53833730855, 92711.53644231803, 92962.53518732364, 11228.719282018015, 11279.830802537968, 11383.54465821358, 11436.097783191432, 11440.771184576428, 18280.81719182791, 23319.88340058323, 23451.41371465693, 23452.179173728837, 23546.411339716302, 7835.511252861938, 7875.330596899214, 7916.802079947932, 7997.400194985399, 8093.109757926623, 4174.686898482626, 4262.786860656955, 4277.294246449301, 4300.182965236595, 4406.537313582027, 3564.7148228141687, 3625.5286812714708, 3633.563972323338, 3653.3789255826573, 3669.3762060449253, 630.4578062865932, 641.9518536109811, 647.9254885688206, 657.917760279965, 661.0414898397247, 600.6486205569703, 615.4552074293556, 654.3587284461255, 676.7766637009775, 694.6526736631685, 7818.530888146729, 8402.747917562483, 8571.52856592881, 9206.355555111211, 10298.0, 668.2582333609689, 703.8451540661047, 704.68993642797, 706.5619316024098, 734.3317581001263, 4636.582707556337, 4942.40691117061, 4950.158473059589, 4951.554360107608, 4992.550670439678, 66989.32526686872, 84611.5769421149, 94209.5289523548, 95335.0, 95440.56839147418, 69772.3022769776, 69828.06461450733, 74748.62625686945, 82663.0, 82730.0, 76025.0, 76659.0, 76723.61638191885, 76941.84587231308, 77640.22359776443, 10889.836652450378, 10965.61620343284, 11043.0, 11111.277766945122, 11158.665240042812, 37899.43150852684, 47597.52402475931, 51762.44712658626, 53630.73184634052, 54221.0, 66164.01312742436, 66504.66747666424, 67976.0, 68825.3117468819, 69355.65322173356, 11044.779104418027, 11270.549178032787, 11503.482343294405, 11637.76724465523, 13281.66795830112, 12363.567275145686, 12392.380380981096, 12436.31600261958, 12472.376381181086, 14366.425342986582, 13544.661383465493, 13959.930200348934, 13964.930175349058, 13997.0, 14203.786943195653, 12503.87496125027, 13389.799153012518, 13499.595012149259, 13610.0, 13950.511732089328, 13092.476300948236, 13344.132631378929, 13471.595852124452, 13640.65898352549, 13978.370973306022, 9244.852626231403, 10924.453777311262, 10931.945340273247, 11247.718807029889, 11782.234154779911, 9296.674616388664, 10140.391576505433, 10976.286541374786, 10989.890101098887, 11043.116550676088], "value": 7366.348121998796, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1601.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 162.0, 120.0, 118.0, 119.0, 120.0, 130.0, 171.0, 116.0, 118.0], "value": 129.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 114.0, 114.0, 150.0, 113.0, 114.0, 111.0, 113.0, 130.0, 113.0], "value": 121.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [239.0, 275.0, 281.0, 198.0, 198.0, 197.0, 194.0, 200.0, 193.0, 191.0], "value": 216.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 57.0, 60.0, 85.0, 61.0, 61.0, 59.0, 60.0, 75.0, 89.0], "value": 68.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 71.0, 87.0, 74.0, 70.0, 71.0, 68.0, 87.0, 104.0, 115.0], "value": 83.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 73.0, 139.0, 136.0, 90.0, 81.0, 82.0, 117.0, 137.0, 118.0], "value": 106.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 91.0, 106.0, 144.0, 132.0, 110.0, 99.0, 148.0, 131.0, 95.0], "value": 121.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 96.0, 104.0, 93.0, 94.0, 92.0, 91.0, 91.0, 90.0, 90.0], "value": 93.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 60.0, 60.0, 62.0, 62.0, 63.0, 63.0, 66.0, 98.0, 61.0], "value": 65.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 67.0, 85.0, 79.0, 77.0, 69.0, 70.0, 72.0, 72.0], "value": 72.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 113.0, 84.0, 83.0, 97.0, 75.0, 95.0, 109.0, 112.0, 113.0], "value": 97.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 162.0, 134.0, 156.0, 157.0, 160.0, 163.0, 163.0, 194.0, 169.0], "value": 163.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 180.0, 177.0, 174.0, 179.0, 178.0, 178.0, 176.0, 177.0, 177.0], "value": 176.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 82.0, 80.0, 91.0, 92.0, 91.0, 65.0, 75.0, 95.0, 92.0], "value": 83.4, "unit": "score"}]}]} 03:36:28 INFO - Return code: 0 03:36:28 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:36:28 ERROR - # TBPL SUCCESS # 03:36:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:36:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:36:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:36:28 INFO - 2017-01-27 03:36:28.226 screenresolution[1684:11618] starting screenresolution argv=screenresolution get 03:36:28 INFO - 2017-01-27 03:36:28.229 screenresolution[1684:11618] Display 0: 1600x1200x32@60 03:36:28 INFO - 2017-01-27 03:36:28.236 screenresolution[1685:11621] starting screenresolution argv=screenresolution list 03:36:28 INFO - Available Modes on Display 0 03:36:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:36:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:36:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:36:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:36:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:36:28 INFO - Intel Iris: 03:36:28 INFO - Chipset Model: Intel Iris 03:36:28 INFO - Type: GPU 03:36:28 INFO - Bus: Built-In 03:36:28 INFO - VRAM (Dynamic, Max): 1536 MB 03:36:28 INFO - Vendor: Intel (0x8086) 03:36:28 INFO - Device ID: 0x0a2e 03:36:28 INFO - Revision ID: 0x0009 03:36:28 INFO - Displays: 03:36:28 INFO - Display: 03:36:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:36:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:36:28 INFO - Display Serial Number: 1600x1200 60 03:36:28 INFO - Main Display: Yes 03:36:28 INFO - Mirror: Off 03:36:28 INFO - Online: Yes 03:36:28 INFO - Rotation: Supported 03:36:28 INFO - Return code: 0 03:36:28 INFO - Running post-action listener: _resource_record_post_action 03:36:28 INFO - [mozharness: 2017-01-27 11:36:28.476319Z] Finished run-tests step (success) 03:36:28 INFO - Running post-run listener: _resource_record_post_run 03:36:28 INFO - Total resource usage - Wall time: 1201s; CPU: 54.0%; Read bytes: 33456640; Write bytes: 559871488; Read time: 207; Write time: 1745 03:36:28 INFO - TinderboxPrint: CPU usage
54.4% 03:36:28 INFO - TinderboxPrint: I/O read bytes / time
33,456,640 / 207 03:36:28 INFO - TinderboxPrint: I/O write bytes / time
559,871,488 / 1,745 03:36:28 INFO - TinderboxPrint: CPU idle
2,192.4 (45.6%) 03:36:28 INFO - TinderboxPrint: CPU system
970.9 (20.2%) 03:36:28 INFO - TinderboxPrint: CPU user
1,642.4 (34.2%) 03:36:28 INFO - TinderboxPrint: Swap in / out
282,234,880 / 0 03:36:28 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 250833408; Write bytes: 246547968; Read time: 18110; Write time: 520 03:36:28 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 29893120; Write bytes: 301879296; Read time: 182; Write time: 1187 03:36:28 INFO - Running post-run listener: _upload_blobber_files 03:36:28 INFO - Blob upload gear active. 03:36:28 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:36:28 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 03:36:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:36:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:36:29 INFO - (blobuploader) - INFO - Open directory for files ... 03:36:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:36:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:36:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:36:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:36:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:36:30 INFO - (blobuploader) - INFO - Done attempting. 03:36:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:36:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:36:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:36:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:36:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:36:31 INFO - (blobuploader) - INFO - Done attempting. 03:36:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 03:36:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:36:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:36:32 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 03:36:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:36:32 INFO - (blobuploader) - INFO - Done attempting. 03:36:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:36:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:36:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:36:35 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:36:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:36:35 INFO - (blobuploader) - INFO - Done attempting. 03:36:35 INFO - (blobuploader) - INFO - Iteration through files over. 03:36:35 INFO - Return code: 0 03:36:35 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:36:35 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:36:35 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/105f2e8e55596de9795a5bce05dd14402e54d6648588b84bb5e951bff4f6b95fcc1866f459360e842f00ac83dd2b6b6a30b6de181be2510349bb9f2be7c1df20", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/75f5689c56c8a08b8edb07250df6076874dece8207c3777d9cca8604cec0a8b51353d5541634173b162024b0bdee6f6c09edaadbdb7b9ece5be31008da8c0c2e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/75bd7a5f58d4b09984841b8cbe2f0383289bb2f2e787ef95325ce30dabd910a251918685083a8b8ecafde660cea181731eadf271bf4e66ca75213d095c90b280", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a1dae7f2cc9f9c0a9113be273a5c0c9e6691880cf43b0eab853f67f5a8ccf8e92fb9c1d82f207f0e5c4ec8218ee959e4c3c1d57658757524f351ffec7eb87b79"} 03:36:35 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:36:35 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:36:35 INFO - Contents: 03:36:35 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/105f2e8e55596de9795a5bce05dd14402e54d6648588b84bb5e951bff4f6b95fcc1866f459360e842f00ac83dd2b6b6a30b6de181be2510349bb9f2be7c1df20", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/75f5689c56c8a08b8edb07250df6076874dece8207c3777d9cca8604cec0a8b51353d5541634173b162024b0bdee6f6c09edaadbdb7b9ece5be31008da8c0c2e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/75bd7a5f58d4b09984841b8cbe2f0383289bb2f2e787ef95325ce30dabd910a251918685083a8b8ecafde660cea181731eadf271bf4e66ca75213d095c90b280", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a1dae7f2cc9f9c0a9113be273a5c0c9e6691880cf43b0eab853f67f5a8ccf8e92fb9c1d82f207f0e5c4ec8218ee959e4c3c1d57658757524f351ffec7eb87b79"} 03:36:35 INFO - Running post-run listener: copy_logs_to_upload_dir 03:36:35 INFO - Copying logs to upload dir... 03:36:35 INFO - mkdir: /builds/slave/test/build/upload/logs 03:36:35 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1264.231233 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2017-01-27 03:36:35.157336) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-27 03:36:35.164536) ========= 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.84tvuSAr4J/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.RGMwrtrArF/Listeners TMPDIR=/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/105f2e8e55596de9795a5bce05dd14402e54d6648588b84bb5e951bff4f6b95fcc1866f459360e842f00ac83dd2b6b6a30b6de181be2510349bb9f2be7c1df20", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/75f5689c56c8a08b8edb07250df6076874dece8207c3777d9cca8604cec0a8b51353d5541634173b162024b0bdee6f6c09edaadbdb7b9ece5be31008da8c0c2e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/75bd7a5f58d4b09984841b8cbe2f0383289bb2f2e787ef95325ce30dabd910a251918685083a8b8ecafde660cea181731eadf271bf4e66ca75213d095c90b280", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a1dae7f2cc9f9c0a9113be273a5c0c9e6691880cf43b0eab853f67f5a8ccf8e92fb9c1d82f207f0e5c4ec8218ee959e4c3c1d57658757524f351ffec7eb87b79"} build_url:https://queue.taskcluster.net/v1/task/ZIw_youVQ5m5pP6RAkUf1A/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011320 build_url: 'https://queue.taskcluster.net/v1/task/ZIw_youVQ5m5pP6RAkUf1A/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/105f2e8e55596de9795a5bce05dd14402e54d6648588b84bb5e951bff4f6b95fcc1866f459360e842f00ac83dd2b6b6a30b6de181be2510349bb9f2be7c1df20", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/75f5689c56c8a08b8edb07250df6076874dece8207c3777d9cca8604cec0a8b51353d5541634173b162024b0bdee6f6c09edaadbdb7b9ece5be31008da8c0c2e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/75bd7a5f58d4b09984841b8cbe2f0383289bb2f2e787ef95325ce30dabd910a251918685083a8b8ecafde660cea181731eadf271bf4e66ca75213d095c90b280", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a1dae7f2cc9f9c0a9113be273a5c0c9e6691880cf43b0eab853f67f5a8ccf8e92fb9c1d82f207f0e5c4ec8218ee959e4c3c1d57658757524f351ffec7eb87b79"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-27 03:36:35.201564) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-27 03:36:35.201895) ========= 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.84tvuSAr4J/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.RGMwrtrArF/Listeners TMPDIR=/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/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.005292 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-27 03:36:35.250836) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-27 03:36:35.254180) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-27 03:36:35.254511) ========= ========= Total master_lag: 0.22 =========