builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0110 starttime: 1492146460.94 results: success (0) buildid: 20170413201349 builduid: 9392e43db30746eb8fa6f63f70439b0b revision: f77f3057b8ee04f1a7546e9cd69066d201e9a221 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-13 22:07:40.938152) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-13 22:07:40.938685) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-13 22:07:40.939015) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-13 22:07:40.966624) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-13 22:07:40.967043) ========= 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.zkR0P1Iu4B/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.kmBOSZdsKA/Listeners TMPDIR=/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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-04-13 22:07:40-- 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% 9.28M=0.001s 2017-04-13 22:07:41 (9.28 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.091614 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-13 22:07:41.082641) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-13 22:07:41.082983) ========= 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.zkR0P1Iu4B/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.kmBOSZdsKA/Listeners TMPDIR=/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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.032369 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-13 22:07:41.138035) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-04-13 22:07:41.138492) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev f77f3057b8ee04f1a7546e9cd69066d201e9a221 --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 f77f3057b8ee04f1a7546e9cd69066d201e9a221 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.zkR0P1Iu4B/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.kmBOSZdsKA/Listeners TMPDIR=/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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-04-13 22:07:41,209 truncating revision to first 12 chars 2017-04-13 22:07:41,209 Setting DEBUG logging. 2017-04-13 22:07:41,209 attempt 1/10 2017-04-13 22:07:41,209 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/f77f3057b8ee?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-13 22:07:43,021 unpacking tar archive at: mozilla-central-f77f3057b8ee/testing/mozharness/ program finished with exit code 0 elapsedTime=2.103004 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-04-13 22:07:43.265390) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-13 22:07:43.265752) ========= script_repo_revision: f77f3057b8ee04f1a7546e9cd69066d201e9a221 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-13 22:07:43.266181) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-13 22:07:43.266481) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-13 22:07:43.286453) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2017-04-13 22:07:43.286821) ========= /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.zkR0P1Iu4B/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.kmBOSZdsKA/Listeners TMPDIR=/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 22:07:43 INFO - MultiFileLogger online at 20170413 22:07:43 in /builds/slave/test 22:07:43 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 22:07:43 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:07:43 INFO - {'append_to_log': False, 22:07:43 INFO - 'base_work_dir': '/builds/slave/test', 22:07:43 INFO - 'blob_upload_branch': 'Firefox', 22:07:43 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:07:43 INFO - 'branch': 'Firefox', 22:07:43 INFO - 'buildbot_json_path': 'buildprops.json', 22:07:43 INFO - 'config_files': ('talos/mac_config.py',), 22:07:43 INFO - 'default_actions': ('clobber', 22:07:43 INFO - 'read-buildbot-config', 22:07:43 INFO - 'download-and-extract', 22:07:43 INFO - 'populate-webroot', 22:07:43 INFO - 'create-virtualenv', 22:07:43 INFO - 'install', 22:07:43 INFO - 'run-tests'), 22:07:43 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:07:43 INFO - 'download_minidump_stackwalk': True, 22:07:43 INFO - 'download_symbols': 'ondemand', 22:07:43 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:07:43 INFO - 'tooltool.py': '/tools/tooltool.py', 22:07:43 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:07:43 INFO - '/tools/misc-python/virtualenv.py')}, 22:07:43 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:07:43 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:07:43 INFO - 'gecko_profile': False, 22:07:43 INFO - 'gecko_profile_interval': 0, 22:07:43 INFO - 'installer_path': 'installer.exe', 22:07:43 INFO - 'log_level': 'info', 22:07:43 INFO - 'log_name': 'talos', 22:07:43 INFO - 'log_to_console': True, 22:07:43 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 22:07:43 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 22:07:43 INFO - 'opt_config_files': (), 22:07:43 INFO - 'pip_index': False, 22:07:43 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:07:43 INFO - 'cmd': ('bash', 22:07:43 INFO - '-c', 22:07:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:07:43 INFO - 'enabled': True, 22:07:43 INFO - 'halt_on_failure': False, 22:07:43 INFO - 'name': 'check_screen_resolution'},), 22:07:43 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:07:43 INFO - 'cmd': ('bash', 22:07:43 INFO - '-c', 22:07:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:07:43 INFO - 'enabled': True, 22:07:43 INFO - 'halt_on_failure': False, 22:07:43 INFO - 'name': 'check_screen_resolution'},), 22:07:43 INFO - 'run_cmd_checks_enabled': True, 22:07:43 INFO - 'suite': 'dromaeojs-e10s', 22:07:43 INFO - 'system_bits': '32', 22:07:43 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:07:43 INFO - 'title': 't-yosemite-r7-0110', 22:07:43 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:07:43 INFO - 'use_talos_json': True, 22:07:43 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:07:43 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:07:43 INFO - 'work_dir': 'build'} 22:07:43 INFO - [mozharness: 2017-04-14 05:07:43.471298Z] Running clobber step. 22:07:43 INFO - Running pre-action listener: _resource_record_pre_action 22:07:43 INFO - Running main action method: clobber 22:07:43 INFO - rmtree: /builds/slave/test/build 22:07:43 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:07:46 INFO - Running post-action listener: _resource_record_post_action 22:07:46 INFO - [mozharness: 2017-04-14 05:07:46.120153Z] Finished clobber step (success) 22:07:46 INFO - [mozharness: 2017-04-14 05:07:46.120359Z] Running read-buildbot-config step. 22:07:46 INFO - Running pre-action listener: _resource_record_pre_action 22:07:46 INFO - Running main action method: read_buildbot_config 22:07:46 INFO - Using buildbot properties: 22:07:46 INFO - { 22:07:46 INFO - "project": "", 22:07:46 INFO - "product": "firefox", 22:07:46 INFO - "script_repo_revision": "production", 22:07:46 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 22:07:46 INFO - "repository": "", 22:07:46 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 22:07:46 INFO - "buildid": "20170413201349", 22:07:46 INFO - "pgo_build": "False", 22:07:46 INFO - "basedir": "/builds/slave/test", 22:07:46 INFO - "buildnumber": 31, 22:07:46 INFO - "slavename": "t-yosemite-r7-0110", 22:07:46 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 22:07:46 INFO - "platform": "yosemite_r7", 22:07:46 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 22:07:46 INFO - "branch": "mozilla-central", 22:07:46 INFO - "repo_path": "mozilla-central", 22:07:46 INFO - "revision": "f77f3057b8ee04f1a7546e9cd69066d201e9a221", 22:07:46 INFO - "stage_platform": "macosx64", 22:07:46 INFO - "builduid": "9392e43db30746eb8fa6f63f70439b0b", 22:07:46 INFO - "slavebuilddir": "test" 22:07:46 INFO - } 22:07:46 INFO - Found installer url https://queue.taskcluster.net/v1/task/e2EjPsLZQ2WiwlIG_zTPoA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 22:07:46 INFO - Running post-action listener: _resource_record_post_action 22:07:46 INFO - [mozharness: 2017-04-14 05:07:46.123918Z] Finished read-buildbot-config step (success) 22:07:46 INFO - [mozharness: 2017-04-14 05:07:46.124062Z] Running download-and-extract step. 22:07:46 INFO - Running pre-action listener: _resource_record_pre_action 22:07:46 INFO - Running main action method: download_and_extract 22:07:46 INFO - mkdir: /builds/slave/test/build/tests 22:07:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:07:46 INFO - trying https://queue.taskcluster.net/v1/task/e2EjPsLZQ2WiwlIG_zTPoA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 22:07:46 INFO - Downloading https://queue.taskcluster.net/v1/task/e2EjPsLZQ2WiwlIG_zTPoA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 22:07:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/e2EjPsLZQ2WiwlIG_zTPoA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 22:07:47 INFO - Downloaded 1325 bytes. 22:07:47 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 22:07:47 INFO - Using the following test package requirements: 22:07:47 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:07:47 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 22:07:47 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 22:07:47 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:07:47 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 22:07:47 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:07:47 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 22:07:47 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:07:47 INFO - u'jsshell-mac.zip'], 22:07:47 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:07:47 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 22:07:47 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 22:07:47 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:07:47 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 22:07:47 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:07:47 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 22:07:47 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:07:47 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 22:07:47 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:07:47 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 22:07:47 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 22:07:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/e2EjPsLZQ2WiwlIG_zTPoA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 22:07:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/e2EjPsLZQ2WiwlIG_zTPoA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:07:47 INFO - Fetch https://queue.taskcluster.net/v1/task/e2EjPsLZQ2WiwlIG_zTPoA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 22:07:49 INFO - Expected file size: 52595812 22:07:49 INFO - Obtained file size: 52595812 22:08:06 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 22:08:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/e2EjPsLZQ2WiwlIG_zTPoA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 22:08:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/e2EjPsLZQ2WiwlIG_zTPoA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:08:06 INFO - Fetch https://queue.taskcluster.net/v1/task/e2EjPsLZQ2WiwlIG_zTPoA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 22:08:08 INFO - Expected file size: 52595812 22:08:08 INFO - Obtained file size: 52595812 22:08:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/e2EjPsLZQ2WiwlIG_zTPoA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 22:08:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/e2EjPsLZQ2WiwlIG_zTPoA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 22:08:24 INFO - Fetch https://queue.taskcluster.net/v1/task/e2EjPsLZQ2WiwlIG_zTPoA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 22:08:25 INFO - Expected file size: 14408853 22:08:25 INFO - Obtained file size: 14408853 22:08:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:08:25 INFO - trying https://queue.taskcluster.net/v1/task/e2EjPsLZQ2WiwlIG_zTPoA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 22:08:25 INFO - Downloading https://queue.taskcluster.net/v1/task/e2EjPsLZQ2WiwlIG_zTPoA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 22:08:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/e2EjPsLZQ2WiwlIG_zTPoA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 22:08:27 INFO - Downloaded 71095973 bytes. 22:08:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/e2EjPsLZQ2WiwlIG_zTPoA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 22:08:27 INFO - mkdir: /builds/slave/test/properties 22:08:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:08:27 INFO - Writing to file /builds/slave/test/properties/build_url 22:08:27 INFO - Contents: 22:08:27 INFO - build_url:https://queue.taskcluster.net/v1/task/e2EjPsLZQ2WiwlIG_zTPoA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 22:08:28 INFO - Running post-action listener: _resource_record_post_action 22:08:28 INFO - Running post-action listener: set_extra_try_arguments 22:08:28 INFO - [mozharness: 2017-04-14 05:08:28.639641Z] Finished download-and-extract step (success) 22:08:28 INFO - [mozharness: 2017-04-14 05:08:28.639793Z] Running populate-webroot step. 22:08:28 INFO - Running pre-action listener: _resource_record_pre_action 22:08:28 INFO - Running main action method: populate_webroot 22:08:28 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:08:28 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:08:28 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:08:28 INFO - '--tppagecycles', 22:08:28 INFO - '7'], 22:08:28 INFO - 'tests': ['tsvgm']}}, 22:08:28 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:08:28 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:08:28 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:08:28 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:08:28 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:08:28 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:08:28 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 22:08:28 INFO - 'talos_options': ['--disable-e10s'], 22:08:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:08:28 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 22:08:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:08:28 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 22:08:28 INFO - 'talos_options': ['--disable-e10s'], 22:08:28 INFO - 'tests': ['damp', 'tps']}, 22:08:28 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 22:08:28 INFO - 'tests': ['damp', 'tps']}, 22:08:28 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:08:28 INFO - 'tests': ['dromaeo_dom']}, 22:08:28 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:08:28 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:08:28 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 22:08:28 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 22:08:28 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:08:28 INFO - 'tests': ['a11yr', 22:08:28 INFO - 'ts_paint', 22:08:28 INFO - 'tpaint', 22:08:28 INFO - 'sessionrestore', 22:08:28 INFO - 'sessionrestore_no_auto_restore', 22:08:28 INFO - 'tabpaint']}, 22:08:28 INFO - 'other-e10s': {'tests': ['a11yr', 22:08:28 INFO - 'ts_paint', 22:08:28 INFO - 'tpaint', 22:08:28 INFO - 'sessionrestore', 22:08:28 INFO - 'sessionrestore_no_auto_restore', 22:08:28 INFO - 'tabpaint']}, 22:08:28 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:08:28 INFO - 'ts_paint', 22:08:28 INFO - 'tpaint', 22:08:28 INFO - 'sessionrestore', 22:08:28 INFO - 'sessionrestore_no_auto_restore', 22:08:28 INFO - 'tabpaint']}, 22:08:28 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:08:28 INFO - 'ts_paint', 22:08:28 INFO - 'tpaint', 22:08:28 INFO - 'sessionrestore', 22:08:28 INFO - 'sessionrestore_no_auto_restore', 22:08:28 INFO - 'tabpaint']}, 22:08:28 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:08:28 INFO - 'tests': ['a11yr', 22:08:28 INFO - 'ts_paint', 22:08:28 INFO - 'tpaint', 22:08:28 INFO - 'sessionrestore', 22:08:28 INFO - 'sessionrestore_no_auto_restore', 22:08:28 INFO - 'tabpaint']}, 22:08:28 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:08:28 INFO - 'tests': ['a11yr', 22:08:28 INFO - 'ts_paint', 22:08:28 INFO - 'tpaint', 22:08:28 INFO - 'sessionrestore', 22:08:28 INFO - 'sessionrestore_no_auto_restore', 22:08:28 INFO - 'tabpaint']}, 22:08:28 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 22:08:28 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 22:08:28 INFO - 'bloom_basic_ref']}, 22:08:28 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:08:28 INFO - 'tests': ['tsvgx', 22:08:28 INFO - 'tsvgr_opacity', 22:08:28 INFO - 'tart', 22:08:28 INFO - 'tscrollx', 22:08:28 INFO - 'cart', 22:08:28 INFO - 'tsvg_static']}, 22:08:28 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:08:28 INFO - 'tsvgr_opacity', 22:08:28 INFO - 'tart', 22:08:28 INFO - 'tscrollx', 22:08:28 INFO - 'cart', 22:08:28 INFO - 'tsvg_static']}, 22:08:28 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 22:08:28 INFO - 'talos_options': ['--disable-e10s'], 22:08:28 INFO - 'tests': ['tp5o']}, 22:08:28 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 22:08:28 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 22:08:28 INFO - 'talos_options': ['--disable-e10s', 22:08:28 INFO - '--xperf_path', 22:08:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:08:28 INFO - 'tests': ['tp5n']}, 22:08:28 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 22:08:28 INFO - 'talos_options': ['--xperf_path', 22:08:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:08:28 INFO - 'tests': ['tp5n']}}, 22:08:28 INFO - 'talos.zip': {'path': '', 22:08:28 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:08:28 INFO - Running post-action listener: _resource_record_post_action 22:08:28 INFO - [mozharness: 2017-04-14 05:08:28.658851Z] Finished populate-webroot step (success) 22:08:28 INFO - [mozharness: 2017-04-14 05:08:28.658989Z] Running create-virtualenv step. 22:08:28 INFO - Running pre-action listener: _resource_record_pre_action 22:08:28 INFO - Running main action method: create_virtualenv 22:08:28 INFO - Creating virtualenv /builds/slave/test/build/venv 22:08:28 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 22:08:28 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:08:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:08:28 INFO - Using real prefix '/tools/python27' 22:08:28 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:08:29 INFO - Installing distribute.............................................................................................................................................................................................done. 22:08:32 INFO - Installing pip.................done. 22:08:32 INFO - Return code: 0 22:08:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 22:08:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 22:08:32 INFO - Reading from file tmpfile_stdout 22:08:32 INFO - Output received: 22:08:32 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 22:08:32 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:08:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:08:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:08:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:08:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:08:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:08:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:08:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10bda2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bda5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe901cc8200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bb80d30>, '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 0x10baa7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bb75be8>, '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.zkR0P1Iu4B/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.kmBOSZdsKA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 22:08:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 22:08:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 22:08:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zkR0P1Iu4B/Render', 22:08:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:08:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:08:32 INFO - 'HOME': '/Users/cltbld', 22:08:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:08:32 INFO - 'LOGNAME': 'cltbld', 22:08:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:08:32 INFO - 'MOZ_NO_REMOTE': '1', 22:08:32 INFO - 'NO_EM_RESTART': '1', 22:08:32 INFO - 'PAGER': '/bin/cat', 22:08:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:08:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:08:32 INFO - 'PWD': '/builds/slave/test', 22:08:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:08:32 INFO - 'SHELL': '/bin/bash', 22:08:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kmBOSZdsKA/Listeners', 22:08:32 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 22:08:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:08:32 INFO - 'USER': 'cltbld', 22:08:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:08:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:08:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:08:32 INFO - 'XPC_FLAGS': '0x0', 22:08:32 INFO - 'XPC_SERVICE_NAME': '0', 22:08:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:08:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:08:32 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 22:08:32 INFO - Cleaning up... 22:08:32 INFO - Return code: 0 22:08:32 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:08:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:08:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:08:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:08:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:08:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:08:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:08:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10bda2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bda5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe901cc8200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bb80d30>, '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 0x10baa7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bb75be8>, '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.zkR0P1Iu4B/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.kmBOSZdsKA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 22:08:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 22:08:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 22:08:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zkR0P1Iu4B/Render', 22:08:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:08:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:08:32 INFO - 'HOME': '/Users/cltbld', 22:08:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:08:32 INFO - 'LOGNAME': 'cltbld', 22:08:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:08:32 INFO - 'MOZ_NO_REMOTE': '1', 22:08:32 INFO - 'NO_EM_RESTART': '1', 22:08:32 INFO - 'PAGER': '/bin/cat', 22:08:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:08:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:08:32 INFO - 'PWD': '/builds/slave/test', 22:08:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:08:32 INFO - 'SHELL': '/bin/bash', 22:08:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kmBOSZdsKA/Listeners', 22:08:32 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 22:08:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:08:32 INFO - 'USER': 'cltbld', 22:08:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:08:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:08:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:08:32 INFO - 'XPC_FLAGS': '0x0', 22:08:32 INFO - 'XPC_SERVICE_NAME': '0', 22:08:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:08:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:08:33 INFO - Downloading/unpacking psutil>=3.1.1 22:08:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:08:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:08:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:08:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:08:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:08:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:08:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:08:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:08:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:08:36 INFO - Installing collected packages: psutil 22:08:36 INFO - Running setup.py install for psutil 22:08:36 INFO - building 'psutil._psutil_osx' extension 22:08:36 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 22:08:37 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 22:08:37 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 22:08:37 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 22:08:37 INFO - building 'psutil._psutil_posix' extension 22:08:37 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 22:08:37 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 22:08:37 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 22:08:37 INFO - ^ 22:08:37 INFO - 1 warning generated. 22:08:37 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 22:08:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:08:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:08:37 INFO - Successfully installed psutil 22:08:37 INFO - Cleaning up... 22:08:38 INFO - Return code: 0 22:08:38 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:08:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:08:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:08:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:08:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:08:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:08:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:08:38 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 0x10bda2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bda5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe901cc8200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bb80d30>, '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 0x10baa7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bb75be8>, '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.zkR0P1Iu4B/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.kmBOSZdsKA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 22:08:38 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 22:08:38 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 22:08:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zkR0P1Iu4B/Render', 22:08:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:08:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:08:38 INFO - 'HOME': '/Users/cltbld', 22:08:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:08:38 INFO - 'LOGNAME': 'cltbld', 22:08:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:08:38 INFO - 'MOZ_NO_REMOTE': '1', 22:08:38 INFO - 'NO_EM_RESTART': '1', 22:08:38 INFO - 'PAGER': '/bin/cat', 22:08:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:08:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:08:38 INFO - 'PWD': '/builds/slave/test', 22:08:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:08:38 INFO - 'SHELL': '/bin/bash', 22:08:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kmBOSZdsKA/Listeners', 22:08:38 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 22:08:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:08:38 INFO - 'USER': 'cltbld', 22:08:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:08:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:08:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:08:38 INFO - 'XPC_FLAGS': '0x0', 22:08:38 INFO - 'XPC_SERVICE_NAME': '0', 22:08:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:08:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:08:39 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:08:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:08:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:08:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:08:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:08:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:08:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:08:42 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:08:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:08:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:08:42 INFO - Installing collected packages: mozsystemmonitor 22:08:42 INFO - Running setup.py install for mozsystemmonitor 22:08:42 INFO - Successfully installed mozsystemmonitor 22:08:42 INFO - Cleaning up... 22:08:42 INFO - Return code: 0 22:08:42 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:08:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:08:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:08:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:08:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:08:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:08:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:08:42 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 0x10bda2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bda5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe901cc8200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bb80d30>, '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 0x10baa7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bb75be8>, '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.zkR0P1Iu4B/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.kmBOSZdsKA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 22:08:42 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 22:08:42 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 22:08:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zkR0P1Iu4B/Render', 22:08:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:08:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:08:42 INFO - 'HOME': '/Users/cltbld', 22:08:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:08:42 INFO - 'LOGNAME': 'cltbld', 22:08:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:08:42 INFO - 'MOZ_NO_REMOTE': '1', 22:08:42 INFO - 'NO_EM_RESTART': '1', 22:08:42 INFO - 'PAGER': '/bin/cat', 22:08:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:08:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:08:42 INFO - 'PWD': '/builds/slave/test', 22:08:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:08:42 INFO - 'SHELL': '/bin/bash', 22:08:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kmBOSZdsKA/Listeners', 22:08:42 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 22:08:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:08:42 INFO - 'USER': 'cltbld', 22:08:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:08:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:08:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:08:42 INFO - 'XPC_FLAGS': '0x0', 22:08:42 INFO - 'XPC_SERVICE_NAME': '0', 22:08:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:08:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:08:42 INFO - Downloading/unpacking jsonschema==2.5.1 22:08:42 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 22:08:42 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 22:08:42 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 22:08:42 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 22:08:42 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 22:08:42 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 22:08:45 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:08:45 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:08:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:08:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:08:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:08:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:08:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:08:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:08:45 INFO - Downloading functools32-3.2.3-2.tar.gz 22:08:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:08:45 INFO - warning: no files found matching '*.txt' 22:08:45 INFO - no previously-included directories found matching 'build' 22:08:45 INFO - no previously-included directories found matching 'dist' 22:08:45 INFO - no previously-included directories found matching '.git*' 22:08:45 INFO - Installing collected packages: jsonschema, functools32 22:08:45 INFO - Running setup.py install for functools32 22:08:46 INFO - warning: no files found matching '*.txt' 22:08:46 INFO - no previously-included directories found matching 'build' 22:08:46 INFO - no previously-included directories found matching 'dist' 22:08:46 INFO - no previously-included directories found matching '.git*' 22:08:46 INFO - Successfully installed jsonschema functools32 22:08:46 INFO - Cleaning up... 22:08:46 INFO - Return code: 0 22:08:46 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:08:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:08:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:08:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:08:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:08:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:08:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:08:46 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 0x10bda2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bda5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe901cc8200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bb80d30>, '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 0x10baa7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bb75be8>, '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.zkR0P1Iu4B/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.kmBOSZdsKA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 22:08:46 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 22:08:46 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 22:08:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zkR0P1Iu4B/Render', 22:08:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:08:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:08:46 INFO - 'HOME': '/Users/cltbld', 22:08:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:08:46 INFO - 'LOGNAME': 'cltbld', 22:08:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:08:46 INFO - 'MOZ_NO_REMOTE': '1', 22:08:46 INFO - 'NO_EM_RESTART': '1', 22:08:46 INFO - 'PAGER': '/bin/cat', 22:08:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:08:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:08:46 INFO - 'PWD': '/builds/slave/test', 22:08:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:08:46 INFO - 'SHELL': '/bin/bash', 22:08:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kmBOSZdsKA/Listeners', 22:08:46 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 22:08:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:08:46 INFO - 'USER': 'cltbld', 22:08:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:08:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:08:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:08:46 INFO - 'XPC_FLAGS': '0x0', 22:08:46 INFO - 'XPC_SERVICE_NAME': '0', 22:08:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:08:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:08:46 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:08:46 INFO - Cleaning up... 22:08:46 INFO - Return code: 0 22:08:46 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:08:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:08:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:08:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:08:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:08:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:08:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:08:46 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 0x10bda2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bda5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe901cc8200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bb80d30>, '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 0x10baa7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bb75be8>, '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.zkR0P1Iu4B/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.kmBOSZdsKA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 22:08:46 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 22:08:46 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 22:08:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zkR0P1Iu4B/Render', 22:08:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:08:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:08:46 INFO - 'HOME': '/Users/cltbld', 22:08:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:08:46 INFO - 'LOGNAME': 'cltbld', 22:08:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:08:46 INFO - 'MOZ_NO_REMOTE': '1', 22:08:46 INFO - 'NO_EM_RESTART': '1', 22:08:46 INFO - 'PAGER': '/bin/cat', 22:08:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:08:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:08:46 INFO - 'PWD': '/builds/slave/test', 22:08:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:08:46 INFO - 'SHELL': '/bin/bash', 22:08:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kmBOSZdsKA/Listeners', 22:08:46 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 22:08:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:08:46 INFO - 'USER': 'cltbld', 22:08:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:08:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:08:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:08:46 INFO - 'XPC_FLAGS': '0x0', 22:08:46 INFO - 'XPC_SERVICE_NAME': '0', 22:08:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:08:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:08:46 INFO - Downloading/unpacking blobuploader==1.2.4 22:08:46 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 22:08:46 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 22:08:46 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 22:08:46 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 22:08:46 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 22:08:46 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 22:08:49 INFO - Downloading blobuploader-1.2.4.tar.gz 22:08:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:08:49 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:08:49 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 22:08:49 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 22:08:49 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 22:08:49 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 22:08:49 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 22:08:49 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 22:08:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:08:50 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:08:50 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 22:08:50 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 22:08:50 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 22:08:50 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 22:08:50 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 22:08:50 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 22:08:50 INFO - Downloading docopt-0.6.1.tar.gz 22:08:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:08:50 INFO - Installing collected packages: blobuploader, requests, docopt 22:08:50 INFO - Running setup.py install for blobuploader 22:08:50 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:08:50 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:08:50 INFO - Running setup.py install for requests 22:08:51 INFO - Running setup.py install for docopt 22:08:51 INFO - Successfully installed blobuploader requests docopt 22:08:51 INFO - Cleaning up... 22:08:51 INFO - Return code: 0 22:08:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:08: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')]} 22:08:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:08:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:08: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')]} 22:08:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:08:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:08:51 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 0x10bda2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bda5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe901cc8200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bb80d30>, '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 0x10baa7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bb75be8>, '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.zkR0P1Iu4B/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.kmBOSZdsKA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 22:08:51 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 22:08:51 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 22:08:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zkR0P1Iu4B/Render', 22:08:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:08:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:08:51 INFO - 'HOME': '/Users/cltbld', 22:08:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:08:51 INFO - 'LOGNAME': 'cltbld', 22:08:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:08:51 INFO - 'MOZ_NO_REMOTE': '1', 22:08:51 INFO - 'NO_EM_RESTART': '1', 22:08:51 INFO - 'PAGER': '/bin/cat', 22:08:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:08:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:08:51 INFO - 'PWD': '/builds/slave/test', 22:08:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:08:51 INFO - 'SHELL': '/bin/bash', 22:08:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kmBOSZdsKA/Listeners', 22:08:51 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 22:08:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:08:51 INFO - 'USER': 'cltbld', 22:08:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:08:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:08:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:08:51 INFO - 'XPC_FLAGS': '0x0', 22:08:51 INFO - 'XPC_SERVICE_NAME': '0', 22:08:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:08:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:08:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:08:51 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-Fco25a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:08:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:08:51 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-RhHh7Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:08:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:08:51 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-snFFPn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:08:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:08:52 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-iIfSnh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:08:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:08:52 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-SyOsYv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:08:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:08:52 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-DJmFIP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:08:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:08:52 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-LZmjk5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:08:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:08:52 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-dltbDS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:08:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:08:52 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-ZiBBDB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:08:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:08:52 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-P6a1rz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:08:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:08:52 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-pR_b5H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:08:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:08:52 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-v9bvO4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:08:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:08:52 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-x9h_Ac-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:08:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:08:53 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-AioS7P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:08:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:08:53 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-gUElCd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:08:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:08:53 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-iikvW3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:08:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:08:53 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-yBLKNS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:08:53 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:08:53 INFO - Running setup.py install for manifestparser 22:08:53 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:08:53 INFO - Running setup.py install for mozcrash 22:08:53 INFO - Running setup.py install for mozdebug 22:08:53 INFO - Running setup.py install for mozdevice 22:08:54 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:08:54 INFO - Running setup.py install for mozfile 22:08:54 INFO - Running setup.py install for mozhttpd 22:08:54 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:08:54 INFO - Running setup.py install for mozinfo 22:08:54 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:08:54 INFO - Running setup.py install for mozInstall 22:08:54 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:08:54 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:08:54 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:08:54 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:08:54 INFO - Running setup.py install for mozleak 22:08:54 INFO - Running setup.py install for mozlog 22:08:54 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:08:55 INFO - Running setup.py install for moznetwork 22:08:55 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:08:55 INFO - Running setup.py install for mozprocess 22:08:55 INFO - Running setup.py install for mozprofile 22:08:55 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:08:55 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:08:55 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:08:55 INFO - Running setup.py install for mozrunner 22:08:55 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:08:55 INFO - Running setup.py install for mozscreenshot 22:08:55 INFO - Running setup.py install for moztest 22:08:55 INFO - Running setup.py install for mozversion 22:08:56 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:08:56 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:08:56 INFO - Cleaning up... 22:08:56 INFO - Return code: 0 22:08:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:08:56 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')]} 22:08:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:08:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:08:56 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')]} 22:08:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:08:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:08:56 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 0x10bda2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bda5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe901cc8200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bb80d30>, '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 0x10baa7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bb75be8>, '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.zkR0P1Iu4B/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.kmBOSZdsKA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 22:08:56 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 22:08:56 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 22:08:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zkR0P1Iu4B/Render', 22:08:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:08:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:08:56 INFO - 'HOME': '/Users/cltbld', 22:08:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:08:56 INFO - 'LOGNAME': 'cltbld', 22:08:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:08:56 INFO - 'MOZ_NO_REMOTE': '1', 22:08:56 INFO - 'NO_EM_RESTART': '1', 22:08:56 INFO - 'PAGER': '/bin/cat', 22:08:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:08:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:08:56 INFO - 'PWD': '/builds/slave/test', 22:08:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:08:56 INFO - 'SHELL': '/bin/bash', 22:08:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kmBOSZdsKA/Listeners', 22:08:56 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 22:08:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:08:56 INFO - 'USER': 'cltbld', 22:08:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:08:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:08:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:08:56 INFO - 'XPC_FLAGS': '0x0', 22:08:56 INFO - 'XPC_SERVICE_NAME': '0', 22:08:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:08:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:08:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:08:56 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-C465tD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:08:56 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)) 22:08:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:08:56 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-RIj2Wn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:08:56 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)) 22:08:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:08:56 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-l7lsLf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:08:56 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)) 22:08:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:08:56 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-MbtUJ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:08:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:08:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:08:56 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-WopHKo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:08:56 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)) 22:08:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:08:56 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-sM5HCZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:08:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 22:08:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:08:56 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-D5INqm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:08:57 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)) 22:08:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:08:57 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-xBHkIm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:08:57 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)) 22:08:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:08:57 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-1nfBIk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:08:57 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)) 22:08:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:08:57 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-_ieznr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:08:57 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)) 22:08:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:08:57 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-nfAg8T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:08:57 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)) 22:08:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:08:57 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-nKSkEy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:08:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 22:08:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:08:57 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-ktKXwe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:08:57 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)) 22:08:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:08:57 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-jGDaM_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:08:57 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)) 22:08:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:08:57 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-HfooKb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:08:57 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)) 22:08:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:08:57 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-nbxEBC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:08:58 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)) 22:08:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:08:58 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-8F5uQ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:08:58 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)) 22:08:58 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)) 22:08:58 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)) 22:08:58 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)) 22:08:58 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:08:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:08:58 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:08: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 22:08: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 22:08: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 22:08: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 22:08: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 22:08: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 22:09:00 INFO - Downloading blessings-1.6.tar.gz 22:09:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:09:00 INFO - Installing collected packages: blessings 22:09:00 INFO - Running setup.py install for blessings 22:09:01 INFO - Successfully installed blessings 22:09:01 INFO - Cleaning up... 22:09:01 INFO - Return code: 0 22:09:01 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:09:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:09:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:09:01 INFO - Reading from file tmpfile_stdout 22:09:01 INFO - Current package versions: 22:09:01 INFO - blessings == 1.6 22:09:01 INFO - blobuploader == 1.2.4 22:09:01 INFO - docopt == 0.6.1 22:09:01 INFO - functools32 == 3.2.3-2 22:09:01 INFO - jsonschema == 2.5.1 22:09:01 INFO - manifestparser == 1.1 22:09:01 INFO - mozInstall == 1.12 22:09:01 INFO - mozcrash == 1.0 22:09:01 INFO - mozdebug == 0.1 22:09:01 INFO - mozdevice == 0.50 22:09:01 INFO - mozfile == 1.2 22:09:01 INFO - mozhttpd == 0.7 22:09:01 INFO - mozinfo == 0.9 22:09:01 INFO - mozleak == 0.1 22:09:01 INFO - mozlog == 3.4 22:09:01 INFO - moznetwork == 0.27 22:09:01 INFO - mozprocess == 0.25 22:09:01 INFO - mozprofile == 0.28 22:09:01 INFO - mozrunner == 6.13 22:09:01 INFO - mozscreenshot == 0.1 22:09:01 INFO - mozsystemmonitor == 0.3 22:09:01 INFO - moztest == 0.8 22:09:01 INFO - mozversion == 1.4 22:09:01 INFO - psutil == 3.1.1 22:09:01 INFO - requests == 1.2.3 22:09:01 INFO - wsgiref == 0.1.2 22:09:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:09:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:09:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:09:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:09:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:09:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10bda2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bda5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe901cc8200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bb80d30>, '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 0x10baa7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bb75be8>, '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.zkR0P1Iu4B/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.kmBOSZdsKA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 22:09:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 22:09:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:09:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zkR0P1Iu4B/Render', 22:09:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:09:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:09:01 INFO - 'HOME': '/Users/cltbld', 22:09:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:09:01 INFO - 'LOGNAME': 'cltbld', 22:09:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:09:01 INFO - 'MOZ_NO_REMOTE': '1', 22:09:01 INFO - 'NO_EM_RESTART': '1', 22:09:01 INFO - 'PAGER': '/bin/cat', 22:09:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:09:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:09:01 INFO - 'PWD': '/builds/slave/test', 22:09:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:09:01 INFO - 'SHELL': '/bin/bash', 22:09:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kmBOSZdsKA/Listeners', 22:09:01 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 22:09:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:09:01 INFO - 'USER': 'cltbld', 22:09:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:09:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:09:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:09:01 INFO - 'XPC_FLAGS': '0x0', 22:09:01 INFO - 'XPC_SERVICE_NAME': '0', 22:09:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:09:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:09:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 22:09:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 22:09:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 22:09:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 22:09:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 22:09:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 22:09:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 22:09:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 22:09:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 22:09:01 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 22:09:01 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 22:09:01 INFO - Cleaning up... 22:09:01 INFO - Return code: 0 22:09:01 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:09:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:09:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:09:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:09:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:09:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:09:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10bda2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bda5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe901cc8200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bb80d30>, '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 0x10baa7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bb75be8>, '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.zkR0P1Iu4B/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.kmBOSZdsKA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 22:09:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 22:09:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 22:09:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zkR0P1Iu4B/Render', 22:09:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:09:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:09:01 INFO - 'HOME': '/Users/cltbld', 22:09:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:09:01 INFO - 'LOGNAME': 'cltbld', 22:09:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:09:01 INFO - 'MOZ_NO_REMOTE': '1', 22:09:01 INFO - 'NO_EM_RESTART': '1', 22:09:01 INFO - 'PAGER': '/bin/cat', 22:09:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:09:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:09:01 INFO - 'PWD': '/builds/slave/test', 22:09:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:09:01 INFO - 'SHELL': '/bin/bash', 22:09:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kmBOSZdsKA/Listeners', 22:09:01 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 22:09:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:09:01 INFO - 'USER': 'cltbld', 22:09:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:09:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:09:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:09:01 INFO - 'XPC_FLAGS': '0x0', 22:09:01 INFO - 'XPC_SERVICE_NAME': '0', 22:09:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:09:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:09:01 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:09:01 INFO - Cleaning up... 22:09:01 INFO - Return code: 0 22:09:01 INFO - Running post-action listener: _resource_record_post_action 22:09:01 INFO - Running post-action listener: _start_resource_monitoring 22:09:01 INFO - Starting resource monitoring. 22:09:01 INFO - [mozharness: 2017-04-14 05:09:01.973611Z] Finished create-virtualenv step (success) 22:09:01 INFO - [mozharness: 2017-04-14 05:09:01.974530Z] Running install step. 22:09:01 INFO - Running pre-action listener: _resource_record_pre_action 22:09:01 INFO - Running main action method: install 22:09:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:09:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:09:02 INFO - Reading from file tmpfile_stdout 22:09:02 INFO - Detecting whether we're running mozinstall >=1.0... 22:09:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:09:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:09:02 INFO - Reading from file tmpfile_stdout 22:09:02 INFO - Output received: 22:09:02 INFO - Usage: mozinstall [options] installer 22:09:02 INFO - Options: 22:09:02 INFO - -h, --help show this help message and exit 22:09:02 INFO - -d DEST, --destination=DEST 22:09:02 INFO - Directory to install application into. [default: 22:09:02 INFO - "/builds/slave/test"] 22:09:02 INFO - --app=APP Application being installed. [default: firefox] 22:09:02 INFO - mkdir: /builds/slave/test/build/application 22:09:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 22:09:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 22:09:21 INFO - Reading from file tmpfile_stdout 22:09:21 INFO - Output received: 22:09:21 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 22:09:21 INFO - Running post-action listener: _resource_record_post_action 22:09:21 INFO - [mozharness: 2017-04-14 05:09:21.923270Z] Finished install step (success) 22:09:21 INFO - [mozharness: 2017-04-14 05:09:21.923441Z] Running run-tests step. 22:09:21 INFO - Running pre-action listener: _resource_record_pre_action 22:09:21 INFO - Running main action method: run_tests 22:09:21 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:09:21 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:09:21 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:09:21 INFO - 2017-04-13 22:09:21.938 screenresolution[1623:7311] starting screenresolution argv=screenresolution get 22:09:21 INFO - 2017-04-13 22:09:21.940 screenresolution[1623:7311] Display 0: 1600x1200x32@60 22:09:21 INFO - 2017-04-13 22:09:21.946 screenresolution[1624:7313] starting screenresolution argv=screenresolution list 22:09:21 INFO - Available Modes on Display 0 22:09:21 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:09:21 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:09:21 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:09:21 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:09:22 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:09:22 INFO - Intel Iris: 22:09:22 INFO - Chipset Model: Intel Iris 22:09:22 INFO - Type: GPU 22:09:22 INFO - Bus: Built-In 22:09:22 INFO - VRAM (Dynamic, Max): 1536 MB 22:09:22 INFO - Vendor: Intel (0x8086) 22:09:22 INFO - Device ID: 0x0a2e 22:09:22 INFO - Revision ID: 0x0009 22:09:22 INFO - Displays: 22:09:22 INFO - Display: 22:09:22 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:09:22 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:09:22 INFO - Display Serial Number: 1600x1200 60 22:09:22 INFO - Main Display: Yes 22:09:22 INFO - Mirror: Off 22:09:22 INFO - Online: Yes 22:09:22 INFO - Rotation: Supported 22:09:22 INFO - Return code: 0 22:09:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:09:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:09:22 INFO - Python 2.7.3 22:09:22 INFO - Return code: 0 22:09:22 INFO - grabbing minidump binary from tooltool 22:09:22 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')]} 22:09:22 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 0x10bb80d30>, '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 0x10baa7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bb75be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:09:22 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 22:09:22 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 22:09:22 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 22:09:22 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:09:22 INFO - Return code: 0 22:09:22 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 22:09:22 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:09:22 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:09:22 INFO - ENV: RUST_BACKTRACE is now 1 22:09:22 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 22:09:22 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:09:22 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/e2EjPsLZQ2WiwlIG_zTPoA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0110', '--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 22:09:22 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/e2EjPsLZQ2WiwlIG_zTPoA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0110 --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 22:09:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zkR0P1Iu4B/Render', 22:09:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:09:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:09:22 INFO - 'HOME': '/Users/cltbld', 22:09:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:09:22 INFO - 'LOGNAME': 'cltbld', 22:09:22 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:09:22 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 22:09:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:09:22 INFO - 'MOZ_NO_REMOTE': '1', 22:09:22 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:09:22 INFO - 'NO_EM_RESTART': '1', 22:09:22 INFO - 'PAGER': '/bin/cat', 22:09:22 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:09:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:09:22 INFO - 'PWD': '/builds/slave/test', 22:09:22 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:09:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:09:22 INFO - 'RUST_BACKTRACE': '1', 22:09:22 INFO - 'SHELL': '/bin/bash', 22:09:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kmBOSZdsKA/Listeners', 22:09:22 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 22:09:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:09:22 INFO - 'USER': 'cltbld', 22:09:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:09:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:09:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:09:22 INFO - 'XPC_FLAGS': '0x0', 22:09:22 INFO - 'XPC_SERVICE_NAME': '0', 22:09:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:09:22 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/e2EjPsLZQ2WiwlIG_zTPoA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0110', '--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 22:09:22 INFO - mozversion application_buildid: 20170413201349 22:09:22 INFO - mozversion application_changeset: f77f3057b8ee04f1a7546e9cd69066d201e9a221 22:09:22 INFO - mozversion application_display_name: Nightly 22:09:22 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:09:22 INFO - mozversion application_name: Firefox 22:09:22 INFO - mozversion application_remotingname: firefox 22:09:22 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 22:09:22 INFO - mozversion application_vendor: Mozilla 22:09:22 INFO - mozversion application_version: 55.0a1 22:09:22 INFO - mozversion platform_buildid: 20170413201349 22:09:22 INFO - mozversion platform_changeset: f77f3057b8ee04f1a7546e9cd69066d201e9a221 22:09:22 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 22:09:22 INFO - mozversion platform_version: 55.0a1 22:09:22 INFO - using testdate: 1492146562 22:09:22 INFO - actual date: 1492146562 22:09:22 INFO - starting webserver on 'localhost:49243' 22:09:22 INFO - SUITE-START | Running 2 tests 22:09:22 INFO - TEST-START | dromaeo_css 22:09:22 INFO - operating with platform_type : mac_ 22:09:22 INFO - Initialising browser for dromaeo_css test... 22:09:22 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/tmp__22FM/profile http://localhost:49243/getInfo.html) 22:09:25 INFO - PID 1634 | 2017-04-13 22:09:25.388 plugin-container[1636:7500] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x953b, name = 'com.apple.tsm.portname' 22:09:25 INFO - PID 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:09:25 INFO - PID 1634 | 2017-04-13 22:09:25.391 plugin-container[1636:7500] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f13, name = 'com.apple.CFPasteboardClient' 22:09:25 INFO - PID 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:09:25 INFO - PID 1634 | 2017-04-13 22:09:25.391 plugin-container[1636:7500] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:09:25 INFO - PID 1634 | __metrics Screen width/height:1600/1200 22:09:25 INFO - PID 1634 | colorDepth:24 22:09:25 INFO - PID 1634 | Browser inner width/height: 1024/689 22:09:25 INFO - PID 1634 | __metrics 22:09:25 ERROR - PID 1634 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3374: TypeError: this.tabs is undefined 22:09:26 INFO - TEST-INFO | 1634: exit 0 22:09:26 INFO - Browser initialized. 22:09:26 INFO - Running cycle 1/1 for dromaeo_css test... 22:09:26 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/tmp__22FM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:09:28 INFO - PID 1637 | 2017-04-13 22:09:28.887 plugin-container[1638:7634] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b4b, name = 'com.apple.tsm.portname' 22:09:28 INFO - PID 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:09:28 INFO - PID 1637 | 2017-04-13 22:09:28.888 plugin-container[1638:7634] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9017, name = 'com.apple.CFPasteboardClient' 22:09:28 INFO - PID 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:09:28 INFO - PID 1637 | 2017-04-13 22:09:28.888 plugin-container[1638:7634] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:09:38 INFO - PID 1637 | RSS: Main: 238104576 22:09:38 INFO - PID 1637 | 22:12:42 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49243/tests/dromaeo/cssquery-ext.html) 22:12:44 INFO - PID 1637 | RSS: Main: 256532480 22:12:44 INFO - PID 1637 | 22:14:26 INFO - PID 1637 | ************************* 22:14:26 INFO - PID 1637 | A coding exception was thrown and uncaught in a Task. 22:14:26 INFO - PID 1637 | 22:14:26 ERROR - PID 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 22:14:26 INFO - PID 1637 | Full stack: 22:14:26 INFO - PID 1637 | ************************* 22:14:26 INFO - PID 1637 | ************************* 22:14:26 INFO - PID 1637 | A coding exception was thrown and uncaught in a Task. 22:14:26 INFO - PID 1637 | 22:14:26 ERROR - PID 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 22:14:26 INFO - PID 1637 | Full stack: 22:14:26 INFO - PID 1637 | ************************* 22:14:26 INFO - PID 1637 | ************************* 22:14:26 INFO - PID 1637 | A coding exception was thrown and uncaught in a Task. 22:14:26 INFO - PID 1637 | 22:14:26 ERROR - PID 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 22:14:26 INFO - PID 1637 | Full stack: 22:14:26 INFO - PID 1637 | ************************* 22:14:26 INFO - PID 1637 | ************************* 22:14:26 INFO - PID 1637 | A coding exception was thrown and uncaught in a Task. 22:14:26 INFO - PID 1637 | 22:14:26 ERROR - PID 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 22:14:26 INFO - PID 1637 | Full stack: 22:14:26 INFO - PID 1637 | ************************* 22:15:46 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-ext.html (next: http://localhost:49243/tests/dromaeo/cssquery-jquery.html) 22:15:46 INFO - PID 1637 | RSS: Main: 263094272 22:15:46 INFO - PID 1637 | 22:18:48 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49243/tests/dromaeo/cssquery-mootools.html) 22:18:49 INFO - PID 1637 | RSS: Main: 274153472 22:18:49 INFO - PID 1637 | 22:21:51 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49243/tests/dromaeo/cssquery-prototype.html) 22:21:51 INFO - PID 1637 | RSS: Main: 267137024 22:21:51 INFO - PID 1637 | 22:24:54 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49243/tests/dromaeo/cssquery-yui.html) 22:24:55 INFO - PID 1637 | RSS: Main: 277426176 22:24:55 INFO - PID 1637 | 22:27:56 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-yui.html (next: http://localhost:49243/tests/dromaeo/cssquery-dojo.html) 22:27:57 INFO - PID 1637 | RSS: Main: 271155200 22:27:57 INFO - PID 1637 | 22:27:57 INFO - PID 1637 | __start_tp_report 22:27:57 INFO - PID 1637 | _x_x_mozilla_page_load 22:27:57 INFO - PID 1637 | _x_x_mozilla_page_load_details 22:27:57 INFO - PID 1637 | |i|pagename|runs| 22:27:57 INFO - PID 1637 | |0;dojo.html;3527.9592520206543;3553.413686741686;3567.3222087803315;3605.603383627799;3621.4748861415096;4954.405471343435;6196.070589411591;6221;6248.9999999999945;6305.716242769075;4821.807127714887;6176.783812566561;6192.752289908398;6212.875742485147;6275.6548389838545;5463.344398672145;5531.917021244684;5542.946840100388;5567.109262517998;5628.915566266509;4942.110420124376;5439.4832490913495;5554.611177217596;5585.776568937237;5623.718814059301;4132.648724858391;5293.867653308698;5561.416051314634;5574.637648552831;5598.832035038955;5471.179323101527;5487.341519017704;5493.835184944418;5539.058360078796;5548.639338442988;5444.237806707064;5470.398256191816;5474.698891560963;5487.067198576252;5493.423190565013;4839.669090999968;5878.735456904449;6145.231846019247;6239.313675495692;6257.2804127525005;6770.898436523456;6780.288069752654;6795.38841504267;6807.4894382921475;6829.93170068298;6862.622555759432;6869.141357330333;6880;6889.965550172267;6895.655217239118;5335.733213339317;6301.495880329563;6335.412989982886;6338.492920566436;6400.775972840975;5843.824685259449;6975.337342952412;6990.370866622028;6992.650367481606;7029;6464.515161362917;6549.672516374162;6558.934410655927;6631.10480085192;6701.765438209639;6789.796306110825;6834.077399550997;6875.1749790025515;6877.690503927335;6880.2431732509385;6473;6481.449076828428;6753.223379311344;6767.492438067165;6795.116634837538;6652.800415987528;6691.7323307068145;6696.06255124283;6740.29226931175;6748.93251067483;5431.565474762009;5432.483914028161;5440.4287549807495;5444.8366549003595;5481.671099734029;4700.811967521261;4817.855464336076;4835.105505481497;4862.246351815475;4883.267509873476;5753.568482363839;6201.286852012076;6286.371362863769;6357.427831495188;6395.968020159962;5615.887682246333;6770.018347339608;6820;6843.863122737617;6845.828854278683;5473.28847249863;6276.623402595859;6335.11308416822;6369.840753981095;6382.5851319665;5786.363500014995;5858.209141765892;5867.295924489089;5869.178315035899;5911.379305172895;6940.548864323702;6989.8951515726285;6991.545549539263;7006.824829379307;7025;6776.966115169393;6844.760433384808;6848.760293389708;6854.2117656470145;6867.038614594061;6716.6977486012265;6785.762498312535;6936.375726184668;6989.091418115612;6993.650317484004;5653.971730141323;6682.164729408824;6722.294159113419;6741.662916854236;6758.4931130166415;6493.8701225976165;6572.671366431755;6827.863442731018;6864.347886950732;6878.484113691593;6088.178095956989;6424.935750642434;6466.224053113657;6490.091387205701;6515.576487528296;6559.245686746085;6565.080888675685;6571.047198156336;6592.307807680125;6595.307492713388;6275.874482510416;6310.6529140895855;6377.362263773585;6405.167328247298;6447.097406363207;6490.448311893542;6597.60414375139;6603.075569420189;6608.570442921195;6610.2398224204835;5517.78608706084;5998.430149135826;6449;6457.61254324742;6459.61242325462 22:27:57 INFO - PID 1637 | |1;ext.html;738.6639079218955;1256.1897576063438;1260.8171815086816;1274.6622145131548;1311.6130741431282;37067;37810.621893781055;37967.81016094919;38036;38085.66700165495;37768;38066.04834879129;38110.809445952735;38129.61870381295;38360;8320.667173313075;8509.829803403929;8574.528400937947;8583.5279059652;8593.527355995418;8608.966923969132;8675.869861952044;8724.08397118303;8730.345224108185;8743.606537705818;8658.480491170518;8671.56642167889;8736.388452808305;8746.737597872043;8776.517291548962;6924.446044316443;8543.060263371024;8682.435641683302;8702.608382622797;8729.519876406795;8517.403781735282;8577.05652378238;8582.527960962145;8591.828163436696;8628.266597339234;36688.44967325492;36944.63055369439;36974;37004.81497592522;37097.62902370969;2963.95554066687;3526.7002304804123;3561.8753343632848;3563.928721425583;3592.7305452090936;30242;30588.388232235455;30700.078997630106;30709.078727638207;30709.846450767825;3119.8752049917975;3156.4002839460427;3162.478191098489;3163.889263875776;3166.4300425923325;269518.65240673866;283694;284416;285538.5723071392;285741.99806013587;44951.426700066;45175.322370164475;45188.93299603052;45215;45255;47899;48752.781180470774;49738;51516.74241628768;51541.484585154416;14150.29248537569;14196.432142714382;14232.501862434972;14242;14264.643383915485;11415.258008229439;11435.942820285845;11441.427928603536;11450.969412752893;11473.196876218584;11358.60244891425;12900.741985160244;13214.33928303581;13258.270795106362;13376.732465350638;5513.669179849222;5738.913916291259;6049.455549000611;6056.273247210363;6102.084687296941;1023.8668973033458;1043.0977204717865;1045.320541647935;1076.1874784537624;1080.8811030786608;20302.25919500878;24273;24455.63316550253;24471.392723964786;25021.62467562988;1131.5643477261315;1136.8237923121928;1145.3128123126223;1182.173541877801;1190.0776897904193;12382.318972456602;12453.1905426147;12525.56160534377;12548;12575.748485030248;345652.2717386397;346044;346452;347669;348139;53965;54121.729391353576;54191.72904135454;54228.457715422344;54232;1536.6696160325585;1549.171193411542;1583.5011971229255;1585.603599100225;1593.3228377939329;23613.52772944497;23639.409014774767;23725.16961906325;23727.52544949126;23910.641340380254;37508.81245593754;37699.81150094342;37710.868673939825;37753.81123094367;37767;315154;319450;319939;320272;320478;34352.48471272983;34547.481787772704;34656.65343346534;35649.46525802063;37388.878333650035;44886.10227795491;45751.313730293405;45758.54241457676;45788;45867.77066114648;29546.374949377132;29554.81780728747;30519;31202.843985780833;31422.685773141977;30677;31150.844245778626;31235;31534;31537.52693709642;36423.27153456969;36431.36058877304;37265.6273437273;37481.62518374781;37497.81251093727;24774.132905348226;25042;30731.692683072884;31387.686123138476;31837.6816231844;23783;24054.63918041196;24591.75408245966;24603.261902142964;24650.87674561616 22:27:57 INFO - PID 1637 | |2;jquery.html;383.9289731399691;424.69846409049575;425.2154774441155;439.93840862279285;453.4354728363188;19586;21192.4701882453;21891.6716249256;22046.669299960515;22098.88950555247;18918.43244702661;20094.296699615516;20153.19387224513;21630.3510894673;23357.299281021555;6120.418560236771;6456.451201647842;6458.644774537399;6459.35406459355;6461.61230326182;5821.18503409523;6103.511719062487;6130.846728831771;6234.50123990082;6305.46403555696;5425;6324.652144132071;6335.8416039598915;6350.618962862243;6352.396522330371;6105.755769769204;6272.090546870678;6306.810795676137;6361.55469117162;6371.713272902684;5405.702686352249;5555.916661250085;5644.322681278232;5689.431056894277;5692.089265717506;27180;27180.32049198786;27329;27457.45085098287;27537.862310688517;2177.150911144655;2245.9438514037115;2253.921112761062;2258.6386178211405;2259.9548009039727;17230.741538876926;17723.46829595114;18063.458096257134;18122.912625242527;18387.62092843042;2134.9146034158616;2179.259051922338;2182.443476913377;2186.6392045312664;2193.7806219377935;164997.17501412536;166393;167353.16323418426;168371;168576;12470.43883025266;12672;12701.872981270253;12775.105742597929;12853.357332133355;16534.503964881074;16542.66914661724;16601.91699041521;16642.251098700588;16653;8791.472511649194;9100;9101.40840845343;9256.120668536478;9256.768580785534;7931.762047138595;7963.725803871353;8627.482351058956;8673.566321683891;8841.823163536694;7819.335356494762;7879.172686867911;7929.881051784228;7935;7967.601619919097;2160.6650969099815;2170.5007848194687;2171.2400659768955;2175.8259339252822;2353.0940587873793;937.2642475656577;957.7214418751046;966.7824739433712;1004.5981607357115;1011.2819897872446;18882.3391181308;20492.872891991083;21189.470263243235;21304.36086917395;21374.679379809317;996.0786272697726;1000.8298589239847;1018.6180182431589;1030.4384110659707;1031.8245898197324;10653.360798352123;10918.235723499438;10952.233343665866;10958.123350131971;10972.615958441416;625729;634101;645040;730187;739194;122674;159557;160774;165707;165945.34054659298;1086.8478413022183;1214.723334458974;1246.7257203415234;1262.4255963536568;1264.178284115314;17847.46457606222;21703.674444883025;22437.775622243567;22480.66279005849;22880;52905.7354713224;63331.683341583;63694.7261054767;64496.67751661399;64936;86722;87979.24041519262;89264.6610300859;90103;92430;7485.438592105723;8878.955605221932;8902.643894244158;8964.096965486337;9009.873765779277;13786.034977551475;13970.231637260049;13997.930010349883;14187.64530886736;14309.713805723619;10270.537825798006;11060.22578419535;11655.13517837142;11868.228565143238;11986.940065299967;8396;10293.279470436997;10375.169986400919;11287.661370158909;11498.770024599295;9760.219182465527;10158.288919775545;11800.937915587638;12134.271943683407;12326.459192600925;8597.355198360023;8612.535868902176;8717.433366831136;8947.955260223658;8981.326400519856;11883.227590206609;11960.401979900797;12044.578439754567;12135.453904574139;12181.025517958366 22:27:57 INFO - PID 1637 | |3;mootools.html;1371.951981680641;1425.052340193772;1431.813864197654;1438.9568312950612;1442.2716528153294;10966.396848173357;11613.187076904609;11671.474783634736;11844.111691623131;12096.213746106498;11615.303081815096;11839;11848.17062805604;11878.346690931996;11881.049516038718;2517.358073691211;2557.8337408068414;2564.4101856572997;2568.5890257558794;2575.0858445251947;2469.506098780242;2486.080150344366;2512.6984761828608;2531.088808029108;2538.657281267024;2472.5178590174924;2520.7101183363966;2527.6208568714746;2527.949441011188;2533.8353007054575;2429.6112621980574;2498.0507407185337;2505.5114252720728;2511.547921374152;2512.824102312839;2123.968140477894;2246.6854640350202;2494.164454907608;2510.497900419905;2516.6099254615565;11658.825117623242;11771;11837.763244735057;11846.466908989116;11859.703507412298;6542.574732642363;6739.191297044386;6796;6808.6255255960905;6821.055999040299;4077.6330130288416;4391.780410979471;4396.450443694538;4481.170983368087;4481.260592002316;1036.8807587127426;1270.6950331920366;1281.5002149161835;1300.9154404963647;1306.5688322853437;16024.278907449192;16494.67010659792;16604.750928735957;16683.83316166835;16783.66432671352;5533.667979921218;5630.380658127603;5800.259922023376;5843.444872737083;5845.707714614252;12141.271523708429;12195.207311524722;12205.93897030527;12232.755344893052;12250.326232057323;6552.3775241352;6563.934360656428;6566.015097735282;6567.408933196035;6578.177727784027;6055.000924847439;6165.229346331708;6167.907481387782;6172.382761723881;6215.471684906834;3701.2597480504005;4361.03402950196;4429.889252768707;4463.598276155161;4479.820807167677;1041.3959903256093;1302.798066299725;1309.738052389507;1326.7412854493475;1330.4279159961116;757.526545908807;759.3014426727424;796.2157275084038;799.0770659887722;817.6565842346229;5493.945060549343;6769.627670478171;6850.143732033496;6899.275576064541;6938.895916561256;850.4939560961218;884.4604791077437;885.4819930340694;907.4011152639226;964.3442459127721;3970.3250447424007;4118.5057793064425;4120.505539335299;4159.708820382604;4227.830886764556;12950.870491294967;13753.243571603658;13930.930345348208;13985.65035874091;14002.87976961861;13621.795673064911;13859.861401385857;13927.373268202955;13929;13951.930240348733;10994.120470362512;10999.505022274017;11123.332600043861;11217.3830439325;11225.101991840633;6280.074953154279;6530.67346632676;6551.246606640298;6629.3039230882;6661.134052573135;11509.194356394972;13370.06409551361;13712.52006179779;13782.862171378158;13888.027838051239;13887.513937012156;14219.50231741884;14295.570442956037;14387.784183237469;14395.856041439452;8814.955925220333;8873.201411872962;8893.73318800437;8947.686830960885;9016.09839016093;10728.678139655824;10838.512266947848;10844.945775271073;10859.619913302997;10865.293755905841;8960.820783584162;9144.177024067865;9213;9248.306377021885;9336.859947100935;7452.701891924262;8897.377183597086;9017.143371379709;9040.367174297999;9085.046070162538;6804.421624161803;7896.84206315882;7952.165022672536;8063.516189028677;8104;5908.659035850851;5956.76172953077;5968.552358573212;6011.27864656244;6071.210742603434;6000.0999850022845;6022.698865056642;6074.757009719739;6141.1402403664415;6176.598521096114 22:27:57 INFO - PID 1637 | |4;prototype.html;342.6453620502779;397.92837289287934;399.82607565708935;403.2398928020681;409.3613962218937;5408;5924;6014.488768454681;6135;6159.938400615993;5352;5815.883682326351;5955.940440595593;5992.340842507331;6004;3636.309101270763;4299.677524185683;4427.225235583776;4445.911081778362;4465.977670111661;3883.8834834954996;4453.376527286183;4472.619827314682;4473.753943533105;4479.70881892676;3460.723142148622;4433.88915277119;4458.197524445599;4461.862225132587;4528.841490547831;3853.614638536152;4054.472918520603;4149.668026557868;4421.226285400042;4454.265046267378;3796.8671096511766;3814.2371525694693;3831.3869780834934;3832.3485007548784;3837.520309961255;4793.23308270675;5889.322727886305;5902.557308201902;5918.082697181943;5921.970390148022;4616.884577885546;5201.817936372247;5255.02781985334;5283.28675628789;5323.733813309319;6413.711382987777;6422.197225346832;6424.357564243585;6440.355964403569;6473.870522589522;3635.6727894489622;4189.518205406356;4317.4819021717285;4366.637688979468;4370.409994650745;6378.553501254914;6705.966470167667;6793.558418702769;6828.487863410264;6867.862642747167;3674.761140525857;3944.6252606002386;4327.32926396409;4363.214621368184;4404.471463424409;3557.964420355815;3898.5321761388814;4448.532904045093;4487.977560112244;4497.955020449754;5069.9720111954775;5141.434442211354;5242.606804489678;5268.15709486488;5274.868128296746;4624.676272660881;5532.723363831873;5545.7781688732;6231.940570103003;6405.19935008124;1327.897844788823;1356.6540532164236;1382.0878220374505;1429.0282607826778;1508.4569554960206;853.8932633420823;864.8486514859875;937.3625934364561;1002.0080120680474;1004.3321191407778;6108;6155.72299246535;6155.907661385083;6166.691665416712;6201.7519299228425;6634.701438435282;6648.102506161703;6692.665366731644;6696.665166741644;6720.630365329856;5320.494553017457;5965.582409231399;6005;6082.543809214327;6105.589131412;2958.6301712285963;3067.619571192972;3163.9558945547915;3445.2764919367214;3470.843812028465;5425.891482170335;5942;6576.934230657728;6746.436354185378;6852.177738671391;6021.42796434338;6183.505319574513;6462.7738029169695;6653.567518111307;6786.219584747719;6418.93581064202;6786.830329241809;6830.351116643911;6854.451643868479;6880.415164710956;5611.38274789781;6552.634326021078;6694.895342268471;6740.12378390806;6740.258571557027;5611.551075913999;5846.298444186725;6463.547551671338;6614.603123812587;6622.271550129385;5943.970280148572;6822.761203357859;6843.3840954314355;6850.0752398425575;6850.6574671267235;3554.360215161192;3836.290286297044;4278.272693642026;4383.890402739957;4398.73607583546;5763.956538029224;6625.635590042401;6651.201855777338;6725.865482690221;6761.492888033319;5957.880842383041;5984.461398474158;6208.441240288215;6529.67351632426;6537.378948999837;5847.239858818326;6221.004639257697;6396.360363963567;6441.291457939717;6541.1268604889665;6339.956820725985;6382.968085159545;6401.903971440339;6404.967975160094;6448.258450278278;6037.003894357382;6365.681715914278;6372.681365931778;6412.61524308543;6454.322296158835;5720.914186287126;5927.940720592739;6352.460040896575;6358.364163583605;6365.64988925595 22:27:57 INFO - PID 1637 | |5;yui.html;767.6507189228901;818.2553875972866;830.1242189490089;862.0991064337767;873.1443185678037;13623.114497557652;14208.786868196985;15924.601884952868;16116.758248626265;16337.83662163378;13213.735725285513;13313.868321192685;17030.659386812255;17077.7438338425;17080.914595427035;952.8380175370187;989.4261328429494;998.0169533009979;998.346083315426;1001.7445551384387;975.9121679048884;998.6754304850916;999.0858364596422;1001.5643195210071;1001.9248556358302;1002.5488530161457;1010.7264846293649;1038.4081073787943;1050.5902697947806;1060.7136073260215;1039.833626619741;1049.3913530152493;1050.0811789684026;1051.9158467322634;1052.36857885269;769.6921231507442;773.5668025905454;774.4888373673349;775.8642237608452;778.4706399648237;14148.717025659533;16084.195790210442;16368.672626547403;16487;16959.406420775213;14265.358058887272;14541.78187327191;16778.412755553618;17093.91453042739;17131.314747410088;13532.864671353258;13818.516351927734;13887.79168312476;14038.227897465635;14060.367283472267;3103.658597554267;3514.3147086318177;3526.347625689256;3541.9291614167573;3548.1661809474754;96990.51504742431;102237.99999999926;116175.41912290468;116787;118372.8162718379;13264.071514994052;13376.46494140243;13597.932010339884;13604.863951360558;13623.795643065361;24154.7584524156;24863.751362486506;25049;25305.87347063253;25425.49149017046;9034.819303614022;9059.456432614064;9130.497822619687;9202.35583509148;9258;4818.277258411266;4820.204666230033;4822.493638168012;4835.830745923876;4915.992221594564;4260.531341552427;4353.978230108829;4394.978025109854;4396.340548917688;4396.736195828196;892.4422236102436;928.5496534180917;931.8415869302235;933.7758937855002;934.9345545811727;617.7175337129354;639.2616527910271;639.5491178718992;641.9229692436937;662.6653539962289;10627.628033018962;11607.419629018515;11711.301861230075;11892.286462812259;11904.166708330335;616.8149555133442;631.8167731357946;633.9904901426482;646.4393702429405;649.6004956951441;5296.126139186991;5398.73006349681;5436.374816896107;5446;5483.670979741229;95622.52188739012;96769.5484567718;97065.02934970701;98583;100700;55346.72326638341;81952;83678;84212.73680894791;84938.30123397497;78320.60839695879;79006.6049669748;80799.5960020196;81258.37483250302;81658;10493.685189444328;10822.40476773785;11928.284302941851;12012.399380031138;12329.260244385365;38046.42930355992;39342.803285983384;39491.80254098711;39689;40011.399829002;68122.65938670473;69101.65449172723;69700.95448568174;70900;70936.93594596189;15244.695106097593;15278.006929549543;15905.920470397574;15946.9202653986;16155.59611009687;17448.040357780443;17810.554736131184;17862.285508579513;17873.374431894823;17904.91047544806;15782.60543486376;16274.104924228808;16286;16562.917185413997;16653.25060372262;16268.51194464168;16268.837311626732;16326.673466530841;16616.169191540615;16635;13309.866901330863;13837.823784978389;16107.194640268173;16108.033517988959;16296.592585185464;9177.311701622539;9661.420314781135;9946.95556966508;10422.843657345298;11228.887711122785;11102.222844400814;12038.578649747216;12348.81476777831;12827.615171544869;13216.405261763435 22:27:57 INFO - PID 1637 | __end_tp_report 22:27:57 INFO - PID 1637 | __start_cc_report 22:27:57 INFO - PID 1637 | _x_x_mozilla_cycle_collect,419 22:27:57 INFO - PID 1637 | __end_cc_report 22:27:57 INFO - PID 1637 | __startTimestamp1492147677131__endTimestamp 22:27:57 INFO - PID 1637 | 22:27:57 INFO - PID 1637 | ------- Summary: start ------- 22:27:57 INFO - PID 1637 | Number of tests: 6 22:27:57 INFO - PID 1637 | 22:27:57 INFO - PID 1637 | [#0] dojo.html Cycles:165 Average:6153.16 Median:6395.97 stddev:773.14 (12.1%) stddev-sans-first:747.58 22:27:57 INFO - PID 1637 | Values: 3528.0 3553.4 3567.3 3605.6 3621.5 4954.4 6196.1 6221.0 6249.0 6305.7 4821.8 6176.8 6192.8 6212.9 6275.7 5463.3 5531.9 5542.9 5567.1 5628.9 4942.1 5439.5 5554.6 5585.8 5623.7 4132.6 5293.9 5561.4 5574.6 5598.8 5471.2 5487.3 5493.8 5539.1 5548.6 5444.2 5470.4 5474.7 5487.1 5493.4 4839.7 5878.7 6145.2 6239.3 6257.3 6770.9 6780.3 6795.4 6807.5 6829.9 6862.6 6869.1 6880.0 6890.0 6895.7 5335.7 6301.5 6335.4 6338.5 6400.8 5843.8 6975.3 6990.4 6992.7 7029.0 6464.5 6549.7 6558.9 6631.1 6701.8 6789.8 6834.1 6875.2 6877.7 6880.2 6473.0 6481.4 6753.2 6767.5 6795.1 6652.8 6691.7 6696.1 6740.3 6748.9 5431.6 5432.5 5440.4 5444.8 5481.7 4700.8 4817.9 4835.1 4862.2 4883.3 5753.6 6201.3 6286.4 6357.4 6396.0 5615.9 6770.0 6820.0 6843.9 6845.8 5473.3 6276.6 6335.1 6369.8 6382.6 5786.4 5858.2 5867.3 5869.2 5911.4 6940.5 6989.9 6991.5 7006.8 7025.0 6777.0 6844.8 6848.8 6854.2 6867.0 6716.7 6785.8 6936.4 6989.1 6993.7 5654.0 6682.2 6722.3 6741.7 6758.5 6493.9 6572.7 6827.9 6864.3 6878.5 6088.2 6424.9 6466.2 6490.1 6515.6 6559.2 6565.1 6571.0 6592.3 6595.3 6275.9 6310.7 6377.4 6405.2 6447.1 6490.4 6597.6 6603.1 6608.6 6610.2 5517.8 5998.4 6449.0 6457.6 6459.6 22:27:57 INFO - PID 1637 | 22:27:57 INFO - PID 1637 | [#1] ext.html Cycles:180 Average:46369.83 Median:34449.98 stddev:83299.19 (241.8%) stddev-sans-first:83462.41 22:27:57 INFO - PID 1637 | Values: 738.7 1256.2 1260.8 1274.7 1311.6 37067.0 37810.6 37967.8 38036.0 38085.7 37768.0 38066.0 38110.8 38129.6 38360.0 8320.7 8509.8 8574.5 8583.5 8593.5 8609.0 8675.9 8724.1 8730.3 8743.6 8658.5 8671.6 8736.4 8746.7 8776.5 6924.4 8543.1 8682.4 8702.6 8729.5 8517.4 8577.1 8582.5 8591.8 8628.3 36688.4 36944.6 36974.0 37004.8 37097.6 2964.0 3526.7 3561.9 3563.9 3592.7 30242.0 30588.4 30700.1 30709.1 30709.8 3119.9 3156.4 3162.5 3163.9 3166.4 269518.7 283694.0 284416.0 285538.6 285742.0 44951.4 45175.3 45188.9 45215.0 45255.0 47899.0 48752.8 49738.0 51516.7 51541.5 14150.3 14196.4 14232.5 14242.0 14264.6 11415.3 11435.9 11441.4 11451.0 11473.2 11358.6 12900.7 13214.3 13258.3 13376.7 5513.7 5738.9 6049.5 6056.3 6102.1 1023.9 1043.1 1045.3 1076.2 1080.9 20302.3 24273.0 24455.6 24471.4 25021.6 1131.6 1136.8 1145.3 1182.2 1190.1 12382.3 12453.2 12525.6 12548.0 12575.7 345652.3 346044.0 346452.0 347669.0 348139.0 53965.0 54121.7 54191.7 54228.5 54232.0 1536.7 1549.2 1583.5 1585.6 1593.3 23613.5 23639.4 23725.2 23727.5 23910.6 37508.8 37699.8 37710.9 37753.8 37767.0 315154.0 319450.0 319939.0 320272.0 320478.0 34352.5 34547.5 34656.7 35649.5 37388.9 44886.1 45751.3 45758.5 45788.0 45867.8 29546.4 29554.8 30519.0 31202.8 31422.7 30677.0 31150.8 31235.0 31534.0 31537.5 36423.3 36431.4 37265.6 37481.6 37497.8 24774.1 25042.0 30731.7 31387.7 31837.7 23783.0 24054.6 24591.8 24603.3 24650.9 22:27:57 INFO - PID 1637 | 22:27:57 INFO - PID 1637 | [#2] jquery.html Cycles:180 Average:40570.27 Median:12341.86 stddev:114253.26 (925.7%) stddev-sans-first:114533.92 22:27:57 INFO - PID 1637 | Values: 383.9 424.7 425.2 439.9 453.4 19586.0 21192.5 21891.7 22046.7 22098.9 18918.4 20094.3 20153.2 21630.4 23357.3 6120.4 6456.5 6458.6 6459.4 6461.6 5821.2 6103.5 6130.8 6234.5 6305.5 5425.0 6324.7 6335.8 6350.6 6352.4 6105.8 6272.1 6306.8 6361.6 6371.7 5405.7 5555.9 5644.3 5689.4 5692.1 27180.0 27180.3 27329.0 27457.5 27537.9 2177.2 2245.9 2253.9 2258.6 2260.0 17230.7 17723.5 18063.5 18122.9 18387.6 2134.9 2179.3 2182.4 2186.6 2193.8 164997.2 166393.0 167353.2 168371.0 168576.0 12470.4 12672.0 12701.9 12775.1 12853.4 16534.5 16542.7 16601.9 16642.3 16653.0 8791.5 9100.0 9101.4 9256.1 9256.8 7931.8 7963.7 8627.5 8673.6 8841.8 7819.3 7879.2 7929.9 7935.0 7967.6 2160.7 2170.5 2171.2 2175.8 2353.1 937.3 957.7 966.8 1004.6 1011.3 18882.3 20492.9 21189.5 21304.4 21374.7 996.1 1000.8 1018.6 1030.4 1031.8 10653.4 10918.2 10952.2 10958.1 10972.6 625729.0 634101.0 645040.0 730187.0 739194.0 122674.0 159557.0 160774.0 165707.0 165945.3 1086.8 1214.7 1246.7 1262.4 1264.2 17847.5 21703.7 22437.8 22480.7 22880.0 52905.7 63331.7 63694.7 64496.7 64936.0 86722.0 87979.2 89264.7 90103.0 92430.0 7485.4 8879.0 8902.6 8964.1 9009.9 13786.0 13970.2 13997.9 14187.6 14309.7 10270.5 11060.2 11655.1 11868.2 11986.9 8396.0 10293.3 10375.2 11287.7 11498.8 9760.2 10158.3 11800.9 12134.3 12326.5 8597.4 8612.5 8717.4 8948.0 8981.3 11883.2 11960.4 12044.6 12135.5 12181.0 22:27:57 INFO - PID 1637 | 22:27:57 INFO - PID 1637 | [#3] mootools.html Cycles:180 Average:7131.78 Median:2548.25 stddev:4473.74 (175.6%) stddev-sans-first:4465.35 22:27:57 INFO - PID 1637 | Values: 1372.0 1425.1 1431.8 1439.0 1442.3 10966.4 11613.2 11671.5 11844.1 12096.2 11615.3 11839.0 11848.2 11878.3 11881.0 2517.4 2557.8 2564.4 2568.6 2575.1 2469.5 2486.1 2512.7 2531.1 2538.7 2472.5 2520.7 2527.6 2527.9 2533.8 2429.6 2498.1 2505.5 2511.5 2512.8 2124.0 2246.7 2494.2 2510.5 2516.6 11658.8 11771.0 11837.8 11846.5 11859.7 6542.6 6739.2 6796.0 6808.6 6821.1 4077.6 4391.8 4396.5 4481.2 4481.3 1036.9 1270.7 1281.5 1300.9 1306.6 16024.3 16494.7 16604.8 16683.8 16783.7 5533.7 5630.4 5800.3 5843.4 5845.7 12141.3 12195.2 12205.9 12232.8 12250.3 6552.4 6563.9 6566.0 6567.4 6578.2 6055.0 6165.2 6167.9 6172.4 6215.5 3701.3 4361.0 4429.9 4463.6 4479.8 1041.4 1302.8 1309.7 1326.7 1330.4 757.5 759.3 796.2 799.1 817.7 5493.9 6769.6 6850.1 6899.3 6938.9 850.5 884.5 885.5 907.4 964.3 3970.3 4118.5 4120.5 4159.7 4227.8 12950.9 13753.2 13930.9 13985.7 14002.9 13621.8 13859.9 13927.4 13929.0 13951.9 10994.1 10999.5 11123.3 11217.4 11225.1 6280.1 6530.7 6551.2 6629.3 6661.1 11509.2 13370.1 13712.5 13782.9 13888.0 13887.5 14219.5 14295.6 14387.8 14395.9 8815.0 8873.2 8893.7 8947.7 9016.1 10728.7 10838.5 10844.9 10859.6 10865.3 8960.8 9144.2 9213.0 9248.3 9336.9 7452.7 8897.4 9017.1 9040.4 9085.0 6804.4 7896.8 7952.2 8063.5 8104.0 5908.7 5956.8 5968.6 6011.3 6071.2 6000.1 6022.7 6074.8 6141.1 6176.6 22:27:57 INFO - PID 1637 | 22:27:57 INFO - PID 1637 | [#4] prototype.html Cycles:180 Average:5152.63 Median:5922.99 stddev:1661.15 (28.0%) stddev-sans-first:1626.10 22:27:57 INFO - PID 1637 | Values: 342.6 397.9 399.8 403.2 409.4 5408.0 5924.0 6014.5 6135.0 6159.9 5352.0 5815.9 5955.9 5992.3 6004.0 3636.3 4299.7 4427.2 4445.9 4466.0 3883.9 4453.4 4472.6 4473.8 4479.7 3460.7 4433.9 4458.2 4461.9 4528.8 3853.6 4054.5 4149.7 4421.2 4454.3 3796.9 3814.2 3831.4 3832.3 3837.5 4793.2 5889.3 5902.6 5918.1 5922.0 4616.9 5201.8 5255.0 5283.3 5323.7 6413.7 6422.2 6424.4 6440.4 6473.9 3635.7 4189.5 4317.5 4366.6 4370.4 6378.6 6706.0 6793.6 6828.5 6867.9 3674.8 3944.6 4327.3 4363.2 4404.5 3558.0 3898.5 4448.5 4488.0 4498.0 5070.0 5141.4 5242.6 5268.2 5274.9 4624.7 5532.7 5545.8 6231.9 6405.2 1327.9 1356.7 1382.1 1429.0 1508.5 853.9 864.8 937.4 1002.0 1004.3 6108.0 6155.7 6155.9 6166.7 6201.8 6634.7 6648.1 6692.7 6696.7 6720.6 5320.5 5965.6 6005.0 6082.5 6105.6 2958.6 3067.6 3164.0 3445.3 3470.8 5425.9 5942.0 6576.9 6746.4 6852.2 6021.4 6183.5 6462.8 6653.6 6786.2 6418.9 6786.8 6830.4 6854.5 6880.4 5611.4 6552.6 6694.9 6740.1 6740.3 5611.6 5846.3 6463.5 6614.6 6622.3 5944.0 6822.8 6843.4 6850.1 6850.7 3554.4 3836.3 4278.3 4383.9 4398.7 5764.0 6625.6 6651.2 6725.9 6761.5 5957.9 5984.5 6208.4 6529.7 6537.4 5847.2 6221.0 6396.4 6441.3 6541.1 6340.0 6383.0 6401.9 6405.0 6448.3 6037.0 6365.7 6372.7 6412.6 6454.3 5720.9 5927.9 6352.5 6358.4 6365.6 22:27:57 INFO - PID 1637 | 22:27:57 INFO - PID 1637 | [#5] yui.html Cycles:180 Average:20892.32 Median:24509.25 stddev:28523.17 (116.4%) stddev-sans-first:28563.16 22:27:57 INFO - PID 1637 | Values: 767.7 818.3 830.1 862.1 873.1 13623.1 14208.8 15924.6 16116.8 16337.8 13213.7 13313.9 17030.7 17077.7 17080.9 952.8 989.4 998.0 998.3 1001.7 975.9 998.7 999.1 1001.6 1001.9 1002.5 1010.7 1038.4 1050.6 1060.7 1039.8 1049.4 1050.1 1051.9 1052.4 769.7 773.6 774.5 775.9 778.5 14148.7 16084.2 16368.7 16487.0 16959.4 14265.4 14541.8 16778.4 17093.9 17131.3 13532.9 13818.5 13887.8 14038.2 14060.4 3103.7 3514.3 3526.3 3541.9 3548.2 96990.5 102238.0 116175.4 116787.0 118372.8 13264.1 13376.5 13597.9 13604.9 13623.8 24154.8 24863.8 25049.0 25305.9 25425.5 9034.8 9059.5 9130.5 9202.4 9258.0 4818.3 4820.2 4822.5 4835.8 4916.0 4260.5 4354.0 4395.0 4396.3 4396.7 892.4 928.5 931.8 933.8 934.9 617.7 639.3 639.5 641.9 662.7 10627.6 11607.4 11711.3 11892.3 11904.2 616.8 631.8 634.0 646.4 649.6 5296.1 5398.7 5436.4 5446.0 5483.7 95622.5 96769.5 97065.0 98583.0 100700.0 55346.7 81952.0 83678.0 84212.7 84938.3 78320.6 79006.6 80799.6 81258.4 81658.0 10493.7 10822.4 11928.3 12012.4 12329.3 38046.4 39342.8 39491.8 39689.0 40011.4 68122.7 69101.7 69701.0 70900.0 70936.9 15244.7 15278.0 15905.9 15946.9 16155.6 17448.0 17810.6 17862.3 17873.4 17904.9 15782.6 16274.1 16286.0 16562.9 16653.3 16268.5 16268.8 16326.7 16616.2 16635.0 13309.9 13837.8 16107.2 16108.0 16296.6 9177.3 9661.4 9947.0 10422.8 11228.9 11102.2 12038.6 12348.8 12827.6 13216.4 22:27:57 INFO - PID 1637 | -------- Summary: end -------- 22:27:57 INFO - PID 1637 | 22:27:57 INFO - TEST-INFO | 1637: exit 0 22:27:57 INFO - TEST-OK | dromaeo_css | took 1115247ms 22:27:57 INFO - TEST-START | kraken 22:27:57 INFO - operating with platform_type : mac_ 22:27:57 INFO - Initialising browser for kraken test... 22:27:57 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/tmpDmTbMQ/profile http://localhost:49243/getInfo.html) 22:28:00 INFO - PID 1649 | 2017-04-13 22:28:00.803 plugin-container[1650:10049] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 22:28:00 INFO - PID 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:28:00 INFO - PID 1649 | 2017-04-13 22:28:00.806 plugin-container[1650:10049] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4623, name = 'com.apple.CFPasteboardClient' 22:28:00 INFO - PID 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:28:00 INFO - PID 1649 | 2017-04-13 22:28:00.806 plugin-container[1650:10049] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:28:00 INFO - PID 1649 | __metrics Screen width/height:1600/1200 22:28:00 INFO - PID 1649 | colorDepth:24 22:28:00 INFO - PID 1649 | Browser inner width/height: 1024/689 22:28:00 INFO - PID 1649 | __metrics 22:28:01 ERROR - PID 1649 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3374: TypeError: this.tabs is undefined 22:28:02 INFO - TEST-INFO | 1649: exit 0 22:28:02 INFO - Browser initialized. 22:28:02 INFO - Running cycle 1/1 for kraken test... 22:28:02 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/tmpDmTbMQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:28:04 INFO - PID 1651 | 2017-04-13 22:28:04.423 plugin-container[1652:10175] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x434b, name = 'com.apple.tsm.portname' 22:28:04 INFO - PID 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:28:04 INFO - PID 1651 | 2017-04-13 22:28:04.423 plugin-container[1652:10175] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9003, name = 'com.apple.CFPasteboardClient' 22:28:04 INFO - PID 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:28:04 INFO - PID 1651 | 2017-04-13 22:28:04.424 plugin-container[1652:10175] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:28:14 INFO - PID 1651 | RSS: Main: 239652864 22:28:14 INFO - PID 1651 | 22:28:17 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection) 22:28:18 INFO - PID 1651 | RSS: Main: 250945536 22:28:18 INFO - PID 1651 | 22:28:20 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-dft) 22:28:21 INFO - PID 1651 | RSS: Main: 259399680 22:28:21 INFO - PID 1651 | 22:28:25 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-fft) 22:28:25 INFO - PID 1651 | RSS: Main: 262197248 22:28:25 INFO - PID 1651 | 22:28:27 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator) 22:28:28 INFO - PID 1651 | RSS: Main: 263962624 22:28:28 INFO - PID 1651 | 22:28:30 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:28:30 INFO - PID 1651 | RSS: Main: 264572928 22:28:30 INFO - PID 1651 | 22:28:33 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom) 22:28:34 INFO - PID 1651 | RSS: Main: 270295040 22:28:34 INFO - PID 1651 | 22:28:37 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate) 22:28:37 INFO - PID 1651 | RSS: Main: 271020032 22:28:37 INFO - PID 1651 | 22:28:40 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial) 22:28:41 INFO - PID 1651 | RSS: Main: 270913536 22:28:41 INFO - PID 1651 | 22:28:42 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:28:43 INFO - PID 1651 | RSS: Main: 270233600 22:28:43 INFO - PID 1651 | 22:28:45 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:28:45 INFO - PID 1651 | RSS: Main: 270442496 22:28:45 INFO - PID 1651 | 22:28:48 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:28:48 INFO - PID 1651 | RSS: Main: 270032896 22:28:48 INFO - PID 1651 | 22:28:51 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:28:52 INFO - PID 1651 | RSS: Main: 270573568 22:28:52 INFO - PID 1651 | 22:28:55 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:28:56 INFO - PID 1651 | RSS: Main: 267698176 22:28:56 INFO - PID 1651 | 22:28:58 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49243/tests/kraken/driver.html?testName=ai-astar) 22:28:58 INFO - PID 1651 | RSS: Main: 269471744 22:28:58 INFO - PID 1651 | 22:28:58 INFO - PID 1651 | __start_tp_report 22:28:58 INFO - PID 1651 | _x_x_mozilla_page_load 22:28:58 INFO - PID 1651 | _x_x_mozilla_page_load_details 22:28:58 INFO - PID 1651 | |i|pagename|runs| 22:28:58 INFO - PID 1651 | |0;ai-astar;104;121;134;114;167;171;96;163;169;173 22:28:58 INFO - PID 1651 | |1;audio-beat-detection;146;114;111;151;110;112;111;114;140;115 22:28:58 INFO - PID 1651 | |2;audio-dft;197;215;221;188;218;276;276;224;202;197 22:28:58 INFO - PID 1651 | |3;audio-fft;78;61;63;83;59;63;61;59;77;58 22:28:58 INFO - PID 1651 | |4;audio-oscillator;83;69;85;70;72;72;89;71;71;90 22:28:58 INFO - PID 1651 | |5;imaging-gaussian-blur;89;90;89;89;90;89;104;102;89;89 22:28:58 INFO - PID 1651 | |6;imaging-darkroom;95;98;95;96;96;96;96;95;117;156 22:28:58 INFO - PID 1651 | |7;imaging-desaturate;91;92;89;91;91;92;93;93;92;91 22:28:58 INFO - PID 1651 | |8;json-parse-financial;52;51;51;52;55;52;52;52;52;53 22:28:58 INFO - PID 1651 | |9;json-stringify-tinderbox;57;65;68;56;73;64;60;60;73;84 22:28:58 INFO - PID 1651 | |10;stanford-crypto-aes;85;82;83;83;99;106;106;103;84;85 22:28:58 INFO - PID 1651 | |11;stanford-crypto-ccm;156;164;146;147;135;180;157;147;188;140 22:28:58 INFO - PID 1651 | |12;stanford-crypto-pbkdf2;202;217;185;191;206;240;243;191;180;201 22:28:58 INFO - PID 1651 | |13;stanford-crypto-sha256-iterative;68;69;72;68;69;69;68;70;67;66 22:28:58 INFO - PID 1651 | __end_tp_report 22:28:58 INFO - PID 1651 | __start_cc_report 22:28:58 INFO - PID 1651 | _x_x_mozilla_cycle_collect,989 22:28:58 INFO - PID 1651 | __end_cc_report 22:28:58 INFO - PID 1651 | __startTimestamp1492147738535__endTimestamp 22:28:58 INFO - PID 1651 | 22:28:58 INFO - PID 1651 | ------- Summary: start ------- 22:28:58 INFO - PID 1651 | Number of tests: 14 22:28:58 INFO - PID 1651 | 22:28:58 INFO - PID 1651 | [#0] ai-astar Cycles:10 Average:141.20 Median:168.00 stddev:30.62 (18.2%) stddev-sans-first:29.37 22:28:58 INFO - PID 1651 | Values: 104.0 121.0 134.0 114.0 167.0 171.0 96.0 163.0 169.0 173.0 22:28:58 INFO - PID 1651 | 22:28:58 INFO - PID 1651 | [#1] audio-beat-detection Cycles:10 Average:122.40 Median:114.50 stddev:16.34 (14.3%) stddev-sans-first:14.93 22:28:58 INFO - PID 1651 | Values: 146.0 114.0 111.0 151.0 110.0 112.0 111.0 114.0 140.0 115.0 22:28:58 INFO - PID 1651 | 22:28:58 INFO - PID 1651 | [#2] audio-dft Cycles:10 Average:221.40 Median:219.50 stddev:31.10 (14.2%) stddev-sans-first:31.71 22:28:58 INFO - PID 1651 | Values: 197.0 215.0 221.0 188.0 218.0 276.0 276.0 224.0 202.0 197.0 22:28:58 INFO - PID 1651 | 22:28:58 INFO - PID 1651 | [#3] audio-fft Cycles:10 Average:66.20 Median:63.00 stddev:9.33 (14.8%) stddev-sans-first:8.87 22:28:58 INFO - PID 1651 | Values: 78.0 61.0 63.0 83.0 59.0 63.0 61.0 59.0 77.0 58.0 22:28:58 INFO - PID 1651 | 22:28:58 INFO - PID 1651 | [#4] audio-oscillator Cycles:10 Average:77.20 Median:77.50 stddev:8.48 (10.9%) stddev-sans-first:8.73 22:28:58 INFO - PID 1651 | Values: 83.0 69.0 85.0 70.0 72.0 72.0 89.0 71.0 71.0 90.0 22:28:58 INFO - PID 1651 | 22:28:58 INFO - PID 1651 | [#5] imaging-gaussian-blur Cycles:10 Average:92.00 Median:89.00 stddev:5.83 (6.6%) stddev-sans-first:6.08 22:28:58 INFO - PID 1651 | Values: 89.0 90.0 89.0 89.0 90.0 89.0 104.0 102.0 89.0 89.0 22:28:58 INFO - PID 1651 | 22:28:58 INFO - PID 1651 | [#6] imaging-darkroom Cycles:10 Average:104.00 Median:96.00 stddev:19.46 (20.3%) stddev-sans-first:20.37 22:28:58 INFO - PID 1651 | Values: 95.0 98.0 95.0 96.0 96.0 96.0 96.0 95.0 117.0 156.0 22:28:58 INFO - PID 1651 | 22:28:58 INFO - PID 1651 | [#7] imaging-desaturate Cycles:10 Average:91.50 Median:92.00 stddev:1.18 (1.3%) stddev-sans-first:1.24 22:28:58 INFO - PID 1651 | Values: 91.0 92.0 89.0 91.0 91.0 92.0 93.0 93.0 92.0 91.0 22:28:58 INFO - PID 1651 | 22:28:58 INFO - PID 1651 | [#8] json-parse-financial Cycles:10 Average:52.20 Median:52.00 stddev:1.14 (2.2%) stddev-sans-first:1.20 22:28:58 INFO - PID 1651 | Values: 52.0 51.0 51.0 52.0 55.0 52.0 52.0 52.0 52.0 53.0 22:28:58 INFO - PID 1651 | 22:28:58 INFO - PID 1651 | [#9] json-stringify-tinderbox Cycles:10 Average:66.00 Median:66.50 stddev:8.72 (13.1%) stddev-sans-first:8.62 22:28:58 INFO - PID 1651 | Values: 57.0 65.0 68.0 56.0 73.0 64.0 60.0 60.0 73.0 84.0 22:28:58 INFO - PID 1651 | 22:28:58 INFO - PID 1651 | [#10] stanford-crypto-aes Cycles:10 Average:91.60 Median:83.50 stddev:10.46 (12.5%) stddev-sans-first:10.82 22:28:58 INFO - PID 1651 | Values: 85.0 82.0 83.0 83.0 99.0 106.0 106.0 103.0 84.0 85.0 22:28:58 INFO - PID 1651 | 22:28:58 INFO - PID 1651 | [#11] stanford-crypto-ccm Cycles:10 Average:156.00 Median:156.50 stddev:17.07 (10.9%) stddev-sans-first:18.11 22:28:58 INFO - PID 1651 | Values: 156.0 164.0 146.0 147.0 135.0 180.0 157.0 147.0 188.0 140.0 22:28:58 INFO - PID 1651 | 22:28:58 INFO - PID 1651 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:205.60 Median:204.00 stddev:21.74 (10.7%) stddev-sans-first:23.02 22:28:58 INFO - PID 1651 | Values: 202.0 217.0 185.0 191.0 206.0 240.0 243.0 191.0 180.0 201.0 22:28:58 INFO - PID 1651 | 22:28:58 INFO - PID 1651 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.60 Median:69.00 stddev:1.65 (2.4%) stddev-sans-first:1.73 22:28:58 INFO - PID 1651 | Values: 68.0 69.0 72.0 68.0 69.0 69.0 68.0 70.0 67.0 66.0 22:28:58 INFO - PID 1651 | -------- Summary: end -------- 22:28:58 INFO - PID 1651 | 22:28:59 INFO - TEST-INFO | 1651: exit 0 22:28:59 INFO - TEST-OK | kraken | took 61850ms 22:28:59 INFO - SUITE-END | took 1177s 22:28:59 INFO - Completed test suite (00:19:37) 22:28:59 INFO - javascript benchmark 22:28:59 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7868.026275812577, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3527.9592520206543, 3553.413686741686, 3567.3222087803315, 3605.603383627799, 3621.4748861415096, 4954.405471343435, 6196.070589411591, 6221.0, 6248.9999999999945, 6305.716242769075, 4821.807127714887, 6176.783812566561, 6192.752289908398, 6212.875742485147, 6275.6548389838545, 5463.344398672145, 5531.917021244684, 5542.946840100388, 5567.109262517998, 5628.915566266509, 4942.110420124376, 5439.4832490913495, 5554.611177217596, 5585.776568937237, 5623.718814059301, 4132.648724858391, 5293.867653308698, 5561.416051314634, 5574.637648552831, 5598.832035038955, 5471.179323101527, 5487.341519017704, 5493.835184944418, 5539.058360078796, 5548.639338442988, 5444.237806707064, 5470.398256191816, 5474.698891560963, 5487.067198576252, 5493.423190565013, 4839.669090999968, 5878.735456904449, 6145.231846019247, 6239.313675495692, 6257.2804127525005, 6770.898436523456, 6780.288069752654, 6795.38841504267, 6807.4894382921475, 6829.93170068298, 6862.622555759432, 6869.141357330333, 6880.0, 6889.965550172267, 6895.655217239118, 5335.733213339317, 6301.495880329563, 6335.412989982886, 6338.492920566436, 6400.775972840975, 5843.824685259449, 6975.337342952412, 6990.370866622028, 6992.650367481606, 7029.0, 6464.515161362917, 6549.672516374162, 6558.934410655927, 6631.10480085192, 6701.765438209639, 6789.796306110825, 6834.077399550997, 6875.1749790025515, 6877.690503927335, 6880.2431732509385, 6473.0, 6481.449076828428, 6753.223379311344, 6767.492438067165, 6795.116634837538, 6652.800415987528, 6691.7323307068145, 6696.06255124283, 6740.29226931175, 6748.93251067483, 5431.565474762009, 5432.483914028161, 5440.4287549807495, 5444.8366549003595, 5481.671099734029, 4700.811967521261, 4817.855464336076, 4835.105505481497, 4862.246351815475, 4883.267509873476, 5753.568482363839, 6201.286852012076, 6286.371362863769, 6357.427831495188, 6395.968020159962, 5615.887682246333, 6770.018347339608, 6820.0, 6843.863122737617, 6845.828854278683, 5473.28847249863, 6276.623402595859, 6335.11308416822, 6369.840753981095, 6382.5851319665, 5786.363500014995, 5858.209141765892, 5867.295924489089, 5869.178315035899, 5911.379305172895, 6940.548864323702, 6989.8951515726285, 6991.545549539263, 7006.824829379307, 7025.0, 6776.966115169393, 6844.760433384808, 6848.760293389708, 6854.2117656470145, 6867.038614594061, 6716.6977486012265, 6785.762498312535, 6936.375726184668, 6989.091418115612, 6993.650317484004, 5653.971730141323, 6682.164729408824, 6722.294159113419, 6741.662916854236, 6758.4931130166415, 6493.8701225976165, 6572.671366431755, 6827.863442731018, 6864.347886950732, 6878.484113691593, 6088.178095956989, 6424.935750642434, 6466.224053113657, 6490.091387205701, 6515.576487528296, 6559.245686746085, 6565.080888675685, 6571.047198156336, 6592.307807680125, 6595.307492713388, 6275.874482510416, 6310.6529140895855, 6377.362263773585, 6405.167328247298, 6447.097406363207, 6490.448311893542, 6597.60414375139, 6603.075569420189, 6608.570442921195, 6610.2398224204835, 5517.78608706084, 5998.430149135826, 6449.0, 6457.61254324742, 6459.61242325462], "value": 6103.780195540683, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [738.6639079218955, 1256.1897576063438, 1260.8171815086816, 1274.6622145131548, 1311.6130741431282, 37067.0, 37810.621893781055, 37967.81016094919, 38036.0, 38085.66700165495, 37768.0, 38066.04834879129, 38110.809445952735, 38129.61870381295, 38360.0, 8320.667173313075, 8509.829803403929, 8574.528400937947, 8583.5279059652, 8593.527355995418, 8608.966923969132, 8675.869861952044, 8724.08397118303, 8730.345224108185, 8743.606537705818, 8658.480491170518, 8671.56642167889, 8736.388452808305, 8746.737597872043, 8776.517291548962, 6924.446044316443, 8543.060263371024, 8682.435641683302, 8702.608382622797, 8729.519876406795, 8517.403781735282, 8577.05652378238, 8582.527960962145, 8591.828163436696, 8628.266597339234, 36688.44967325492, 36944.63055369439, 36974.0, 37004.81497592522, 37097.62902370969, 2963.95554066687, 3526.7002304804123, 3561.8753343632848, 3563.928721425583, 3592.7305452090936, 30242.0, 30588.388232235455, 30700.078997630106, 30709.078727638207, 30709.846450767825, 3119.8752049917975, 3156.4002839460427, 3162.478191098489, 3163.889263875776, 3166.4300425923325, 269518.65240673866, 283694.0, 284416.0, 285538.5723071392, 285741.99806013587, 44951.426700066, 45175.322370164475, 45188.93299603052, 45215.0, 45255.0, 47899.0, 48752.781180470774, 49738.0, 51516.74241628768, 51541.484585154416, 14150.29248537569, 14196.432142714382, 14232.501862434972, 14242.0, 14264.643383915485, 11415.258008229439, 11435.942820285845, 11441.427928603536, 11450.969412752893, 11473.196876218584, 11358.60244891425, 12900.741985160244, 13214.33928303581, 13258.270795106362, 13376.732465350638, 5513.669179849222, 5738.913916291259, 6049.455549000611, 6056.273247210363, 6102.084687296941, 1023.8668973033458, 1043.0977204717865, 1045.320541647935, 1076.1874784537624, 1080.8811030786608, 20302.25919500878, 24273.0, 24455.63316550253, 24471.392723964786, 25021.62467562988, 1131.5643477261315, 1136.8237923121928, 1145.3128123126223, 1182.173541877801, 1190.0776897904193, 12382.318972456602, 12453.1905426147, 12525.56160534377, 12548.0, 12575.748485030248, 345652.2717386397, 346044.0, 346452.0, 347669.0, 348139.0, 53965.0, 54121.729391353576, 54191.72904135454, 54228.457715422344, 54232.0, 1536.6696160325585, 1549.171193411542, 1583.5011971229255, 1585.603599100225, 1593.3228377939329, 23613.52772944497, 23639.409014774767, 23725.16961906325, 23727.52544949126, 23910.641340380254, 37508.81245593754, 37699.81150094342, 37710.868673939825, 37753.81123094367, 37767.0, 315154.0, 319450.0, 319939.0, 320272.0, 320478.0, 34352.48471272983, 34547.481787772704, 34656.65343346534, 35649.46525802063, 37388.878333650035, 44886.10227795491, 45751.313730293405, 45758.54241457676, 45788.0, 45867.77066114648, 29546.374949377132, 29554.81780728747, 30519.0, 31202.843985780833, 31422.685773141977, 30677.0, 31150.844245778626, 31235.0, 31534.0, 31537.52693709642, 36423.27153456969, 36431.36058877304, 37265.6273437273, 37481.62518374781, 37497.81251093727, 24774.132905348226, 25042.0, 30731.692683072884, 31387.686123138476, 31837.6816231844, 23783.0, 24054.63918041196, 24591.75408245966, 24603.261902142964, 24650.87674561616], "value": 17729.15085168591, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [383.9289731399691, 424.69846409049575, 425.2154774441155, 439.93840862279285, 453.4354728363188, 19586.0, 21192.4701882453, 21891.6716249256, 22046.669299960515, 22098.88950555247, 18918.43244702661, 20094.296699615516, 20153.19387224513, 21630.3510894673, 23357.299281021555, 6120.418560236771, 6456.451201647842, 6458.644774537399, 6459.35406459355, 6461.61230326182, 5821.18503409523, 6103.511719062487, 6130.846728831771, 6234.50123990082, 6305.46403555696, 5425.0, 6324.652144132071, 6335.8416039598915, 6350.618962862243, 6352.396522330371, 6105.755769769204, 6272.090546870678, 6306.810795676137, 6361.55469117162, 6371.713272902684, 5405.702686352249, 5555.916661250085, 5644.322681278232, 5689.431056894277, 5692.089265717506, 27180.0, 27180.32049198786, 27329.0, 27457.45085098287, 27537.862310688517, 2177.150911144655, 2245.9438514037115, 2253.921112761062, 2258.6386178211405, 2259.9548009039727, 17230.741538876926, 17723.46829595114, 18063.458096257134, 18122.912625242527, 18387.62092843042, 2134.9146034158616, 2179.259051922338, 2182.443476913377, 2186.6392045312664, 2193.7806219377935, 164997.17501412536, 166393.0, 167353.16323418426, 168371.0, 168576.0, 12470.43883025266, 12672.0, 12701.872981270253, 12775.105742597929, 12853.357332133355, 16534.503964881074, 16542.66914661724, 16601.91699041521, 16642.251098700588, 16653.0, 8791.472511649194, 9100.0, 9101.40840845343, 9256.120668536478, 9256.768580785534, 7931.762047138595, 7963.725803871353, 8627.482351058956, 8673.566321683891, 8841.823163536694, 7819.335356494762, 7879.172686867911, 7929.881051784228, 7935.0, 7967.601619919097, 2160.6650969099815, 2170.5007848194687, 2171.2400659768955, 2175.8259339252822, 2353.0940587873793, 937.2642475656577, 957.7214418751046, 966.7824739433712, 1004.5981607357115, 1011.2819897872446, 18882.3391181308, 20492.872891991083, 21189.470263243235, 21304.36086917395, 21374.679379809317, 996.0786272697726, 1000.8298589239847, 1018.6180182431589, 1030.4384110659707, 1031.8245898197324, 10653.360798352123, 10918.235723499438, 10952.233343665866, 10958.123350131971, 10972.615958441416, 625729.0, 634101.0, 645040.0, 730187.0, 739194.0, 122674.0, 159557.0, 160774.0, 165707.0, 165945.34054659298, 1086.8478413022183, 1214.723334458974, 1246.7257203415234, 1262.4255963536568, 1264.178284115314, 17847.46457606222, 21703.674444883025, 22437.775622243567, 22480.66279005849, 22880.0, 52905.7354713224, 63331.683341583, 63694.7261054767, 64496.67751661399, 64936.0, 86722.0, 87979.24041519262, 89264.6610300859, 90103.0, 92430.0, 7485.438592105723, 8878.955605221932, 8902.643894244158, 8964.096965486337, 9009.873765779277, 13786.034977551475, 13970.231637260049, 13997.930010349883, 14187.64530886736, 14309.713805723619, 10270.537825798006, 11060.22578419535, 11655.13517837142, 11868.228565143238, 11986.940065299967, 8396.0, 10293.279470436997, 10375.169986400919, 11287.661370158909, 11498.770024599295, 9760.219182465527, 10158.288919775545, 11800.937915587638, 12134.271943683407, 12326.459192600925, 8597.355198360023, 8612.535868902176, 8717.433366831136, 8947.955260223658, 8981.326400519856, 11883.227590206609, 11960.401979900797, 12044.578439754567, 12135.453904574139, 12181.025517958366], "value": 10669.625224496569, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1371.951981680641, 1425.052340193772, 1431.813864197654, 1438.9568312950612, 1442.2716528153294, 10966.396848173357, 11613.187076904609, 11671.474783634736, 11844.111691623131, 12096.213746106498, 11615.303081815096, 11839.0, 11848.17062805604, 11878.346690931996, 11881.049516038718, 2517.358073691211, 2557.8337408068414, 2564.4101856572997, 2568.5890257558794, 2575.0858445251947, 2469.506098780242, 2486.080150344366, 2512.6984761828608, 2531.088808029108, 2538.657281267024, 2472.5178590174924, 2520.7101183363966, 2527.6208568714746, 2527.949441011188, 2533.8353007054575, 2429.6112621980574, 2498.0507407185337, 2505.5114252720728, 2511.547921374152, 2512.824102312839, 2123.968140477894, 2246.6854640350202, 2494.164454907608, 2510.497900419905, 2516.6099254615565, 11658.825117623242, 11771.0, 11837.763244735057, 11846.466908989116, 11859.703507412298, 6542.574732642363, 6739.191297044386, 6796.0, 6808.6255255960905, 6821.055999040299, 4077.6330130288416, 4391.780410979471, 4396.450443694538, 4481.170983368087, 4481.260592002316, 1036.8807587127426, 1270.6950331920366, 1281.5002149161835, 1300.9154404963647, 1306.5688322853437, 16024.278907449192, 16494.67010659792, 16604.750928735957, 16683.83316166835, 16783.66432671352, 5533.667979921218, 5630.380658127603, 5800.259922023376, 5843.444872737083, 5845.707714614252, 12141.271523708429, 12195.207311524722, 12205.93897030527, 12232.755344893052, 12250.326232057323, 6552.3775241352, 6563.934360656428, 6566.015097735282, 6567.408933196035, 6578.177727784027, 6055.000924847439, 6165.229346331708, 6167.907481387782, 6172.382761723881, 6215.471684906834, 3701.2597480504005, 4361.03402950196, 4429.889252768707, 4463.598276155161, 4479.820807167677, 1041.3959903256093, 1302.798066299725, 1309.738052389507, 1326.7412854493475, 1330.4279159961116, 757.526545908807, 759.3014426727424, 796.2157275084038, 799.0770659887722, 817.6565842346229, 5493.945060549343, 6769.627670478171, 6850.143732033496, 6899.275576064541, 6938.895916561256, 850.4939560961218, 884.4604791077437, 885.4819930340694, 907.4011152639226, 964.3442459127721, 3970.3250447424007, 4118.5057793064425, 4120.505539335299, 4159.708820382604, 4227.830886764556, 12950.870491294967, 13753.243571603658, 13930.930345348208, 13985.65035874091, 14002.87976961861, 13621.795673064911, 13859.861401385857, 13927.373268202955, 13929.0, 13951.930240348733, 10994.120470362512, 10999.505022274017, 11123.332600043861, 11217.3830439325, 11225.101991840633, 6280.074953154279, 6530.67346632676, 6551.246606640298, 6629.3039230882, 6661.134052573135, 11509.194356394972, 13370.06409551361, 13712.52006179779, 13782.862171378158, 13888.027838051239, 13887.513937012156, 14219.50231741884, 14295.570442956037, 14387.784183237469, 14395.856041439452, 8814.955925220333, 8873.201411872962, 8893.73318800437, 8947.686830960885, 9016.09839016093, 10728.678139655824, 10838.512266947848, 10844.945775271073, 10859.619913302997, 10865.293755905841, 8960.820783584162, 9144.177024067865, 9213.0, 9248.306377021885, 9336.859947100935, 7452.701891924262, 8897.377183597086, 9017.143371379709, 9040.367174297999, 9085.046070162538, 6804.421624161803, 7896.84206315882, 7952.165022672536, 8063.516189028677, 8104.0, 5908.659035850851, 5956.76172953077, 5968.552358573212, 6011.27864656244, 6071.210742603434, 6000.0999850022845, 6022.698865056642, 6074.757009719739, 6141.1402403664415, 6176.598521096114], "value": 5392.823396357148, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [342.6453620502779, 397.92837289287934, 399.82607565708935, 403.2398928020681, 409.3613962218937, 5408.0, 5924.0, 6014.488768454681, 6135.0, 6159.938400615993, 5352.0, 5815.883682326351, 5955.940440595593, 5992.340842507331, 6004.0, 3636.309101270763, 4299.677524185683, 4427.225235583776, 4445.911081778362, 4465.977670111661, 3883.8834834954996, 4453.376527286183, 4472.619827314682, 4473.753943533105, 4479.70881892676, 3460.723142148622, 4433.88915277119, 4458.197524445599, 4461.862225132587, 4528.841490547831, 3853.614638536152, 4054.472918520603, 4149.668026557868, 4421.226285400042, 4454.265046267378, 3796.8671096511766, 3814.2371525694693, 3831.3869780834934, 3832.3485007548784, 3837.520309961255, 4793.23308270675, 5889.322727886305, 5902.557308201902, 5918.082697181943, 5921.970390148022, 4616.884577885546, 5201.817936372247, 5255.02781985334, 5283.28675628789, 5323.733813309319, 6413.711382987777, 6422.197225346832, 6424.357564243585, 6440.355964403569, 6473.870522589522, 3635.6727894489622, 4189.518205406356, 4317.4819021717285, 4366.637688979468, 4370.409994650745, 6378.553501254914, 6705.966470167667, 6793.558418702769, 6828.487863410264, 6867.862642747167, 3674.761140525857, 3944.6252606002386, 4327.32926396409, 4363.214621368184, 4404.471463424409, 3557.964420355815, 3898.5321761388814, 4448.532904045093, 4487.977560112244, 4497.955020449754, 5069.9720111954775, 5141.434442211354, 5242.606804489678, 5268.15709486488, 5274.868128296746, 4624.676272660881, 5532.723363831873, 5545.7781688732, 6231.940570103003, 6405.19935008124, 1327.897844788823, 1356.6540532164236, 1382.0878220374505, 1429.0282607826778, 1508.4569554960206, 853.8932633420823, 864.8486514859875, 937.3625934364561, 1002.0080120680474, 1004.3321191407778, 6108.0, 6155.72299246535, 6155.907661385083, 6166.691665416712, 6201.7519299228425, 6634.701438435282, 6648.102506161703, 6692.665366731644, 6696.665166741644, 6720.630365329856, 5320.494553017457, 5965.582409231399, 6005.0, 6082.543809214327, 6105.589131412, 2958.6301712285963, 3067.619571192972, 3163.9558945547915, 3445.2764919367214, 3470.843812028465, 5425.891482170335, 5942.0, 6576.934230657728, 6746.436354185378, 6852.177738671391, 6021.42796434338, 6183.505319574513, 6462.7738029169695, 6653.567518111307, 6786.219584747719, 6418.93581064202, 6786.830329241809, 6830.351116643911, 6854.451643868479, 6880.415164710956, 5611.38274789781, 6552.634326021078, 6694.895342268471, 6740.12378390806, 6740.258571557027, 5611.551075913999, 5846.298444186725, 6463.547551671338, 6614.603123812587, 6622.271550129385, 5943.970280148572, 6822.761203357859, 6843.3840954314355, 6850.0752398425575, 6850.6574671267235, 3554.360215161192, 3836.290286297044, 4278.272693642026, 4383.890402739957, 4398.73607583546, 5763.956538029224, 6625.635590042401, 6651.201855777338, 6725.865482690221, 6761.492888033319, 5957.880842383041, 5984.461398474158, 6208.441240288215, 6529.67351632426, 6537.378948999837, 5847.239858818326, 6221.004639257697, 6396.360363963567, 6441.291457939717, 6541.1268604889665, 6339.956820725985, 6382.968085159545, 6401.903971440339, 6404.967975160094, 6448.258450278278, 6037.003894357382, 6365.681715914278, 6372.681365931778, 6412.61524308543, 6454.322296158835, 5720.914186287126, 5927.940720592739, 6352.460040896575, 6358.364163583605, 6365.64988925595], "value": 4627.437548451521, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [767.6507189228901, 818.2553875972866, 830.1242189490089, 862.0991064337767, 873.1443185678037, 13623.114497557652, 14208.786868196985, 15924.601884952868, 16116.758248626265, 16337.83662163378, 13213.735725285513, 13313.868321192685, 17030.659386812255, 17077.7438338425, 17080.914595427035, 952.8380175370187, 989.4261328429494, 998.0169533009979, 998.346083315426, 1001.7445551384387, 975.9121679048884, 998.6754304850916, 999.0858364596422, 1001.5643195210071, 1001.9248556358302, 1002.5488530161457, 1010.7264846293649, 1038.4081073787943, 1050.5902697947806, 1060.7136073260215, 1039.833626619741, 1049.3913530152493, 1050.0811789684026, 1051.9158467322634, 1052.36857885269, 769.6921231507442, 773.5668025905454, 774.4888373673349, 775.8642237608452, 778.4706399648237, 14148.717025659533, 16084.195790210442, 16368.672626547403, 16487.0, 16959.406420775213, 14265.358058887272, 14541.78187327191, 16778.412755553618, 17093.91453042739, 17131.314747410088, 13532.864671353258, 13818.516351927734, 13887.79168312476, 14038.227897465635, 14060.367283472267, 3103.658597554267, 3514.3147086318177, 3526.347625689256, 3541.9291614167573, 3548.1661809474754, 96990.51504742431, 102237.99999999926, 116175.41912290468, 116787.0, 118372.8162718379, 13264.071514994052, 13376.46494140243, 13597.932010339884, 13604.863951360558, 13623.795643065361, 24154.7584524156, 24863.751362486506, 25049.0, 25305.87347063253, 25425.49149017046, 9034.819303614022, 9059.456432614064, 9130.497822619687, 9202.35583509148, 9258.0, 4818.277258411266, 4820.204666230033, 4822.493638168012, 4835.830745923876, 4915.992221594564, 4260.531341552427, 4353.978230108829, 4394.978025109854, 4396.340548917688, 4396.736195828196, 892.4422236102436, 928.5496534180917, 931.8415869302235, 933.7758937855002, 934.9345545811727, 617.7175337129354, 639.2616527910271, 639.5491178718992, 641.9229692436937, 662.6653539962289, 10627.628033018962, 11607.419629018515, 11711.301861230075, 11892.286462812259, 11904.166708330335, 616.8149555133442, 631.8167731357946, 633.9904901426482, 646.4393702429405, 649.6004956951441, 5296.126139186991, 5398.73006349681, 5436.374816896107, 5446.0, 5483.670979741229, 95622.52188739012, 96769.5484567718, 97065.02934970701, 98583.0, 100700.0, 55346.72326638341, 81952.0, 83678.0, 84212.73680894791, 84938.30123397497, 78320.60839695879, 79006.6049669748, 80799.5960020196, 81258.37483250302, 81658.0, 10493.685189444328, 10822.40476773785, 11928.284302941851, 12012.399380031138, 12329.260244385365, 38046.42930355992, 39342.803285983384, 39491.80254098711, 39689.0, 40011.399829002, 68122.65938670473, 69101.65449172723, 69700.95448568174, 70900.0, 70936.93594596189, 15244.695106097593, 15278.006929549543, 15905.920470397574, 15946.9202653986, 16155.59611009687, 17448.040357780443, 17810.554736131184, 17862.285508579513, 17873.374431894823, 17904.91047544806, 15782.60543486376, 16274.104924228808, 16286.0, 16562.917185413997, 16653.25060372262, 16268.51194464168, 16268.837311626732, 16326.673466530841, 16616.169191540615, 16635.0, 13309.866901330863, 13837.823784978389, 16107.194640268173, 16108.033517988959, 16296.592585185464, 9177.311701622539, 9661.420314781135, 9946.95556966508, 10422.843657345298, 11228.887711122785, 11102.222844400814, 12038.578649747216, 12348.81476777831, 12827.615171544869, 13216.405261763435], "value": 8233.22088431027, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1555.8999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 121.0, 134.0, 114.0, 167.0, 171.0, 96.0, 163.0, 169.0, 173.0], "value": 141.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 114.0, 111.0, 151.0, 110.0, 112.0, 111.0, 114.0, 140.0, 115.0], "value": 122.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 215.0, 221.0, 188.0, 218.0, 276.0, 276.0, 224.0, 202.0, 197.0], "value": 221.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 61.0, 63.0, 83.0, 59.0, 63.0, 61.0, 59.0, 77.0, 58.0], "value": 66.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 69.0, 85.0, 70.0, 72.0, 72.0, 89.0, 71.0, 71.0, 90.0], "value": 77.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 90.0, 89.0, 89.0, 90.0, 89.0, 104.0, 102.0, 89.0, 89.0], "value": 92.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 98.0, 95.0, 96.0, 96.0, 96.0, 96.0, 95.0, 117.0, 156.0], "value": 104.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 92.0, 89.0, 91.0, 91.0, 92.0, 93.0, 93.0, 92.0, 91.0], "value": 91.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 51.0, 51.0, 52.0, 55.0, 52.0, 52.0, 52.0, 52.0, 53.0], "value": 52.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 65.0, 68.0, 56.0, 73.0, 64.0, 60.0, 60.0, 73.0, 84.0], "value": 66.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 82.0, 83.0, 83.0, 99.0, 106.0, 106.0, 103.0, 84.0, 85.0], "value": 91.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 164.0, 146.0, 147.0, 135.0, 180.0, 157.0, 147.0, 188.0, 140.0], "value": 156.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [202.0, 217.0, 185.0, 191.0, 206.0, 240.0, 243.0, 191.0, 180.0, 201.0], "value": 205.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 72.0, 68.0, 69.0, 69.0, 68.0, 70.0, 67.0, 66.0], "value": 68.6, "unit": "score"}]}]} 22:28:59 INFO - Return code: 0 22:28:59 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:28:59 ERROR - # TBPL SUCCESS # 22:28:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:28:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:28:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:28:59 INFO - 2017-04-13 22:28:59.902 screenresolution[1656:10568] starting screenresolution argv=screenresolution get 22:28:59 INFO - 2017-04-13 22:28:59.905 screenresolution[1656:10568] Display 0: 1600x1200x32@60 22:28:59 INFO - 2017-04-13 22:28:59.911 screenresolution[1657:10571] starting screenresolution argv=screenresolution list 22:28:59 INFO - Available Modes on Display 0 22:28:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:28:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:28:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:28:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:29:00 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:29:00 INFO - Intel Iris: 22:29:00 INFO - Chipset Model: Intel Iris 22:29:00 INFO - Type: GPU 22:29:00 INFO - Bus: Built-In 22:29:00 INFO - VRAM (Dynamic, Max): 1536 MB 22:29:00 INFO - Vendor: Intel (0x8086) 22:29:00 INFO - Device ID: 0x0a2e 22:29:00 INFO - Revision ID: 0x0009 22:29:00 INFO - Displays: 22:29:00 INFO - Display: 22:29:00 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:29:00 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:29:00 INFO - Display Serial Number: 1600x1200 60 22:29:00 INFO - Main Display: Yes 22:29:00 INFO - Mirror: Off 22:29:00 INFO - Online: Yes 22:29:00 INFO - Rotation: Supported 22:29:00 INFO - Return code: 0 22:29:00 INFO - Running post-action listener: _resource_record_post_action 22:29:00 INFO - [mozharness: 2017-04-14 05:29:00.156646Z] Finished run-tests step (success) 22:29:00 INFO - Running post-run listener: _resource_record_post_run 22:29:00 INFO - Total resource usage - Wall time: 1197s; CPU: 54.0%; Read bytes: 40978944; Write bytes: 514040832; Read time: 398; Write time: 1911 22:29:00 INFO - TinderboxPrint: CPU usage
54.2% 22:29:00 INFO - TinderboxPrint: I/O read bytes / time
40,978,944 / 398 22:29:00 INFO - TinderboxPrint: I/O write bytes / time
514,040,832 / 1,911 22:29:00 INFO - TinderboxPrint: CPU idle
2,193.6 (45.8%) 22:29:00 INFO - TinderboxPrint: CPU system
964.9 (20.1%) 22:29:00 INFO - TinderboxPrint: CPU user
1,631.3 (34.1%) 22:29:00 INFO - TinderboxPrint: Swap in / out
248,332,288 / 0 22:29:00 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 194589184; Write bytes: 193562624; Read time: 15061; Write time: 615 22:29:00 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 40008192; Write bytes: 303639552; Read time: 383; Write time: 1245 22:29:00 INFO - Running post-run listener: _upload_blobber_files 22:29:00 INFO - Blob upload gear active. 22:29:00 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:29:00 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 22:29:00 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'] 22:29:00 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 22:29:01 INFO - (blobuploader) - INFO - Open directory for files ... 22:29:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 22:29:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:29:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:29:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 22:29:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:29:01 INFO - (blobuploader) - INFO - Done attempting. 22:29:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 22:29:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:29:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:29:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 22:29:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:29:02 INFO - (blobuploader) - INFO - Done attempting. 22:29:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 22:29:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:29:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:29:03 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 22:29:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:29:03 INFO - (blobuploader) - INFO - Done attempting. 22:29:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:29:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:29:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:29:05 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:29:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:29:05 INFO - (blobuploader) - INFO - Done attempting. 22:29:05 INFO - (blobuploader) - INFO - Iteration through files over. 22:29:05 INFO - Return code: 0 22:29:05 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:29:05 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:29:05 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e4e8803c5a37e247fbb2e695377eb8966b87361ce233321f594ac0dea6c246665fd3c8e945350eb7157889c7c5bbd8388974508b51e377e7f96d2b8f53a4c23d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/31bcee45fdc611e3bd53b942957279aaa2c8d063fbeb65f8f2ba328f12d8e275eb8a5ac7506ebb98ed767ddf1ac91c93e6075f9fa8077f3bfc85cdf165228013", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5cca0c631254b4f2455ec0b10ba91db8c0611f70c725770220e7583e8026c9f92f33355d5aa568088d041595ce96687b6d3c1f8a158e570c21c6c92c444cf3e0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/550103066ec7d7fa46711e9b25a22dee5c1453a653f8e939f8e36680d1ac8af914740b72310bfaab838369e441a3f2c8287d6c9b9f1d8653eec2eff7c733dc1d"} 22:29:05 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:29:05 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:29:05 INFO - Contents: 22:29:05 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e4e8803c5a37e247fbb2e695377eb8966b87361ce233321f594ac0dea6c246665fd3c8e945350eb7157889c7c5bbd8388974508b51e377e7f96d2b8f53a4c23d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/31bcee45fdc611e3bd53b942957279aaa2c8d063fbeb65f8f2ba328f12d8e275eb8a5ac7506ebb98ed767ddf1ac91c93e6075f9fa8077f3bfc85cdf165228013", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5cca0c631254b4f2455ec0b10ba91db8c0611f70c725770220e7583e8026c9f92f33355d5aa568088d041595ce96687b6d3c1f8a158e570c21c6c92c444cf3e0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/550103066ec7d7fa46711e9b25a22dee5c1453a653f8e939f8e36680d1ac8af914740b72310bfaab838369e441a3f2c8287d6c9b9f1d8653eec2eff7c733dc1d"} 22:29:05 INFO - Running post-run listener: copy_logs_to_upload_dir 22:29:05 INFO - Copying logs to upload dir... 22:29:05 INFO - mkdir: /builds/slave/test/build/upload/logs 22:29:05 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1282.372000 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2017-04-13 22:29:05.697014) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-13 22:29:05.700854) ========= 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.zkR0P1Iu4B/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.kmBOSZdsKA/Listeners TMPDIR=/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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/e4e8803c5a37e247fbb2e695377eb8966b87361ce233321f594ac0dea6c246665fd3c8e945350eb7157889c7c5bbd8388974508b51e377e7f96d2b8f53a4c23d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/31bcee45fdc611e3bd53b942957279aaa2c8d063fbeb65f8f2ba328f12d8e275eb8a5ac7506ebb98ed767ddf1ac91c93e6075f9fa8077f3bfc85cdf165228013", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5cca0c631254b4f2455ec0b10ba91db8c0611f70c725770220e7583e8026c9f92f33355d5aa568088d041595ce96687b6d3c1f8a158e570c21c6c92c444cf3e0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/550103066ec7d7fa46711e9b25a22dee5c1453a653f8e939f8e36680d1ac8af914740b72310bfaab838369e441a3f2c8287d6c9b9f1d8653eec2eff7c733dc1d"} build_url:https://queue.taskcluster.net/v1/task/e2EjPsLZQ2WiwlIG_zTPoA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011470 build_url: 'https://queue.taskcluster.net/v1/task/e2EjPsLZQ2WiwlIG_zTPoA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e4e8803c5a37e247fbb2e695377eb8966b87361ce233321f594ac0dea6c246665fd3c8e945350eb7157889c7c5bbd8388974508b51e377e7f96d2b8f53a4c23d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/31bcee45fdc611e3bd53b942957279aaa2c8d063fbeb65f8f2ba328f12d8e275eb8a5ac7506ebb98ed767ddf1ac91c93e6075f9fa8077f3bfc85cdf165228013", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5cca0c631254b4f2455ec0b10ba91db8c0611f70c725770220e7583e8026c9f92f33355d5aa568088d041595ce96687b6d3c1f8a158e570c21c6c92c444cf3e0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/550103066ec7d7fa46711e9b25a22dee5c1453a653f8e939f8e36680d1ac8af914740b72310bfaab838369e441a3f2c8287d6c9b9f1d8653eec2eff7c733dc1d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-13 22:29:05.735887) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-13 22:29:05.736239) ========= 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.zkR0P1Iu4B/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.kmBOSZdsKA/Listeners TMPDIR=/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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.005238 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-13 22:29:05.785593) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-13 22:29:05.785925) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-13 22:29:05.786277) ========= ========= Total master_lag: 0.18 =========